builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0293 starttime: 1480156859.96 results: success (0) buildid: 20161126004005 builduid: 2dd85c114d6048d08be196af4bcca96f revision: d87b17a47f600b30cad7e7a48dfaa42d3faa7678 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-26 02:40:59.962703) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-26 02:40:59.963245) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-26 02:40:59.963531) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-26 02:41:00.175678) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 02:41:00.176045) ========= 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.NYokUkO630/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.O5dL5UDssD/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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-26 02:41:00-- 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% 8.13M=0.001s 2016-11-26 02:41:00 (8.13 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.164120 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 02:41:00.385413) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 02:41:00.385687) ========= 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.NYokUkO630/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.O5dL5UDssD/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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.020825 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 02:41:00.458950) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-26 02:41:00.459270) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d87b17a47f600b30cad7e7a48dfaa42d3faa7678 --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 d87b17a47f600b30cad7e7a48dfaa42d3faa7678 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NYokUkO630/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.O5dL5UDssD/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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-26 02:41:00,551 truncating revision to first 12 chars 2016-11-26 02:41:00,551 Setting DEBUG logging. 2016-11-26 02:41:00,551 attempt 1/10 2016-11-26 02:41:00,551 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d87b17a47f60?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-26 02:41:01,657 unpacking tar archive at: mozilla-aurora-d87b17a47f60/testing/mozharness/ program finished with exit code 0 elapsedTime=1.364742 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-26 02:41:01.858798) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-26 02:41:01.859087) ========= script_repo_revision: d87b17a47f600b30cad7e7a48dfaa42d3faa7678 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-26 02:41:01.859469) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-26 02:41:01.859743) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-26 02:41:01.885183) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-26 02:41:01.885612) ========= /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.NYokUkO630/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.O5dL5UDssD/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:41:02 INFO - MultiFileLogger online at 20161126 02:41:02 in /builds/slave/test 02:41:02 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 02:41:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:41:02 INFO - {'append_to_log': False, 02:41:02 INFO - 'base_work_dir': '/builds/slave/test', 02:41:02 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:41:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:41:02 INFO - 'branch': 'Mozilla-Aurora', 02:41:02 INFO - 'buildbot_json_path': 'buildprops.json', 02:41:02 INFO - 'config_files': ('talos/mac_config.py',), 02:41:02 INFO - 'default_actions': ('clobber', 02:41:02 INFO - 'read-buildbot-config', 02:41:02 INFO - 'download-and-extract', 02:41:02 INFO - 'populate-webroot', 02:41:02 INFO - 'create-virtualenv', 02:41:02 INFO - 'install', 02:41:02 INFO - 'run-tests'), 02:41:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:41:02 INFO - 'download_minidump_stackwalk': True, 02:41:02 INFO - 'download_symbols': 'ondemand', 02:41:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:41:02 INFO - 'tooltool.py': '/tools/tooltool.py', 02:41:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:41:02 INFO - '/tools/misc-python/virtualenv.py')}, 02:41:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:41:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:41:02 INFO - 'installer_path': 'installer.exe', 02:41:02 INFO - 'log_level': 'info', 02:41:02 INFO - 'log_name': 'talos', 02:41:02 INFO - 'log_to_console': True, 02:41:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:41:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:41:02 INFO - 'opt_config_files': (), 02:41:02 INFO - 'pip_index': False, 02:41:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:41:02 INFO - 'cmd': ('bash', 02:41:02 INFO - '-c', 02:41:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:41:02 INFO - 'enabled': True, 02:41:02 INFO - 'halt_on_failure': False, 02:41:02 INFO - 'name': 'check_screen_resolution'},), 02:41:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:41:02 INFO - 'cmd': ('bash', 02:41:02 INFO - '-c', 02:41:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:41:02 INFO - 'enabled': True, 02:41:02 INFO - 'halt_on_failure': False, 02:41:02 INFO - 'name': 'check_screen_resolution'},), 02:41:02 INFO - 'run_cmd_checks_enabled': True, 02:41:02 INFO - 'sps_profile': False, 02:41:02 INFO - 'sps_profile_interval': 0, 02:41:02 INFO - 'suite': 'dromaeojs', 02:41:02 INFO - 'system_bits': '32', 02:41:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:41:02 INFO - 'title': 't-yosemite-r7-0293', 02:41:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:41:02 INFO - 'use_talos_json': True, 02:41:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:41:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:41:02 INFO - 'work_dir': 'build'} 02:41:02 INFO - [mozharness: 2016-11-26 10:41:02.086406Z] Running clobber step. 02:41:02 INFO - Running pre-action listener: _resource_record_pre_action 02:41:02 INFO - Running main action method: clobber 02:41:02 INFO - rmtree: /builds/slave/test/build 02:41:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:41:03 INFO - Running post-action listener: _resource_record_post_action 02:41:03 INFO - [mozharness: 2016-11-26 10:41:03.045559Z] Finished clobber step (success) 02:41:03 INFO - [mozharness: 2016-11-26 10:41:03.045816Z] Running read-buildbot-config step. 02:41:03 INFO - Running pre-action listener: _resource_record_pre_action 02:41:03 INFO - Running main action method: read_buildbot_config 02:41:03 INFO - Using buildbot properties: 02:41:03 INFO - { 02:41:03 INFO - "project": "", 02:41:03 INFO - "product": "firefox", 02:41:03 INFO - "script_repo_revision": "production", 02:41:03 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:41:03 INFO - "repository": "", 02:41:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:41:03 INFO - "buildid": "20161126004005", 02:41:03 INFO - "stage_platform": "macosx64", 02:41:03 INFO - "basedir": "/builds/slave/test", 02:41:03 INFO - "nightly_build": "True", 02:41:03 INFO - "buildnumber": 49, 02:41:03 INFO - "slavename": "t-yosemite-r7-0293", 02:41:03 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:41:03 INFO - "platform": "yosemite_r7", 02:41:03 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:41:03 INFO - "branch": "mozilla-aurora", 02:41:03 INFO - "repo_path": "releases/mozilla-aurora", 02:41:03 INFO - "revision": "d87b17a47f600b30cad7e7a48dfaa42d3faa7678", 02:41:03 INFO - "pgo_build": "False", 02:41:03 INFO - "builduid": "2dd85c114d6048d08be196af4bcca96f", 02:41:03 INFO - "slavebuilddir": "test" 02:41:03 INFO - } 02:41:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:41:03 INFO - Running post-action listener: _resource_record_post_action 02:41:03 INFO - [mozharness: 2016-11-26 10:41:03.051677Z] Finished read-buildbot-config step (success) 02:41:03 INFO - [mozharness: 2016-11-26 10:41:03.051849Z] Running download-and-extract step. 02:41:03 INFO - Running pre-action listener: _resource_record_pre_action 02:41:03 INFO - Running main action method: download_and_extract 02:41:03 INFO - mkdir: /builds/slave/test/build/tests 02:41:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:03 INFO - trying https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:41:03 INFO - Downloading https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/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 02:41:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/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 02:41:04 INFO - Downloaded 1199 bytes. 02:41:04 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:41:04 INFO - Using the following test package requirements: 02:41:04 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:41:04 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:04 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:41:04 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:04 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:41:04 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:04 INFO - u'jsshell-mac.zip'], 02:41:04 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:04 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:41:04 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:41:04 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:04 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:41:04 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:04 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:41:04 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:04 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:41:04 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:04 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:41:04 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:41:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:41:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:41:04 INFO - Fetch https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:41:05 INFO - Http code: 200 02:41:05 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 02:41:05 INFO - x-amz-cf-id: CkzjqkUKljNDu3AtktiVV0hhll1pk8rZFJ6tpzY9NNaGWeXP_HMYxg== 02:41:05 INFO - x-amz-version-id: OZciowVam0qopmCdSsIDeugx6YAr9QHk 02:41:06 INFO - Expected file size: 16278980 02:41:06 INFO - Obtained file size: 16278980 02:41:12 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 02:41:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:41:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:41:12 INFO - Fetch https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:41:13 INFO - Http code: 200 02:41:13 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 02:41:13 INFO - x-amz-cf-id: D9lH2EPu92RK3NiV-khv-D5drLgzm0rYw7GZcrrfNa4PHRlssUDeqA== 02:41:13 INFO - x-amz-version-id: OZciowVam0qopmCdSsIDeugx6YAr9QHk 02:41:13 INFO - Expected file size: 16278980 02:41:13 INFO - Obtained file size: 16278980 02:41:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:41:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:41:19 INFO - Fetch https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:41:21 INFO - Http code: 200 02:41:21 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 02:41:21 INFO - x-amz-cf-id: nVFacbtQZ5iwweOR9o53-KrZ86cVnny92JubXjutlyZbePHd5gJmig== 02:41:21 INFO - x-amz-version-id: F1nC2HgKaGMrp0X6P8lH4Nce0SQgZV3q 02:41:21 INFO - Expected file size: 14409038 02:41:21 INFO - Obtained file size: 14409038 02:41:22 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')]} 02:41:22 INFO - trying https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:41:22 INFO - Downloading https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:41:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/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 02:41:26 INFO - Downloaded 109741947 bytes. 02:41:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:41:26 INFO - mkdir: /builds/slave/test/properties 02:41:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:41:26 INFO - Writing to file /builds/slave/test/properties/build_url 02:41:26 INFO - Contents: 02:41:26 INFO - build_url:https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:41:26 INFO - Running post-action listener: _resource_record_post_action 02:41:26 INFO - Running post-action listener: set_extra_try_arguments 02:41:26 INFO - [mozharness: 2016-11-26 10:41:26.727206Z] Finished download-and-extract step (success) 02:41:26 INFO - [mozharness: 2016-11-26 10:41:26.727326Z] Running populate-webroot step. 02:41:26 INFO - Running pre-action listener: _resource_record_pre_action 02:41:26 INFO - Running main action method: populate_webroot 02:41:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:41:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:41:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:41:26 INFO - '--tppagecycles', 02:41:26 INFO - '7'], 02:41:26 INFO - 'tests': ['tsvgm']}}, 02:41:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:41:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:41:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:41:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:41:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:41:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:41:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:26 INFO - 'talos_options': ['--disable-e10s'], 02:41:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:41:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:41:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:26 INFO - 'talos_options': ['--disable-e10s'], 02:41:26 INFO - 'tests': ['damp', 'tps']}, 02:41:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:26 INFO - 'tests': ['damp', 'tps']}, 02:41:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:41:26 INFO - 'tests': ['dromaeo_dom']}, 02:41:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:41:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:41:26 INFO - 'tests': ['basic_compositor_video']}, 02:41:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:41:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:41:26 INFO - 'tests': ['a11yr', 02:41:26 INFO - 'ts_paint', 02:41:26 INFO - 'tpaint', 02:41:26 INFO - 'sessionrestore', 02:41:26 INFO - 'sessionrestore_no_auto_restore', 02:41:26 INFO - 'tabpaint']}, 02:41:26 INFO - 'other-e10s': {'tests': ['a11yr', 02:41:26 INFO - 'ts_paint', 02:41:26 INFO - 'tpaint', 02:41:26 INFO - 'sessionrestore', 02:41:26 INFO - 'sessionrestore_no_auto_restore', 02:41:26 INFO - 'tabpaint']}, 02:41:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:41:26 INFO - 'ts_paint', 02:41:26 INFO - 'tpaint', 02:41:26 INFO - 'sessionrestore', 02:41:26 INFO - 'sessionrestore_no_auto_restore', 02:41:26 INFO - 'tabpaint']}, 02:41:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:41:26 INFO - 'ts_paint', 02:41:26 INFO - 'tpaint', 02:41:26 INFO - 'sessionrestore', 02:41:26 INFO - 'sessionrestore_no_auto_restore', 02:41:26 INFO - 'tabpaint']}, 02:41:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:41:26 INFO - 'tests': ['a11yr', 02:41:26 INFO - 'ts_paint', 02:41:26 INFO - 'tpaint', 02:41:26 INFO - 'sessionrestore', 02:41:26 INFO - 'sessionrestore_no_auto_restore', 02:41:26 INFO - 'tabpaint']}, 02:41:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:41:26 INFO - 'tests': ['a11yr', 02:41:26 INFO - 'ts_paint', 02:41:26 INFO - 'tpaint', 02:41:26 INFO - 'sessionrestore', 02:41:26 INFO - 'sessionrestore_no_auto_restore', 02:41:26 INFO - 'tabpaint']}, 02:41:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:41:26 INFO - 'tests': ['tsvgx', 02:41:26 INFO - 'tsvgr_opacity', 02:41:26 INFO - 'tart', 02:41:26 INFO - 'tscrollx', 02:41:26 INFO - 'cart']}, 02:41:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:41:26 INFO - 'tsvgr_opacity', 02:41:26 INFO - 'tart', 02:41:26 INFO - 'tscrollx', 02:41:26 INFO - 'cart']}, 02:41:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:26 INFO - 'talos_options': ['--disable-e10s'], 02:41:26 INFO - 'tests': ['tp5o']}, 02:41:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:26 INFO - 'tests': ['tp5o']}, 02:41:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:26 INFO - 'talos_options': ['--disable-e10s', 02:41:26 INFO - '--xperf_path', 02:41:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:41:26 INFO - 'tests': ['tp5n']}, 02:41:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:26 INFO - 'talos_options': ['--xperf_path', 02:41:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:41:26 INFO - 'tests': ['tp5n']}}, 02:41:26 INFO - 'talos.zip': {'path': '', 02:41:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:41:26 INFO - Running post-action listener: _resource_record_post_action 02:41:26 INFO - [mozharness: 2016-11-26 10:41:26.746334Z] Finished populate-webroot step (success) 02:41:26 INFO - [mozharness: 2016-11-26 10:41:26.746472Z] Running create-virtualenv step. 02:41:26 INFO - Running pre-action listener: _resource_record_pre_action 02:41:26 INFO - Running main action method: create_virtualenv 02:41:26 INFO - Creating virtualenv /builds/slave/test/build/venv 02:41:26 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 02:41:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:41:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:41:26 INFO - Using real prefix '/tools/python27' 02:41:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:41:27 INFO - Installing distribute.............................................................................................................................................................................................done. 02:41:30 INFO - Installing pip.................done. 02:41:30 INFO - Return code: 0 02:41:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:41:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:41:30 INFO - Reading from file tmpfile_stdout 02:41:30 INFO - Output received: 02:41:30 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) 02:41:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:30 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 0x10b5bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b533570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd519d69a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3d1d30>, '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 0x10b237db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c9b20>, '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.NYokUkO630/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.O5dL5UDssD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:41:30 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 02:41:30 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 02:41:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NYokUkO630/Render', 02:41:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:30 INFO - 'HOME': '/Users/cltbld', 02:41:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:30 INFO - 'LOGNAME': 'cltbld', 02:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:30 INFO - 'MOZ_NO_REMOTE': '1', 02:41:30 INFO - 'NO_EM_RESTART': '1', 02:41:30 INFO - 'PAGER': '/bin/cat', 02:41:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:30 INFO - 'PWD': '/builds/slave/test', 02:41:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:30 INFO - 'SHELL': '/bin/bash', 02:41:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5dL5UDssD/Listeners', 02:41:30 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:41:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:30 INFO - 'USER': 'cltbld', 02:41:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:30 INFO - 'XPC_FLAGS': '0x0', 02:41:30 INFO - 'XPC_SERVICE_NAME': '0', 02:41:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:31 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 02:41:31 INFO - Cleaning up... 02:41:31 INFO - Return code: 0 02:41:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:31 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 0x10b5bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b533570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd519d69a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3d1d30>, '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 0x10b237db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c9b20>, '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.NYokUkO630/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.O5dL5UDssD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:41:31 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 02:41:31 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 02:41:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NYokUkO630/Render', 02:41:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:31 INFO - 'HOME': '/Users/cltbld', 02:41:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:31 INFO - 'LOGNAME': 'cltbld', 02:41:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:31 INFO - 'MOZ_NO_REMOTE': '1', 02:41:31 INFO - 'NO_EM_RESTART': '1', 02:41:31 INFO - 'PAGER': '/bin/cat', 02:41:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:31 INFO - 'PWD': '/builds/slave/test', 02:41:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:31 INFO - 'SHELL': '/bin/bash', 02:41:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5dL5UDssD/Listeners', 02:41:31 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:41:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:31 INFO - 'USER': 'cltbld', 02:41:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:31 INFO - 'XPC_FLAGS': '0x0', 02:41:31 INFO - 'XPC_SERVICE_NAME': '0', 02:41:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:31 INFO - Downloading/unpacking psutil>=3.1.1 02:41:31 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 02:41:31 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 02:41:31 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 02:41:31 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 02:41:31 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 02:41:31 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 02:41:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:41:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:41:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:41:35 INFO - Installing collected packages: psutil 02:41:35 INFO - Running setup.py install for psutil 02:41:35 INFO - building 'psutil._psutil_osx' extension 02:41:35 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 02:41:36 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 02:41:36 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 02:41:36 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 02:41:36 INFO - building 'psutil._psutil_posix' extension 02:41:36 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 02:41:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:41:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:41:36 INFO - ^ 02:41:36 INFO - 1 warning generated. 02:41:36 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 02:41:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:41:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:41:36 INFO - Successfully installed psutil 02:41:36 INFO - Cleaning up... 02:41:36 INFO - Return code: 0 02:41:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:41:36 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')]} 02:41:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:36 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')]} 02:41:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:36 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 0x10b5bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b533570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd519d69a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3d1d30>, '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 0x10b237db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c9b20>, '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.NYokUkO630/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.O5dL5UDssD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:41:36 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 02:41:36 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 02:41:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NYokUkO630/Render', 02:41:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:36 INFO - 'HOME': '/Users/cltbld', 02:41:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:36 INFO - 'LOGNAME': 'cltbld', 02:41:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:36 INFO - 'MOZ_NO_REMOTE': '1', 02:41:36 INFO - 'NO_EM_RESTART': '1', 02:41:36 INFO - 'PAGER': '/bin/cat', 02:41:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:36 INFO - 'PWD': '/builds/slave/test', 02:41:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:36 INFO - 'SHELL': '/bin/bash', 02:41:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5dL5UDssD/Listeners', 02:41:36 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:41:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:36 INFO - 'USER': 'cltbld', 02:41:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:36 INFO - 'XPC_FLAGS': '0x0', 02:41:36 INFO - 'XPC_SERVICE_NAME': '0', 02:41:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:41:37 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 02:41:37 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 02:41:37 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 02:41:37 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 02:41:37 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 02:41:37 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 02:41:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:41:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:41:39 INFO - Installing collected packages: mozsystemmonitor 02:41:39 INFO - Running setup.py install for mozsystemmonitor 02:41:39 INFO - Successfully installed mozsystemmonitor 02:41:39 INFO - Cleaning up... 02:41:39 INFO - Return code: 0 02:41:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:41: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')]} 02:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41: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')]} 02:41:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41: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', '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 0x10b5bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b533570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd519d69a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3d1d30>, '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 0x10b237db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c9b20>, '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.NYokUkO630/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.O5dL5UDssD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:41: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:41: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 jsonschema==2.5.1 02:41:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NYokUkO630/Render', 02:41:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:39 INFO - 'HOME': '/Users/cltbld', 02:41:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:39 INFO - 'LOGNAME': 'cltbld', 02:41:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:39 INFO - 'MOZ_NO_REMOTE': '1', 02:41:39 INFO - 'NO_EM_RESTART': '1', 02:41:39 INFO - 'PAGER': '/bin/cat', 02:41:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:39 INFO - 'PWD': '/builds/slave/test', 02:41:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:39 INFO - 'SHELL': '/bin/bash', 02:41:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5dL5UDssD/Listeners', 02:41:39 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:41:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:39 INFO - 'USER': 'cltbld', 02:41:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:39 INFO - 'XPC_FLAGS': '0x0', 02:41:39 INFO - 'XPC_SERVICE_NAME': '0', 02:41:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:40 INFO - Downloading/unpacking jsonschema==2.5.1 02:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:41:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:41: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 02:41: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 02:41: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 02:41: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 02:41: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 02:41: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 02:41:43 INFO - Downloading functools32-3.2.3-2.tar.gz 02:41:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:41:43 INFO - warning: no files found matching '*.txt' 02:41:43 INFO - no previously-included directories found matching 'build' 02:41:43 INFO - no previously-included directories found matching 'dist' 02:41:43 INFO - no previously-included directories found matching '.git*' 02:41:43 INFO - Installing collected packages: jsonschema, functools32 02:41:43 INFO - Running setup.py install for functools32 02:41:43 INFO - warning: no files found matching '*.txt' 02:41:43 INFO - no previously-included directories found matching 'build' 02:41:43 INFO - no previously-included directories found matching 'dist' 02:41:43 INFO - no previously-included directories found matching '.git*' 02:41:43 INFO - Successfully installed jsonschema functools32 02:41:43 INFO - Cleaning up... 02:41:43 INFO - Return code: 0 02:41:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b5bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b533570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd519d69a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3d1d30>, '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 0x10b237db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c9b20>, '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.NYokUkO630/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.O5dL5UDssD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:41:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:41:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:41:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NYokUkO630/Render', 02:41:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:43 INFO - 'HOME': '/Users/cltbld', 02:41:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:43 INFO - 'LOGNAME': 'cltbld', 02:41:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:43 INFO - 'MOZ_NO_REMOTE': '1', 02:41:43 INFO - 'NO_EM_RESTART': '1', 02:41:43 INFO - 'PAGER': '/bin/cat', 02:41:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:43 INFO - 'PWD': '/builds/slave/test', 02:41:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:43 INFO - 'SHELL': '/bin/bash', 02:41:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5dL5UDssD/Listeners', 02:41:43 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:41:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:43 INFO - 'USER': 'cltbld', 02:41:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:43 INFO - 'XPC_FLAGS': '0x0', 02:41:43 INFO - 'XPC_SERVICE_NAME': '0', 02:41:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:41:43 INFO - Cleaning up... 02:41:43 INFO - Return code: 0 02:41:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b5bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b533570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd519d69a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3d1d30>, '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 0x10b237db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c9b20>, '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.NYokUkO630/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.O5dL5UDssD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:41:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:41:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:41:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NYokUkO630/Render', 02:41:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:43 INFO - 'HOME': '/Users/cltbld', 02:41:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:43 INFO - 'LOGNAME': 'cltbld', 02:41:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:43 INFO - 'MOZ_NO_REMOTE': '1', 02:41:43 INFO - 'NO_EM_RESTART': '1', 02:41:43 INFO - 'PAGER': '/bin/cat', 02:41:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:43 INFO - 'PWD': '/builds/slave/test', 02:41:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:43 INFO - 'SHELL': '/bin/bash', 02:41:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5dL5UDssD/Listeners', 02:41:43 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:41:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:43 INFO - 'USER': 'cltbld', 02:41:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:43 INFO - 'XPC_FLAGS': '0x0', 02:41:43 INFO - 'XPC_SERVICE_NAME': '0', 02:41:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:44 INFO - Downloading/unpacking blobuploader==1.2.4 02:41: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 02:41: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 02:41: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 02:41: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 02:41: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 02:41: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 02:41:48 INFO - Downloading blobuploader-1.2.4.tar.gz 02:41:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:41:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:41: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 02:41: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 02:41: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 02:41: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 02:41: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 02:41: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 02:41:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:41:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:41: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 02:41: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 02:41: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 02:41: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 02:41: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 02:41: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 02:41:50 INFO - Downloading docopt-0.6.1.tar.gz 02:41:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:41:50 INFO - Installing collected packages: blobuploader, requests, docopt 02:41:50 INFO - Running setup.py install for blobuploader 02:41:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:41:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:41:50 INFO - Running setup.py install for requests 02:41:51 INFO - Running setup.py install for docopt 02:41:51 INFO - Successfully installed blobuploader requests docopt 02:41:51 INFO - Cleaning up... 02:41:51 INFO - Return code: 0 02:41:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:41:51 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')]} 02:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:51 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')]} 02:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:51 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 0x10b5bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b533570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd519d69a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3d1d30>, '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 0x10b237db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c9b20>, '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.NYokUkO630/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.O5dL5UDssD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:41:51 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 02:41:51 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 02:41:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NYokUkO630/Render', 02:41:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:51 INFO - 'HOME': '/Users/cltbld', 02:41:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:51 INFO - 'LOGNAME': 'cltbld', 02:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:51 INFO - 'MOZ_NO_REMOTE': '1', 02:41:51 INFO - 'NO_EM_RESTART': '1', 02:41:51 INFO - 'PAGER': '/bin/cat', 02:41:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:51 INFO - 'PWD': '/builds/slave/test', 02:41:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:51 INFO - 'SHELL': '/bin/bash', 02:41:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5dL5UDssD/Listeners', 02:41:51 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:41:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:51 INFO - 'USER': 'cltbld', 02:41:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:51 INFO - 'XPC_FLAGS': '0x0', 02:41:51 INFO - 'XPC_SERVICE_NAME': '0', 02:41:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:41:51 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-_H3VM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:41:51 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-rEAR5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:41:51 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-xz5nOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:41:51 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-RSHQCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:41:52 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-tKc4C2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:41:52 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-7u9Te_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:41:52 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-SA2f3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:41:52 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-wh2lW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:41:52 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Vqikzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:41:52 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-vi_Zb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:41:52 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-NG36OD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:41:52 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-icgEc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:41:52 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-_tLEWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:41:52 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-sWyR0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:53 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ZOTTxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:41:53 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-CE0D6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:41:53 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-hBV0Uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:41:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:41:53 INFO - Running setup.py install for manifestparser 02:41:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:41:53 INFO - Running setup.py install for mozcrash 02:41:53 INFO - Running setup.py install for mozdebug 02:41:53 INFO - Running setup.py install for mozdevice 02:41:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:41:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:41:54 INFO - Running setup.py install for mozfile 02:41:54 INFO - Running setup.py install for mozhttpd 02:41:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:41:54 INFO - Running setup.py install for mozinfo 02:41:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:41:54 INFO - Running setup.py install for mozInstall 02:41:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:41:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:41:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:41:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:41:54 INFO - Running setup.py install for mozleak 02:41:54 INFO - Running setup.py install for mozlog 02:41:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:41:54 INFO - Running setup.py install for moznetwork 02:41:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:41:55 INFO - Running setup.py install for mozprocess 02:41:55 INFO - Running setup.py install for mozprofile 02:41:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:41:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:41:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:41:55 INFO - Running setup.py install for mozrunner 02:41:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:41:55 INFO - Running setup.py install for mozscreenshot 02:41:55 INFO - Running setup.py install for moztest 02:41:55 INFO - Running setup.py install for mozversion 02:41:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:41:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:41:55 INFO - Cleaning up... 02:41:56 INFO - Return code: 0 02:41:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:56 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 0x10b5bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b533570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd519d69a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3d1d30>, '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 0x10b237db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c9b20>, '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.NYokUkO630/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.O5dL5UDssD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:41:56 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 02:41:56 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 02:41:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NYokUkO630/Render', 02:41:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:56 INFO - 'HOME': '/Users/cltbld', 02:41:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:56 INFO - 'LOGNAME': 'cltbld', 02:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:56 INFO - 'MOZ_NO_REMOTE': '1', 02:41:56 INFO - 'NO_EM_RESTART': '1', 02:41:56 INFO - 'PAGER': '/bin/cat', 02:41:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:56 INFO - 'PWD': '/builds/slave/test', 02:41:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:56 INFO - 'SHELL': '/bin/bash', 02:41:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5dL5UDssD/Listeners', 02:41:56 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:41:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:56 INFO - 'USER': 'cltbld', 02:41:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:56 INFO - 'XPC_FLAGS': '0x0', 02:41:56 INFO - 'XPC_SERVICE_NAME': '0', 02:41:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:41:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-XtB3ja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:41:56 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)) 02:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:41:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-VxY8Pr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:41:56 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)) 02:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:41:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Qk076f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:41:56 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)) 02:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:41:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-qg3NQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:41: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)) 02:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:41:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-PYiEC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:41: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)) 02:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:41:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-9LSXKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:41: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)) 02:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:41:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-XVs42S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:41:57 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)) 02:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:41:57 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-I9mGi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:41:57 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)) 02:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:41:57 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-SAJe_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:41:57 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)) 02:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:41:57 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-26ewwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:41:57 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)) 02:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:41:57 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ykBt8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:41:57 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)) 02:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:41:57 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-nkF89l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:41:57 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)) 02:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:41:57 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-D59dz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:41:57 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)) 02:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:41:57 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-AznJyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:41: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)) 02:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:57 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-AeCHcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:41: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)) 02:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:41:57 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-uvfjwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:41: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)) 02:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:41:57 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-0yXwT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:41:58 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)) 02:41:58 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)) 02:41:58 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)) 02:41:58 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)) 02:41:58 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)) 02:41:58 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)) 02:41:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:00 INFO - Downloading blessings-1.6.tar.gz 02:42:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:42:00 INFO - Installing collected packages: blessings 02:42:00 INFO - Running setup.py install for blessings 02:42:00 INFO - Successfully installed blessings 02:42:00 INFO - Cleaning up... 02:42:01 INFO - Return code: 0 02:42:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:42:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:42:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:42:01 INFO - Reading from file tmpfile_stdout 02:42:01 INFO - Current package versions: 02:42:01 INFO - blessings == 1.6 02:42:01 INFO - blobuploader == 1.2.4 02:42:01 INFO - docopt == 0.6.1 02:42:01 INFO - functools32 == 3.2.3-2 02:42:01 INFO - jsonschema == 2.5.1 02:42:01 INFO - manifestparser == 1.1 02:42:01 INFO - mozInstall == 1.12 02:42:01 INFO - mozcrash == 1.0 02:42:01 INFO - mozdebug == 0.1 02:42:01 INFO - mozdevice == 0.48 02:42:01 INFO - mozfile == 1.2 02:42:01 INFO - mozhttpd == 0.7 02:42:01 INFO - mozinfo == 0.9 02:42:01 INFO - mozleak == 0.1 02:42:01 INFO - mozlog == 3.4 02:42:01 INFO - moznetwork == 0.27 02:42:01 INFO - mozprocess == 0.23 02:42:01 INFO - mozprofile == 0.28 02:42:01 INFO - mozrunner == 6.13 02:42:01 INFO - mozscreenshot == 0.1 02:42:01 INFO - mozsystemmonitor == 0.3 02:42:01 INFO - moztest == 0.7 02:42:01 INFO - mozversion == 1.4 02:42:01 INFO - psutil == 3.1.1 02:42:01 INFO - requests == 1.2.3 02:42:01 INFO - wsgiref == 0.1.2 02:42:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:42: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')]} 02:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42: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')]} 02:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42: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 0x10b5bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b533570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd519d69a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3d1d30>, '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 0x10b237db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c9b20>, '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.NYokUkO630/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.O5dL5UDssD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:42: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 02:42: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 02:42:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NYokUkO630/Render', 02:42:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:01 INFO - 'HOME': '/Users/cltbld', 02:42:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:01 INFO - 'LOGNAME': 'cltbld', 02:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:01 INFO - 'MOZ_NO_REMOTE': '1', 02:42:01 INFO - 'NO_EM_RESTART': '1', 02:42:01 INFO - 'PAGER': '/bin/cat', 02:42:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:01 INFO - 'PWD': '/builds/slave/test', 02:42:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:01 INFO - 'SHELL': '/bin/bash', 02:42:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5dL5UDssD/Listeners', 02:42:01 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:42:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:01 INFO - 'USER': 'cltbld', 02:42:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:01 INFO - 'XPC_FLAGS': '0x0', 02:42:01 INFO - 'XPC_SERVICE_NAME': '0', 02:42:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42: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)) 02:42: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)) 02:42: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)) 02:42: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)) 02:42: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)) 02:42: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)) 02:42: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)) 02:42: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)) 02:42: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)) 02:42: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)) 02:42: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)) 02:42:01 INFO - Cleaning up... 02:42:01 INFO - Return code: 0 02:42:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:42: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')]} 02:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42: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')]} 02:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42: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 0x10b5bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b533570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd519d69a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3d1d30>, '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 0x10b237db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c9b20>, '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.NYokUkO630/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.O5dL5UDssD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 02:42: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 02:42: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 02:42:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NYokUkO630/Render', 02:42:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:01 INFO - 'HOME': '/Users/cltbld', 02:42:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:01 INFO - 'LOGNAME': 'cltbld', 02:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:01 INFO - 'MOZ_NO_REMOTE': '1', 02:42:01 INFO - 'NO_EM_RESTART': '1', 02:42:01 INFO - 'PAGER': '/bin/cat', 02:42:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:01 INFO - 'PWD': '/builds/slave/test', 02:42:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:01 INFO - 'SHELL': '/bin/bash', 02:42:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5dL5UDssD/Listeners', 02:42:01 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:42:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:01 INFO - 'USER': 'cltbld', 02:42:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:01 INFO - 'XPC_FLAGS': '0x0', 02:42:01 INFO - 'XPC_SERVICE_NAME': '0', 02:42:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:42:01 INFO - Cleaning up... 02:42:01 INFO - Return code: 0 02:42:01 INFO - Running post-action listener: _resource_record_post_action 02:42:01 INFO - Running post-action listener: _start_resource_monitoring 02:42:01 INFO - Starting resource monitoring. 02:42:01 INFO - [mozharness: 2016-11-26 10:42:01.881445Z] Finished create-virtualenv step (success) 02:42:01 INFO - [mozharness: 2016-11-26 10:42:01.882506Z] Running install step. 02:42:01 INFO - Running pre-action listener: _resource_record_pre_action 02:42:01 INFO - Running main action method: install 02:42:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:42:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:42:02 INFO - Reading from file tmpfile_stdout 02:42:02 INFO - Detecting whether we're running mozinstall >=1.0... 02:42:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:42:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:42:02 INFO - Reading from file tmpfile_stdout 02:42:02 INFO - Output received: 02:42:02 INFO - Usage: mozinstall [options] installer 02:42:02 INFO - Options: 02:42:02 INFO - -h, --help show this help message and exit 02:42:02 INFO - -d DEST, --destination=DEST 02:42:02 INFO - Directory to install application into. [default: 02:42:02 INFO - "/builds/slave/test"] 02:42:02 INFO - --app=APP Application being installed. [default: firefox] 02:42:02 INFO - mkdir: /builds/slave/test/build/application 02:42: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'] 02:42: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 02:42:33 INFO - Reading from file tmpfile_stdout 02:42:33 INFO - Output received: 02:42:33 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:42:33 INFO - Running post-action listener: _resource_record_post_action 02:42:33 INFO - [mozharness: 2016-11-26 10:42:33.378793Z] Finished install step (success) 02:42:33 INFO - [mozharness: 2016-11-26 10:42:33.378931Z] Running run-tests step. 02:42:33 INFO - Running pre-action listener: _resource_record_pre_action 02:42:33 INFO - Running main action method: run_tests 02:42:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:42:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:42:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:42:33 INFO - 2016-11-26 02:42:33.391 screenresolution[2213:26073] starting screenresolution argv=screenresolution get 02:42:33 INFO - 2016-11-26 02:42:33.394 screenresolution[2213:26073] Display 0: 1600x1200x32@60 02:42:33 INFO - 2016-11-26 02:42:33.399 screenresolution[2214:26075] starting screenresolution argv=screenresolution list 02:42:33 INFO - Available Modes on Display 0 02:42:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:42:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:42:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:42:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:42:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:42:33 INFO - Intel Iris: 02:42:33 INFO - Chipset Model: Intel Iris 02:42:33 INFO - Type: GPU 02:42:33 INFO - Bus: Built-In 02:42:33 INFO - VRAM (Dynamic, Max): 1536 MB 02:42:33 INFO - Vendor: Intel (0x8086) 02:42:33 INFO - Device ID: 0x0a2e 02:42:33 INFO - Revision ID: 0x0009 02:42:33 INFO - Displays: 02:42:33 INFO - Display: 02:42:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:42:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:42:33 INFO - Display Serial Number: 1600x1200 60 02:42:33 INFO - Main Display: Yes 02:42:33 INFO - Mirror: Off 02:42:33 INFO - Online: Yes 02:42:33 INFO - Rotation: Supported 02:42:33 INFO - Return code: 0 02:42:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:42:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:42:33 INFO - Python 2.7.3 02:42:33 INFO - Return code: 0 02:42:33 INFO - grabbing minidump binary from tooltool 02:42:33 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')]} 02:42:33 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 0x10b3d1d30>, '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 0x10b237db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:42:33 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 02:42:33 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 02:42:33 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 02:42:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:42:33 INFO - Return code: 0 02:42:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:42:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:42:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:42:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:42:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:42:33 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/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0293', '--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 02:42:33 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/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0293 --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 02:42:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NYokUkO630/Render', 02:42:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:33 INFO - 'HOME': '/Users/cltbld', 02:42:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:33 INFO - 'LOGNAME': 'cltbld', 02:42:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:42:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:33 INFO - 'MOZ_NO_REMOTE': '1', 02:42:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:42:33 INFO - 'NO_EM_RESTART': '1', 02:42:33 INFO - 'PAGER': '/bin/cat', 02:42:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:33 INFO - 'PWD': '/builds/slave/test', 02:42:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:42:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:33 INFO - 'SHELL': '/bin/bash', 02:42:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5dL5UDssD/Listeners', 02:42:33 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 02:42:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:33 INFO - 'USER': 'cltbld', 02:42:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:33 INFO - 'XPC_FLAGS': '0x0', 02:42:33 INFO - 'XPC_SERVICE_NAME': '0', 02:42:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:33 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/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0293', '--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 02:42:33 INFO - mozversion application_buildid: 20161126004005 02:42:33 INFO - mozversion application_changeset: d87b17a47f600b30cad7e7a48dfaa42d3faa7678 02:42:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:42:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:42:33 INFO - mozversion application_name: Firefox 02:42:33 INFO - mozversion application_remotingname: firefox-dev 02:42:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:42:33 INFO - mozversion application_vendor: Mozilla 02:42:33 INFO - mozversion application_version: 52.0a2 02:42:33 INFO - mozversion platform_buildid: 20161126004005 02:42:33 INFO - mozversion platform_changeset: d87b17a47f600b30cad7e7a48dfaa42d3faa7678 02:42:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:42:33 INFO - mozversion platform_version: 52.0a2 02:42:33 INFO - using testdate: 1480156953 02:42:33 INFO - actual date: 1480156953 02:42:33 INFO - starting webserver on 'localhost:53877' 02:42:33 INFO - SUITE-START | Running 2 tests 02:42:33 INFO - TEST-START | dromaeo_css 02:42:33 INFO - operating with platform_type : mac_ 02:42:33 INFO - Initialising browser for dromaeo_css test... 02:42:34 INFO - TEST-INFO | started process 2224 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmppNT5Vo/profile http://localhost:53877/getInfo.html) 02:42:36 INFO - PROCESS | 2224 | __metrics Screen width/height:1600/1200 02:42:36 INFO - PROCESS | 2224 | colorDepth:24 02:42:36 INFO - PROCESS | 2224 | Browser inner width/height: 1024/689 02:42:36 INFO - PROCESS | 2224 | __metrics 02:42:36 INFO - PROCESS | 2224 | 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" 02:42:36 INFO - PROCESS | 2224 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:42:37 INFO - TEST-INFO | 2224: exit 0 02:42:37 INFO - Browser initialized. 02:42:37 INFO - Running cycle 1/1 for dromaeo_css test... 02:42:37 INFO - TEST-INFO | started process 2227 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmppNT5Vo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:42:49 INFO - PROCESS | 2227 | RSS: Main: 222892032 02:42:49 INFO - PROCESS | 2227 | 02:43:43 INFO - PROCESS | 2227 | 1480157023723 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] 02:45:54 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:53877/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53877/tests/dromaeo/cssquery-ext.html) 02:45:56 INFO - PROCESS | 2227 | RSS: Main: 336347136 02:45:56 INFO - PROCESS | 2227 | 02:48:59 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:53877/tests/dromaeo/cssquery-ext.html (next: http://localhost:53877/tests/dromaeo/cssquery-jquery.html) 02:48:59 INFO - PROCESS | 2227 | RSS: Main: 348422144 02:48:59 INFO - PROCESS | 2227 | 02:52:01 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:53877/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53877/tests/dromaeo/cssquery-mootools.html) 02:52:02 INFO - PROCESS | 2227 | RSS: Main: 312680448 02:52:02 INFO - PROCESS | 2227 | 02:55:04 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:53877/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53877/tests/dromaeo/cssquery-prototype.html) 02:55:05 INFO - PROCESS | 2227 | RSS: Main: 317767680 02:55:05 INFO - PROCESS | 2227 | 02:58:09 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:53877/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53877/tests/dromaeo/cssquery-yui.html) 02:58:09 INFO - PROCESS | 2227 | RSS: Main: 307736576 02:58:09 INFO - PROCESS | 2227 | 03:01:12 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:53877/tests/dromaeo/cssquery-yui.html (next: http://localhost:53877/tests/dromaeo/cssquery-dojo.html) 03:01:12 INFO - PROCESS | 2227 | RSS: Main: 424796160 03:01:12 INFO - PROCESS | 2227 | 03:01:12 INFO - PROCESS | 2227 | __start_tp_report 03:01:12 INFO - PROCESS | 2227 | _x_x_mozilla_page_load 03:01:12 INFO - PROCESS | 2227 | _x_x_mozilla_page_load_details 03:01:12 INFO - PROCESS | 2227 | |i|pagename|runs| 03:01:12 INFO - PROCESS | 2227 | |0;dojo.html;3317.402867483853;3317.452620317648;3345.9330813383717;3346.2470944037577;3381.9684996076203;5627.521660658838;5629.887402251952;5686.0333743263645;5694.5159661428725;5720.141978703196;5630.915536266959;5655.8586035349135;5739.569532285074;5765.827025189251;5771.942280577193;5170.508801663836;5217.765200565965;5233.110371236899;5256.605754568403;5291.788328466857;5174.482551744814;5191.948080519184;5209.348831396075;5270.604704647148;5327.866803329928;4391.27543955247;5270.446603106676;5285.445028272034;5294.92057619136;5298.019866324723;5197.194434862602;5220.216967454874;5293.9735301323635;5308.973455132699;5312.574994000471;5142.151544995071;5176.2235664650225;5227.320448341691;5232.764525596357;5284.81503147388;5713.971430142864;5723.313202415695;5788.507976821975;5810.738516766755;5829.708514574297;6134.8159555213415;6136.294326152461;6165.414285642857;6166.167567378437;6172;6244.219472565929;6285.057241413779;6302.243730752293;6320.841978950517;6360.459360954324;5851.765929362863;5893.233879595625;5905.911411328833;5907.556933230025;5951.434613711691;6241.594296370721;6244.968775156095;6300.086487459293;6308.842278943063;6309.873802523924;6230.345813689588;6230.750769969241;6234.844128896814;6234.906476402857;6238.875222495525;6293.30773614902;6310.5898116622275;6323.936760632427;6334.841628959313;6345.555811093179;6030.396960304022;6037.154798328237;6131.724072416752;6146.647737497743;6220.03589443637;6254.186955695822;6296.842578935563;6301.873962520815;6305.96847015762;6309.7160627770945;4918.803247870117;4951;4965.577925876269;4976.104301225741;5005.27423523587;4377.737335759864;4410.823567057282;4472.776361181928;4484.551544845489;4499.842505512292;5699.259096317535;5705.657660540381;5708;5710.714464276853;5716.742746576414;5234.9476505235225;6216.378362163748;6252.687365631791;6281.026440901667;6311.842203944938;5769.048107062287;5773.134029895549;5782.508486778671;5795.710214489259;5830.47525722687;5436.320459942507;5445.455454454523;5450.182472629058;5466.590005749585;5475.698836563947;6229.470495007794;6235.127082208403;6290.056491526125;6290.402411770874;6304.527160463075;6272.874542509216;6309.274433440213;6314.147590075281;6321.051842223703;6321.431071203614;6275.780347687809;6351.07909353132;6361.236651601837;6365.96817015912;6368.395002474757;6058.757649693963;6105.206323177958;6106.755729770937;6118.326984031664;6244.250689917239;6023.698815059317;6152.477039451696;6219.191505104308;6246.968765156145;6257.687115644108;6086.573939824169;6118.60229085108;6129.724162412613;6136.386361363828;6137.539684523589;6043.365446628217;6108.633481991094;6120.755169793337;6128.203333566607;6143.139960405636;5814.592978491465;5852.297724273115;5928.34788173292;5939.346671866005;5941.970290148522;6165.383461653799;6165.84585385351;6197.81406557804;6205.751769929152;6208.0998255253635;5954.464098231007;5987.6108052976415;6008.639481631116;6018.939810601838;6075.878482430238 03:01:12 INFO - PROCESS | 2227 | |1;ext.html;478.9832355867541;481.8458093410108;504.9267856160857;513.3967588084;520.9218617207418;30347.241318967037;31217.751289948374;31270.53094203583;31298.374032519336;31337;40351;40406.39390409146;40466;42203.57796422035;42261;8065.717699880505;9269.443833370002;9410.576524056434;9426.198773104294;9427.575759090823;9403.38877972933;9474.621015159386;9485.051494850495;9485.573149208301;9491.335606507548;9460.8580871287;9495.81008379832;9510.239180865514;9517.000714924938;9532.571034303473;9380.953095234514;9482.620695172185;9483.573239204252;9489.952550237205;9492.098250666175;9316.254699624082;9380.812383752354;9390.014048524874;9407.388519746195;9413.717588472287;38230.617693822984;38589.61410385917;38601;38620.22759544822;38622;3402.761806673534;3485.494603282535;3684.705223582107;3810.333191691471;3863.97604634772;27809.72190278112;28151.155465336073;29721.70278297211;30414.391712165852;30433.087007389593;3168.889088881901;3212.903612891617;3217.356528694247;3264.836758162106;3319.468884978416;493888;495410;495842;496507;507582;32517;33175.502367464505;33469.665303347145;56163;56640.716796415756;34732.65267347345;34799.30401391958;34835.82582087124;34869.825650871586;34905.65094349024;14439.494617688331;14553.708925821424;14561.635959101108;14569.562913112624;14576.562703118923;12225.327606981524;12318.81521777174;12331.260124392566;12336.50653973849;12346.95050920664;7683.807904802425;7714.614269286513;7773.572453515111;7782.260685235007;7787.92212077872;3123.828189449602;3174.587303650557;3187.0757480330444;3189.824559649195;3217.099212220582;828.29594844383;905.9637614495479;942.5098948546718;1002.3134153105146;1040.1886528507775;22600.886995564917;29048;29354.668156379776;29666;29773.40453190924;844.9831003379898;949.912350358849;1024.7489365105596;1037.4708898461838;1054.3094273251033;13835.478097409277;15318.080915145127;16049.117298548459;16604.58488537772;16617.83382166163;682879;683866;687858;687954;688477;57373.426265737646;57763;57879.4212057874;57955.42044579585;58038.7098064507;1067.199600299775;1136.891995260449;1180.7756526259984;1198.4247561170687;1329.6011196640968;27855.303617409725;29800;29905;30439.695603043685;30491.542626861057;35227.47158792672;47955;48061;48110.518894812005;48119;579470;586088;587732;587851;587920.362389129;21780.01989910426;24221.878890605432;27170.72829271682;27230.455390892468;27870.024549140682;45439;45481.862953426426;45834;46018;46154.846128845704;36791;36840.81579592085;37605.62394376021;39034;39292.60707392889;37412.43881341837;37412.812935935144;37518.624813751514;38619.80690096531;38800.6119938797;36623.45064823976;37170.8141459291;37186.62813371832;37421.81289093537;37431.25137497289;32793.01620951375;32862.671373285964;33718.66281337155;33940;33969.49045764365;21424.785752142277;24752;24945.251642450756;25469.745302546737;25574.48851022932 03:01:12 INFO - PROCESS | 2227 | |2;jquery.html;353.31104346524285;375.46683709133026;376.30195986445125;385.62209035145554;407.3523098273744;14684;14986;16370.754438683414;19726.309579164732;20513.58972820543;19661.508462288413;19662.311819086335;20141.899290503563;20351.982400879933;20384.388468345973;5082.466341034194;5849.356570777221;6151.261848578154;6157.938420615804;6165.845853853644;5868.4424979626865;5920.733566989475;5994.670293133876;5995.580309378345;5998.8800223995495;5671.829845104653;5925.88148237035;5931.317898441691;5938.0499120140785;5942.851428714273;5948.434898684618;6016.75932962681;6018.7893423730175;6024.277086749618;6029.939700602981;5049.6212784041345;5094.6433749637545;5226.242194881759;5240.633155679104;5244.370675518924;25467.490650186894;25601.487970240676;25754.969801207928;25777.742222577723;26016.219513414628;2228.7882651148116;2312.17917639239;2451.2156110044793;2659.5079910216477;3092.551580020907;13817.723645527034;14009.929950350284;14030.92984535081;14407.78388324176;15011.474598389113;1759.683257013737;1800.6308706715092;1825.2151574822822;1868.3554173810014;1905.4759941016193;114719;118684.40657796743;121580;128729;130814.3459282707;10976.286541374786;11463.713407164887;11534.711632209093;11572.884271157349;11719.06247500198;14518.419263229353;14563.781543276858;14573.052751571115;14591;14605.269736513132;8251.95874020626;8445.930746967033;8525.593277109332;8563.529005904735;8633.611487483078;8358.707445239508;8373.37199710012;8403.663853445794;8513.787155321166;8531.914680853237;4375.584319489643;4459.910801783946;4464.397306363664;4540.455145382575;4542.568455996741;1586.2148236622716;1830.1581272614746;1888.376835644234;1896.990515047416;1913.206019501919;378.2812655953655;384.50782997762906;399.7401688902179;803.6745118227127;918.6784625381197;11379.772404552028;11540.942295288638;11626.941865290619;11776.764464710657;11920.642380728592;437.3658195616369;822.1454930399564;926.9582868770922;927.6104036304769;946.2004606107828;10739.677809665722;11305.830412543819;11309.943450282695;11325.2072354936;11381.317120972768;537869;629502;630244;636737;641821;71273.2872671277;78794.6060269695;129905;136547;138695.30652346674;935.1770442010986;997.140333278445;1009.8737657792776;1013.2198207380424;1039.948002599882;18104.366347177787;18118.818811811714;18935.43193704191;18989.14548845333;19354.54849882777;51225.74387128041;51586.96826063533;54676.63308417321;55504.722476388975;57952.2614321571;76300;77354;80103;80891.59554202191;81851;6731.4951378647775;6787.694553744995;7638.319569694659;7832.177621349904;8069.7175598853755;9680.419174849532;12329.69175770577;12455.813162802384;12574.245545266947;12700.618981430573;10651.89348106509;10789.784204315712;10884.727881803019;11006.614768483065;11154.107671386108;9631.518424078908;10049.899501004897;10879.836802448128;11250;11377.2604780689;11090;11112;11164.944175279072;11547.71130721705;11615.303081815118;6927.68825402848;7112.57324560528;7237.312455316736;8091.757247282591;8230.506169629842;10561.471926403496;10744.033037026706;11074.6189569243;11114.166437517057;11120.944395278295 03:01:12 INFO - PROCESS | 2227 | |3;mootools.html;1316.657669006058;1473.3738161281456;1498.2658497336306;1626.2194146809532;1787.8927264364147;7471.364933980612;7834.099078605962;8185.263326300632;8477.237048665618;8497.787555311104;8353.91646083539;8419.32645388369;8437.02974157972;8441.324694024479;8452.661893524251;2277.6241920083166;2309.9884500577473;2312.2022902098834;2313.086330899294;2314.085936055257;2280.304507125326;2313.421644588853;2326.127702111708;2332.696749422581;2335.1243283768586;2269.3305474884946;2279.8290128240365;2301.850379725321;2306.6540018997202;2312.9537409251807;2261.2198791416936;2272.488690044735;2295.724513058435;2297.7702229777055;2300.9654855177187;2286.1769762885347;2309.411100169446;2310.9653355199684;2313.1903833658253;2315.409570559513;8187.426880118394;8303.792405189859;8370.204830541088;8373.07896131425;8412.915870841274;5066.16408292635;5247.842564723064;5254.789808407659;5255.5795536357;5271.051210782058;3434.845431955543;3565.322588708137;3607.152319204985;3644.7630903991158;3665.358562251622;1058.830587106067;1071.4374953149659;1096.1614365010694;1106.1537923488538;1108.2630051016065;9571.28215383842;10868.891311086867;10985.231033827635;11014.39420831854;11072.335659860435;3550.1479644885044;4096.467459230281;4377.430933978626;4419.270820314612;4436.245838207513;8661.220490155916;8663.263622592025;8663.917010373703;8692.95653521741;8716.651333946571;5067.569256613156;5069.493050694975;5071.416787069535;5075.87310317245;5076.6192535559985;3709.536307961505;4151.501819781585;4278.272693642089;4423.469183697976;4773.260144677511;3321.5183798349585;3542.1144713821545;3626.9093272668392;3640.9635903641156;3713.9071523212137;1136.2273653915252;1139.8461207737016;1147.3746807989498;1150.8734039255676;1151.9827202591969;747.6598147842718;860.6514361683528;873.2490058549643;883.7746374674546;893.7542175901679;5383.246345511631;5446.258982459118;5583.274174357308;5618;5788.565857560616;411.6028032948219;447.90817882334045;448.98977301072586;449.13765570105477;452.5180682573049;3149.6062992125985;3597.640235976434;3741.7006639468777;3790.5451345838123;3815.389537673959;8619.396642234984;9731.56207970643;9755.317127801127;10118.54466549007;10374.636887708893;10085.193184545218;10326.793464130826;10340.586376544854;10347.482625868677;10355.689329320132;7673.309402153833;7784.065912090586;7807.960960195162;7822.335101516434;7871.645775940211;5548.0845660465575;5673.177389278614;5788.507976821849;5878.382769809279;5904.675242861684;9443.386179898285;9588.66439674608;9602.231821454407;9645.758856028655;9710.465924374228;8173.386995975445;8859.867101993605;9636.614535418505;9721.416714997124;9741.464219467998;6664.233613134357;7021.929780702128;7209.783706488814;7273.927260727325;7324;7763.4953728007495;7915.05019397676;7930.682772689028;8007.799805004922;8021.117677055636;7212.2427145151105;7252.637368131678;7257.673404696907;7276.527025743309;7277.235890231449;7073.575585464889;7101.076860008166;7137.607431591312;7180.60506672138;7226.313500217471;6398.90401643985;6475.3200913903365;6543.345665433419;6592.143021407186;6601.53789234749;4643.976780116213;4734;4773.47248880355;4873.293372460902;4964.205727083649;4799.136155492045;4813.831515896927;4849.3210950467665;4920.606351491943;4986.80052797884 03:01:12 INFO - PROCESS | 2227 | |4;prototype.html;302.4118090314338;305.82415111311;312.8545226469691;318.120397102013;320.7642382848604;3654.9583368739914;4499.807550998984;4863.829765958192;5108.948910510894;5164.816498636107;3979.900502487439;5063.721495317756;5071.315372424721;5087.338645976027;5109.616778741591;3821.7515861468987;3958.6342711764396;3991.4012898065375;4039.7576145431367;4074.7555146691143;3763.962360376402;3986.784030870583;4020.12058363679;4033.366486572928;4073.144639625682;3432.5366075579727;3747.1943532140585;3923.3722604383306;3943.2704949584277;3985.8007099645047;3926.9789854637716;3928.1750832325247;3930.6219737058955;3951.1900060487533;3963.920721585552;3465.6361082086523;3466.861325546975;3473.6873681368547;3474.029704602579;3474.1488335357744;4727.810887564493;4804.327394164819;4858.416989961227;4908.4846091160625;4908.502906613481;3717.2863809419005;3776.66010059096;4365.388845561624;4523.163214805239;4653.441587009546;4769.427668679746;4996.201367507694;5478.534324582415;5481.068218402841;5487.478689524489;3379.1552111972005;3668.248009158116;3801.448789925473;3812.390017597199;3817.408301713211;4969.950300497021;5367.704776237305;5697.119950416394;5713.428657134295;5764.942350576483;3783.886483405502;3784.4890939723336;3785.2240290740333;3789.981050094732;3803.2773772983596;3378.540326768691;3873.4964454620717;3877.495925529663;3894.221155768801;3909.706771992112;4091.631753142172;4404.713693609905;4432;4487.439070116236;4527;4917.311576379309;5469.808556700496;5482.533984611273;5496.367917689437;5505.394406615269;1164.5167255589006;1190.880572262071;1195.947566141807;1224.6345324962604;1238.6779437346354;786.6342150899859;848.87266909964;862.8791969124328;867.2888231649987;870.8606622940426;4690.742009189527;4708;4730.432348118248;4911.551092427742;4934.309196712463;4997.375328083989;5144.088736763694;5323.057084164252;5436.53789427903;5439.456054394608;4238.202786161119;4360.8909777255185;4878.0975219823995;4879.829205977774;4892.877678058077;2592.468543948486;2691.300261931912;2786.4009238013923;2810;2832.6317578714634;4855.660103792771;5313.149896016696;5560.332760068737;5618.11793049333;5908.897828639999;4964.652474326763;5175.560077393388;5907.23205983228;5947.494462970696;5949.524038076944;4955.925661115086;5983.700814959235;5992.490638295706;5998.220231369894;6001.699915004232;4557.726536407826;4744.746245533344;5695.943040569707;5727.828165155052;5809.862674156077;5010.649254552146;5087.880666253566;5639.351474580476;5674.4892959633835;5679.488846003879;4969.602431805519;5640.661560306395;5670.461306175906;5679.574031947538;5692.893629356999;3200.9519857202627;3565.2691198303764;3620.262274108495;3823.8088095595667;3838.347480928193;5694.487496125369;5715.914261286167;5718.828435146952;5737.680333523396;5741.655500669973;5065.493450654905;5248.905853434328;5580.9441905580425;5599.884085197458;5605.5515558756015;4603.238057165674;5100.38795344561;5395.946040539544;5604.3835178131185;5605.019121653776;4996.800127994839;5170.612204084633;5368.919466208088;5386.596005299697;5447.536959358499;5276.894462110659;5524.309461317335;5580.609357344947;5583.994880921311;5590.916136258041;4834.758262086812;5085.64400491962;5151.150060240168;5456.226726314043;5495.230667706444 03:01:12 INFO - PROCESS | 2227 | |5;yui.html;697.3131465506475;718.1418205244735;741.0255513999091;762.2910693055456;790.9841803163937;14076.788848167273;14209.573712788608;14591.34338954747;14736.042157259784;14749.92625036876;12845.48618055279;13002.414891329912;13142;13159;13171.868281317184;656.566666000441;670.1991120610872;819.9303059239942;828.718235799828;829.531314807135;762.2377622377622;785.2461636828623;821.4250024982506;823.4153750836913;826.3182874128838;878.83302172587;879.2306731609841;880.6697488441835;883.9955800221022;887.9778005549848;875.369733791669;877.8332116897801;882.8057827277988;885.8626912828522;887.9067697891758;651.1144843013498;651.5406638319973;651.7816531461966;655.6426747422666;657.8223879552542;11264.380459074748;12337.383130843422;12391.442385092603;12397.504099835996;12401.875981240162;13002.15485993417;13370.465181392732;13400;13403.664908377272;13558.728825423535;9303.76740581484;10995.560177592886;11233.10135189191;11279.92840680134;11425;3464.8787292444645;3519.0850378901414;3605.260921511084;3702.426123950792;3802.39161734124;55347;91445;91505.62741558882;91602.08397916068;95308.52345738227;10215.57064274744;10235.232357573212;10475.161987041018;10477.47612619366;10504.159667226604;20326.491837704176;20365.592688146153;20431.693524597144;20584.588308233753;20759.169633214806;7437.590932498656;7457.7389791357045;7570.735024274234;7572.091349038151;7628.542287462659;4152.688548358885;4217.093324935154;4276.5082015568605;4395.824167033346;4415.359772832921;3425.3491836551475;3534.3814832404228;3538.9823050885097;3540.2034542228307;3541.6812486876306;551.8896220755785;634.8762690038606;635.2472320300459;640.8494003909078;652.1326635574675;537.978480860769;569.9200015969797;633.1357696744003;647.252423450915;665.5507532415697;8657.307415406753;9211.769705757275;9245.768855778524;9425.811483770287;9550.331476796697;569.1036617327643;613.828128124126;646.2148489585177;659.6932426421737;675.6284043775904;4477.2164871147415;4762.999770048259;4821.686590371614;4925.285833554115;5089;118149.40925295319;121777.39111304564;126500;129231.35384323019;129298;85110.14889851146;109256;110287;110734.44632776784;111717.44141279241;78270.21729782743;81531.18468815235;83188.75216871752;97792.51103744436;100296.49555256678;9675.806483870423;9687.41875487473;9812.803743925224;9838.852417213891;9855.704328870146;57919.42080579309;58233.4176658245;58555;59244.70377648084;60755.99999999823;69176;71717.6414117947;81746.59126704329;85034.57482712547;86177.13822861941;11989.580364687194;12109.69725756863;12143;12395.93802030984;12437.689057773629;13604.251766152956;14152.716945661236;14235.217063061216;14299.142051476945;14364.425422983382;12705.110642254953;12771.93614031924;13089.476420943056;13324.800127997894;13732.45070197181;12900.612981610566;13197.406116724578;13208.009399294899;13221.669458263621;13267.933660331637;12558.937205313914;12678.492860285854;12691.682707932376;12811;13434.059615826798;10916.836247456136;11079.446027698745;11109.555617775199;11462.598809041643;11716.41417929083;11000;11297.14003403417;11494.655160345203;11511;11566.826497602711 03:01:12 INFO - PROCESS | 2227 | __end_tp_report 03:01:12 INFO - PROCESS | 2227 | __start_cc_report 03:01:12 INFO - PROCESS | 2227 | _x_x_mozilla_cycle_collect,1262 03:01:12 INFO - PROCESS | 2227 | __end_cc_report 03:01:12 INFO - PROCESS | 2227 | __startTimestamp1480158072489__endTimestamp 03:01:12 INFO - PROCESS | 2227 | 03:01:12 INFO - PROCESS | 2227 | ------- Summary: start ------- 03:01:12 INFO - PROCESS | 2227 | Number of tests: 6 03:01:12 INFO - PROCESS | 2227 | 03:01:12 INFO - PROCESS | 2227 | [#0] dojo.html Cycles:165 Average:5761.70 Median:5987.61 stddev:646.01 (10.8%) stddev-sans-first:618.88 03:01:12 INFO - PROCESS | 2227 | Values: 3317.4 3317.5 3345.9 3346.2 3382.0 5627.5 5629.9 5686.0 5694.5 5720.1 5630.9 5655.9 5739.6 5765.8 5771.9 5170.5 5217.8 5233.1 5256.6 5291.8 5174.5 5191.9 5209.3 5270.6 5327.9 4391.3 5270.4 5285.4 5294.9 5298.0 5197.2 5220.2 5294.0 5309.0 5312.6 5142.2 5176.2 5227.3 5232.8 5284.8 5714.0 5723.3 5788.5 5810.7 5829.7 6134.8 6136.3 6165.4 6166.2 6172.0 6244.2 6285.1 6302.2 6320.8 6360.5 5851.8 5893.2 5905.9 5907.6 5951.4 6241.6 6245.0 6300.1 6308.8 6309.9 6230.3 6230.8 6234.8 6234.9 6238.9 6293.3 6310.6 6323.9 6334.8 6345.6 6030.4 6037.2 6131.7 6146.6 6220.0 6254.2 6296.8 6301.9 6306.0 6309.7 4918.8 4951.0 4965.6 4976.1 5005.3 4377.7 4410.8 4472.8 4484.6 4499.8 5699.3 5705.7 5708.0 5710.7 5716.7 5234.9 6216.4 6252.7 6281.0 6311.8 5769.0 5773.1 5782.5 5795.7 5830.5 5436.3 5445.5 5450.2 5466.6 5475.7 6229.5 6235.1 6290.1 6290.4 6304.5 6272.9 6309.3 6314.1 6321.1 6321.4 6275.8 6351.1 6361.2 6366.0 6368.4 6058.8 6105.2 6106.8 6118.3 6244.3 6023.7 6152.5 6219.2 6247.0 6257.7 6086.6 6118.6 6129.7 6136.4 6137.5 6043.4 6108.6 6120.8 6128.2 6143.1 5814.6 5852.3 5928.3 5939.3 5942.0 6165.4 6165.8 6197.8 6205.8 6208.1 5954.5 5987.6 6008.6 6018.9 6075.9 03:01:12 INFO - PROCESS | 2227 | 03:01:12 INFO - PROCESS | 2227 | [#1] ext.html Cycles:180 Average:69486.13 Median:37474.94 stddev:159720.27 (426.2%) stddev-sans-first:160084.29 03:01:12 INFO - PROCESS | 2227 | Values: 479.0 481.8 504.9 513.4 520.9 30347.2 31217.8 31270.5 31298.4 31337.0 40351.0 40406.4 40466.0 42203.6 42261.0 8065.7 9269.4 9410.6 9426.2 9427.6 9403.4 9474.6 9485.1 9485.6 9491.3 9460.9 9495.8 9510.2 9517.0 9532.6 9381.0 9482.6 9483.6 9490.0 9492.1 9316.3 9380.8 9390.0 9407.4 9413.7 38230.6 38589.6 38601.0 38620.2 38622.0 3402.8 3485.5 3684.7 3810.3 3864.0 27809.7 28151.2 29721.7 30414.4 30433.1 3168.9 3212.9 3217.4 3264.8 3319.5 493888.0 495410.0 495842.0 496507.0 507582.0 32517.0 33175.5 33469.7 56163.0 56640.7 34732.7 34799.3 34835.8 34869.8 34905.7 14439.5 14553.7 14561.6 14569.6 14576.6 12225.3 12318.8 12331.3 12336.5 12347.0 7683.8 7714.6 7773.6 7782.3 7787.9 3123.8 3174.6 3187.1 3189.8 3217.1 828.3 906.0 942.5 1002.3 1040.2 22600.9 29048.0 29354.7 29666.0 29773.4 845.0 949.9 1024.7 1037.5 1054.3 13835.5 15318.1 16049.1 16604.6 16617.8 682879.0 683866.0 687858.0 687954.0 688477.0 57373.4 57763.0 57879.4 57955.4 58038.7 1067.2 1136.9 1180.8 1198.4 1329.6 27855.3 29800.0 29905.0 30439.7 30491.5 35227.5 47955.0 48061.0 48110.5 48119.0 579470.0 586088.0 587732.0 587851.0 587920.4 21780.0 24221.9 27170.7 27230.5 27870.0 45439.0 45481.9 45834.0 46018.0 46154.8 36791.0 36840.8 37605.6 39034.0 39292.6 37412.4 37412.8 37518.6 38619.8 38800.6 36623.5 37170.8 37186.6 37421.8 37431.3 32793.0 32862.7 33718.7 33940.0 33969.5 21424.8 24752.0 24945.3 25469.7 25574.5 03:01:12 INFO - PROCESS | 2227 | 03:01:12 INFO - PROCESS | 2227 | [#2] jquery.html Cycles:180 Average:34928.99 Median:25896.98 stddev:102660.68 (396.4%) stddev-sans-first:102915.85 03:01:12 INFO - PROCESS | 2227 | Values: 353.3 375.5 376.3 385.6 407.4 14684.0 14986.0 16370.8 19726.3 20513.6 19661.5 19662.3 20141.9 20352.0 20384.4 5082.5 5849.4 6151.3 6157.9 6165.8 5868.4 5920.7 5994.7 5995.6 5998.9 5671.8 5925.9 5931.3 5938.0 5942.9 5948.4 6016.8 6018.8 6024.3 6029.9 5049.6 5094.6 5226.2 5240.6 5244.4 25467.5 25601.5 25755.0 25777.7 26016.2 2228.8 2312.2 2451.2 2659.5 3092.6 13817.7 14009.9 14030.9 14407.8 15011.5 1759.7 1800.6 1825.2 1868.4 1905.5 114719.0 118684.4 121580.0 128729.0 130814.3 10976.3 11463.7 11534.7 11572.9 11719.1 14518.4 14563.8 14573.1 14591.0 14605.3 8252.0 8445.9 8525.6 8563.5 8633.6 8358.7 8373.4 8403.7 8513.8 8531.9 4375.6 4459.9 4464.4 4540.5 4542.6 1586.2 1830.2 1888.4 1897.0 1913.2 378.3 384.5 399.7 803.7 918.7 11379.8 11540.9 11626.9 11776.8 11920.6 437.4 822.1 927.0 927.6 946.2 10739.7 11305.8 11309.9 11325.2 11381.3 537869.0 629502.0 630244.0 636737.0 641821.0 71273.3 78794.6 129905.0 136547.0 138695.3 935.2 997.1 1009.9 1013.2 1039.9 18104.4 18118.8 18935.4 18989.1 19354.5 51225.7 51587.0 54676.6 55504.7 57952.3 76300.0 77354.0 80103.0 80891.6 81851.0 6731.5 6787.7 7638.3 7832.2 8069.7 9680.4 12329.7 12455.8 12574.2 12700.6 10651.9 10789.8 10884.7 11006.6 11154.1 9631.5 10049.9 10879.8 11250.0 11377.3 11090.0 11112.0 11164.9 11547.7 11615.3 6927.7 7112.6 7237.3 8091.8 8230.5 10561.5 10744.0 11074.6 11114.2 11120.9 03:01:12 INFO - PROCESS | 2227 | 03:01:12 INFO - PROCESS | 2227 | [#3] mootools.html Cycles:180 Average:5385.51 Median:5026.48 stddev:2996.94 (59.6%) stddev-sans-first:2989.75 03:01:12 INFO - PROCESS | 2227 | Values: 1316.7 1473.4 1498.3 1626.2 1787.9 7471.4 7834.1 8185.3 8477.2 8497.8 8353.9 8419.3 8437.0 8441.3 8452.7 2277.6 2310.0 2312.2 2313.1 2314.1 2280.3 2313.4 2326.1 2332.7 2335.1 2269.3 2279.8 2301.9 2306.7 2313.0 2261.2 2272.5 2295.7 2297.8 2301.0 2286.2 2309.4 2311.0 2313.2 2315.4 8187.4 8303.8 8370.2 8373.1 8412.9 5066.2 5247.8 5254.8 5255.6 5271.1 3434.8 3565.3 3607.2 3644.8 3665.4 1058.8 1071.4 1096.2 1106.2 1108.3 9571.3 10868.9 10985.2 11014.4 11072.3 3550.1 4096.5 4377.4 4419.3 4436.2 8661.2 8663.3 8663.9 8693.0 8716.7 5067.6 5069.5 5071.4 5075.9 5076.6 3709.5 4151.5 4278.3 4423.5 4773.3 3321.5 3542.1 3626.9 3641.0 3713.9 1136.2 1139.8 1147.4 1150.9 1152.0 747.7 860.7 873.2 883.8 893.8 5383.2 5446.3 5583.3 5618.0 5788.6 411.6 447.9 449.0 449.1 452.5 3149.6 3597.6 3741.7 3790.5 3815.4 8619.4 9731.6 9755.3 10118.5 10374.6 10085.2 10326.8 10340.6 10347.5 10355.7 7673.3 7784.1 7808.0 7822.3 7871.6 5548.1 5673.2 5788.5 5878.4 5904.7 9443.4 9588.7 9602.2 9645.8 9710.5 8173.4 8859.9 9636.6 9721.4 9741.5 6664.2 7021.9 7209.8 7273.9 7324.0 7763.5 7915.1 7930.7 8007.8 8021.1 7212.2 7252.6 7257.7 7276.5 7277.2 7073.6 7101.1 7137.6 7180.6 7226.3 6398.9 6475.3 6543.3 6592.1 6601.5 4644.0 4734.0 4773.5 4873.3 4964.2 4799.1 4813.8 4849.3 4920.6 4986.8 03:01:12 INFO - PROCESS | 2227 | 03:01:12 INFO - PROCESS | 2227 | [#4] prototype.html Cycles:180 Average:4400.66 Median:4900.68 stddev:1363.38 (27.8%) stddev-sans-first:1332.05 03:01:12 INFO - PROCESS | 2227 | Values: 302.4 305.8 312.9 318.1 320.8 3655.0 4499.8 4863.8 5108.9 5164.8 3979.9 5063.7 5071.3 5087.3 5109.6 3821.8 3958.6 3991.4 4039.8 4074.8 3764.0 3986.8 4020.1 4033.4 4073.1 3432.5 3747.2 3923.4 3943.3 3985.8 3927.0 3928.2 3930.6 3951.2 3963.9 3465.6 3466.9 3473.7 3474.0 3474.1 4727.8 4804.3 4858.4 4908.5 4908.5 3717.3 3776.7 4365.4 4523.2 4653.4 4769.4 4996.2 5478.5 5481.1 5487.5 3379.2 3668.2 3801.4 3812.4 3817.4 4970.0 5367.7 5697.1 5713.4 5764.9 3783.9 3784.5 3785.2 3790.0 3803.3 3378.5 3873.5 3877.5 3894.2 3909.7 4091.6 4404.7 4432.0 4487.4 4527.0 4917.3 5469.8 5482.5 5496.4 5505.4 1164.5 1190.9 1195.9 1224.6 1238.7 786.6 848.9 862.9 867.3 870.9 4690.7 4708.0 4730.4 4911.6 4934.3 4997.4 5144.1 5323.1 5436.5 5439.5 4238.2 4360.9 4878.1 4879.8 4892.9 2592.5 2691.3 2786.4 2810.0 2832.6 4855.7 5313.1 5560.3 5618.1 5908.9 4964.7 5175.6 5907.2 5947.5 5949.5 4955.9 5983.7 5992.5 5998.2 6001.7 4557.7 4744.7 5695.9 5727.8 5809.9 5010.6 5087.9 5639.4 5674.5 5679.5 4969.6 5640.7 5670.5 5679.6 5692.9 3201.0 3565.3 3620.3 3823.8 3838.3 5694.5 5715.9 5718.8 5737.7 5741.7 5065.5 5248.9 5580.9 5599.9 5605.6 4603.2 5100.4 5395.9 5604.4 5605.0 4996.8 5170.6 5368.9 5386.6 5447.5 5276.9 5524.3 5580.6 5584.0 5590.9 4834.8 5085.6 5151.2 5456.2 5495.2 03:01:12 INFO - PROCESS | 2227 | 03:01:12 INFO - PROCESS | 2227 | [#5] yui.html Cycles:180 Average:21488.92 Median:4246.80 stddev:32605.63 (767.8%) stddev-sans-first:32659.73 03:01:12 INFO - PROCESS | 2227 | Values: 697.3 718.1 741.0 762.3 791.0 14076.8 14209.6 14591.3 14736.0 14749.9 12845.5 13002.4 13142.0 13159.0 13171.9 656.6 670.2 819.9 828.7 829.5 762.2 785.2 821.4 823.4 826.3 878.8 879.2 880.7 884.0 888.0 875.4 877.8 882.8 885.9 887.9 651.1 651.5 651.8 655.6 657.8 11264.4 12337.4 12391.4 12397.5 12401.9 13002.2 13370.5 13400.0 13403.7 13558.7 9303.8 10995.6 11233.1 11279.9 11425.0 3464.9 3519.1 3605.3 3702.4 3802.4 55347.0 91445.0 91505.6 91602.1 95308.5 10215.6 10235.2 10475.2 10477.5 10504.2 20326.5 20365.6 20431.7 20584.6 20759.2 7437.6 7457.7 7570.7 7572.1 7628.5 4152.7 4217.1 4276.5 4395.8 4415.4 3425.3 3534.4 3539.0 3540.2 3541.7 551.9 634.9 635.2 640.8 652.1 538.0 569.9 633.1 647.3 665.6 8657.3 9211.8 9245.8 9425.8 9550.3 569.1 613.8 646.2 659.7 675.6 4477.2 4763.0 4821.7 4925.3 5089.0 118149.4 121777.4 126500.0 129231.4 129298.0 85110.1 109256.0 110287.0 110734.4 111717.4 78270.2 81531.2 83188.8 97792.5 100296.5 9675.8 9687.4 9812.8 9838.9 9855.7 57919.4 58233.4 58555.0 59244.7 60756.0 69176.0 71717.6 81746.6 85034.6 86177.1 11989.6 12109.7 12143.0 12395.9 12437.7 13604.3 14152.7 14235.2 14299.1 14364.4 12705.1 12771.9 13089.5 13324.8 13732.5 12900.6 13197.4 13208.0 13221.7 13267.9 12558.9 12678.5 12691.7 12811.0 13434.1 10916.8 11079.4 11109.6 11462.6 11716.4 11000.0 11297.1 11494.7 11511.0 11566.8 03:01:12 INFO - PROCESS | 2227 | -------- Summary: end -------- 03:01:12 INFO - PROCESS | 2227 | 03:01:13 INFO - TEST-INFO | 2227: exit 0 03:01:13 INFO - TEST-OK | dromaeo_css | took 1119188ms 03:01:13 INFO - TEST-START | kraken 03:01:13 INFO - operating with platform_type : mac_ 03:01:13 INFO - Initialising browser for kraken test... 03:01:13 INFO - TEST-INFO | started process 2234 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpcevoN3/profile http://localhost:53877/getInfo.html) 03:01:15 INFO - PROCESS | 2234 | __metrics Screen width/height:1600/1200 03:01:15 INFO - PROCESS | 2234 | colorDepth:24 03:01:15 INFO - PROCESS | 2234 | Browser inner width/height: 1024/689 03:01:15 INFO - PROCESS | 2234 | __metrics 03:01:15 INFO - PROCESS | 2234 | 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" 03:01:15 INFO - PROCESS | 2234 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:01:16 INFO - TEST-INFO | 2234: exit 0 03:01:16 INFO - Browser initialized. 03:01:16 INFO - Running cycle 1/1 for kraken test... 03:01:16 INFO - TEST-INFO | started process 2235 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpcevoN3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:01:29 INFO - PROCESS | 2235 | RSS: Main: 223031296 03:01:29 INFO - PROCESS | 2235 | 03:01:31 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:53877/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53877/tests/kraken/driver.html?testName=audio-beat-detection) 03:01:32 INFO - PROCESS | 2235 | RSS: Main: 272318464 03:01:32 INFO - PROCESS | 2235 | 03:01:35 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:53877/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53877/tests/kraken/driver.html?testName=audio-dft) 03:01:36 INFO - PROCESS | 2235 | RSS: Main: 282140672 03:01:36 INFO - PROCESS | 2235 | 03:01:39 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:53877/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53877/tests/kraken/driver.html?testName=audio-fft) 03:01:39 INFO - PROCESS | 2235 | RSS: Main: 377856000 03:01:39 INFO - PROCESS | 2235 | 03:01:41 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:53877/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53877/tests/kraken/driver.html?testName=audio-oscillator) 03:01:42 INFO - PROCESS | 2235 | RSS: Main: 283385856 03:01:42 INFO - PROCESS | 2235 | 03:01:44 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:53877/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53877/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:01:45 INFO - PROCESS | 2235 | RSS: Main: 284622848 03:01:45 INFO - PROCESS | 2235 | 03:01:47 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:53877/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53877/tests/kraken/driver.html?testName=imaging-darkroom) 03:01:48 INFO - PROCESS | 2235 | RSS: Main: 326135808 03:01:48 INFO - PROCESS | 2235 | 03:01:51 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:53877/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53877/tests/kraken/driver.html?testName=imaging-desaturate) 03:01:51 INFO - PROCESS | 2235 | RSS: Main: 326385664 03:01:51 INFO - PROCESS | 2235 | 03:01:54 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:53877/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53877/tests/kraken/driver.html?testName=json-parse-financial) 03:01:55 INFO - PROCESS | 2235 | RSS: Main: 322801664 03:01:55 INFO - PROCESS | 2235 | 03:01:56 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:53877/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53877/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:01:57 INFO - PROCESS | 2235 | RSS: Main: 301383680 03:01:57 INFO - PROCESS | 2235 | 03:01:59 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:53877/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53877/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:01:59 INFO - PROCESS | 2235 | RSS: Main: 301182976 03:01:59 INFO - PROCESS | 2235 | 03:02:02 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:53877/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53877/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:02:02 INFO - PROCESS | 2235 | RSS: Main: 303546368 03:02:02 INFO - PROCESS | 2235 | 03:02:05 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:53877/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53877/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:02:06 INFO - PROCESS | 2235 | RSS: Main: 311914496 03:02:06 INFO - PROCESS | 2235 | 03:02:09 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:53877/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53877/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:02:09 INFO - PROCESS | 2235 | RSS: Main: 312209408 03:02:09 INFO - PROCESS | 2235 | 03:02:11 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:53877/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53877/tests/kraken/driver.html?testName=ai-astar) 03:02:11 INFO - PROCESS | 2235 | RSS: Main: 355844096 03:02:11 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | __start_tp_report 03:02:12 INFO - PROCESS | 2235 | _x_x_mozilla_page_load 03:02:12 INFO - PROCESS | 2235 | _x_x_mozilla_page_load_details 03:02:12 INFO - PROCESS | 2235 | |i|pagename|runs| 03:02:12 INFO - PROCESS | 2235 | |0;ai-astar;117;114;114;115;114;114;115;115;115;114 03:02:12 INFO - PROCESS | 2235 | |1;audio-beat-detection;151;114;112;148;116;114;129;143;175;127 03:02:12 INFO - PROCESS | 2235 | |2;audio-dft;183;185;180;182;182;185;182;184;184;184 03:02:12 INFO - PROCESS | 2235 | |3;audio-fft;81;59;60;89;62;61;58;53;88;69 03:02:12 INFO - PROCESS | 2235 | |4;audio-oscillator;91;70;94;70;67;71;88;68;69;119 03:02:12 INFO - PROCESS | 2235 | |5;imaging-gaussian-blur;88;85;87;84;88;84;88;85;87;84 03:02:12 INFO - PROCESS | 2235 | |6;imaging-darkroom;95;103;96;100;145;96;96;96;96;104 03:02:12 INFO - PROCESS | 2235 | |7;imaging-desaturate;83;85;79;82;84;79;82;83;78;81 03:02:12 INFO - PROCESS | 2235 | |8;json-parse-financial;56;54;54;54;56;58;57;61;57;58 03:02:12 INFO - PROCESS | 2235 | |9;json-stringify-tinderbox;75;78;65;75;74;70;70;68;71;71 03:02:12 INFO - PROCESS | 2235 | |10;stanford-crypto-aes;86;83;85;85;94;102;83;84;84;85 03:02:12 INFO - PROCESS | 2235 | |11;stanford-crypto-ccm;147;145;159;144;152;159;138;150;186;146 03:02:12 INFO - PROCESS | 2235 | |12;stanford-crypto-pbkdf2;170;170;164;165;165;167;169;166;165;167 03:02:12 INFO - PROCESS | 2235 | |13;stanford-crypto-sha256-iterative;68;67;66;65;67;64;69;84;79;67 03:02:12 INFO - PROCESS | 2235 | __end_tp_report 03:02:12 INFO - PROCESS | 2235 | __start_cc_report 03:02:12 INFO - PROCESS | 2235 | _x_x_mozilla_cycle_collect,1940 03:02:12 INFO - PROCESS | 2235 | __end_cc_report 03:02:12 INFO - PROCESS | 2235 | __startTimestamp1480158131998__endTimestamp 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | ------- Summary: start ------- 03:02:12 INFO - PROCESS | 2235 | Number of tests: 14 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.00 stddev:0.95 (0.8%) stddev-sans-first:0.53 03:02:12 INFO - PROCESS | 2235 | Values: 117.0 114.0 114.0 115.0 114.0 114.0 115.0 115.0 115.0 114.0 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | [#1] audio-beat-detection Cycles:10 Average:132.90 Median:136.00 stddev:20.86 (15.3%) stddev-sans-first:21.07 03:02:12 INFO - PROCESS | 2235 | Values: 151.0 114.0 112.0 148.0 116.0 114.0 129.0 143.0 175.0 127.0 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | [#2] audio-dft Cycles:10 Average:183.10 Median:184.00 stddev:1.60 (0.9%) stddev-sans-first:1.69 03:02:12 INFO - PROCESS | 2235 | Values: 183.0 185.0 180.0 182.0 182.0 185.0 182.0 184.0 184.0 184.0 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | [#3] audio-fft Cycles:10 Average:68.00 Median:65.50 stddev:13.19 (20.1%) stddev-sans-first:13.13 03:02:12 INFO - PROCESS | 2235 | Values: 81.0 59.0 60.0 89.0 62.0 61.0 58.0 53.0 88.0 69.0 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | [#4] audio-oscillator Cycles:10 Average:80.70 Median:70.50 stddev:17.04 (24.2%) stddev-sans-first:17.66 03:02:12 INFO - PROCESS | 2235 | Values: 91.0 70.0 94.0 70.0 67.0 71.0 88.0 68.0 69.0 119.0 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.76 (2.0%) stddev-sans-first:1.72 03:02:12 INFO - PROCESS | 2235 | Values: 88.0 85.0 87.0 84.0 88.0 84.0 88.0 85.0 87.0 84.0 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | [#6] imaging-darkroom Cycles:10 Average:102.70 Median:96.00 stddev:15.21 (15.8%) stddev-sans-first:15.88 03:02:12 INFO - PROCESS | 2235 | Values: 95.0 103.0 96.0 100.0 145.0 96.0 96.0 96.0 96.0 104.0 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | [#7] imaging-desaturate Cycles:10 Average:81.60 Median:82.50 stddev:2.32 (2.8%) stddev-sans-first:2.40 03:02:12 INFO - PROCESS | 2235 | Values: 83.0 85.0 79.0 82.0 84.0 79.0 82.0 83.0 78.0 81.0 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:2.22 (3.9%) stddev-sans-first:2.35 03:02:12 INFO - PROCESS | 2235 | Values: 56.0 54.0 54.0 54.0 56.0 58.0 57.0 61.0 57.0 58.0 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:72.50 stddev:3.83 (5.3%) stddev-sans-first:3.87 03:02:12 INFO - PROCESS | 2235 | Values: 75.0 78.0 65.0 75.0 74.0 70.0 70.0 68.0 71.0 71.0 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:85.00 stddev:6.12 (7.2%) stddev-sans-first:6.48 03:02:12 INFO - PROCESS | 2235 | Values: 86.0 83.0 85.0 85.0 94.0 102.0 83.0 84.0 84.0 85.0 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | [#11] stanford-crypto-ccm Cycles:10 Average:152.60 Median:151.00 stddev:13.43 (8.9%) stddev-sans-first:14.10 03:02:12 INFO - PROCESS | 2235 | Values: 147.0 145.0 159.0 144.0 152.0 159.0 138.0 150.0 186.0 146.0 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:167.00 stddev:2.20 (1.3%) stddev-sans-first:2.01 03:02:12 INFO - PROCESS | 2235 | Values: 170.0 170.0 164.0 165.0 165.0 167.0 169.0 166.0 165.0 167.0 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - PROCESS | 2235 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:67.50 stddev:6.54 (9.7%) stddev-sans-first:6.91 03:02:12 INFO - PROCESS | 2235 | Values: 68.0 67.0 66.0 65.0 67.0 64.0 69.0 84.0 79.0 67.0 03:02:12 INFO - PROCESS | 2235 | -------- Summary: end -------- 03:02:12 INFO - PROCESS | 2235 | 03:02:12 INFO - TEST-INFO | 2235: exit 0 03:02:13 INFO - TEST-OK | kraken | took 59884ms 03:02:13 INFO - SUITE-END | took 1179s 03:02:13 INFO - Completed test suite (00:19:39) 03:02:13 INFO - javascript benchmark 03:02:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3317.402867483853, 3317.452620317648, 3345.9330813383717, 3346.2470944037577, 3381.9684996076203, 5627.521660658838, 5629.887402251952, 5686.0333743263645, 5694.5159661428725, 5720.141978703196, 5630.915536266959, 5655.8586035349135, 5739.569532285074, 5765.827025189251, 5771.942280577193, 5170.508801663836, 5217.765200565965, 5233.110371236899, 5256.605754568403, 5291.788328466857, 5174.482551744814, 5191.948080519184, 5209.348831396075, 5270.604704647148, 5327.866803329928, 4391.27543955247, 5270.446603106676, 5285.445028272034, 5294.92057619136, 5298.019866324723, 5197.194434862602, 5220.216967454874, 5293.9735301323635, 5308.973455132699, 5312.574994000471, 5142.151544995071, 5176.2235664650225, 5227.320448341691, 5232.764525596357, 5284.81503147388, 5713.971430142864, 5723.313202415695, 5788.507976821975, 5810.738516766755, 5829.708514574297, 6134.8159555213415, 6136.294326152461, 6165.414285642857, 6166.167567378437, 6172.0, 6244.219472565929, 6285.057241413779, 6302.243730752293, 6320.841978950517, 6360.459360954324, 5851.765929362863, 5893.233879595625, 5905.911411328833, 5907.556933230025, 5951.434613711691, 6241.594296370721, 6244.968775156095, 6300.086487459293, 6308.842278943063, 6309.873802523924, 6230.345813689588, 6230.750769969241, 6234.844128896814, 6234.906476402857, 6238.875222495525, 6293.30773614902, 6310.5898116622275, 6323.936760632427, 6334.841628959313, 6345.555811093179, 6030.396960304022, 6037.154798328237, 6131.724072416752, 6146.647737497743, 6220.03589443637, 6254.186955695822, 6296.842578935563, 6301.873962520815, 6305.96847015762, 6309.7160627770945, 4918.803247870117, 4951.0, 4965.577925876269, 4976.104301225741, 5005.27423523587, 4377.737335759864, 4410.823567057282, 4472.776361181928, 4484.551544845489, 4499.842505512292, 5699.259096317535, 5705.657660540381, 5708.0, 5710.714464276853, 5716.742746576414, 5234.9476505235225, 6216.378362163748, 6252.687365631791, 6281.026440901667, 6311.842203944938, 5769.048107062287, 5773.134029895549, 5782.508486778671, 5795.710214489259, 5830.47525722687, 5436.320459942507, 5445.455454454523, 5450.182472629058, 5466.590005749585, 5475.698836563947, 6229.470495007794, 6235.127082208403, 6290.056491526125, 6290.402411770874, 6304.527160463075, 6272.874542509216, 6309.274433440213, 6314.147590075281, 6321.051842223703, 6321.431071203614, 6275.780347687809, 6351.07909353132, 6361.236651601837, 6365.96817015912, 6368.395002474757, 6058.757649693963, 6105.206323177958, 6106.755729770937, 6118.326984031664, 6244.250689917239, 6023.698815059317, 6152.477039451696, 6219.191505104308, 6246.968765156145, 6257.687115644108, 6086.573939824169, 6118.60229085108, 6129.724162412613, 6136.386361363828, 6137.539684523589, 6043.365446628217, 6108.633481991094, 6120.755169793337, 6128.203333566607, 6143.139960405636, 5814.592978491465, 5852.297724273115, 5928.34788173292, 5939.346671866005, 5941.970290148522, 6165.383461653799, 6165.84585385351, 6197.81406557804, 6205.751769929152, 6208.0998255253635, 5954.464098231007, 5987.6108052976415, 6008.639481631116, 6018.939810601838, 6075.878482430238], "value": 5719.584981962024, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.9832355867541, 481.8458093410108, 504.9267856160857, 513.3967588084, 520.9218617207418, 30347.241318967037, 31217.751289948374, 31270.53094203583, 31298.374032519336, 31337.0, 40351.0, 40406.39390409146, 40466.0, 42203.57796422035, 42261.0, 8065.717699880505, 9269.443833370002, 9410.576524056434, 9426.198773104294, 9427.575759090823, 9403.38877972933, 9474.621015159386, 9485.051494850495, 9485.573149208301, 9491.335606507548, 9460.8580871287, 9495.81008379832, 9510.239180865514, 9517.000714924938, 9532.571034303473, 9380.953095234514, 9482.620695172185, 9483.573239204252, 9489.952550237205, 9492.098250666175, 9316.254699624082, 9380.812383752354, 9390.014048524874, 9407.388519746195, 9413.717588472287, 38230.617693822984, 38589.61410385917, 38601.0, 38620.22759544822, 38622.0, 3402.761806673534, 3485.494603282535, 3684.705223582107, 3810.333191691471, 3863.97604634772, 27809.72190278112, 28151.155465336073, 29721.70278297211, 30414.391712165852, 30433.087007389593, 3168.889088881901, 3212.903612891617, 3217.356528694247, 3264.836758162106, 3319.468884978416, 493888.0, 495410.0, 495842.0, 496507.0, 507582.0, 32517.0, 33175.502367464505, 33469.665303347145, 56163.0, 56640.716796415756, 34732.65267347345, 34799.30401391958, 34835.82582087124, 34869.825650871586, 34905.65094349024, 14439.494617688331, 14553.708925821424, 14561.635959101108, 14569.562913112624, 14576.562703118923, 12225.327606981524, 12318.81521777174, 12331.260124392566, 12336.50653973849, 12346.95050920664, 7683.807904802425, 7714.614269286513, 7773.572453515111, 7782.260685235007, 7787.92212077872, 3123.828189449602, 3174.587303650557, 3187.0757480330444, 3189.824559649195, 3217.099212220582, 828.29594844383, 905.9637614495479, 942.5098948546718, 1002.3134153105146, 1040.1886528507775, 22600.886995564917, 29048.0, 29354.668156379776, 29666.0, 29773.40453190924, 844.9831003379898, 949.912350358849, 1024.7489365105596, 1037.4708898461838, 1054.3094273251033, 13835.478097409277, 15318.080915145127, 16049.117298548459, 16604.58488537772, 16617.83382166163, 682879.0, 683866.0, 687858.0, 687954.0, 688477.0, 57373.426265737646, 57763.0, 57879.4212057874, 57955.42044579585, 58038.7098064507, 1067.199600299775, 1136.891995260449, 1180.7756526259984, 1198.4247561170687, 1329.6011196640968, 27855.303617409725, 29800.0, 29905.0, 30439.695603043685, 30491.542626861057, 35227.47158792672, 47955.0, 48061.0, 48110.518894812005, 48119.0, 579470.0, 586088.0, 587732.0, 587851.0, 587920.362389129, 21780.01989910426, 24221.878890605432, 27170.72829271682, 27230.455390892468, 27870.024549140682, 45439.0, 45481.862953426426, 45834.0, 46018.0, 46154.846128845704, 36791.0, 36840.81579592085, 37605.62394376021, 39034.0, 39292.60707392889, 37412.43881341837, 37412.812935935144, 37518.624813751514, 38619.80690096531, 38800.6119938797, 36623.45064823976, 37170.8141459291, 37186.62813371832, 37421.81289093537, 37431.25137497289, 32793.01620951375, 32862.671373285964, 33718.66281337155, 33940.0, 33969.49045764365, 21424.785752142277, 24752.0, 24945.251642450756, 25469.745302546737, 25574.48851022932], "value": 18009.271870268367, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.31104346524285, 375.46683709133026, 376.30195986445125, 385.62209035145554, 407.3523098273744, 14684.0, 14986.0, 16370.754438683414, 19726.309579164732, 20513.58972820543, 19661.508462288413, 19662.311819086335, 20141.899290503563, 20351.982400879933, 20384.388468345973, 5082.466341034194, 5849.356570777221, 6151.261848578154, 6157.938420615804, 6165.845853853644, 5868.4424979626865, 5920.733566989475, 5994.670293133876, 5995.580309378345, 5998.8800223995495, 5671.829845104653, 5925.88148237035, 5931.317898441691, 5938.0499120140785, 5942.851428714273, 5948.434898684618, 6016.75932962681, 6018.7893423730175, 6024.277086749618, 6029.939700602981, 5049.6212784041345, 5094.6433749637545, 5226.242194881759, 5240.633155679104, 5244.370675518924, 25467.490650186894, 25601.487970240676, 25754.969801207928, 25777.742222577723, 26016.219513414628, 2228.7882651148116, 2312.17917639239, 2451.2156110044793, 2659.5079910216477, 3092.551580020907, 13817.723645527034, 14009.929950350284, 14030.92984535081, 14407.78388324176, 15011.474598389113, 1759.683257013737, 1800.6308706715092, 1825.2151574822822, 1868.3554173810014, 1905.4759941016193, 114719.0, 118684.40657796743, 121580.0, 128729.0, 130814.3459282707, 10976.286541374786, 11463.713407164887, 11534.711632209093, 11572.884271157349, 11719.06247500198, 14518.419263229353, 14563.781543276858, 14573.052751571115, 14591.0, 14605.269736513132, 8251.95874020626, 8445.930746967033, 8525.593277109332, 8563.529005904735, 8633.611487483078, 8358.707445239508, 8373.37199710012, 8403.663853445794, 8513.787155321166, 8531.914680853237, 4375.584319489643, 4459.910801783946, 4464.397306363664, 4540.455145382575, 4542.568455996741, 1586.2148236622716, 1830.1581272614746, 1888.376835644234, 1896.990515047416, 1913.206019501919, 378.2812655953655, 384.50782997762906, 399.7401688902179, 803.6745118227127, 918.6784625381197, 11379.772404552028, 11540.942295288638, 11626.941865290619, 11776.764464710657, 11920.642380728592, 437.3658195616369, 822.1454930399564, 926.9582868770922, 927.6104036304769, 946.2004606107828, 10739.677809665722, 11305.830412543819, 11309.943450282695, 11325.2072354936, 11381.317120972768, 537869.0, 629502.0, 630244.0, 636737.0, 641821.0, 71273.2872671277, 78794.6060269695, 129905.0, 136547.0, 138695.30652346674, 935.1770442010986, 997.140333278445, 1009.8737657792776, 1013.2198207380424, 1039.948002599882, 18104.366347177787, 18118.818811811714, 18935.43193704191, 18989.14548845333, 19354.54849882777, 51225.74387128041, 51586.96826063533, 54676.63308417321, 55504.722476388975, 57952.2614321571, 76300.0, 77354.0, 80103.0, 80891.59554202191, 81851.0, 6731.4951378647775, 6787.694553744995, 7638.319569694659, 7832.177621349904, 8069.7175598853755, 9680.419174849532, 12329.69175770577, 12455.813162802384, 12574.245545266947, 12700.618981430573, 10651.89348106509, 10789.784204315712, 10884.727881803019, 11006.614768483065, 11154.107671386108, 9631.518424078908, 10049.899501004897, 10879.836802448128, 11250.0, 11377.2604780689, 11090.0, 11112.0, 11164.944175279072, 11547.71130721705, 11615.303081815118, 6927.68825402848, 7112.57324560528, 7237.312455316736, 8091.757247282591, 8230.506169629842, 10561.471926403496, 10744.033037026706, 11074.6189569243, 11114.166437517057, 11120.944395278295], "value": 9228.416350230129, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.657669006058, 1473.3738161281456, 1498.2658497336306, 1626.2194146809532, 1787.8927264364147, 7471.364933980612, 7834.099078605962, 8185.263326300632, 8477.237048665618, 8497.787555311104, 8353.91646083539, 8419.32645388369, 8437.02974157972, 8441.324694024479, 8452.661893524251, 2277.6241920083166, 2309.9884500577473, 2312.2022902098834, 2313.086330899294, 2314.085936055257, 2280.304507125326, 2313.421644588853, 2326.127702111708, 2332.696749422581, 2335.1243283768586, 2269.3305474884946, 2279.8290128240365, 2301.850379725321, 2306.6540018997202, 2312.9537409251807, 2261.2198791416936, 2272.488690044735, 2295.724513058435, 2297.7702229777055, 2300.9654855177187, 2286.1769762885347, 2309.411100169446, 2310.9653355199684, 2313.1903833658253, 2315.409570559513, 8187.426880118394, 8303.792405189859, 8370.204830541088, 8373.07896131425, 8412.915870841274, 5066.16408292635, 5247.842564723064, 5254.789808407659, 5255.5795536357, 5271.051210782058, 3434.845431955543, 3565.322588708137, 3607.152319204985, 3644.7630903991158, 3665.358562251622, 1058.830587106067, 1071.4374953149659, 1096.1614365010694, 1106.1537923488538, 1108.2630051016065, 9571.28215383842, 10868.891311086867, 10985.231033827635, 11014.39420831854, 11072.335659860435, 3550.1479644885044, 4096.467459230281, 4377.430933978626, 4419.270820314612, 4436.245838207513, 8661.220490155916, 8663.263622592025, 8663.917010373703, 8692.95653521741, 8716.651333946571, 5067.569256613156, 5069.493050694975, 5071.416787069535, 5075.87310317245, 5076.6192535559985, 3709.536307961505, 4151.501819781585, 4278.272693642089, 4423.469183697976, 4773.260144677511, 3321.5183798349585, 3542.1144713821545, 3626.9093272668392, 3640.9635903641156, 3713.9071523212137, 1136.2273653915252, 1139.8461207737016, 1147.3746807989498, 1150.8734039255676, 1151.9827202591969, 747.6598147842718, 860.6514361683528, 873.2490058549643, 883.7746374674546, 893.7542175901679, 5383.246345511631, 5446.258982459118, 5583.274174357308, 5618.0, 5788.565857560616, 411.6028032948219, 447.90817882334045, 448.98977301072586, 449.13765570105477, 452.5180682573049, 3149.6062992125985, 3597.640235976434, 3741.7006639468777, 3790.5451345838123, 3815.389537673959, 8619.396642234984, 9731.56207970643, 9755.317127801127, 10118.54466549007, 10374.636887708893, 10085.193184545218, 10326.793464130826, 10340.586376544854, 10347.482625868677, 10355.689329320132, 7673.309402153833, 7784.065912090586, 7807.960960195162, 7822.335101516434, 7871.645775940211, 5548.0845660465575, 5673.177389278614, 5788.507976821849, 5878.382769809279, 5904.675242861684, 9443.386179898285, 9588.66439674608, 9602.231821454407, 9645.758856028655, 9710.465924374228, 8173.386995975445, 8859.867101993605, 9636.614535418505, 9721.416714997124, 9741.464219467998, 6664.233613134357, 7021.929780702128, 7209.783706488814, 7273.927260727325, 7324.0, 7763.4953728007495, 7915.05019397676, 7930.682772689028, 8007.799805004922, 8021.117677055636, 7212.2427145151105, 7252.637368131678, 7257.673404696907, 7276.527025743309, 7277.235890231449, 7073.575585464889, 7101.076860008166, 7137.607431591312, 7180.60506672138, 7226.313500217471, 6398.90401643985, 6475.3200913903365, 6543.345665433419, 6592.143021407186, 6601.53789234749, 4643.976780116213, 4734.0, 4773.47248880355, 4873.293372460902, 4964.205727083649, 4799.136155492045, 4813.831515896927, 4849.3210950467665, 4920.606351491943, 4986.80052797884], "value": 4278.179132237812, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.4118090314338, 305.82415111311, 312.8545226469691, 318.120397102013, 320.7642382848604, 3654.9583368739914, 4499.807550998984, 4863.829765958192, 5108.948910510894, 5164.816498636107, 3979.900502487439, 5063.721495317756, 5071.315372424721, 5087.338645976027, 5109.616778741591, 3821.7515861468987, 3958.6342711764396, 3991.4012898065375, 4039.7576145431367, 4074.7555146691143, 3763.962360376402, 3986.784030870583, 4020.12058363679, 4033.366486572928, 4073.144639625682, 3432.5366075579727, 3747.1943532140585, 3923.3722604383306, 3943.2704949584277, 3985.8007099645047, 3926.9789854637716, 3928.1750832325247, 3930.6219737058955, 3951.1900060487533, 3963.920721585552, 3465.6361082086523, 3466.861325546975, 3473.6873681368547, 3474.029704602579, 3474.1488335357744, 4727.810887564493, 4804.327394164819, 4858.416989961227, 4908.4846091160625, 4908.502906613481, 3717.2863809419005, 3776.66010059096, 4365.388845561624, 4523.163214805239, 4653.441587009546, 4769.427668679746, 4996.201367507694, 5478.534324582415, 5481.068218402841, 5487.478689524489, 3379.1552111972005, 3668.248009158116, 3801.448789925473, 3812.390017597199, 3817.408301713211, 4969.950300497021, 5367.704776237305, 5697.119950416394, 5713.428657134295, 5764.942350576483, 3783.886483405502, 3784.4890939723336, 3785.2240290740333, 3789.981050094732, 3803.2773772983596, 3378.540326768691, 3873.4964454620717, 3877.495925529663, 3894.221155768801, 3909.706771992112, 4091.631753142172, 4404.713693609905, 4432.0, 4487.439070116236, 4527.0, 4917.311576379309, 5469.808556700496, 5482.533984611273, 5496.367917689437, 5505.394406615269, 1164.5167255589006, 1190.880572262071, 1195.947566141807, 1224.6345324962604, 1238.6779437346354, 786.6342150899859, 848.87266909964, 862.8791969124328, 867.2888231649987, 870.8606622940426, 4690.742009189527, 4708.0, 4730.432348118248, 4911.551092427742, 4934.309196712463, 4997.375328083989, 5144.088736763694, 5323.057084164252, 5436.53789427903, 5439.456054394608, 4238.202786161119, 4360.8909777255185, 4878.0975219823995, 4879.829205977774, 4892.877678058077, 2592.468543948486, 2691.300261931912, 2786.4009238013923, 2810.0, 2832.6317578714634, 4855.660103792771, 5313.149896016696, 5560.332760068737, 5618.11793049333, 5908.897828639999, 4964.652474326763, 5175.560077393388, 5907.23205983228, 5947.494462970696, 5949.524038076944, 4955.925661115086, 5983.700814959235, 5992.490638295706, 5998.220231369894, 6001.699915004232, 4557.726536407826, 4744.746245533344, 5695.943040569707, 5727.828165155052, 5809.862674156077, 5010.649254552146, 5087.880666253566, 5639.351474580476, 5674.4892959633835, 5679.488846003879, 4969.602431805519, 5640.661560306395, 5670.461306175906, 5679.574031947538, 5692.893629356999, 3200.9519857202627, 3565.2691198303764, 3620.262274108495, 3823.8088095595667, 3838.347480928193, 5694.487496125369, 5715.914261286167, 5718.828435146952, 5737.680333523396, 5741.655500669973, 5065.493450654905, 5248.905853434328, 5580.9441905580425, 5599.884085197458, 5605.5515558756015, 4603.238057165674, 5100.38795344561, 5395.946040539544, 5604.3835178131185, 5605.019121653776, 4996.800127994839, 5170.612204084633, 5368.919466208088, 5386.596005299697, 5447.536959358499, 5276.894462110659, 5524.309461317335, 5580.609357344947, 5583.994880921311, 5590.916136258041, 4834.758262086812, 5085.64400491962, 5151.150060240168, 5456.226726314043, 5495.230667706444], "value": 3967.4488001883965, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.3131465506475, 718.1418205244735, 741.0255513999091, 762.2910693055456, 790.9841803163937, 14076.788848167273, 14209.573712788608, 14591.34338954747, 14736.042157259784, 14749.92625036876, 12845.48618055279, 13002.414891329912, 13142.0, 13159.0, 13171.868281317184, 656.566666000441, 670.1991120610872, 819.9303059239942, 828.718235799828, 829.531314807135, 762.2377622377622, 785.2461636828623, 821.4250024982506, 823.4153750836913, 826.3182874128838, 878.83302172587, 879.2306731609841, 880.6697488441835, 883.9955800221022, 887.9778005549848, 875.369733791669, 877.8332116897801, 882.8057827277988, 885.8626912828522, 887.9067697891758, 651.1144843013498, 651.5406638319973, 651.7816531461966, 655.6426747422666, 657.8223879552542, 11264.380459074748, 12337.383130843422, 12391.442385092603, 12397.504099835996, 12401.875981240162, 13002.15485993417, 13370.465181392732, 13400.0, 13403.664908377272, 13558.728825423535, 9303.76740581484, 10995.560177592886, 11233.10135189191, 11279.92840680134, 11425.0, 3464.8787292444645, 3519.0850378901414, 3605.260921511084, 3702.426123950792, 3802.39161734124, 55347.0, 91445.0, 91505.62741558882, 91602.08397916068, 95308.52345738227, 10215.57064274744, 10235.232357573212, 10475.161987041018, 10477.47612619366, 10504.159667226604, 20326.491837704176, 20365.592688146153, 20431.693524597144, 20584.588308233753, 20759.169633214806, 7437.590932498656, 7457.7389791357045, 7570.735024274234, 7572.091349038151, 7628.542287462659, 4152.688548358885, 4217.093324935154, 4276.5082015568605, 4395.824167033346, 4415.359772832921, 3425.3491836551475, 3534.3814832404228, 3538.9823050885097, 3540.2034542228307, 3541.6812486876306, 551.8896220755785, 634.8762690038606, 635.2472320300459, 640.8494003909078, 652.1326635574675, 537.978480860769, 569.9200015969797, 633.1357696744003, 647.252423450915, 665.5507532415697, 8657.307415406753, 9211.769705757275, 9245.768855778524, 9425.811483770287, 9550.331476796697, 569.1036617327643, 613.828128124126, 646.2148489585177, 659.6932426421737, 675.6284043775904, 4477.2164871147415, 4762.999770048259, 4821.686590371614, 4925.285833554115, 5089.0, 118149.40925295319, 121777.39111304564, 126500.0, 129231.35384323019, 129298.0, 85110.14889851146, 109256.0, 110287.0, 110734.44632776784, 111717.44141279241, 78270.21729782743, 81531.18468815235, 83188.75216871752, 97792.51103744436, 100296.49555256678, 9675.806483870423, 9687.41875487473, 9812.803743925224, 9838.852417213891, 9855.704328870146, 57919.42080579309, 58233.4176658245, 58555.0, 59244.70377648084, 60755.99999999823, 69176.0, 71717.6414117947, 81746.59126704329, 85034.57482712547, 86177.13822861941, 11989.580364687194, 12109.69725756863, 12143.0, 12395.93802030984, 12437.689057773629, 13604.251766152956, 14152.716945661236, 14235.217063061216, 14299.142051476945, 14364.425422983382, 12705.110642254953, 12771.93614031924, 13089.476420943056, 13324.800127997894, 13732.45070197181, 12900.612981610566, 13197.406116724578, 13208.009399294899, 13221.669458263621, 13267.933660331637, 12558.937205313914, 12678.492860285854, 12691.682707932376, 12811.0, 13434.059615826798, 10916.836247456136, 11079.446027698745, 11109.555617775199, 11462.598809041643, 11716.41417929083, 11000.0, 11297.14003403417, 11494.655160345203, 11511.0, 11566.826497602711], "value": 7357.740902081638, "unit": "score"}], "name": "dromaeo_css", "value": 7010.645513903364, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 114.0, 115.0, 114.0, 114.0, 115.0, 115.0, 115.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 114.0, 112.0, 148.0, 116.0, 114.0, 129.0, 143.0, 175.0, 127.0], "value": 132.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 180.0, 182.0, 182.0, 185.0, 182.0, 184.0, 184.0, 184.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 89.0, 62.0, 61.0, 58.0, 53.0, 88.0, 69.0], "value": 68.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 94.0, 70.0, 67.0, 71.0, 88.0, 68.0, 69.0, 119.0], "value": 80.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 84.0, 88.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 103.0, 96.0, 100.0, 145.0, 96.0, 96.0, 96.0, 96.0, 104.0], "value": 102.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 79.0, 82.0, 84.0, 79.0, 82.0, 83.0, 78.0, 81.0], "value": 81.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 54.0, 56.0, 58.0, 57.0, 61.0, 57.0, 58.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 65.0, 75.0, 74.0, 70.0, 70.0, 68.0, 71.0, 71.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 85.0, 85.0, 94.0, 102.0, 83.0, 84.0, 84.0, 85.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 159.0, 144.0, 152.0, 159.0, 138.0, 150.0, 186.0, 146.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 164.0, 165.0, 165.0, 167.0, 169.0, 166.0, 165.0, 167.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 65.0, 67.0, 64.0, 69.0, 84.0, 79.0, 67.0], "value": 69.6, "unit": "score"}], "name": "kraken", "value": 1454.0, "alertThreshold": 2.0}]} 03:02:13 INFO - Return code: 0 03:02:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:02:13 INFO - # TBPL SUCCESS # 03:02:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:02:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:02:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:02:13 INFO - 2016-11-26 03:02:13.331 screenresolution[2239:28489] starting screenresolution argv=screenresolution get 03:02:13 INFO - 2016-11-26 03:02:13.335 screenresolution[2239:28489] Display 0: 1600x1200x32@60 03:02:13 INFO - 2016-11-26 03:02:13.341 screenresolution[2240:28492] starting screenresolution argv=screenresolution list 03:02:13 INFO - Available Modes on Display 0 03:02:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:02:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:02:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:02:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:02:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:02:13 INFO - Intel Iris: 03:02:13 INFO - Chipset Model: Intel Iris 03:02:13 INFO - Type: GPU 03:02:13 INFO - Bus: Built-In 03:02:13 INFO - VRAM (Dynamic, Max): 1536 MB 03:02:13 INFO - Vendor: Intel (0x8086) 03:02:13 INFO - Device ID: 0x0a2e 03:02:13 INFO - Revision ID: 0x0009 03:02:13 INFO - Displays: 03:02:13 INFO - Display: 03:02:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:02:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:02:13 INFO - Display Serial Number: 1600x1200 60 03:02:13 INFO - Main Display: Yes 03:02:13 INFO - Mirror: Off 03:02:13 INFO - Online: Yes 03:02:13 INFO - Rotation: Supported 03:02:13 INFO - Return code: 0 03:02:13 INFO - Running post-action listener: _resource_record_post_action 03:02:13 INFO - [mozharness: 2016-11-26 11:02:13.586913Z] Finished run-tests step (success) 03:02:13 INFO - Running post-run listener: _resource_record_post_run 03:02:13 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 983040; Write bytes: 661560832; Read time: 12; Write time: 1928 03:02:13 INFO - TinderboxPrint: CPU usage
53.4% 03:02:13 INFO - TinderboxPrint: I/O read bytes / time
983,040 / 12 03:02:13 INFO - TinderboxPrint: I/O write bytes / time
661,560,832 / 1,928 03:02:13 INFO - TinderboxPrint: CPU idle
2,260.2 (46.6%) 03:02:13 INFO - TinderboxPrint: CPU system
968.0 (20.0%) 03:02:13 INFO - TinderboxPrint: CPU user
1,617.5 (33.4%) 03:02:13 INFO - TinderboxPrint: Swap in / out
348,475,392 / 0 03:02:13 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 344207872; Write bytes: 346048000; Read time: 27160; Write time: 732 03:02:13 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 983040; Write bytes: 308541440; Read time: 12; Write time: 1165 03:02:14 INFO - Running post-run listener: _upload_blobber_files 03:02:14 INFO - Blob upload gear active. 03:02:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:02:14 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 03:02:14 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'] 03:02:14 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 03:02:14 INFO - (blobuploader) - INFO - Open directory for files ... 03:02:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:02:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:02:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:15 INFO - (blobuploader) - INFO - Done attempting. 03:02:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:02:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:02:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:16 INFO - (blobuploader) - INFO - Done attempting. 03:02:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:02:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:02:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:18 INFO - (blobuploader) - INFO - Done attempting. 03:02:18 INFO - (blobuploader) - INFO - Iteration through files over. 03:02:18 INFO - Return code: 0 03:02:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:02:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:02:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc648052396fcc4d20bf3ad4d071516fe8d79b6dcd07a0a0f0035582b6747808d622e57fcbe649ee77e363a8ce2c6df471b123fa6e5f61ded466a7c125bbfa8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eba7be2df8bbde5447724d48460e9f40a7b8f7b2f680d3d404265e8f9908d64de8a6e5761aa99a5587bac412eb2e1d4440dd8e65d3f84ec9c4c7ecd12e805823", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/274db80881abca53c0b9f18e9bcab233a8f170d3f5f181a92da46f8a202afcea7188e01d44d5d76b2e1aeea1154c1eeb71f35ba3bc32790fb933244198268a08"} 03:02:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:02:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:02:18 INFO - Contents: 03:02:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc648052396fcc4d20bf3ad4d071516fe8d79b6dcd07a0a0f0035582b6747808d622e57fcbe649ee77e363a8ce2c6df471b123fa6e5f61ded466a7c125bbfa8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eba7be2df8bbde5447724d48460e9f40a7b8f7b2f680d3d404265e8f9908d64de8a6e5761aa99a5587bac412eb2e1d4440dd8e65d3f84ec9c4c7ecd12e805823", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/274db80881abca53c0b9f18e9bcab233a8f170d3f5f181a92da46f8a202afcea7188e01d44d5d76b2e1aeea1154c1eeb71f35ba3bc32790fb933244198268a08"} 03:02:18 INFO - Running post-run listener: copy_logs_to_upload_dir 03:02:18 INFO - Copying logs to upload dir... 03:02:18 INFO - mkdir: /builds/slave/test/build/upload/logs 03:02:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.947953 ========= master_lag: 1.68 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-26 03:02:20.509721) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-26 03:02:20.513363) ========= 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.NYokUkO630/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.O5dL5UDssD/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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/bc648052396fcc4d20bf3ad4d071516fe8d79b6dcd07a0a0f0035582b6747808d622e57fcbe649ee77e363a8ce2c6df471b123fa6e5f61ded466a7c125bbfa8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eba7be2df8bbde5447724d48460e9f40a7b8f7b2f680d3d404265e8f9908d64de8a6e5761aa99a5587bac412eb2e1d4440dd8e65d3f84ec9c4c7ecd12e805823", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/274db80881abca53c0b9f18e9bcab233a8f170d3f5f181a92da46f8a202afcea7188e01d44d5d76b2e1aeea1154c1eeb71f35ba3bc32790fb933244198268a08"} build_url:https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011185 build_url: 'https://queue.taskcluster.net/v1/task/KenOEgEtSb2s_6x5GDoghw/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/bc648052396fcc4d20bf3ad4d071516fe8d79b6dcd07a0a0f0035582b6747808d622e57fcbe649ee77e363a8ce2c6df471b123fa6e5f61ded466a7c125bbfa8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eba7be2df8bbde5447724d48460e9f40a7b8f7b2f680d3d404265e8f9908d64de8a6e5761aa99a5587bac412eb2e1d4440dd8e65d3f84ec9c4c7ecd12e805823", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/274db80881abca53c0b9f18e9bcab233a8f170d3f5f181a92da46f8a202afcea7188e01d44d5d76b2e1aeea1154c1eeb71f35ba3bc32790fb933244198268a08"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-26 03:02:20.547456) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 03:02:20.547777) ========= 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.NYokUkO630/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.O5dL5UDssD/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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.005226 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 03:02:20.598346) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-26 03:02:20.598860) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-26 03:02:20.599420) ========= ========= Total master_lag: 1.88 =========