builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0293 starttime: 1480555495.32 results: success (0) buildid: 20161130141744 builduid: 15d4596afa324fb8bdbd79dbdd1c81cf revision: 3fa3896980f6726c3897ce1645a839996d5d5e8b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:55.322835) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:55.323293) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:55.323573) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:55.348904) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:55.349267) ========= 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.zraoBHbN5X/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.cpDvbNbsuu/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-30 17:24:55-- 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% 67.9M=0s 2016-11-30 17:24:55 (67.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122009 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:55.494693) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:55.495027) ========= 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.zraoBHbN5X/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.cpDvbNbsuu/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.019977 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:55.543904) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:55.544271) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3fa3896980f6726c3897ce1645a839996d5d5e8b --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 3fa3896980f6726c3897ce1645a839996d5d5e8b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zraoBHbN5X/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.cpDvbNbsuu/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-30 17:24:55,626 truncating revision to first 12 chars 2016-11-30 17:24:55,626 Setting DEBUG logging. 2016-11-30 17:24:55,626 attempt 1/10 2016-11-30 17:24:55,626 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3fa3896980f6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-30 17:24:56,310 unpacking tar archive at: mozilla-aurora-3fa3896980f6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.927196 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:56.495213) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:56.495556) ========= script_repo_revision: 3fa3896980f6726c3897ce1645a839996d5d5e8b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:56.495983) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:56.496290) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:56.516024) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-30 17:24:56.516408) ========= /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.zraoBHbN5X/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.cpDvbNbsuu/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 17:24:56 INFO - MultiFileLogger online at 20161130 17:24:56 in /builds/slave/test 17:24:56 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 17:24:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:24:56 INFO - {'append_to_log': False, 17:24:56 INFO - 'base_work_dir': '/builds/slave/test', 17:24:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:24:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:24:56 INFO - 'branch': 'Mozilla-Aurora', 17:24:56 INFO - 'buildbot_json_path': 'buildprops.json', 17:24:56 INFO - 'config_files': ('talos/mac_config.py',), 17:24:56 INFO - 'default_actions': ('clobber', 17:24:56 INFO - 'read-buildbot-config', 17:24:56 INFO - 'download-and-extract', 17:24:56 INFO - 'populate-webroot', 17:24:56 INFO - 'create-virtualenv', 17:24:56 INFO - 'install', 17:24:56 INFO - 'run-tests'), 17:24:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:24:56 INFO - 'download_minidump_stackwalk': True, 17:24:56 INFO - 'download_symbols': 'ondemand', 17:24:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:24:56 INFO - 'tooltool.py': '/tools/tooltool.py', 17:24:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:24:56 INFO - '/tools/misc-python/virtualenv.py')}, 17:24:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:24:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:24:56 INFO - 'installer_path': 'installer.exe', 17:24:56 INFO - 'log_level': 'info', 17:24:56 INFO - 'log_name': 'talos', 17:24:56 INFO - 'log_to_console': True, 17:24:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:24:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:24:56 INFO - 'opt_config_files': (), 17:24:56 INFO - 'pip_index': False, 17:24:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:24:56 INFO - 'cmd': ('bash', 17:24:56 INFO - '-c', 17:24:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:24:56 INFO - 'enabled': True, 17:24:56 INFO - 'halt_on_failure': False, 17:24:56 INFO - 'name': 'check_screen_resolution'},), 17:24:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:24:56 INFO - 'cmd': ('bash', 17:24:56 INFO - '-c', 17:24:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:24:56 INFO - 'enabled': True, 17:24:56 INFO - 'halt_on_failure': False, 17:24:56 INFO - 'name': 'check_screen_resolution'},), 17:24:56 INFO - 'run_cmd_checks_enabled': True, 17:24:56 INFO - 'sps_profile': False, 17:24:56 INFO - 'sps_profile_interval': 0, 17:24:56 INFO - 'suite': 'dromaeojs', 17:24:56 INFO - 'system_bits': '32', 17:24:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:24:56 INFO - 'title': 't-yosemite-r7-0293', 17:24:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:24:56 INFO - 'use_talos_json': True, 17:24:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:24:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:24:56 INFO - 'work_dir': 'build'} 17:24:56 INFO - [mozharness: 2016-12-01 01:24:56.711071Z] Running clobber step. 17:24:56 INFO - Running pre-action listener: _resource_record_pre_action 17:24:56 INFO - Running main action method: clobber 17:24:56 INFO - rmtree: /builds/slave/test/build 17:24:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:24:58 INFO - Running post-action listener: _resource_record_post_action 17:24:58 INFO - [mozharness: 2016-12-01 01:24:58.839651Z] Finished clobber step (success) 17:24:58 INFO - [mozharness: 2016-12-01 01:24:58.839809Z] Running read-buildbot-config step. 17:24:58 INFO - Running pre-action listener: _resource_record_pre_action 17:24:58 INFO - Running main action method: read_buildbot_config 17:24:58 INFO - Using buildbot properties: 17:24:58 INFO - { 17:24:58 INFO - "project": "", 17:24:58 INFO - "product": "firefox", 17:24:58 INFO - "script_repo_revision": "production", 17:24:58 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 17:24:58 INFO - "repository": "", 17:24:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 17:24:58 INFO - "buildid": "20161130141744", 17:24:58 INFO - "pgo_build": "False", 17:24:58 INFO - "basedir": "/builds/slave/test", 17:24:58 INFO - "buildnumber": 3, 17:24:58 INFO - "slavename": "t-yosemite-r7-0293", 17:24:58 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 17:24:58 INFO - "platform": "yosemite_r7", 17:24:58 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 17:24:58 INFO - "branch": "mozilla-aurora", 17:24:58 INFO - "repo_path": "releases/mozilla-aurora", 17:24:58 INFO - "revision": "3fa3896980f6726c3897ce1645a839996d5d5e8b", 17:24:58 INFO - "stage_platform": "macosx64", 17:24:58 INFO - "builduid": "15d4596afa324fb8bdbd79dbdd1c81cf", 17:24:58 INFO - "slavebuilddir": "test" 17:24:58 INFO - } 17:24:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 17:24:58 INFO - Running post-action listener: _resource_record_post_action 17:24:58 INFO - [mozharness: 2016-12-01 01:24:58.843007Z] Finished read-buildbot-config step (success) 17:24:58 INFO - [mozharness: 2016-12-01 01:24:58.843130Z] Running download-and-extract step. 17:24:58 INFO - Running pre-action listener: _resource_record_pre_action 17:24:58 INFO - Running main action method: download_and_extract 17:24:58 INFO - mkdir: /builds/slave/test/build/tests 17:24:58 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')]} 17:24:58 INFO - trying https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 17:24:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/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 17:24:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/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 17:25:00 INFO - Downloaded 1199 bytes. 17:25:00 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 17:25:00 INFO - Using the following test package requirements: 17:25:00 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 17:25:00 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:25:00 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 17:25:00 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:25:00 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 17:25:00 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:25:00 INFO - u'jsshell-mac.zip'], 17:25:00 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:25:00 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 17:25:00 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 17:25:00 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:25:00 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 17:25:00 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:25:00 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 17:25:00 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:25:00 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 17:25:00 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:25:00 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 17:25:00 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 17:25:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 17:25:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:25:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 17:25:00 INFO - Http code: 200 17:25:00 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 17:25:00 INFO - x-amz-cf-id: KnVI5QoXsg27MjLEjzMKZT_OwD-yyNPDtrmzBPnNggnmyN66YIlAiw== 17:25:00 INFO - x-amz-version-id: mStQbsv2ce.oUxf5KqhhTjryVkWf9H9w 17:25:00 INFO - Expected file size: 16285165 17:25:00 INFO - Obtained file size: 16285165 17:25:07 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 17:25:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 17:25:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:25:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 17:25:08 INFO - Http code: 200 17:25:08 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 17:25:08 INFO - x-amz-cf-id: I4BGeJo2R7LYFnIjnFXNt6AQRHx86tcdPI4xsLrt4IVb4ojBL42JLw== 17:25:08 INFO - x-amz-version-id: mStQbsv2ce.oUxf5KqhhTjryVkWf9H9w 17:25:08 INFO - Expected file size: 16285165 17:25:08 INFO - Obtained file size: 16285165 17:25:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 17:25:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 17:25:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 17:25:16 INFO - Http code: 200 17:25:16 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 17:25:16 INFO - x-amz-cf-id: lU2h5_3h5VoAtz5ZC1gIkkqrBjl7c_XS3Gkarz9VhVv_z9HffCioEg== 17:25:16 INFO - x-amz-version-id: lfZInHrhkJvAQzOAoNt0RN86foAyAZCi 17:25:16 INFO - Expected file size: 14409038 17:25:16 INFO - Obtained file size: 14409038 17:25:16 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')]} 17:25:16 INFO - trying https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:25:16 INFO - Downloading https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 17:25:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/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 17:25:19 INFO - Downloaded 109765617 bytes. 17:25:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:25:19 INFO - mkdir: /builds/slave/test/properties 17:25:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:25:19 INFO - Writing to file /builds/slave/test/properties/build_url 17:25:19 INFO - Contents: 17:25:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:25:20 INFO - Running post-action listener: _resource_record_post_action 17:25:20 INFO - Running post-action listener: set_extra_try_arguments 17:25:20 INFO - [mozharness: 2016-12-01 01:25:20.061966Z] Finished download-and-extract step (success) 17:25:20 INFO - [mozharness: 2016-12-01 01:25:20.062128Z] Running populate-webroot step. 17:25:20 INFO - Running pre-action listener: _resource_record_pre_action 17:25:20 INFO - Running main action method: populate_webroot 17:25:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:25:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:25:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:25:20 INFO - '--tppagecycles', 17:25:20 INFO - '7'], 17:25:20 INFO - 'tests': ['tsvgm']}}, 17:25:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:25:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:25:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:25:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:25:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:20 INFO - 'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:25:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:25:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:20 INFO - 'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['damp', 'tps']}, 17:25:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:20 INFO - 'tests': ['damp', 'tps']}, 17:25:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['dromaeo_dom']}, 17:25:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:25:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['basic_compositor_video']}, 17:25:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:25:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['a11yr', 17:25:20 INFO - 'ts_paint', 17:25:20 INFO - 'tpaint', 17:25:20 INFO - 'sessionrestore', 17:25:20 INFO - 'sessionrestore_no_auto_restore', 17:25:20 INFO - 'tabpaint']}, 17:25:20 INFO - 'other-e10s': {'tests': ['a11yr', 17:25:20 INFO - 'ts_paint', 17:25:20 INFO - 'tpaint', 17:25:20 INFO - 'sessionrestore', 17:25:20 INFO - 'sessionrestore_no_auto_restore', 17:25:20 INFO - 'tabpaint']}, 17:25:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:25:20 INFO - 'ts_paint', 17:25:20 INFO - 'tpaint', 17:25:20 INFO - 'sessionrestore', 17:25:20 INFO - 'sessionrestore_no_auto_restore', 17:25:20 INFO - 'tabpaint']}, 17:25:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:25:20 INFO - 'ts_paint', 17:25:20 INFO - 'tpaint', 17:25:20 INFO - 'sessionrestore', 17:25:20 INFO - 'sessionrestore_no_auto_restore', 17:25:20 INFO - 'tabpaint']}, 17:25:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['a11yr', 17:25:20 INFO - 'ts_paint', 17:25:20 INFO - 'tpaint', 17:25:20 INFO - 'sessionrestore', 17:25:20 INFO - 'sessionrestore_no_auto_restore', 17:25:20 INFO - 'tabpaint']}, 17:25:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['a11yr', 17:25:20 INFO - 'ts_paint', 17:25:20 INFO - 'tpaint', 17:25:20 INFO - 'sessionrestore', 17:25:20 INFO - 'sessionrestore_no_auto_restore', 17:25:20 INFO - 'tabpaint']}, 17:25:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['tsvgx', 17:25:20 INFO - 'tsvgr_opacity', 17:25:20 INFO - 'tart', 17:25:20 INFO - 'tscrollx', 17:25:20 INFO - 'cart']}, 17:25:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:25:20 INFO - 'tsvgr_opacity', 17:25:20 INFO - 'tart', 17:25:20 INFO - 'tscrollx', 17:25:20 INFO - 'cart']}, 17:25:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:20 INFO - 'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['tp5o']}, 17:25:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:20 INFO - 'tests': ['tp5o']}, 17:25:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:20 INFO - 'talos_options': ['--disable-e10s', 17:25:20 INFO - '--xperf_path', 17:25:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:25:20 INFO - 'tests': ['tp5n']}, 17:25:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:20 INFO - 'talos_options': ['--xperf_path', 17:25:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:25:20 INFO - 'tests': ['tp5n']}}, 17:25:20 INFO - 'talos.zip': {'path': '', 17:25:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:25:20 INFO - Running post-action listener: _resource_record_post_action 17:25:20 INFO - [mozharness: 2016-12-01 01:25:20.081345Z] Finished populate-webroot step (success) 17:25:20 INFO - [mozharness: 2016-12-01 01:25:20.081458Z] Running create-virtualenv step. 17:25:20 INFO - Running pre-action listener: _resource_record_pre_action 17:25:20 INFO - Running main action method: create_virtualenv 17:25:20 INFO - Creating virtualenv /builds/slave/test/build/venv 17:25:20 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 17:25:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:25:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:25:20 INFO - Using real prefix '/tools/python27' 17:25:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:25:20 INFO - Installing distribute.............................................................................................................................................................................................done. 17:25:23 INFO - Installing pip.................done. 17:25:23 INFO - Return code: 0 17:25:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:25:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:25:24 INFO - Reading from file tmpfile_stdout 17:25:24 INFO - Output received: 17:25:24 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) 17:25:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:25:24 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')]} 17:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:24 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')]} 17:25:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:24 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 0x100a13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100989570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bdacc4f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100827d30>, '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 0x10068ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10081fb20>, '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.zraoBHbN5X/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.cpDvbNbsuu/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 17:25:24 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 17:25:24 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 17:25:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zraoBHbN5X/Render', 17:25:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:24 INFO - 'HOME': '/Users/cltbld', 17:25:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:24 INFO - 'LOGNAME': 'cltbld', 17:25:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:24 INFO - 'MOZ_NO_REMOTE': '1', 17:25:24 INFO - 'NO_EM_RESTART': '1', 17:25:24 INFO - 'PAGER': '/bin/cat', 17:25:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:24 INFO - 'PWD': '/builds/slave/test', 17:25:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:24 INFO - 'SHELL': '/bin/bash', 17:25:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpDvbNbsuu/Listeners', 17:25:24 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:25:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:24 INFO - 'USER': 'cltbld', 17:25:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:24 INFO - 'XPC_FLAGS': '0x0', 17:25:24 INFO - 'XPC_SERVICE_NAME': '0', 17:25:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:24 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 17:25:24 INFO - Cleaning up... 17:25:24 INFO - Return code: 0 17:25:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:25:24 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')]} 17:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:24 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')]} 17:25:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:24 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 0x100a13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100989570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bdacc4f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100827d30>, '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 0x10068ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10081fb20>, '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.zraoBHbN5X/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.cpDvbNbsuu/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 17:25:24 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 17:25:24 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 17:25:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zraoBHbN5X/Render', 17:25:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:24 INFO - 'HOME': '/Users/cltbld', 17:25:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:24 INFO - 'LOGNAME': 'cltbld', 17:25:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:24 INFO - 'MOZ_NO_REMOTE': '1', 17:25:24 INFO - 'NO_EM_RESTART': '1', 17:25:24 INFO - 'PAGER': '/bin/cat', 17:25:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:24 INFO - 'PWD': '/builds/slave/test', 17:25:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:24 INFO - 'SHELL': '/bin/bash', 17:25:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpDvbNbsuu/Listeners', 17:25:24 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:25:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:24 INFO - 'USER': 'cltbld', 17:25:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:24 INFO - 'XPC_FLAGS': '0x0', 17:25:24 INFO - 'XPC_SERVICE_NAME': '0', 17:25:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:24 INFO - Downloading/unpacking psutil>=3.1.1 17:25:24 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 17:25:24 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 17:25:24 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 17:25:24 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 17:25:24 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 17:25:24 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 17:25:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:25:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:25:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:25:28 INFO - Installing collected packages: psutil 17:25:28 INFO - Running setup.py install for psutil 17:25:28 INFO - building 'psutil._psutil_osx' extension 17:25:28 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 17:25:28 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 17:25:28 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 17:25:28 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 17:25:28 INFO - building 'psutil._psutil_posix' extension 17:25:28 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 17:25:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:25:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:25:28 INFO - ^ 17:25:28 INFO - 1 warning generated. 17:25:28 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 17:25:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:25:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:25:28 INFO - Successfully installed psutil 17:25:28 INFO - Cleaning up... 17:25:29 INFO - Return code: 0 17:25:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:25:29 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')]} 17:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:29 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')]} 17:25:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:29 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 0x100a13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100989570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bdacc4f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100827d30>, '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 0x10068ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10081fb20>, '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.zraoBHbN5X/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.cpDvbNbsuu/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 17:25:29 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 17:25:29 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 17:25:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zraoBHbN5X/Render', 17:25:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:29 INFO - 'HOME': '/Users/cltbld', 17:25:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:29 INFO - 'LOGNAME': 'cltbld', 17:25:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:29 INFO - 'MOZ_NO_REMOTE': '1', 17:25:29 INFO - 'NO_EM_RESTART': '1', 17:25:29 INFO - 'PAGER': '/bin/cat', 17:25:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:29 INFO - 'PWD': '/builds/slave/test', 17:25:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:29 INFO - 'SHELL': '/bin/bash', 17:25:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpDvbNbsuu/Listeners', 17:25:29 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:25:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:29 INFO - 'USER': 'cltbld', 17:25:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:29 INFO - 'XPC_FLAGS': '0x0', 17:25:29 INFO - 'XPC_SERVICE_NAME': '0', 17:25:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:25:29 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 17:25:29 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 17:25:29 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 17:25:29 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 17:25:29 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 17:25:29 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 17:25:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:25:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:25:31 INFO - Installing collected packages: mozsystemmonitor 17:25:31 INFO - Running setup.py install for mozsystemmonitor 17:25:32 INFO - Successfully installed mozsystemmonitor 17:25:32 INFO - Cleaning up... 17:25:32 INFO - Return code: 0 17:25:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:25:32 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')]} 17:25:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:32 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')]} 17:25:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:32 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 0x100a13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100989570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bdacc4f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100827d30>, '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 0x10068ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10081fb20>, '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.zraoBHbN5X/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.cpDvbNbsuu/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 17:25:32 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 17:25:32 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 17:25:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zraoBHbN5X/Render', 17:25:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:32 INFO - 'HOME': '/Users/cltbld', 17:25:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:32 INFO - 'LOGNAME': 'cltbld', 17:25:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:32 INFO - 'MOZ_NO_REMOTE': '1', 17:25:32 INFO - 'NO_EM_RESTART': '1', 17:25:32 INFO - 'PAGER': '/bin/cat', 17:25:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:32 INFO - 'PWD': '/builds/slave/test', 17:25:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:32 INFO - 'SHELL': '/bin/bash', 17:25:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpDvbNbsuu/Listeners', 17:25:32 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:25:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:32 INFO - 'USER': 'cltbld', 17:25:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:32 INFO - 'XPC_FLAGS': '0x0', 17:25:32 INFO - 'XPC_SERVICE_NAME': '0', 17:25:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:32 INFO - Downloading/unpacking jsonschema==2.5.1 17:25:32 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 17:25:32 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 17:25:32 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 17:25:32 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 17:25:32 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 17:25:32 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 17:25:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:25:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:35 INFO - Downloading functools32-3.2.3-2.tar.gz 17:25:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:25:35 INFO - warning: no files found matching '*.txt' 17:25:35 INFO - no previously-included directories found matching 'build' 17:25:35 INFO - no previously-included directories found matching 'dist' 17:25:35 INFO - no previously-included directories found matching '.git*' 17:25:35 INFO - Installing collected packages: jsonschema, functools32 17:25:35 INFO - Running setup.py install for functools32 17:25:35 INFO - warning: no files found matching '*.txt' 17:25:35 INFO - no previously-included directories found matching 'build' 17:25:35 INFO - no previously-included directories found matching 'dist' 17:25:35 INFO - no previously-included directories found matching '.git*' 17:25:35 INFO - Successfully installed jsonschema functools32 17:25:35 INFO - Cleaning up... 17:25:35 INFO - Return code: 0 17:25:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:25:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100a13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100989570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bdacc4f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100827d30>, '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 0x10068ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10081fb20>, '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.zraoBHbN5X/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.cpDvbNbsuu/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 17:25:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:25:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:25:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zraoBHbN5X/Render', 17:25:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:35 INFO - 'HOME': '/Users/cltbld', 17:25:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:35 INFO - 'LOGNAME': 'cltbld', 17:25:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:35 INFO - 'MOZ_NO_REMOTE': '1', 17:25:35 INFO - 'NO_EM_RESTART': '1', 17:25:35 INFO - 'PAGER': '/bin/cat', 17:25:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:35 INFO - 'PWD': '/builds/slave/test', 17:25:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:35 INFO - 'SHELL': '/bin/bash', 17:25:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpDvbNbsuu/Listeners', 17:25:35 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:25:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:35 INFO - 'USER': 'cltbld', 17:25:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:35 INFO - 'XPC_FLAGS': '0x0', 17:25:35 INFO - 'XPC_SERVICE_NAME': '0', 17:25:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:25:36 INFO - Cleaning up... 17:25:36 INFO - Return code: 0 17:25:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:25: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')]} 17:25:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25: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')]} 17:25:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25: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', '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 0x100a13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100989570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bdacc4f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100827d30>, '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 0x10068ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10081fb20>, '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.zraoBHbN5X/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.cpDvbNbsuu/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 17:25: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:25: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 blobuploader==1.2.4 17:25:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zraoBHbN5X/Render', 17:25:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:36 INFO - 'HOME': '/Users/cltbld', 17:25:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:36 INFO - 'LOGNAME': 'cltbld', 17:25:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:36 INFO - 'MOZ_NO_REMOTE': '1', 17:25:36 INFO - 'NO_EM_RESTART': '1', 17:25:36 INFO - 'PAGER': '/bin/cat', 17:25:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:36 INFO - 'PWD': '/builds/slave/test', 17:25:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:36 INFO - 'SHELL': '/bin/bash', 17:25:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpDvbNbsuu/Listeners', 17:25:36 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:25:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:36 INFO - 'USER': 'cltbld', 17:25:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:36 INFO - 'XPC_FLAGS': '0x0', 17:25:36 INFO - 'XPC_SERVICE_NAME': '0', 17:25:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:36 INFO - Downloading/unpacking blobuploader==1.2.4 17:25:36 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 17:25:36 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 17:25:36 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 17:25:36 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 17:25:36 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 17:25:36 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 17:25:38 INFO - Downloading blobuploader-1.2.4.tar.gz 17:25:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:25:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:25:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:40 INFO - Downloading docopt-0.6.1.tar.gz 17:25:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:25:40 INFO - Installing collected packages: blobuploader, requests, docopt 17:25:40 INFO - Running setup.py install for blobuploader 17:25:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:25:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:25:40 INFO - Running setup.py install for requests 17:25:40 INFO - Running setup.py install for docopt 17:25:41 INFO - Successfully installed blobuploader requests docopt 17:25:41 INFO - Cleaning up... 17:25:41 INFO - Return code: 0 17:25:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:41 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 0x100a13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100989570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bdacc4f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100827d30>, '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 0x10068ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10081fb20>, '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.zraoBHbN5X/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.cpDvbNbsuu/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 17:25:41 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 17:25:41 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 17:25:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zraoBHbN5X/Render', 17:25:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:41 INFO - 'HOME': '/Users/cltbld', 17:25:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:41 INFO - 'LOGNAME': 'cltbld', 17:25:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:41 INFO - 'MOZ_NO_REMOTE': '1', 17:25:41 INFO - 'NO_EM_RESTART': '1', 17:25:41 INFO - 'PAGER': '/bin/cat', 17:25:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:41 INFO - 'PWD': '/builds/slave/test', 17:25:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:41 INFO - 'SHELL': '/bin/bash', 17:25:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpDvbNbsuu/Listeners', 17:25:41 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:25:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:41 INFO - 'USER': 'cltbld', 17:25:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:41 INFO - 'XPC_FLAGS': '0x0', 17:25:41 INFO - 'XPC_SERVICE_NAME': '0', 17:25:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:25:41 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-HSWyb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:25:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:25:41 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-IV90fa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:25:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:25:41 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-hP0KFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:25:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:25:41 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-s9gu0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:25:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:25:41 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-vgI1By-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:25:42 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-x6lqjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:25:42 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-9VLpMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:25:42 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-YWhoQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:25:42 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-d4TuvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:25:42 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-JvJw3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:25:42 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-T5JaRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:25:42 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-2ov9YS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:25:42 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-KadroU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:25:42 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-NU138V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:25:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:42 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-BoumJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:25:43 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-EbGQm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:25:43 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-t9bIXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:25:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:25:43 INFO - Running setup.py install for manifestparser 17:25:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:25:43 INFO - Running setup.py install for mozcrash 17:25:43 INFO - Running setup.py install for mozdebug 17:25:43 INFO - Running setup.py install for mozdevice 17:25:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:25:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:25:43 INFO - Running setup.py install for mozfile 17:25:44 INFO - Running setup.py install for mozhttpd 17:25:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:25:44 INFO - Running setup.py install for mozinfo 17:25:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:25:44 INFO - Running setup.py install for mozInstall 17:25:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:25:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:25:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:25:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:25:44 INFO - Running setup.py install for mozleak 17:25:44 INFO - Running setup.py install for mozlog 17:25:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:25:44 INFO - Running setup.py install for moznetwork 17:25:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:25:44 INFO - Running setup.py install for mozprocess 17:25:45 INFO - Running setup.py install for mozprofile 17:25:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:25:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:25:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:25:45 INFO - Running setup.py install for mozrunner 17:25:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:25:45 INFO - Running setup.py install for mozscreenshot 17:25:45 INFO - Running setup.py install for moztest 17:25:45 INFO - Running setup.py install for mozversion 17:25:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:25:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:25:45 INFO - Cleaning up... 17:25:45 INFO - Return code: 0 17:25:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:45 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 0x100a13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100989570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bdacc4f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100827d30>, '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 0x10068ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10081fb20>, '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.zraoBHbN5X/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.cpDvbNbsuu/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 17:25:45 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 17:25:45 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 17:25:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zraoBHbN5X/Render', 17:25:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:45 INFO - 'HOME': '/Users/cltbld', 17:25:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:45 INFO - 'LOGNAME': 'cltbld', 17:25:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:45 INFO - 'MOZ_NO_REMOTE': '1', 17:25:45 INFO - 'NO_EM_RESTART': '1', 17:25:45 INFO - 'PAGER': '/bin/cat', 17:25:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:45 INFO - 'PWD': '/builds/slave/test', 17:25:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:45 INFO - 'SHELL': '/bin/bash', 17:25:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpDvbNbsuu/Listeners', 17:25:45 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:25:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:45 INFO - 'USER': 'cltbld', 17:25:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:45 INFO - 'XPC_FLAGS': '0x0', 17:25:45 INFO - 'XPC_SERVICE_NAME': '0', 17:25:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:25:46 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-2CHjUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:25:46 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)) 17:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:25:46 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-QGMw4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:25:46 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)) 17:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:25:46 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-j4A3tq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:25:46 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)) 17:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:25:46 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Wy2B7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:25:46 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)) 17:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:25:46 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ENimsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:25:46 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)) 17:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:25:46 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-9Tsbha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:25:46 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)) 17:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:25:46 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-4kQOyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:25:46 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)) 17:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:25:46 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-KInead-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:25:47 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)) 17:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:25:47 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-wddIMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:25:47 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)) 17:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:25:47 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-o4qGBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:25:47 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)) 17:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:25:47 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ptnMqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:25:47 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)) 17:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:25:47 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Z0b54L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:25:47 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)) 17:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:25:47 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-nLy5Pd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:25:47 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)) 17:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:25:47 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-puuxsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:25:47 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)) 17:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:47 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-gTFpf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:47 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)) 17:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:25:47 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-3QNXX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:25:47 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)) 17:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:25:47 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-6ObOFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:25:47 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)) 17:25:47 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)) 17:25:47 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)) 17:25:47 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)) 17:25:47 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)) 17:25:47 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)) 17:25:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:25: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 17:25: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 17:25: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 17:25: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 17:25: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 17:25: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 17:25:50 INFO - Downloading blessings-1.6.tar.gz 17:25:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:25:50 INFO - Installing collected packages: blessings 17:25:50 INFO - Running setup.py install for blessings 17:25:50 INFO - Successfully installed blessings 17:25:50 INFO - Cleaning up... 17:25:50 INFO - Return code: 0 17:25:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:25:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:25:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:25:51 INFO - Reading from file tmpfile_stdout 17:25:51 INFO - Current package versions: 17:25:51 INFO - blessings == 1.6 17:25:51 INFO - blobuploader == 1.2.4 17:25:51 INFO - docopt == 0.6.1 17:25:51 INFO - functools32 == 3.2.3-2 17:25:51 INFO - jsonschema == 2.5.1 17:25:51 INFO - manifestparser == 1.1 17:25:51 INFO - mozInstall == 1.12 17:25:51 INFO - mozcrash == 1.0 17:25:51 INFO - mozdebug == 0.1 17:25:51 INFO - mozdevice == 0.48 17:25:51 INFO - mozfile == 1.2 17:25:51 INFO - mozhttpd == 0.7 17:25:51 INFO - mozinfo == 0.9 17:25:51 INFO - mozleak == 0.1 17:25:51 INFO - mozlog == 3.4 17:25:51 INFO - moznetwork == 0.27 17:25:51 INFO - mozprocess == 0.23 17:25:51 INFO - mozprofile == 0.28 17:25:51 INFO - mozrunner == 6.13 17:25:51 INFO - mozscreenshot == 0.1 17:25:51 INFO - mozsystemmonitor == 0.3 17:25:51 INFO - moztest == 0.7 17:25:51 INFO - mozversion == 1.4 17:25:51 INFO - psutil == 3.1.1 17:25:51 INFO - requests == 1.2.3 17:25:51 INFO - wsgiref == 0.1.2 17:25:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25: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')]} 17:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25: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')]} 17:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:52 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 0x100a13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100989570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bdacc4f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100827d30>, '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 0x10068ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10081fb20>, '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.zraoBHbN5X/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.cpDvbNbsuu/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 17:25:52 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 17:25:52 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 17:25:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zraoBHbN5X/Render', 17:25:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:52 INFO - 'HOME': '/Users/cltbld', 17:25:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:52 INFO - 'LOGNAME': 'cltbld', 17:25:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:52 INFO - 'MOZ_NO_REMOTE': '1', 17:25:52 INFO - 'NO_EM_RESTART': '1', 17:25:52 INFO - 'PAGER': '/bin/cat', 17:25:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:52 INFO - 'PWD': '/builds/slave/test', 17:25:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:52 INFO - 'SHELL': '/bin/bash', 17:25:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpDvbNbsuu/Listeners', 17:25:52 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:25:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:52 INFO - 'USER': 'cltbld', 17:25:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:52 INFO - 'XPC_FLAGS': '0x0', 17:25:52 INFO - 'XPC_SERVICE_NAME': '0', 17:25:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:52 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)) 17:25:52 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)) 17:25:52 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)) 17:25:52 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)) 17:25:52 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)) 17:25:52 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)) 17:25:52 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)) 17:25:52 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)) 17:25:52 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)) 17:25:52 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)) 17:25:52 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)) 17:25:52 INFO - Cleaning up... 17:25:52 INFO - Return code: 0 17:25:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100a13490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100989570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bdacc4f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100827d30>, '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 0x10068ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10081fb20>, '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.zraoBHbN5X/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.cpDvbNbsuu/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 17:25:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:25:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:25:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zraoBHbN5X/Render', 17:25:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:52 INFO - 'HOME': '/Users/cltbld', 17:25:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:52 INFO - 'LOGNAME': 'cltbld', 17:25:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:52 INFO - 'MOZ_NO_REMOTE': '1', 17:25:52 INFO - 'NO_EM_RESTART': '1', 17:25:52 INFO - 'PAGER': '/bin/cat', 17:25:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:52 INFO - 'PWD': '/builds/slave/test', 17:25:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:52 INFO - 'SHELL': '/bin/bash', 17:25:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpDvbNbsuu/Listeners', 17:25:52 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:25:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:52 INFO - 'USER': 'cltbld', 17:25:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:52 INFO - 'XPC_FLAGS': '0x0', 17:25:52 INFO - 'XPC_SERVICE_NAME': '0', 17:25:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:25:53 INFO - Cleaning up... 17:25:53 INFO - Return code: 0 17:25:53 INFO - Running post-action listener: _resource_record_post_action 17:25:53 INFO - Running post-action listener: _start_resource_monitoring 17:25:53 INFO - Starting resource monitoring. 17:25:53 INFO - [mozharness: 2016-12-01 01:25:53.260846Z] Finished create-virtualenv step (success) 17:25:53 INFO - [mozharness: 2016-12-01 01:25:53.261761Z] Running install step. 17:25:53 INFO - Running pre-action listener: _resource_record_pre_action 17:25:53 INFO - Running main action method: install 17:25:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:25:53 INFO - Reading from file tmpfile_stdout 17:25:53 INFO - Detecting whether we're running mozinstall >=1.0... 17:25:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:25:53 INFO - Reading from file tmpfile_stdout 17:25:53 INFO - Output received: 17:25:53 INFO - Usage: mozinstall [options] installer 17:25:53 INFO - Options: 17:25:53 INFO - -h, --help show this help message and exit 17:25:53 INFO - -d DEST, --destination=DEST 17:25:53 INFO - Directory to install application into. [default: 17:25:53 INFO - "/builds/slave/test"] 17:25:53 INFO - --app=APP Application being installed. [default: firefox] 17:25:53 INFO - mkdir: /builds/slave/test/build/application 17:25:53 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'] 17:25:53 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 17:26:25 INFO - Reading from file tmpfile_stdout 17:26:25 INFO - Output received: 17:26:25 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:26:25 INFO - Running post-action listener: _resource_record_post_action 17:26:25 INFO - [mozharness: 2016-12-01 01:26:25.053774Z] Finished install step (success) 17:26:25 INFO - [mozharness: 2016-12-01 01:26:25.053953Z] Running run-tests step. 17:26:25 INFO - Running pre-action listener: _resource_record_pre_action 17:26:25 INFO - Running main action method: run_tests 17:26:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:26:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:26:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:26:25 INFO - 2016-11-30 17:26:25.068 screenresolution[2457:27670] starting screenresolution argv=screenresolution get 17:26:25 INFO - 2016-11-30 17:26:25.071 screenresolution[2457:27670] Display 0: 1600x1200x32@60 17:26:25 INFO - 2016-11-30 17:26:25.077 screenresolution[2458:27672] starting screenresolution argv=screenresolution list 17:26:25 INFO - Available Modes on Display 0 17:26:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:26:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:26:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:26:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:26:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:26:25 INFO - Intel Iris: 17:26:25 INFO - Chipset Model: Intel Iris 17:26:25 INFO - Type: GPU 17:26:25 INFO - Bus: Built-In 17:26:25 INFO - VRAM (Dynamic, Max): 1536 MB 17:26:25 INFO - Vendor: Intel (0x8086) 17:26:25 INFO - Device ID: 0x0a2e 17:26:25 INFO - Revision ID: 0x0009 17:26:25 INFO - Displays: 17:26:25 INFO - Display: 17:26:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:26:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:26:25 INFO - Display Serial Number: 1600x1200 60 17:26:25 INFO - Main Display: Yes 17:26:25 INFO - Mirror: Off 17:26:25 INFO - Online: Yes 17:26:25 INFO - Rotation: Supported 17:26:25 INFO - Return code: 0 17:26:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:26:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:26:25 INFO - Python 2.7.3 17:26:25 INFO - Return code: 0 17:26:25 INFO - grabbing minidump binary from tooltool 17:26:25 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')]} 17:26:25 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 0x100827d30>, '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 0x10068ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10081fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:26:25 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 17:26:25 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 17:26:25 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 17:26:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:26:25 INFO - Return code: 0 17:26:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:26:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:26:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:26:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:26:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:26:25 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/Xb7NEB8-SR6ucPQnPkRDSA/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 17:26:25 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/Xb7NEB8-SR6ucPQnPkRDSA/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 17:26:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zraoBHbN5X/Render', 17:26:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:25 INFO - 'HOME': '/Users/cltbld', 17:26:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:25 INFO - 'LOGNAME': 'cltbld', 17:26:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:26:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:25 INFO - 'MOZ_NO_REMOTE': '1', 17:26:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:26:25 INFO - 'NO_EM_RESTART': '1', 17:26:25 INFO - 'PAGER': '/bin/cat', 17:26:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:25 INFO - 'PWD': '/builds/slave/test', 17:26:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:26:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:25 INFO - 'SHELL': '/bin/bash', 17:26:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpDvbNbsuu/Listeners', 17:26:25 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:26:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:25 INFO - 'USER': 'cltbld', 17:26:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:25 INFO - 'XPC_FLAGS': '0x0', 17:26:25 INFO - 'XPC_SERVICE_NAME': '0', 17:26:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:25 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/Xb7NEB8-SR6ucPQnPkRDSA/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 17:26:25 INFO - mozversion application_buildid: 20161130141744 17:26:25 INFO - mozversion application_changeset: 3fa3896980f6726c3897ce1645a839996d5d5e8b 17:26:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:26:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:26:25 INFO - mozversion application_name: Firefox 17:26:25 INFO - mozversion application_remotingname: firefox-dev 17:26:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:26:25 INFO - mozversion application_vendor: Mozilla 17:26:25 INFO - mozversion application_version: 52.0a2 17:26:25 INFO - mozversion platform_buildid: 20161130141744 17:26:25 INFO - mozversion platform_changeset: 3fa3896980f6726c3897ce1645a839996d5d5e8b 17:26:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:26:25 INFO - mozversion platform_version: 52.0a2 17:26:25 INFO - using testdate: 1480555585 17:26:25 INFO - actual date: 1480555585 17:26:25 INFO - starting webserver on 'localhost:62267' 17:26:25 INFO - SUITE-START | Running 2 tests 17:26:25 INFO - TEST-START | dromaeo_css 17:26:25 INFO - operating with platform_type : mac_ 17:26:25 INFO - Initialising browser for dromaeo_css test... 17:26:25 INFO - TEST-INFO | started process 2468 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpZwZxHj/profile http://localhost:62267/getInfo.html) 17:26:28 INFO - PROCESS | 2468 | __metrics Screen width/height:1600/1200 17:26:28 INFO - PROCESS | 2468 | colorDepth:24 17:26:28 INFO - PROCESS | 2468 | Browser inner width/height: 1024/689 17:26:28 INFO - PROCESS | 2468 | __metrics 17:26:28 INFO - PROCESS | 2468 | 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" 17:26:28 INFO - PROCESS | 2468 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:26:29 INFO - TEST-INFO | 2468: exit 0 17:26:29 INFO - Browser initialized. 17:26:29 INFO - Running cycle 1/1 for dromaeo_css test... 17:26:29 INFO - TEST-INFO | started process 2470 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpZwZxHj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:26:41 INFO - PROCESS | 2470 | RSS: Main: 222535680 17:26:41 INFO - PROCESS | 2470 | 17:27:33 INFO - PROCESS | 2470 | 1480555653156 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] 17:29:46 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:62267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62267/tests/dromaeo/cssquery-ext.html) 17:29:48 INFO - PROCESS | 2470 | RSS: Main: 370032640 17:29:48 INFO - PROCESS | 2470 | 17:32:51 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:62267/tests/dromaeo/cssquery-ext.html (next: http://localhost:62267/tests/dromaeo/cssquery-jquery.html) 17:32:51 INFO - PROCESS | 2470 | RSS: Main: 348880896 17:32:51 INFO - PROCESS | 2470 | 17:35:54 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:62267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62267/tests/dromaeo/cssquery-mootools.html) 17:35:54 INFO - PROCESS | 2470 | RSS: Main: 326238208 17:35:54 INFO - PROCESS | 2470 | 17:38:57 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:62267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62267/tests/dromaeo/cssquery-prototype.html) 17:38:57 INFO - PROCESS | 2470 | RSS: Main: 315035648 17:38:57 INFO - PROCESS | 2470 | 17:42:01 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:62267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62267/tests/dromaeo/cssquery-yui.html) 17:42:01 INFO - PROCESS | 2470 | RSS: Main: 306966528 17:42:01 INFO - PROCESS | 2470 | 17:45:05 INFO - PROCESS | 2470 | Cycle 1(1): loaded http://localhost:62267/tests/dromaeo/cssquery-yui.html (next: http://localhost:62267/tests/dromaeo/cssquery-dojo.html) 17:45:05 INFO - PROCESS | 2470 | RSS: Main: 439713792 17:45:05 INFO - PROCESS | 2470 | 17:45:05 INFO - PROCESS | 2470 | __start_tp_report 17:45:05 INFO - PROCESS | 2470 | _x_x_mozilla_page_load 17:45:05 INFO - PROCESS | 2470 | _x_x_mozilla_page_load_details 17:45:05 INFO - PROCESS | 2470 | |i|pagename|runs| 17:45:05 INFO - PROCESS | 2470 | |0;dojo.html;3237.077432931615;3242.351529694061;3246.3182731626366;3265.5918010248733;3314.171457135716;5573.637713548616;5623.971880140603;5635.3237611486575;5679.5456363490875;5680.886382272352;5572.4706152915505;5660.830175094743;5665;5692.800751973671;5716.1711551824965;5087.49125087492;5134.871628209287;5146.871328216787;5182.740862956837;5215.139501982168;5129.871753206181;5142.665726727751;5159.200323949775;5258.579313654898;5323.148296272597;5177.922331165017;5185.818496352628;5208.218767184933;5208.270842082112;5215.973920130394;5215.113430716788;5244.842654720364;5274.55166310864;5288.81489147878;5307;5074.594032477393;5108.693478391308;5148.330717006766;5211.947880521222;5226.764795584208;5572.3034620672415;5589.357223919261;5618.91571626426;5625.268715067056;5647.09646456569;6075.119107729354;6109.877802443926;6154.7230374633245;6222.284437289724;6225.750969961241;6248.875022499525;6256.374362563753;6295.811125666238;6299;6300.117983482316;5624.859378515488;5683.886322273532;5693.743781529841;5730.226419433407;5791.449812267828;6250.312465628777;6308.526860485482;6313.05304204373;6313.936860631335;6315.873682526416;6115.082737589397;6133.754649813957;6135.846603834851;6137.96931015351;6139.815805525752;6090.964536028886;6103.816885493442;6104.314742242901;6188.8762224755255;6258.342873998165;6079.787207447718;6119.8681629442035;6133.3866613338305;6184.6598437085495;6202.627842329474;6176.567640265138;6214.9689251554355;6215.471684906744;6228.626282423069;6235.438810506986;4812;4866.391701037371;4914.778834952364;4916.434610019822;4935.901281974412;4350.456192975878;4374.19077470669;4415.955840441618;4435.534268901783;4449.17690227309;5756.597038207286;5766.250387449605;5773.624714393551;5778.191053252548;5789.9867523183275;6245.906311405332;6267.749290028348;6268.655223962726;6269.686515674198;6281.434670879643;5585.944140558542;5649.604527683105;5652.547796176296;5653.547716182696;5685.971570142206;5345.812896548602;5374.355077390738;5386.91919621206;5402.729863506809;5425.186222066722;5325.760340784752;6266.905996409966;6277.748890044347;6283.120363149072;6300.810975670737;4887.36464259644;6035.36628653985;6272.529560283088;6293.685315734104;6294.276158241861;6203.4416902479;6207.34822843613;6240.687965601793;6272.843178920563;6293.559450838397;6154.292256390572;6188.659623720739;6190.350013248544;6194.5044396449075;6220.968895155676;6236.968815156077;6277.748890044347;6303.684815759102;6307.30619631831;6312.715927783169;5938.406159384027;5980.611260268069;5981.641101533922;6035.215421995113;6040.305364882919;6062.333143354315;6077.939220607915;6078.240219972504;6084.148219249397;6119.663418512025;5741.827745167651;5881.176635271151;5903.11453281994;5904.852378690567;5914.763409463573;5868.178455016216;5980.312264089685;6002.669853158118;6034.275886893601;6067.120267561268;5923.822285331447;5941.405859414197;5949.6430214187285;5953.880922381441;5977.22296101504 17:45:05 INFO - PROCESS | 2470 | |1;ext.html;430.30076126294773;504.29398841621725;506.61750378464257;531.4658767938222;549.0830313376661;28452.28869278269;30810.53784193239;30859.22851928706;30880.53679194814;38918;39176;41594;41602.37596436056;41736.747897563124;41756.95607609804;9125.315601329894;9357.766055848624;9368.765780855465;9387.76530586734;9404.81190376192;8031.156728543507;8662.696805611804;8967.192952634261;9422.199113075398;9483.430994140374;8811.823763524726;8836.425632333878;9379.531023448835;9433.292503062263;9452.196563292095;9363.96996330403;9435.858462123073;9436.85844712323;9451.52742362886;9453.243740500811;9161.679341223093;9247.815043699156;9262.583183756748;9276.350655454122;9287.257019438497;38221.808890955646;38484.11369147325;38505.80747096246;38606.613933860586;38615;3124.453220686371;3436.432988556885;3441.466572681213;3590.3896337622405;3638.5815631202486;25114.623280650805;27524.58713119305;29138.854305728335;30323.69676303231;30449.391012179854;2670.6127611496418;2779.277387879166;3134.498480243173;3197.3605278944074;3209.1035579407767;489918;489925.55037224584;490460;490961;503379;33304.50043249353;59010;59612.70193649004;59682.40317596769;59743.70128149331;33689.32621347559;34683.4797478038;35064;35087.29825403477;35181;12696.492140314469;14286.499972500913;14316.928415358065;14320.57038288853;14346.49787257441;11993.400329983466;12105.576304829288;12109.515619375303;12131.878681213251;12151.088668349908;7761.068671759312;7769.4561380703935;7775.922240777633;7835.412344074103;7863.646135923897;2970.3910698307227;3068.969310306913;3190.1067701043744;3231.2406584452633;3263.6083669959758;919.2324409118461;961.5288508630721;971.3977334052933;990.6334656177116;1001.2140469731372;28229.71770282312;28571.57142642862;29304.706952930625;29456.705432945826;30024.399512010074;1044.9216308776872;1083.193021199196;1097.2757979733337;1110.9945499323126;1124.600766727816;14765.852341476662;14924;15186.316615752317;15555.922220389051;16020.519384418487;670381;674854.251457489;675179;675409;676170;54676;57767.13349299764;58274.377653005715;58386.708066459396;58578.414215857294;1219.0856857356982;1260.294235228275;1329.4017692038428;1381.095382524448;1385.0166381868785;29255.268618284714;29311.413771724874;29791.404171916005;29809;30518.84740576283;49647.751761240965;50325.74837125791;50507;50508.747456262485;50533.49466505435;573864;579772;579922;582210;583274;23265.883670581537;23825.642615360437;25656.615150773127;26432.603510947734;26450.867745661148;43553.78223108864;45139.322910155715;47641.04717905553;47882.04235915193;49237;38010.809945950095;38076.048098796644;38088.238235235694;38486.615133849424;40040.79877603677;37953.29210185604;37967.860964171115;38101.61898380981;39238.80380598079;39515.604843951194;36967.8151609251;37238;37586;38086.23827523489;38167.618323817514;32442.675573243967;33691.663083368854;34367.65632343745;34396.31207375789;34525.65474345224;24592;25116.748832511443;25126.874365628053;25337.746622534276;25588.232353029438 17:45:05 INFO - PROCESS | 2470 | |2;jquery.html;350.1281808297337;364.0915914792593;367.6837919389325;379.99050023749413;394.5521832719864;14735.70528589429;15126.924365378172;15628.296726647286;19590.80409195911;20059;18172.09139543024;19503.12235949382;20122.295719649814;20216.88807115608;20340.288089916856;5280.894382112356;5841.970790146043;6025.608335458203;6078.574499785017;6103.26760788706;4836.733979631119;5962.403759624046;6032.758689652409;6037;6049.334573196945;4677.602403795681;5976.432238937294;5978.103284507337;5982.491488223484;5997.820065398045;5874.765009399619;5973.432523910265;5992.43071908168;5995.52035837132;5998.130271110708;5018.64869459138;5170.784865556591;5176.171812509981;5240.318758561362;5243.632945693765;24282.635760463607;24946.501069978498;24964.50070998588;25265.747342526523;25499.617505737428;2263.9660405093937;2277.4420267034516;2277.681124642535;2283.680284760135;2982;14385.496507622183;14968.326425310779;15165.620859478602;15179.241037948059;15935.282912268976;1769.2480695704273;1847.5196448923243;1859.4886406238393;1867.1971052447577;1898.2122419196016;99635;101228.88939995362;110740;123127.38436307851;135269.999999999;11444.771104577861;11617.302961822317;11754.471048802825;11845.703857403634;11880;14628.926855365655;14696.853031469549;14788.482403115839;14844.554663360117;14850.331735071948;8268.545230012407;8532.70135545253;8547.188017138362;8625.69810056645;8632.697855575025;6817.795466136024;8336.458130221516;8347.789570512363;8515.48907065578;8557.144285571392;4452.844150454718;4512.075024619945;4515.458145022619;4553.2942393929;4576.382188404589;1862.4598866328613;1904.9238030478903;1906.3708976037874;1907.7233801099042;1908.236705317884;837.233930953171;888.2893685051932;941.1623654947102;959.2757468111531;998.6854140945579;20097.899510502353;21168.78831211668;21215.15139394407;21288.574228515343;21349.89325053365;896.6234181643725;909.9636014559476;915.4690279637794;941.6798288581915;942.2132519346286;8925.955370223108;10558.52486638103;10855.402952837512;11130.220884538167;11147;627063;632607;633852;635752;638069;81977.59011204906;107948.47094877547;118591.73378529026;133775.33112334376;140060.8990865138;851.0638297872291;869.6086760957594;926.231228080678;1017.8880323164447;1018.2719355660738;18001.369952051617;18407.90796046011;18623;19061.714074289175;19098;49579;51631;52848;52850.73574632102;53235;63678;70095.13988388974;71918;73474.89787653297;78817.60591197008;7681.15507294186;7813.218678132142;8012.318952888837;8171.509709417454;8276.751697449086;12143.392830358624;12417.875821241672;12517.749645006867;12552.184108032949;12906.225626462443;10784.245102842726;10816.783664326827;10913.126949844152;11029.44852757343;11071.280366776135;10739.892601073889;10819.67540973772;10992.505337260003;11112.722181945515;11142.442877855912;11028.28316159447;11093.778124437627;11131.443427828739;11140.777184456427;11432.771344573228;7727.459077864495;7877.842443151219;7924.643391047532;8084.434089613671;8319.792005199919;8231.46495477792;9825.213982881496;10390.324628899098;11039.779204416027;11105.888941110808 17:45:05 INFO - PROCESS | 2470 | |3;mootools.html;1448.0804689022475;1476.4315738440698;1617.4096454793996;1762.3126980477623;1814.8548116150716;7981.960090199548;8414.873776893344;8420.157984201584;8440;8440.91559084409;7409.407247420207;7633.580153091578;7647.158812530617;7714.151443341228;8301.418900676956;2027.9493012674654;2054.5171884607107;2059.5057186275335;2261.694671219389;2350.17743789674;2361.917332893349;2373.774491423312;2375.4417711837705;2378.940526486834;2379.9762002380016;2307.2501437032947;2328.767123287666;2334.8482348647367;2347.859128452298;2350.0717216699395;2109.9050542725613;2215.6898034275214;2309.6073667476485;2332.6384410416244;2362.704661917269;2328.0687724910003;2328.5226528561775;2359.433735903388;2360.9965764549997;2361.1853910401055;8154.469959452616;8331.000279966382;8353.122922093215;8355.83288334236;8361.749147525585;4648.651351148677;4878.317035615017;5191.974040129775;5192.402873669538;5207.817726379596;3391.3895498810202;3499.632538583463;3538.02704256329;3565.893023209308;3585.731070169748;942.2273735536862;1059.586761163147;1070.983935240972;1099.5326986030905;1106.673531308266;10717.089047430978;10815.026647601675;10860.022597966143;10876.456177191109;11001.779964400666;4225.408442818008;4390.078083602415;4481.282994720894;4482.462104547475;4486.842960496367;8611.741647750578;8613.913860861436;8615.483071015758;8622.655093796178;8626.784330391665;5023.372078490189;5093.261477085876;5100.566451851634;5108.948910510847;5110.540051395318;4622.168009758276;4642.620731531317;4706.694064885772;4716.905661886812;4724.102420540086;3371.6628337166576;3470.7917524948584;3496.7027802636553;3497;3593.4070878304788;1084.951177197028;1102.443266150589;1103.1119948441421;1105.4086063955745;1117.7149826794277;708.7838209346096;829.4898637337993;879.6745204274459;920.0983036624145;925.3707478914268;5390.380106287827;5491.313585801775;5544.417836127149;5626.718664066781;5739.684317362502;879.0681877210178;945.2485274207022;952.1050212799946;952.5856252530172;965.3001573858896;3696.1129328960856;3807.6002019787525;3813.7139714521522;3859.4982652255026;3886.1256217351433;9784;10033.448160351105;10062.345947513388;10106.675464643173;10174.949125254474;9494.430334179971;9969.950150249348;10036.749081273027;10054.798904021878;10057.195424366033;7673.731419400406;7896.368290536743;7902.68389264423;7905.525668459911;7919.7624071276805;5534.501894829324;5568.248286481436;5616.66300022;5707.600467967202;5735.942640573541;9224.35429519954;9292.860607091034;9295.209907157967;9312.348135630711;9318.860217096884;9138.68014619485;9382.343235973689;9431.905680943102;9533.380330278509;9666.081722236377;5839;7084.220735718964;7100.7869763907165;7235.023271858442;7274.381677557467;7697.961510192413;7788.026496687914;7789.922100778919;7812.335951444191;7824.295813376823;5794;6850.965745171242;6978.755743548952;7117.82205444868;7121.252268511732;6705.8994115087335;6778.796636100925;6900.2409734928115;6967.303269672992;7030.578165310098;6313.3686631336495;6510.609363438209;6602.966985165043;6634.502412318999;6660.167479065117;4836.540528649773;4855.393075865517;4894.2658601210105;4905.0189962007025;4917.311576379381;4733.881652958566;4783.330333753347;4868.14807408695;4948.1835497142565;4972.825951091694 17:45:05 INFO - PROCESS | 2470 | |4;prototype.html;275.87861341009955;297.5001996645637;305.45781238302004;308.74065784740816;309.953506973954;4627.560381763736;5016.322796422482;5254.238135470355;5315.495027972342;5385.3537575491;5033.049056490656;5094.108531007068;5198.324217851674;5236.895262094756;5365.946340536593;3640.526731524898;3972.0864201233735;4045.2111838191563;4078.143589846136;4097.8770636880945;3806.6764325032404;3867.690584753227;4060.2285565742413;4063.2076745034583;4097.938530922023;3739.5325584301963;3998.720089593715;4023.718339716221;4074.633283004529;4093.6111069448357;4000.0399904023116;4013.257547353749;4035.4552135461777;4051.7163798534116;4054.432379466877;3469.6877281044576;3474.7567670263093;3478.4434490481653;3517.7361697872507;3545.822708864572;4617.538246175389;4823.082035368621;4834.274858771178;5204.687718736888;5243.895122097537;4523.851170693927;4578.985246491533;4612.70191812852;4615.423072115986;4634.404733349302;4573.794179261941;4952.90094198114;5603.635763675348;5654.688992105433;5680.971595141998;3269.9346013079603;3642.3427340559983;3811.6760075393627;3817.4655548223272;3836.8105887175066;4883.438404583448;5220.582353411718;5766.88466230673;5777.248957635523;5877.353491115974;3795.4702127021583;3842.9615703843165;3850.3646898261472;3858.5562660294427;3872.496575445174;3324.833758312123;3643.2713457308646;3778.8852288574935;3942.211557688474;3968.940465893014;4332.610065094157;4501.324801279834;4563.498015218323;4635.258358662665;4651.325557794101;5201.505856943584;5619.971900140473;5627.6904770237215;5629.352624448159;5648.265725455664;1142.0977427832086;1200.6218041316956;1238.5169783784331;1255.7049093463029;1289.4133169407896;687.7833482453111;805.3637626275176;812.3298278919915;813.8128230507017;847.4110493207285;4331.97834010828;4747.667663263538;4815.544072523038;5018.874528136826;5086.720230387365;4674.686030414899;4687.976560117246;5100.005498927673;5539.335279766454;5543.916841247385;4000.2799496090984;4176.2691528982305;4668.949486365541;4758;5009.524095210949;2284.97715022851;2406.9157579484986;2422.9636555451684;2776.1393967870026;2823.774098072149;5464.153056276204;5471.753771080311;5495.95576840399;5555.583321255267;5583.720813959286;4701.367234348776;4712.58337705161;4802.783874725646;5664.376918538957;5931.322580000341;4818.855434336975;4988.879726116386;5860.952304801479;5948.762049517971;5966.284045914518;4726.589439385869;5489.92946375453;5585.765693722381;5800.622959507618;5817.447342502455;5671.943280567141;5689.6586204827845;5700.857478563069;5740.339860915881;5771.97114014444;5063.620228482805;5915.692879776804;5952.553558483044;5956.493698035715;5980.073088671263;3651.8904432867057;3839.155385815116;3877.3796192609047;3893.3186692329296;3898.96101038986;4983.729149066965;5026.623003274842;5528.889422211453;5718.25662663851;5736.741846616829;5520.53075488588;5557.582816381797;5561.555075593862;5586.821139755418;5621.285507920124;4752.714837109785;5342.4064705794135;5495.53287970527;5552.916706249329;5577.525910297508;4932.383452068491;5074.340335756328;5207.635465517377;5422.240886275846;5452.437354443406;5456.672599643875;5457.372402173642;5478.287822583039;5502.2296878437855;5629.523009875642;5182.429932707475;5208.401033881152;5270.392530278149;5490.780368785204;5526.696031718294 17:45:05 INFO - PROCESS | 2470 | |5;yui.html;713.4115399540312;723.1936390924118;734.4748504819057;749.4229443328637;757.3675980556242;13509.729805403898;15617.765733513992;16263.756043659354;16270;16285.51143465695;14799.33402996865;14871.405143794262;15008.399664013454;15013.174275414847;15067;636.0618088319737;648.4714957309777;807.4670717326566;808.5835794565774;812.0499016151088;651.9739210431577;660.1714847865921;779.4504874063799;796.398718967179;811.6307080278459;845.9890430935035;847.3348421489133;849.027863096757;849.8385306791721;849.8852654891604;727.2327694282529;846.1242613894651;846.2172490446312;846.9491830490159;847.4745657692271;499.21872269897494;505.24718169926547;614.0972770028052;630;634.5177664974607;13802.03385762997;13978.650533736636;14038.719225615532;14100.435982560686;14104.942129340237;12597.992160627127;12827.807582886264;12924;12937.935310323483;13156.342182890912;11902.702432439259;12008.459619317151;12043.518259269618;12044;12156.392180391034;3599.3521166190076;3644.7630903991158;3795.905102372435;4296.548862369437;4357.368181613649;81823;82967;89142.55428722879;90583;91153.08846911578;10179.694609161737;10447.164226861833;10642.787144257227;10757.46212689378;11003.119750419946;19912.800871991094;20200.49498762543;20205.191792328143;20367.694484582742;20556.28053018167;7625.771226863203;7743.457957943003;7752.26353496417;7894.881576776353;7925.28672419485;4057.3102572562734;4247.68142389322;4303.1393721255245;4367.10474352757;4435.822567097344;3394.0835974286806;3474.513568100468;3477.287156133037;3481.9634294355483;3521.4013617685055;615.984600384994;616.2512547255017;622.3061286665336;623.5354660777659;624.3693869192149;527.9181726832347;594.4501336263938;600.4445887554083;669.9866002679919;670.3129292475154;6713.966430167818;8725.91274087251;8842.290344011144;8985.146411090936;9488.146066854017;626.3110578363763;634.6921742954663;637.8692368064628;647.7150053976235;664.1996394344803;3777.735558510878;4363.2800587903275;4388.714733542373;4392.187445322562;4429.269170586882;67277;85806;92337;99496;103401;71761.2823871765;88450.55774721086;92202;93453;94786.52606736922;69336.30663693299;75605.48789024189;76003.61998189974;78464;79824.20175798167;8987.101289870961;9168.358214925158;9397.107274808883;9421.246300296098;9469.289803264921;36381.63618363783;41456.75629730992;46125.76937115293;48512;51371.48628513667;64833.67583162054;66348.33651663421;70057.29942700508;70548.94176587458;72676.63661681658;10173.389596624227;11651.359175245443;12292.631221063382;12377.690557736129;12474.438650260941;12467;13048.626342058276;13648.41406821229;14565.854341456874;14970;11864.288142711464;12620.810687839506;13114.803277950648;13171.275579842817;13508.32458377097;12653.493860245855;13562.59312220635;13695.109817861807;13794.37925293344;14357.425702971765;12119.333436661069;12912.35438228066;13009.674758131123;13283.203007819562;13353;9223.815523689624;9662.227021838376;9800.264980126376;10359.378437293786;11031.558737650405;7876.763697089096;10647.094996925349;10660.520276587727;10677.039066484054;10731.570737170427 17:45:05 INFO - PROCESS | 2470 | __end_tp_report 17:45:05 INFO - PROCESS | 2470 | __start_cc_report 17:45:05 INFO - PROCESS | 2470 | _x_x_mozilla_cycle_collect,1847 17:45:05 INFO - PROCESS | 2470 | __end_cc_report 17:45:05 INFO - PROCESS | 2470 | __startTimestamp1480556705400__endTimestamp 17:45:05 INFO - PROCESS | 2470 | 17:45:05 INFO - PROCESS | 2470 | ------- Summary: start ------- 17:45:05 INFO - PROCESS | 2470 | Number of tests: 6 17:45:05 INFO - PROCESS | 2470 | 17:45:05 INFO - PROCESS | 2470 | [#0] dojo.html Cycles:165 Average:5704.65 Median:5914.76 stddev:647.81 (11.0%) stddev-sans-first:620.20 17:45:05 INFO - PROCESS | 2470 | Values: 3237.1 3242.4 3246.3 3265.6 3314.2 5573.6 5624.0 5635.3 5679.5 5680.9 5572.5 5660.8 5665.0 5692.8 5716.2 5087.5 5134.9 5146.9 5182.7 5215.1 5129.9 5142.7 5159.2 5258.6 5323.1 5177.9 5185.8 5208.2 5208.3 5216.0 5215.1 5244.8 5274.6 5288.8 5307.0 5074.6 5108.7 5148.3 5211.9 5226.8 5572.3 5589.4 5618.9 5625.3 5647.1 6075.1 6109.9 6154.7 6222.3 6225.8 6248.9 6256.4 6295.8 6299.0 6300.1 5624.9 5683.9 5693.7 5730.2 5791.4 6250.3 6308.5 6313.1 6313.9 6315.9 6115.1 6133.8 6135.8 6138.0 6139.8 6091.0 6103.8 6104.3 6188.9 6258.3 6079.8 6119.9 6133.4 6184.7 6202.6 6176.6 6215.0 6215.5 6228.6 6235.4 4812.0 4866.4 4914.8 4916.4 4935.9 4350.5 4374.2 4416.0 4435.5 4449.2 5756.6 5766.3 5773.6 5778.2 5790.0 6245.9 6267.7 6268.7 6269.7 6281.4 5585.9 5649.6 5652.5 5653.5 5686.0 5345.8 5374.4 5386.9 5402.7 5425.2 5325.8 6266.9 6277.7 6283.1 6300.8 4887.4 6035.4 6272.5 6293.7 6294.3 6203.4 6207.3 6240.7 6272.8 6293.6 6154.3 6188.7 6190.4 6194.5 6221.0 6237.0 6277.7 6303.7 6307.3 6312.7 5938.4 5980.6 5981.6 6035.2 6040.3 6062.3 6077.9 6078.2 6084.1 6119.7 5741.8 5881.2 5903.1 5904.9 5914.8 5868.2 5980.3 6002.7 6034.3 6067.1 5923.8 5941.4 5949.6 5953.9 5977.2 17:45:05 INFO - PROCESS | 2470 | 17:45:05 INFO - PROCESS | 2470 | [#1] ext.html Cycles:180 Average:69369.72 Median:38134.62 stddev:157470.21 (412.9%) stddev-sans-first:157826.88 17:45:05 INFO - PROCESS | 2470 | Values: 430.3 504.3 506.6 531.5 549.1 28452.3 30810.5 30859.2 30880.5 38918.0 39176.0 41594.0 41602.4 41736.7 41757.0 9125.3 9357.8 9368.8 9387.8 9404.8 8031.2 8662.7 8967.2 9422.2 9483.4 8811.8 8836.4 9379.5 9433.3 9452.2 9364.0 9435.9 9436.9 9451.5 9453.2 9161.7 9247.8 9262.6 9276.4 9287.3 38221.8 38484.1 38505.8 38606.6 38615.0 3124.5 3436.4 3441.5 3590.4 3638.6 25114.6 27524.6 29138.9 30323.7 30449.4 2670.6 2779.3 3134.5 3197.4 3209.1 489918.0 489925.6 490460.0 490961.0 503379.0 33304.5 59010.0 59612.7 59682.4 59743.7 33689.3 34683.5 35064.0 35087.3 35181.0 12696.5 14286.5 14316.9 14320.6 14346.5 11993.4 12105.6 12109.5 12131.9 12151.1 7761.1 7769.5 7775.9 7835.4 7863.6 2970.4 3069.0 3190.1 3231.2 3263.6 919.2 961.5 971.4 990.6 1001.2 28229.7 28571.6 29304.7 29456.7 30024.4 1044.9 1083.2 1097.3 1111.0 1124.6 14765.9 14924.0 15186.3 15555.9 16020.5 670381.0 674854.3 675179.0 675409.0 676170.0 54676.0 57767.1 58274.4 58386.7 58578.4 1219.1 1260.3 1329.4 1381.1 1385.0 29255.3 29311.4 29791.4 29809.0 30518.8 49647.8 50325.7 50507.0 50508.7 50533.5 573864.0 579772.0 579922.0 582210.0 583274.0 23265.9 23825.6 25656.6 26432.6 26450.9 43553.8 45139.3 47641.0 47882.0 49237.0 38010.8 38076.0 38088.2 38486.6 40040.8 37953.3 37967.9 38101.6 39238.8 39515.6 36967.8 37238.0 37586.0 38086.2 38167.6 32442.7 33691.7 34367.7 34396.3 34525.7 24592.0 25116.7 25126.9 25337.7 25588.2 17:45:05 INFO - PROCESS | 2470 | 17:45:05 INFO - PROCESS | 2470 | [#2] jquery.html Cycles:180 Average:35324.37 Median:14240.81 stddev:105078.66 (737.9%) stddev-sans-first:105340.62 17:45:05 INFO - PROCESS | 2470 | Values: 350.1 364.1 367.7 380.0 394.6 14735.7 15126.9 15628.3 19590.8 20059.0 18172.1 19503.1 20122.3 20216.9 20340.3 5280.9 5842.0 6025.6 6078.6 6103.3 4836.7 5962.4 6032.8 6037.0 6049.3 4677.6 5976.4 5978.1 5982.5 5997.8 5874.8 5973.4 5992.4 5995.5 5998.1 5018.6 5170.8 5176.2 5240.3 5243.6 24282.6 24946.5 24964.5 25265.7 25499.6 2264.0 2277.4 2277.7 2283.7 2982.0 14385.5 14968.3 15165.6 15179.2 15935.3 1769.2 1847.5 1859.5 1867.2 1898.2 99635.0 101228.9 110740.0 123127.4 135270.0 11444.8 11617.3 11754.5 11845.7 11880.0 14628.9 14696.9 14788.5 14844.6 14850.3 8268.5 8532.7 8547.2 8625.7 8632.7 6817.8 8336.5 8347.8 8515.5 8557.1 4452.8 4512.1 4515.5 4553.3 4576.4 1862.5 1904.9 1906.4 1907.7 1908.2 837.2 888.3 941.2 959.3 998.7 20097.9 21168.8 21215.2 21288.6 21349.9 896.6 910.0 915.5 941.7 942.2 8926.0 10558.5 10855.4 11130.2 11147.0 627063.0 632607.0 633852.0 635752.0 638069.0 81977.6 107948.5 118591.7 133775.3 140060.9 851.1 869.6 926.2 1017.9 1018.3 18001.4 18407.9 18623.0 19061.7 19098.0 49579.0 51631.0 52848.0 52850.7 53235.0 63678.0 70095.1 71918.0 73474.9 78817.6 7681.2 7813.2 8012.3 8171.5 8276.8 12143.4 12417.9 12517.7 12552.2 12906.2 10784.2 10816.8 10913.1 11029.4 11071.3 10739.9 10819.7 10992.5 11112.7 11142.4 11028.3 11093.8 11131.4 11140.8 11432.8 7727.5 7877.8 7924.6 8084.4 8319.8 8231.5 9825.2 10390.3 11039.8 11105.9 17:45:05 INFO - PROCESS | 2470 | 17:45:05 INFO - PROCESS | 2470 | [#3] mootools.html Cycles:180 Average:5368.95 Median:5104.76 stddev:2937.07 (57.5%) stddev-sans-first:2930.53 17:45:05 INFO - PROCESS | 2470 | Values: 1448.1 1476.4 1617.4 1762.3 1814.9 7982.0 8414.9 8420.2 8440.0 8440.9 7409.4 7633.6 7647.2 7714.2 8301.4 2027.9 2054.5 2059.5 2261.7 2350.2 2361.9 2373.8 2375.4 2378.9 2380.0 2307.3 2328.8 2334.8 2347.9 2350.1 2109.9 2215.7 2309.6 2332.6 2362.7 2328.1 2328.5 2359.4 2361.0 2361.2 8154.5 8331.0 8353.1 8355.8 8361.7 4648.7 4878.3 5192.0 5192.4 5207.8 3391.4 3499.6 3538.0 3565.9 3585.7 942.2 1059.6 1071.0 1099.5 1106.7 10717.1 10815.0 10860.0 10876.5 11001.8 4225.4 4390.1 4481.3 4482.5 4486.8 8611.7 8613.9 8615.5 8622.7 8626.8 5023.4 5093.3 5100.6 5108.9 5110.5 4622.2 4642.6 4706.7 4716.9 4724.1 3371.7 3470.8 3496.7 3497.0 3593.4 1085.0 1102.4 1103.1 1105.4 1117.7 708.8 829.5 879.7 920.1 925.4 5390.4 5491.3 5544.4 5626.7 5739.7 879.1 945.2 952.1 952.6 965.3 3696.1 3807.6 3813.7 3859.5 3886.1 9784.0 10033.4 10062.3 10106.7 10174.9 9494.4 9970.0 10036.7 10054.8 10057.2 7673.7 7896.4 7902.7 7905.5 7919.8 5534.5 5568.2 5616.7 5707.6 5735.9 9224.4 9292.9 9295.2 9312.3 9318.9 9138.7 9382.3 9431.9 9533.4 9666.1 5839.0 7084.2 7100.8 7235.0 7274.4 7698.0 7788.0 7789.9 7812.3 7824.3 5794.0 6851.0 6978.8 7117.8 7121.3 6705.9 6778.8 6900.2 6967.3 7030.6 6313.4 6510.6 6603.0 6634.5 6660.2 4836.5 4855.4 4894.3 4905.0 4917.3 4733.9 4783.3 4868.1 4948.2 4972.8 17:45:05 INFO - PROCESS | 2470 | 17:45:05 INFO - PROCESS | 2470 | [#4] prototype.html Cycles:180 Average:4447.15 Median:4907.91 stddev:1377.77 (28.1%) stddev-sans-first:1345.59 17:45:05 INFO - PROCESS | 2470 | Values: 275.9 297.5 305.5 308.7 310.0 4627.6 5016.3 5254.2 5315.5 5385.4 5033.0 5094.1 5198.3 5236.9 5365.9 3640.5 3972.1 4045.2 4078.1 4097.9 3806.7 3867.7 4060.2 4063.2 4097.9 3739.5 3998.7 4023.7 4074.6 4093.6 4000.0 4013.3 4035.5 4051.7 4054.4 3469.7 3474.8 3478.4 3517.7 3545.8 4617.5 4823.1 4834.3 5204.7 5243.9 4523.9 4579.0 4612.7 4615.4 4634.4 4573.8 4952.9 5603.6 5654.7 5681.0 3269.9 3642.3 3811.7 3817.5 3836.8 4883.4 5220.6 5766.9 5777.2 5877.4 3795.5 3843.0 3850.4 3858.6 3872.5 3324.8 3643.3 3778.9 3942.2 3968.9 4332.6 4501.3 4563.5 4635.3 4651.3 5201.5 5620.0 5627.7 5629.4 5648.3 1142.1 1200.6 1238.5 1255.7 1289.4 687.8 805.4 812.3 813.8 847.4 4332.0 4747.7 4815.5 5018.9 5086.7 4674.7 4688.0 5100.0 5539.3 5543.9 4000.3 4176.3 4668.9 4758.0 5009.5 2285.0 2406.9 2423.0 2776.1 2823.8 5464.2 5471.8 5496.0 5555.6 5583.7 4701.4 4712.6 4802.8 5664.4 5931.3 4818.9 4988.9 5861.0 5948.8 5966.3 4726.6 5489.9 5585.8 5800.6 5817.4 5671.9 5689.7 5700.9 5740.3 5772.0 5063.6 5915.7 5952.6 5956.5 5980.1 3651.9 3839.2 3877.4 3893.3 3899.0 4983.7 5026.6 5528.9 5718.3 5736.7 5520.5 5557.6 5561.6 5586.8 5621.3 4752.7 5342.4 5495.5 5552.9 5577.5 4932.4 5074.3 5207.6 5422.2 5452.4 5456.7 5457.4 5478.3 5502.2 5629.5 5182.4 5208.4 5270.4 5490.8 5526.7 17:45:05 INFO - PROCESS | 2470 | 17:45:05 INFO - PROCESS | 2470 | [#5] yui.html Cycles:180 Average:19104.44 Median:24505.61 stddev:26897.72 (109.8%) stddev-sans-first:26937.73 17:45:05 INFO - PROCESS | 2470 | Values: 713.4 723.2 734.5 749.4 757.4 13509.7 15617.8 16263.8 16270.0 16285.5 14799.3 14871.4 15008.4 15013.2 15067.0 636.1 648.5 807.5 808.6 812.0 652.0 660.2 779.5 796.4 811.6 846.0 847.3 849.0 849.8 849.9 727.2 846.1 846.2 846.9 847.5 499.2 505.2 614.1 630.0 634.5 13802.0 13978.7 14038.7 14100.4 14104.9 12598.0 12827.8 12924.0 12937.9 13156.3 11902.7 12008.5 12043.5 12044.0 12156.4 3599.4 3644.8 3795.9 4296.5 4357.4 81823.0 82967.0 89142.6 90583.0 91153.1 10179.7 10447.2 10642.8 10757.5 11003.1 19912.8 20200.5 20205.2 20367.7 20556.3 7625.8 7743.5 7752.3 7894.9 7925.3 4057.3 4247.7 4303.1 4367.1 4435.8 3394.1 3474.5 3477.3 3482.0 3521.4 616.0 616.3 622.3 623.5 624.4 527.9 594.5 600.4 670.0 670.3 6714.0 8725.9 8842.3 8985.1 9488.1 626.3 634.7 637.9 647.7 664.2 3777.7 4363.3 4388.7 4392.2 4429.3 67277.0 85806.0 92337.0 99496.0 103401.0 71761.3 88450.6 92202.0 93453.0 94786.5 69336.3 75605.5 76003.6 78464.0 79824.2 8987.1 9168.4 9397.1 9421.2 9469.3 36381.6 41456.8 46125.8 48512.0 51371.5 64833.7 66348.3 70057.3 70548.9 72676.6 10173.4 11651.4 12292.6 12377.7 12474.4 12467.0 13048.6 13648.4 14565.9 14970.0 11864.3 12620.8 13114.8 13171.3 13508.3 12653.5 13562.6 13695.1 13794.4 14357.4 12119.3 12912.4 13009.7 13283.2 13353.0 9223.8 9662.2 9800.3 10359.4 11031.6 7876.8 10647.1 10660.5 10677.0 10731.6 17:45:05 INFO - PROCESS | 2470 | -------- Summary: end -------- 17:45:05 INFO - PROCESS | 2470 | 17:45:06 INFO - TEST-INFO | 2470: exit 0 17:45:06 INFO - TEST-OK | dromaeo_css | took 1120375ms 17:45:06 INFO - TEST-START | kraken 17:45:06 INFO - operating with platform_type : mac_ 17:45:06 INFO - Initialising browser for kraken test... 17:45:06 INFO - TEST-INFO | started process 2473 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpQOjL3d/profile http://localhost:62267/getInfo.html) 17:45:08 INFO - PROCESS | 2473 | __metrics Screen width/height:1600/1200 17:45:08 INFO - PROCESS | 2473 | colorDepth:24 17:45:08 INFO - PROCESS | 2473 | Browser inner width/height: 1024/689 17:45:08 INFO - PROCESS | 2473 | __metrics 17:45:08 INFO - PROCESS | 2473 | 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" 17:45:08 INFO - PROCESS | 2473 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:45:09 INFO - TEST-INFO | 2473: exit 0 17:45:09 INFO - Browser initialized. 17:45:09 INFO - Running cycle 1/1 for kraken test... 17:45:09 INFO - TEST-INFO | started process 2474 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpQOjL3d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:45:21 INFO - PROCESS | 2474 | RSS: Main: 222937088 17:45:21 INFO - PROCESS | 2474 | 17:45:24 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:62267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62267/tests/kraken/driver.html?testName=audio-beat-detection) 17:45:25 INFO - PROCESS | 2474 | RSS: Main: 272928768 17:45:25 INFO - PROCESS | 2474 | 17:45:28 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:62267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62267/tests/kraken/driver.html?testName=audio-dft) 17:45:28 INFO - PROCESS | 2474 | RSS: Main: 283746304 17:45:28 INFO - PROCESS | 2474 | 17:45:31 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:62267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62267/tests/kraken/driver.html?testName=audio-fft) 17:45:32 INFO - PROCESS | 2474 | RSS: Main: 382984192 17:45:32 INFO - PROCESS | 2474 | 17:45:34 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:62267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62267/tests/kraken/driver.html?testName=audio-oscillator) 17:45:35 INFO - PROCESS | 2474 | RSS: Main: 283930624 17:45:35 INFO - PROCESS | 2474 | 17:45:37 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:62267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:45:38 INFO - PROCESS | 2474 | RSS: Main: 285241344 17:45:38 INFO - PROCESS | 2474 | 17:45:40 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:62267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62267/tests/kraken/driver.html?testName=imaging-darkroom) 17:45:41 INFO - PROCESS | 2474 | RSS: Main: 324415488 17:45:41 INFO - PROCESS | 2474 | 17:45:44 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:62267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62267/tests/kraken/driver.html?testName=imaging-desaturate) 17:45:44 INFO - PROCESS | 2474 | RSS: Main: 327057408 17:45:44 INFO - PROCESS | 2474 | 17:45:47 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:62267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62267/tests/kraken/driver.html?testName=json-parse-financial) 17:45:48 INFO - PROCESS | 2474 | RSS: Main: 323620864 17:45:48 INFO - PROCESS | 2474 | 17:45:49 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:62267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:45:50 INFO - PROCESS | 2474 | RSS: Main: 300638208 17:45:50 INFO - PROCESS | 2474 | 17:45:52 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:62267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62267/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:45:53 INFO - PROCESS | 2474 | RSS: Main: 302460928 17:45:53 INFO - PROCESS | 2474 | 17:45:55 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:62267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:45:55 INFO - PROCESS | 2474 | RSS: Main: 304812032 17:45:55 INFO - PROCESS | 2474 | 17:45:58 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:62267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:45:59 INFO - PROCESS | 2474 | RSS: Main: 313802752 17:45:59 INFO - PROCESS | 2474 | 17:46:02 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:62267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:46:03 INFO - PROCESS | 2474 | RSS: Main: 314105856 17:46:03 INFO - PROCESS | 2474 | 17:46:04 INFO - PROCESS | 2474 | Cycle 1(1): loaded http://localhost:62267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62267/tests/kraken/driver.html?testName=ai-astar) 17:46:05 INFO - PROCESS | 2474 | RSS: Main: 358092800 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | __start_tp_report 17:46:05 INFO - PROCESS | 2474 | _x_x_mozilla_page_load 17:46:05 INFO - PROCESS | 2474 | _x_x_mozilla_page_load_details 17:46:05 INFO - PROCESS | 2474 | |i|pagename|runs| 17:46:05 INFO - PROCESS | 2474 | |0;ai-astar;115;111;106;115;116;115;116;116;114;116 17:46:05 INFO - PROCESS | 2474 | |1;audio-beat-detection;152;114;110;134;119;144;106;131;176;139 17:46:05 INFO - PROCESS | 2474 | |2;audio-dft;184;183;184;186;185;184;184;186;186;186 17:46:05 INFO - PROCESS | 2474 | |3;audio-fft;81;59;60;90;61;60;58;58;82;64 17:46:05 INFO - PROCESS | 2474 | |4;audio-oscillator;90;94;126;100;67;68;91;66;84;124 17:46:05 INFO - PROCESS | 2474 | |5;imaging-gaussian-blur;89;86;89;87;89;86;90;88;90;85 17:46:05 INFO - PROCESS | 2474 | |6;imaging-darkroom;95;95;96;96;95;95;96;96;95;94 17:46:05 INFO - PROCESS | 2474 | |7;imaging-desaturate;80;81;79;79;81;80;84;84;81;84 17:46:05 INFO - PROCESS | 2474 | |8;json-parse-financial;56;56;56;56;57;60;59;57;57;57 17:46:05 INFO - PROCESS | 2474 | |9;json-stringify-tinderbox;66;76;82;76;73;72;71;72;70;71 17:46:05 INFO - PROCESS | 2474 | |10;stanford-crypto-aes;86;85;85;85;83;84;85;84;84;85 17:46:05 INFO - PROCESS | 2474 | |11;stanford-crypto-ccm;150;155;147;148;146;169;149;153;213;164 17:46:05 INFO - PROCESS | 2474 | |12;stanford-crypto-pbkdf2;169;169;170;170;174;168;171;168;168;168 17:46:05 INFO - PROCESS | 2474 | |13;stanford-crypto-sha256-iterative;70;69;70;70;68;69;69;68;69;69 17:46:05 INFO - PROCESS | 2474 | __end_tp_report 17:46:05 INFO - PROCESS | 2474 | __start_cc_report 17:46:05 INFO - PROCESS | 2474 | _x_x_mozilla_cycle_collect,2069 17:46:05 INFO - PROCESS | 2474 | __end_cc_report 17:46:05 INFO - PROCESS | 2474 | __startTimestamp1480556765038__endTimestamp 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | ------- Summary: start ------- 17:46:05 INFO - PROCESS | 2474 | Number of tests: 14 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | [#0] ai-astar Cycles:10 Average:114.00 Median:115.50 stddev:3.20 (2.8%) stddev-sans-first:3.37 17:46:05 INFO - PROCESS | 2474 | Values: 115.0 111.0 106.0 115.0 116.0 115.0 116.0 116.0 114.0 116.0 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:136.50 stddev:21.56 (15.8%) stddev-sans-first:21.69 17:46:05 INFO - PROCESS | 2474 | Values: 152.0 114.0 110.0 134.0 119.0 144.0 106.0 131.0 176.0 139.0 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.50 stddev:1.14 (0.6%) stddev-sans-first:1.17 17:46:05 INFO - PROCESS | 2474 | Values: 184.0 183.0 184.0 186.0 185.0 184.0 184.0 186.0 186.0 186.0 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | [#3] audio-fft Cycles:10 Average:67.30 Median:62.50 stddev:12.10 (19.4%) stddev-sans-first:11.78 17:46:05 INFO - PROCESS | 2474 | Values: 81.0 59.0 60.0 90.0 61.0 60.0 58.0 58.0 82.0 64.0 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:76.00 stddev:21.56 (28.4%) stddev-sans-first:22.87 17:46:05 INFO - PROCESS | 2474 | Values: 90.0 94.0 126.0 100.0 67.0 68.0 91.0 66.0 84.0 124.0 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.00 stddev:1.79 (2.0%) stddev-sans-first:1.86 17:46:05 INFO - PROCESS | 2474 | Values: 89.0 86.0 89.0 87.0 89.0 86.0 90.0 88.0 90.0 85.0 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | [#6] imaging-darkroom Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 17:46:05 INFO - PROCESS | 2474 | Values: 95.0 95.0 96.0 96.0 95.0 95.0 96.0 96.0 95.0 94.0 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | [#7] imaging-desaturate Cycles:10 Average:81.30 Median:81.00 stddev:2.00 (2.5%) stddev-sans-first:2.07 17:46:05 INFO - PROCESS | 2474 | Values: 80.0 81.0 79.0 79.0 81.0 80.0 84.0 84.0 81.0 84.0 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.00 stddev:1.37 (2.4%) stddev-sans-first:1.39 17:46:05 INFO - PROCESS | 2474 | Values: 56.0 56.0 56.0 56.0 57.0 60.0 59.0 57.0 57.0 57.0 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:72.50 stddev:4.31 (5.9%) stddev-sans-first:3.77 17:46:05 INFO - PROCESS | 2474 | Values: 66.0 76.0 82.0 76.0 73.0 72.0 71.0 72.0 70.0 71.0 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:85.00 stddev:0.84 (1.0%) stddev-sans-first:0.73 17:46:05 INFO - PROCESS | 2474 | Values: 86.0 85.0 85.0 85.0 83.0 84.0 85.0 84.0 84.0 85.0 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | [#11] stanford-crypto-ccm Cycles:10 Average:159.40 Median:154.00 stddev:20.29 (13.2%) stddev-sans-first:21.24 17:46:05 INFO - PROCESS | 2474 | Values: 150.0 155.0 147.0 148.0 146.0 169.0 149.0 153.0 213.0 164.0 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.50 Median:169.50 stddev:1.90 (1.1%) stddev-sans-first:2.01 17:46:05 INFO - PROCESS | 2474 | Values: 169.0 169.0 170.0 170.0 174.0 168.0 171.0 168.0 168.0 168.0 17:46:05 INFO - PROCESS | 2474 | 17:46:05 INFO - PROCESS | 2474 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 17:46:05 INFO - PROCESS | 2474 | Values: 70.0 69.0 70.0 70.0 68.0 69.0 69.0 68.0 69.0 69.0 17:46:05 INFO - PROCESS | 2474 | -------- Summary: end -------- 17:46:05 INFO - PROCESS | 2474 | 17:46:06 INFO - TEST-INFO | 2474: exit 0 17:46:06 INFO - TEST-OK | kraken | took 60065ms 17:46:06 INFO - SUITE-END | took 1180s 17:46:06 INFO - Completed test suite (00:19:40) 17:46:06 INFO - javascript benchmark 17:46:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3237.077432931615, 3242.351529694061, 3246.3182731626366, 3265.5918010248733, 3314.171457135716, 5573.637713548616, 5623.971880140603, 5635.3237611486575, 5679.5456363490875, 5680.886382272352, 5572.4706152915505, 5660.830175094743, 5665.0, 5692.800751973671, 5716.1711551824965, 5087.49125087492, 5134.871628209287, 5146.871328216787, 5182.740862956837, 5215.139501982168, 5129.871753206181, 5142.665726727751, 5159.200323949775, 5258.579313654898, 5323.148296272597, 5177.922331165017, 5185.818496352628, 5208.218767184933, 5208.270842082112, 5215.973920130394, 5215.113430716788, 5244.842654720364, 5274.55166310864, 5288.81489147878, 5307.0, 5074.594032477393, 5108.693478391308, 5148.330717006766, 5211.947880521222, 5226.764795584208, 5572.3034620672415, 5589.357223919261, 5618.91571626426, 5625.268715067056, 5647.09646456569, 6075.119107729354, 6109.877802443926, 6154.7230374633245, 6222.284437289724, 6225.750969961241, 6248.875022499525, 6256.374362563753, 6295.811125666238, 6299.0, 6300.117983482316, 5624.859378515488, 5683.886322273532, 5693.743781529841, 5730.226419433407, 5791.449812267828, 6250.312465628777, 6308.526860485482, 6313.05304204373, 6313.936860631335, 6315.873682526416, 6115.082737589397, 6133.754649813957, 6135.846603834851, 6137.96931015351, 6139.815805525752, 6090.964536028886, 6103.816885493442, 6104.314742242901, 6188.8762224755255, 6258.342873998165, 6079.787207447718, 6119.8681629442035, 6133.3866613338305, 6184.6598437085495, 6202.627842329474, 6176.567640265138, 6214.9689251554355, 6215.471684906744, 6228.626282423069, 6235.438810506986, 4812.0, 4866.391701037371, 4914.778834952364, 4916.434610019822, 4935.901281974412, 4350.456192975878, 4374.19077470669, 4415.955840441618, 4435.534268901783, 4449.17690227309, 5756.597038207286, 5766.250387449605, 5773.624714393551, 5778.191053252548, 5789.9867523183275, 6245.906311405332, 6267.749290028348, 6268.655223962726, 6269.686515674198, 6281.434670879643, 5585.944140558542, 5649.604527683105, 5652.547796176296, 5653.547716182696, 5685.971570142206, 5345.812896548602, 5374.355077390738, 5386.91919621206, 5402.729863506809, 5425.186222066722, 5325.760340784752, 6266.905996409966, 6277.748890044347, 6283.120363149072, 6300.810975670737, 4887.36464259644, 6035.36628653985, 6272.529560283088, 6293.685315734104, 6294.276158241861, 6203.4416902479, 6207.34822843613, 6240.687965601793, 6272.843178920563, 6293.559450838397, 6154.292256390572, 6188.659623720739, 6190.350013248544, 6194.5044396449075, 6220.968895155676, 6236.968815156077, 6277.748890044347, 6303.684815759102, 6307.30619631831, 6312.715927783169, 5938.406159384027, 5980.611260268069, 5981.641101533922, 6035.215421995113, 6040.305364882919, 6062.333143354315, 6077.939220607915, 6078.240219972504, 6084.148219249397, 6119.663418512025, 5741.827745167651, 5881.176635271151, 5903.11453281994, 5904.852378690567, 5914.763409463573, 5868.178455016216, 5980.312264089685, 6002.669853158118, 6034.275886893601, 6067.120267561268, 5923.822285331447, 5941.405859414197, 5949.6430214187285, 5953.880922381441, 5977.22296101504], "value": 5662.057823637612, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.30076126294773, 504.29398841621725, 506.61750378464257, 531.4658767938222, 549.0830313376661, 28452.28869278269, 30810.53784193239, 30859.22851928706, 30880.53679194814, 38918.0, 39176.0, 41594.0, 41602.37596436056, 41736.747897563124, 41756.95607609804, 9125.315601329894, 9357.766055848624, 9368.765780855465, 9387.76530586734, 9404.81190376192, 8031.156728543507, 8662.696805611804, 8967.192952634261, 9422.199113075398, 9483.430994140374, 8811.823763524726, 8836.425632333878, 9379.531023448835, 9433.292503062263, 9452.196563292095, 9363.96996330403, 9435.858462123073, 9436.85844712323, 9451.52742362886, 9453.243740500811, 9161.679341223093, 9247.815043699156, 9262.583183756748, 9276.350655454122, 9287.257019438497, 38221.808890955646, 38484.11369147325, 38505.80747096246, 38606.613933860586, 38615.0, 3124.453220686371, 3436.432988556885, 3441.466572681213, 3590.3896337622405, 3638.5815631202486, 25114.623280650805, 27524.58713119305, 29138.854305728335, 30323.69676303231, 30449.391012179854, 2670.6127611496418, 2779.277387879166, 3134.498480243173, 3197.3605278944074, 3209.1035579407767, 489918.0, 489925.55037224584, 490460.0, 490961.0, 503379.0, 33304.50043249353, 59010.0, 59612.70193649004, 59682.40317596769, 59743.70128149331, 33689.32621347559, 34683.4797478038, 35064.0, 35087.29825403477, 35181.0, 12696.492140314469, 14286.499972500913, 14316.928415358065, 14320.57038288853, 14346.49787257441, 11993.400329983466, 12105.576304829288, 12109.515619375303, 12131.878681213251, 12151.088668349908, 7761.068671759312, 7769.4561380703935, 7775.922240777633, 7835.412344074103, 7863.646135923897, 2970.3910698307227, 3068.969310306913, 3190.1067701043744, 3231.2406584452633, 3263.6083669959758, 919.2324409118461, 961.5288508630721, 971.3977334052933, 990.6334656177116, 1001.2140469731372, 28229.71770282312, 28571.57142642862, 29304.706952930625, 29456.705432945826, 30024.399512010074, 1044.9216308776872, 1083.193021199196, 1097.2757979733337, 1110.9945499323126, 1124.600766727816, 14765.852341476662, 14924.0, 15186.316615752317, 15555.922220389051, 16020.519384418487, 670381.0, 674854.251457489, 675179.0, 675409.0, 676170.0, 54676.0, 57767.13349299764, 58274.377653005715, 58386.708066459396, 58578.414215857294, 1219.0856857356982, 1260.294235228275, 1329.4017692038428, 1381.095382524448, 1385.0166381868785, 29255.268618284714, 29311.413771724874, 29791.404171916005, 29809.0, 30518.84740576283, 49647.751761240965, 50325.74837125791, 50507.0, 50508.747456262485, 50533.49466505435, 573864.0, 579772.0, 579922.0, 582210.0, 583274.0, 23265.883670581537, 23825.642615360437, 25656.615150773127, 26432.603510947734, 26450.867745661148, 43553.78223108864, 45139.322910155715, 47641.04717905553, 47882.04235915193, 49237.0, 38010.809945950095, 38076.048098796644, 38088.238235235694, 38486.615133849424, 40040.79877603677, 37953.29210185604, 37967.860964171115, 38101.61898380981, 39238.80380598079, 39515.604843951194, 36967.8151609251, 37238.0, 37586.0, 38086.23827523489, 38167.618323817514, 32442.675573243967, 33691.663083368854, 34367.65632343745, 34396.31207375789, 34525.65474345224, 24592.0, 25116.748832511443, 25126.874365628053, 25337.746622534276, 25588.232353029438], "value": 18191.394604392695, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.1281808297337, 364.0915914792593, 367.6837919389325, 379.99050023749413, 394.5521832719864, 14735.70528589429, 15126.924365378172, 15628.296726647286, 19590.80409195911, 20059.0, 18172.09139543024, 19503.12235949382, 20122.295719649814, 20216.88807115608, 20340.288089916856, 5280.894382112356, 5841.970790146043, 6025.608335458203, 6078.574499785017, 6103.26760788706, 4836.733979631119, 5962.403759624046, 6032.758689652409, 6037.0, 6049.334573196945, 4677.602403795681, 5976.432238937294, 5978.103284507337, 5982.491488223484, 5997.820065398045, 5874.765009399619, 5973.432523910265, 5992.43071908168, 5995.52035837132, 5998.130271110708, 5018.64869459138, 5170.784865556591, 5176.171812509981, 5240.318758561362, 5243.632945693765, 24282.635760463607, 24946.501069978498, 24964.50070998588, 25265.747342526523, 25499.617505737428, 2263.9660405093937, 2277.4420267034516, 2277.681124642535, 2283.680284760135, 2982.0, 14385.496507622183, 14968.326425310779, 15165.620859478602, 15179.241037948059, 15935.282912268976, 1769.2480695704273, 1847.5196448923243, 1859.4886406238393, 1867.1971052447577, 1898.2122419196016, 99635.0, 101228.88939995362, 110740.0, 123127.38436307851, 135269.999999999, 11444.771104577861, 11617.302961822317, 11754.471048802825, 11845.703857403634, 11880.0, 14628.926855365655, 14696.853031469549, 14788.482403115839, 14844.554663360117, 14850.331735071948, 8268.545230012407, 8532.70135545253, 8547.188017138362, 8625.69810056645, 8632.697855575025, 6817.795466136024, 8336.458130221516, 8347.789570512363, 8515.48907065578, 8557.144285571392, 4452.844150454718, 4512.075024619945, 4515.458145022619, 4553.2942393929, 4576.382188404589, 1862.4598866328613, 1904.9238030478903, 1906.3708976037874, 1907.7233801099042, 1908.236705317884, 837.233930953171, 888.2893685051932, 941.1623654947102, 959.2757468111531, 998.6854140945579, 20097.899510502353, 21168.78831211668, 21215.15139394407, 21288.574228515343, 21349.89325053365, 896.6234181643725, 909.9636014559476, 915.4690279637794, 941.6798288581915, 942.2132519346286, 8925.955370223108, 10558.52486638103, 10855.402952837512, 11130.220884538167, 11147.0, 627063.0, 632607.0, 633852.0, 635752.0, 638069.0, 81977.59011204906, 107948.47094877547, 118591.73378529026, 133775.33112334376, 140060.8990865138, 851.0638297872291, 869.6086760957594, 926.231228080678, 1017.8880323164447, 1018.2719355660738, 18001.369952051617, 18407.90796046011, 18623.0, 19061.714074289175, 19098.0, 49579.0, 51631.0, 52848.0, 52850.73574632102, 53235.0, 63678.0, 70095.13988388974, 71918.0, 73474.89787653297, 78817.60591197008, 7681.15507294186, 7813.218678132142, 8012.318952888837, 8171.509709417454, 8276.751697449086, 12143.392830358624, 12417.875821241672, 12517.749645006867, 12552.184108032949, 12906.225626462443, 10784.245102842726, 10816.783664326827, 10913.126949844152, 11029.44852757343, 11071.280366776135, 10739.892601073889, 10819.67540973772, 10992.505337260003, 11112.722181945515, 11142.442877855912, 11028.28316159447, 11093.778124437627, 11131.443427828739, 11140.777184456427, 11432.771344573228, 7727.459077864495, 7877.842443151219, 7924.643391047532, 8084.434089613671, 8319.792005199919, 8231.46495477792, 9825.213982881496, 10390.324628899098, 11039.779204416027, 11105.888941110808], "value": 9476.94454589234, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.0804689022475, 1476.4315738440698, 1617.4096454793996, 1762.3126980477623, 1814.8548116150716, 7981.960090199548, 8414.873776893344, 8420.157984201584, 8440.0, 8440.91559084409, 7409.407247420207, 7633.580153091578, 7647.158812530617, 7714.151443341228, 8301.418900676956, 2027.9493012674654, 2054.5171884607107, 2059.5057186275335, 2261.694671219389, 2350.17743789674, 2361.917332893349, 2373.774491423312, 2375.4417711837705, 2378.940526486834, 2379.9762002380016, 2307.2501437032947, 2328.767123287666, 2334.8482348647367, 2347.859128452298, 2350.0717216699395, 2109.9050542725613, 2215.6898034275214, 2309.6073667476485, 2332.6384410416244, 2362.704661917269, 2328.0687724910003, 2328.5226528561775, 2359.433735903388, 2360.9965764549997, 2361.1853910401055, 8154.469959452616, 8331.000279966382, 8353.122922093215, 8355.83288334236, 8361.749147525585, 4648.651351148677, 4878.317035615017, 5191.974040129775, 5192.402873669538, 5207.817726379596, 3391.3895498810202, 3499.632538583463, 3538.02704256329, 3565.893023209308, 3585.731070169748, 942.2273735536862, 1059.586761163147, 1070.983935240972, 1099.5326986030905, 1106.673531308266, 10717.089047430978, 10815.026647601675, 10860.022597966143, 10876.456177191109, 11001.779964400666, 4225.408442818008, 4390.078083602415, 4481.282994720894, 4482.462104547475, 4486.842960496367, 8611.741647750578, 8613.913860861436, 8615.483071015758, 8622.655093796178, 8626.784330391665, 5023.372078490189, 5093.261477085876, 5100.566451851634, 5108.948910510847, 5110.540051395318, 4622.168009758276, 4642.620731531317, 4706.694064885772, 4716.905661886812, 4724.102420540086, 3371.6628337166576, 3470.7917524948584, 3496.7027802636553, 3497.0, 3593.4070878304788, 1084.951177197028, 1102.443266150589, 1103.1119948441421, 1105.4086063955745, 1117.7149826794277, 708.7838209346096, 829.4898637337993, 879.6745204274459, 920.0983036624145, 925.3707478914268, 5390.380106287827, 5491.313585801775, 5544.417836127149, 5626.718664066781, 5739.684317362502, 879.0681877210178, 945.2485274207022, 952.1050212799946, 952.5856252530172, 965.3001573858896, 3696.1129328960856, 3807.6002019787525, 3813.7139714521522, 3859.4982652255026, 3886.1256217351433, 9784.0, 10033.448160351105, 10062.345947513388, 10106.675464643173, 10174.949125254474, 9494.430334179971, 9969.950150249348, 10036.749081273027, 10054.798904021878, 10057.195424366033, 7673.731419400406, 7896.368290536743, 7902.68389264423, 7905.525668459911, 7919.7624071276805, 5534.501894829324, 5568.248286481436, 5616.66300022, 5707.600467967202, 5735.942640573541, 9224.35429519954, 9292.860607091034, 9295.209907157967, 9312.348135630711, 9318.860217096884, 9138.68014619485, 9382.343235973689, 9431.905680943102, 9533.380330278509, 9666.081722236377, 5839.0, 7084.220735718964, 7100.7869763907165, 7235.023271858442, 7274.381677557467, 7697.961510192413, 7788.026496687914, 7789.922100778919, 7812.335951444191, 7824.295813376823, 5794.0, 6850.965745171242, 6978.755743548952, 7117.82205444868, 7121.252268511732, 6705.8994115087335, 6778.796636100925, 6900.2409734928115, 6967.303269672992, 7030.578165310098, 6313.3686631336495, 6510.609363438209, 6602.966985165043, 6634.502412318999, 6660.167479065117, 4836.540528649773, 4855.393075865517, 4894.2658601210105, 4905.0189962007025, 4917.311576379381, 4733.881652958566, 4783.330333753347, 4868.14807408695, 4948.1835497142565, 4972.825951091694], "value": 4353.3207716049665, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.87861341009955, 297.5001996645637, 305.45781238302004, 308.74065784740816, 309.953506973954, 4627.560381763736, 5016.322796422482, 5254.238135470355, 5315.495027972342, 5385.3537575491, 5033.049056490656, 5094.108531007068, 5198.324217851674, 5236.895262094756, 5365.946340536593, 3640.526731524898, 3972.0864201233735, 4045.2111838191563, 4078.143589846136, 4097.8770636880945, 3806.6764325032404, 3867.690584753227, 4060.2285565742413, 4063.2076745034583, 4097.938530922023, 3739.5325584301963, 3998.720089593715, 4023.718339716221, 4074.633283004529, 4093.6111069448357, 4000.0399904023116, 4013.257547353749, 4035.4552135461777, 4051.7163798534116, 4054.432379466877, 3469.6877281044576, 3474.7567670263093, 3478.4434490481653, 3517.7361697872507, 3545.822708864572, 4617.538246175389, 4823.082035368621, 4834.274858771178, 5204.687718736888, 5243.895122097537, 4523.851170693927, 4578.985246491533, 4612.70191812852, 4615.423072115986, 4634.404733349302, 4573.794179261941, 4952.90094198114, 5603.635763675348, 5654.688992105433, 5680.971595141998, 3269.9346013079603, 3642.3427340559983, 3811.6760075393627, 3817.4655548223272, 3836.8105887175066, 4883.438404583448, 5220.582353411718, 5766.88466230673, 5777.248957635523, 5877.353491115974, 3795.4702127021583, 3842.9615703843165, 3850.3646898261472, 3858.5562660294427, 3872.496575445174, 3324.833758312123, 3643.2713457308646, 3778.8852288574935, 3942.211557688474, 3968.940465893014, 4332.610065094157, 4501.324801279834, 4563.498015218323, 4635.258358662665, 4651.325557794101, 5201.505856943584, 5619.971900140473, 5627.6904770237215, 5629.352624448159, 5648.265725455664, 1142.0977427832086, 1200.6218041316956, 1238.5169783784331, 1255.7049093463029, 1289.4133169407896, 687.7833482453111, 805.3637626275176, 812.3298278919915, 813.8128230507017, 847.4110493207285, 4331.97834010828, 4747.667663263538, 4815.544072523038, 5018.874528136826, 5086.720230387365, 4674.686030414899, 4687.976560117246, 5100.005498927673, 5539.335279766454, 5543.916841247385, 4000.2799496090984, 4176.2691528982305, 4668.949486365541, 4758.0, 5009.524095210949, 2284.97715022851, 2406.9157579484986, 2422.9636555451684, 2776.1393967870026, 2823.774098072149, 5464.153056276204, 5471.753771080311, 5495.95576840399, 5555.583321255267, 5583.720813959286, 4701.367234348776, 4712.58337705161, 4802.783874725646, 5664.376918538957, 5931.322580000341, 4818.855434336975, 4988.879726116386, 5860.952304801479, 5948.762049517971, 5966.284045914518, 4726.589439385869, 5489.92946375453, 5585.765693722381, 5800.622959507618, 5817.447342502455, 5671.943280567141, 5689.6586204827845, 5700.857478563069, 5740.339860915881, 5771.97114014444, 5063.620228482805, 5915.692879776804, 5952.553558483044, 5956.493698035715, 5980.073088671263, 3651.8904432867057, 3839.155385815116, 3877.3796192609047, 3893.3186692329296, 3898.96101038986, 4983.729149066965, 5026.623003274842, 5528.889422211453, 5718.25662663851, 5736.741846616829, 5520.53075488588, 5557.582816381797, 5561.555075593862, 5586.821139755418, 5621.285507920124, 4752.714837109785, 5342.4064705794135, 5495.53287970527, 5552.916706249329, 5577.525910297508, 4932.383452068491, 5074.340335756328, 5207.635465517377, 5422.240886275846, 5452.437354443406, 5456.672599643875, 5457.372402173642, 5478.287822583039, 5502.2296878437855, 5629.523009875642, 5182.429932707475, 5208.401033881152, 5270.392530278149, 5490.780368785204, 5526.696031718294], "value": 3996.334872668771, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.4115399540312, 723.1936390924118, 734.4748504819057, 749.4229443328637, 757.3675980556242, 13509.729805403898, 15617.765733513992, 16263.756043659354, 16270.0, 16285.51143465695, 14799.33402996865, 14871.405143794262, 15008.399664013454, 15013.174275414847, 15067.0, 636.0618088319737, 648.4714957309777, 807.4670717326566, 808.5835794565774, 812.0499016151088, 651.9739210431577, 660.1714847865921, 779.4504874063799, 796.398718967179, 811.6307080278459, 845.9890430935035, 847.3348421489133, 849.027863096757, 849.8385306791721, 849.8852654891604, 727.2327694282529, 846.1242613894651, 846.2172490446312, 846.9491830490159, 847.4745657692271, 499.21872269897494, 505.24718169926547, 614.0972770028052, 630.0, 634.5177664974607, 13802.03385762997, 13978.650533736636, 14038.719225615532, 14100.435982560686, 14104.942129340237, 12597.992160627127, 12827.807582886264, 12924.0, 12937.935310323483, 13156.342182890912, 11902.702432439259, 12008.459619317151, 12043.518259269618, 12044.0, 12156.392180391034, 3599.3521166190076, 3644.7630903991158, 3795.905102372435, 4296.548862369437, 4357.368181613649, 81823.0, 82967.0, 89142.55428722879, 90583.0, 91153.08846911578, 10179.694609161737, 10447.164226861833, 10642.787144257227, 10757.46212689378, 11003.119750419946, 19912.800871991094, 20200.49498762543, 20205.191792328143, 20367.694484582742, 20556.28053018167, 7625.771226863203, 7743.457957943003, 7752.26353496417, 7894.881576776353, 7925.28672419485, 4057.3102572562734, 4247.68142389322, 4303.1393721255245, 4367.10474352757, 4435.822567097344, 3394.0835974286806, 3474.513568100468, 3477.287156133037, 3481.9634294355483, 3521.4013617685055, 615.984600384994, 616.2512547255017, 622.3061286665336, 623.5354660777659, 624.3693869192149, 527.9181726832347, 594.4501336263938, 600.4445887554083, 669.9866002679919, 670.3129292475154, 6713.966430167818, 8725.91274087251, 8842.290344011144, 8985.146411090936, 9488.146066854017, 626.3110578363763, 634.6921742954663, 637.8692368064628, 647.7150053976235, 664.1996394344803, 3777.735558510878, 4363.2800587903275, 4388.714733542373, 4392.187445322562, 4429.269170586882, 67277.0, 85806.0, 92337.0, 99496.0, 103401.0, 71761.2823871765, 88450.55774721086, 92202.0, 93453.0, 94786.52606736922, 69336.30663693299, 75605.48789024189, 76003.61998189974, 78464.0, 79824.20175798167, 8987.101289870961, 9168.358214925158, 9397.107274808883, 9421.246300296098, 9469.289803264921, 36381.63618363783, 41456.75629730992, 46125.76937115293, 48512.0, 51371.48628513667, 64833.67583162054, 66348.33651663421, 70057.29942700508, 70548.94176587458, 72676.63661681658, 10173.389596624227, 11651.359175245443, 12292.631221063382, 12377.690557736129, 12474.438650260941, 12467.0, 13048.626342058276, 13648.41406821229, 14565.854341456874, 14970.0, 11864.288142711464, 12620.810687839506, 13114.803277950648, 13171.275579842817, 13508.32458377097, 12653.493860245855, 13562.59312220635, 13695.109817861807, 13794.37925293344, 14357.425702971765, 12119.333436661069, 12912.35438228066, 13009.674758131123, 13283.203007819562, 13353.0, 9223.815523689624, 9662.227021838376, 9800.264980126376, 10359.378437293786, 11031.558737650405, 7876.763697089096, 10647.094996925349, 10660.520276587727, 10677.039066484054, 10731.570737170427], "value": 7082.2554609879635, "unit": "score"}], "name": "dromaeo_css", "value": 7025.889874004209, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 111.0, 106.0, 115.0, 116.0, 115.0, 116.0, 116.0, 114.0, 116.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 114.0, 110.0, 134.0, 119.0, 144.0, 106.0, 131.0, 176.0, 139.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 184.0, 186.0, 185.0, 184.0, 184.0, 186.0, 186.0, 186.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 90.0, 61.0, 60.0, 58.0, 58.0, 82.0, 64.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 126.0, 100.0, 67.0, 68.0, 91.0, 66.0, 84.0, 124.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 87.0, 89.0, 86.0, 90.0, 88.0, 90.0, 85.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 94.0], "value": 95.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 79.0, 79.0, 81.0, 80.0, 84.0, 84.0, 81.0, 84.0], "value": 81.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 56.0, 57.0, 60.0, 59.0, 57.0, 57.0, 57.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 76.0, 82.0, 76.0, 73.0, 72.0, 71.0, 72.0, 70.0, 71.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 85.0, 83.0, 84.0, 85.0, 84.0, 84.0, 85.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 155.0, 147.0, 148.0, 146.0, 169.0, 149.0, 153.0, 213.0, 164.0], "value": 159.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 170.0, 170.0, 174.0, 168.0, 171.0, 168.0, 168.0, 168.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 70.0, 70.0, 68.0, 69.0, 69.0, 68.0, 69.0, 69.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1466.6999999999998, "alertThreshold": 2.0}]} 17:46:06 INFO - Return code: 0 17:46:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:46:06 INFO - # TBPL SUCCESS # 17:46:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:46:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:46:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:46:06 INFO - 2016-11-30 17:46:06.386 screenresolution[2478:29892] starting screenresolution argv=screenresolution get 17:46:06 INFO - 2016-11-30 17:46:06.389 screenresolution[2478:29892] Display 0: 1600x1200x32@60 17:46:06 INFO - 2016-11-30 17:46:06.395 screenresolution[2479:29895] starting screenresolution argv=screenresolution list 17:46:06 INFO - Available Modes on Display 0 17:46:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:46:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:46:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:46:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:46:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:46:06 INFO - Intel Iris: 17:46:06 INFO - Chipset Model: Intel Iris 17:46:06 INFO - Type: GPU 17:46:06 INFO - Bus: Built-In 17:46:06 INFO - VRAM (Dynamic, Max): 1536 MB 17:46:06 INFO - Vendor: Intel (0x8086) 17:46:06 INFO - Device ID: 0x0a2e 17:46:06 INFO - Revision ID: 0x0009 17:46:06 INFO - Displays: 17:46:06 INFO - Display: 17:46:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:46:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:46:06 INFO - Display Serial Number: 1600x1200 60 17:46:06 INFO - Main Display: Yes 17:46:06 INFO - Mirror: Off 17:46:06 INFO - Online: Yes 17:46:06 INFO - Rotation: Supported 17:46:06 INFO - Return code: 0 17:46:06 INFO - Running post-action listener: _resource_record_post_action 17:46:06 INFO - [mozharness: 2016-12-01 01:46:06.638829Z] Finished run-tests step (success) 17:46:06 INFO - Running post-run listener: _resource_record_post_run 17:46:06 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 102400; Write bytes: 651719168; Read time: 1; Write time: 1911 17:46:06 INFO - TinderboxPrint: CPU usage
53.8% 17:46:06 INFO - TinderboxPrint: I/O read bytes / time
102,400 / 1 17:46:06 INFO - TinderboxPrint: I/O write bytes / time
651,719,168 / 1,911 17:46:06 INFO - TinderboxPrint: CPU idle
2,242.7 (46.2%) 17:46:06 INFO - TinderboxPrint: CPU system
976.5 (20.1%) 17:46:06 INFO - TinderboxPrint: CPU user
1,630.5 (33.6%) 17:46:06 INFO - TinderboxPrint: Swap in / out
348,094,464 / 0 17:46:06 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 338092544; Write bytes: 333415936; Read time: 26522; Write time: 707 17:46:06 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 102400; Write bytes: 299652608; Read time: 1; Write time: 1144 17:46:07 INFO - Running post-run listener: _upload_blobber_files 17:46:07 INFO - Blob upload gear active. 17:46:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:46:07 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 17:46:07 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'] 17:46:07 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 17:46:07 INFO - (blobuploader) - INFO - Open directory for files ... 17:46:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:46:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:46:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:46:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:46:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:46:08 INFO - (blobuploader) - INFO - Done attempting. 17:46:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:46:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:46:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:46:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:46:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:46:11 INFO - (blobuploader) - INFO - Done attempting. 17:46:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:46:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:46:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:46:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:46:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:46:13 INFO - (blobuploader) - INFO - Done attempting. 17:46:13 INFO - (blobuploader) - INFO - Iteration through files over. 17:46:13 INFO - Return code: 0 17:46:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:46:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:46:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5fcd5e42353eb6ffaa68a4b7b6b5166eb05b7f37de9b6a5c9f4b55af3bec31f376320d7bed71c52798b936d40e30b0894ff631824c294e892119fcdbeafaf4d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95708ab779b7b8c205c908e31aaedd8765ee507cfa00fa55de14d9a5f3c2db2e82372aef66a7c83f538b0387d067fae06ad061eaa1a4e0e10e4d15f938e2f3f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d87f77dc84a213848e0c218883a14fa5ecbcf9f9281b239899631fc70b627be05b549171b10f273d4357f3bbe2c19a8c5473a51c084c24076203e74fe3b42e13"} 17:46:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:46:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:46:13 INFO - Contents: 17:46:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5fcd5e42353eb6ffaa68a4b7b6b5166eb05b7f37de9b6a5c9f4b55af3bec31f376320d7bed71c52798b936d40e30b0894ff631824c294e892119fcdbeafaf4d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95708ab779b7b8c205c908e31aaedd8765ee507cfa00fa55de14d9a5f3c2db2e82372aef66a7c83f538b0387d067fae06ad061eaa1a4e0e10e4d15f938e2f3f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d87f77dc84a213848e0c218883a14fa5ecbcf9f9281b239899631fc70b627be05b549171b10f273d4357f3bbe2c19a8c5473a51c084c24076203e74fe3b42e13"} 17:46:13 INFO - Running post-run listener: copy_logs_to_upload_dir 17:46:13 INFO - Copying logs to upload dir... 17:46:13 INFO - mkdir: /builds/slave/test/build/upload/logs 17:46:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.939985 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-30 17:46:13.495378) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 17:46:13.499242) ========= 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.zraoBHbN5X/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.cpDvbNbsuu/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/f5fcd5e42353eb6ffaa68a4b7b6b5166eb05b7f37de9b6a5c9f4b55af3bec31f376320d7bed71c52798b936d40e30b0894ff631824c294e892119fcdbeafaf4d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95708ab779b7b8c205c908e31aaedd8765ee507cfa00fa55de14d9a5f3c2db2e82372aef66a7c83f538b0387d067fae06ad061eaa1a4e0e10e4d15f938e2f3f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d87f77dc84a213848e0c218883a14fa5ecbcf9f9281b239899631fc70b627be05b549171b10f273d4357f3bbe2c19a8c5473a51c084c24076203e74fe3b42e13"} build_url:https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011256 build_url: 'https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/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/f5fcd5e42353eb6ffaa68a4b7b6b5166eb05b7f37de9b6a5c9f4b55af3bec31f376320d7bed71c52798b936d40e30b0894ff631824c294e892119fcdbeafaf4d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95708ab779b7b8c205c908e31aaedd8765ee507cfa00fa55de14d9a5f3c2db2e82372aef66a7c83f538b0387d067fae06ad061eaa1a4e0e10e4d15f938e2f3f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d87f77dc84a213848e0c218883a14fa5ecbcf9f9281b239899631fc70b627be05b549171b10f273d4357f3bbe2c19a8c5473a51c084c24076203e74fe3b42e13"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 17:46:13.533212) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:46:13.533531) ========= 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.zraoBHbN5X/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.cpDvbNbsuu/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.005418 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:46:13.582668) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 17:46:13.582985) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 17:46:13.583294) ========= ========= Total master_lag: 0.18 =========