builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0258 starttime: 1483072147.76 results: success (0) buildid: 20161229183557 builduid: 51183e9316cd47a19a8b120f9f4ec78d revision: d017df016ffdeb2392d517d44cd0eae1917c02cb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 20:29:07.762442) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 20:29:07.762934) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 20:29:07.763268) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 20:29:07.854128) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 20:29:07.854578) ========= 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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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-12-29 20:29:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 211M=0s 2016-12-29 20:29:08 (211 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.337230 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 20:29:08.216167) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 20:29:08.216498) ========= 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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.025383 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 20:29:08.265453) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 20:29:08.265821) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d017df016ffdeb2392d517d44cd0eae1917c02cb --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 d017df016ffdeb2392d517d44cd0eae1917c02cb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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-12-29 20:29:08,365 truncating revision to first 12 chars 2016-12-29 20:29:08,365 Setting DEBUG logging. 2016-12-29 20:29:08,365 attempt 1/10 2016-12-29 20:29:08,365 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d017df016ffd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 20:29:09,390 unpacking tar archive at: mozilla-aurora-d017df016ffd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.300430 ========= master_lag: 0.52 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 20:29:10.088293) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 20:29:10.088640) ========= script_repo_revision: d017df016ffdeb2392d517d44cd0eae1917c02cb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 20:29:10.089034) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 20:29:10.089311) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 20:29:10.109781) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-29 20:29:10.110168) ========= /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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 20:29:10 INFO - MultiFileLogger online at 20161229 20:29:10 in /builds/slave/test 20:29:10 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 20:29:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:29:10 INFO - {'append_to_log': False, 20:29:10 INFO - 'base_work_dir': '/builds/slave/test', 20:29:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:29:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:29:10 INFO - 'branch': 'Mozilla-Aurora', 20:29:10 INFO - 'buildbot_json_path': 'buildprops.json', 20:29:10 INFO - 'config_files': ('talos/mac_config.py',), 20:29:10 INFO - 'default_actions': ('clobber', 20:29:10 INFO - 'read-buildbot-config', 20:29:10 INFO - 'download-and-extract', 20:29:10 INFO - 'populate-webroot', 20:29:10 INFO - 'create-virtualenv', 20:29:10 INFO - 'install', 20:29:10 INFO - 'run-tests'), 20:29:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:29:10 INFO - 'download_minidump_stackwalk': True, 20:29:10 INFO - 'download_symbols': 'ondemand', 20:29:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:29:10 INFO - 'tooltool.py': '/tools/tooltool.py', 20:29:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:29:10 INFO - '/tools/misc-python/virtualenv.py')}, 20:29:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:29:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:29:10 INFO - 'installer_path': 'installer.exe', 20:29:10 INFO - 'log_level': 'info', 20:29:10 INFO - 'log_name': 'talos', 20:29:10 INFO - 'log_to_console': True, 20:29:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:29:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:29:10 INFO - 'opt_config_files': (), 20:29:10 INFO - 'pip_index': False, 20:29:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:29:10 INFO - 'cmd': ('bash', 20:29:10 INFO - '-c', 20:29:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:29:10 INFO - 'enabled': True, 20:29:10 INFO - 'halt_on_failure': False, 20:29:10 INFO - 'name': 'check_screen_resolution'},), 20:29:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:29:10 INFO - 'cmd': ('bash', 20:29:10 INFO - '-c', 20:29:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:29:10 INFO - 'enabled': True, 20:29:10 INFO - 'halt_on_failure': False, 20:29:10 INFO - 'name': 'check_screen_resolution'},), 20:29:10 INFO - 'run_cmd_checks_enabled': True, 20:29:10 INFO - 'sps_profile': False, 20:29:10 INFO - 'sps_profile_interval': 0, 20:29:10 INFO - 'suite': 'dromaeojs', 20:29:10 INFO - 'system_bits': '32', 20:29:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:29:10 INFO - 'title': 't-yosemite-r7-0258', 20:29:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:29:10 INFO - 'use_talos_json': True, 20:29:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:29:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:29:10 INFO - 'work_dir': 'build'} 20:29:10 INFO - [mozharness: 2016-12-30 04:29:10.316606Z] Running clobber step. 20:29:10 INFO - Running pre-action listener: _resource_record_pre_action 20:29:10 INFO - Running main action method: clobber 20:29:10 INFO - rmtree: /builds/slave/test/build 20:29:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:29:12 INFO - Running post-action listener: _resource_record_post_action 20:29:12 INFO - [mozharness: 2016-12-30 04:29:12.538356Z] Finished clobber step (success) 20:29:12 INFO - [mozharness: 2016-12-30 04:29:12.538533Z] Running read-buildbot-config step. 20:29:12 INFO - Running pre-action listener: _resource_record_pre_action 20:29:12 INFO - Running main action method: read_buildbot_config 20:29:12 INFO - Using buildbot properties: 20:29:12 INFO - { 20:29:12 INFO - "project": "", 20:29:12 INFO - "product": "firefox", 20:29:12 INFO - "script_repo_revision": "production", 20:29:12 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 20:29:12 INFO - "repository": "", 20:29:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 20:29:12 INFO - "buildid": "20161229183557", 20:29:12 INFO - "pgo_build": "False", 20:29:12 INFO - "basedir": "/builds/slave/test", 20:29:12 INFO - "buildnumber": 4, 20:29:12 INFO - "slavename": "t-yosemite-r7-0258", 20:29:12 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:29:12 INFO - "platform": "yosemite_r7", 20:29:12 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 20:29:12 INFO - "branch": "mozilla-aurora", 20:29:12 INFO - "repo_path": "releases/mozilla-aurora", 20:29:12 INFO - "revision": "d017df016ffdeb2392d517d44cd0eae1917c02cb", 20:29:12 INFO - "stage_platform": "macosx64", 20:29:12 INFO - "builduid": "51183e9316cd47a19a8b120f9f4ec78d", 20:29:12 INFO - "slavebuilddir": "test" 20:29:12 INFO - } 20:29:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 20:29:12 INFO - Running post-action listener: _resource_record_post_action 20:29:12 INFO - [mozharness: 2016-12-30 04:29:12.541892Z] Finished read-buildbot-config step (success) 20:29:12 INFO - [mozharness: 2016-12-30 04:29:12.542015Z] Running download-and-extract step. 20:29:12 INFO - Running pre-action listener: _resource_record_pre_action 20:29:12 INFO - Running main action method: download_and_extract 20:29:12 INFO - mkdir: /builds/slave/test/build/tests 20:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:12 INFO - trying https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 20:29:12 INFO - Downloading https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/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 20:29:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/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 20:29:14 INFO - Downloaded 1199 bytes. 20:29:14 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 20:29:14 INFO - Using the following test package requirements: 20:29:14 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 20:29:14 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:29:14 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 20:29:14 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:29:14 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 20:29:14 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:29:14 INFO - u'jsshell-mac.zip'], 20:29:14 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:29:14 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 20:29:14 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 20:29:14 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:29:14 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 20:29:14 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:29:14 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 20:29:14 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:29:14 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 20:29:14 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:29:14 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 20:29:14 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 20:29:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 20:29:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:29:14 INFO - Fetch https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 20:29:14 INFO - Http code: 200 20:29:14 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 20:29:14 INFO - x-amz-cf-id: FcY3IGvIpACKF8w9uTBop2nC_PiKl4nR66j06t3Jd2thXdnUAhBK4w== 20:29:14 INFO - x-amz-version-id: xgPD.9_hYa9kvWFu68X6.he5_tWj3VeA 20:29:14 INFO - Expected file size: 16268379 20:29:14 INFO - Obtained file size: 16268379 20:29:21 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 20:29:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 20:29:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:29:21 INFO - Fetch https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 20:29:22 INFO - Http code: 200 20:29:22 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 20:29:22 INFO - x-amz-cf-id: lvcZcbJrS_bRQj_tgKjFKqStspogJR7JdhEpLRkpK3Jldsb0GDHq1g== 20:29:22 INFO - x-amz-version-id: xgPD.9_hYa9kvWFu68X6.he5_tWj3VeA 20:29:22 INFO - Expected file size: 16268379 20:29:22 INFO - Obtained file size: 16268379 20:29:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 20:29:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 20:29:28 INFO - Fetch https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 20:29:29 INFO - Http code: 200 20:29:29 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 20:29:29 INFO - x-amz-cf-id: e8nizYghnpcIitCoLEQPIIfKvqn2AzJEXW8HZ82dX9HCNqvtMOGR0A== 20:29:29 INFO - x-amz-version-id: qxuHrnAOvMFGtld9VfcV.myDiuBKnXi4 20:29:29 INFO - Expected file size: 14409038 20:29:29 INFO - Obtained file size: 14409038 20:29: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')]} 20:29:29 INFO - trying https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:29:29 INFO - Downloading https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 20:29:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/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 20:29:32 INFO - Downloaded 109824314 bytes. 20:29:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:29:32 INFO - mkdir: /builds/slave/test/properties 20:29:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:29:32 INFO - Writing to file /builds/slave/test/properties/build_url 20:29:32 INFO - Contents: 20:29:32 INFO - build_url:https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:29:33 INFO - Running post-action listener: _resource_record_post_action 20:29:33 INFO - Running post-action listener: set_extra_try_arguments 20:29:33 INFO - [mozharness: 2016-12-30 04:29:33.521322Z] Finished download-and-extract step (success) 20:29:33 INFO - [mozharness: 2016-12-30 04:29:33.521448Z] Running populate-webroot step. 20:29:33 INFO - Running pre-action listener: _resource_record_pre_action 20:29:33 INFO - Running main action method: populate_webroot 20:29:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:29:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:29:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:29:33 INFO - '--tppagecycles', 20:29:33 INFO - '7'], 20:29:33 INFO - 'tests': ['tsvgm']}}, 20:29:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:29:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:29:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:29:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:29:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:29:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:29:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:33 INFO - 'talos_options': ['--disable-e10s'], 20:29:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:29:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:29:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:33 INFO - 'talos_options': ['--disable-e10s'], 20:29:33 INFO - 'tests': ['damp', 'tps']}, 20:29:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:33 INFO - 'tests': ['damp', 'tps']}, 20:29:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:29:33 INFO - 'tests': ['dromaeo_dom']}, 20:29:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:29:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:29:33 INFO - 'tests': ['basic_compositor_video']}, 20:29:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:29:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:29:33 INFO - 'tests': ['a11yr', 20:29:33 INFO - 'ts_paint', 20:29:33 INFO - 'tpaint', 20:29:33 INFO - 'sessionrestore', 20:29:33 INFO - 'sessionrestore_no_auto_restore', 20:29:33 INFO - 'tabpaint']}, 20:29:33 INFO - 'other-e10s': {'tests': ['a11yr', 20:29:33 INFO - 'ts_paint', 20:29:33 INFO - 'tpaint', 20:29:33 INFO - 'sessionrestore', 20:29:33 INFO - 'sessionrestore_no_auto_restore', 20:29:33 INFO - 'tabpaint']}, 20:29:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:29:33 INFO - 'ts_paint', 20:29:33 INFO - 'tpaint', 20:29:33 INFO - 'sessionrestore', 20:29:33 INFO - 'sessionrestore_no_auto_restore', 20:29:33 INFO - 'tabpaint']}, 20:29:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:29:33 INFO - 'ts_paint', 20:29:33 INFO - 'tpaint', 20:29:33 INFO - 'sessionrestore', 20:29:33 INFO - 'sessionrestore_no_auto_restore', 20:29:33 INFO - 'tabpaint']}, 20:29:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:29:33 INFO - 'tests': ['a11yr', 20:29:33 INFO - 'ts_paint', 20:29:33 INFO - 'tpaint', 20:29:33 INFO - 'sessionrestore', 20:29:33 INFO - 'sessionrestore_no_auto_restore', 20:29:33 INFO - 'tabpaint']}, 20:29:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:29:33 INFO - 'tests': ['a11yr', 20:29:33 INFO - 'ts_paint', 20:29:33 INFO - 'tpaint', 20:29:33 INFO - 'sessionrestore', 20:29:33 INFO - 'sessionrestore_no_auto_restore', 20:29:33 INFO - 'tabpaint']}, 20:29:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:29:33 INFO - 'tests': ['tsvgx', 20:29:33 INFO - 'tsvgr_opacity', 20:29:33 INFO - 'tart', 20:29:33 INFO - 'tscrollx', 20:29:33 INFO - 'cart']}, 20:29:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:29:33 INFO - 'tsvgr_opacity', 20:29:33 INFO - 'tart', 20:29:33 INFO - 'tscrollx', 20:29:33 INFO - 'cart']}, 20:29:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:33 INFO - 'talos_options': ['--disable-e10s'], 20:29:33 INFO - 'tests': ['tp5o']}, 20:29:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:33 INFO - 'tests': ['tp5o']}, 20:29:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:33 INFO - 'talos_options': ['--disable-e10s', 20:29:33 INFO - '--xperf_path', 20:29:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:29:33 INFO - 'tests': ['tp5n']}, 20:29:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:33 INFO - 'talos_options': ['--xperf_path', 20:29:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:29:33 INFO - 'tests': ['tp5n']}}, 20:29:33 INFO - 'talos.zip': {'path': '', 20:29:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:29:33 INFO - Running post-action listener: _resource_record_post_action 20:29:33 INFO - [mozharness: 2016-12-30 04:29:33.540270Z] Finished populate-webroot step (success) 20:29:33 INFO - [mozharness: 2016-12-30 04:29:33.540373Z] Running create-virtualenv step. 20:29:33 INFO - Running pre-action listener: _resource_record_pre_action 20:29:33 INFO - Running main action method: create_virtualenv 20:29:33 INFO - Creating virtualenv /builds/slave/test/build/venv 20:29:33 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 20:29:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:29:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:29:33 INFO - Using real prefix '/tools/python27' 20:29:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:29:34 INFO - Installing distribute.............................................................................................................................................................................................done. 20:29:37 INFO - Installing pip.................done. 20:29:37 INFO - Return code: 0 20:29:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:29:37 INFO - Reading from file tmpfile_stdout 20:29:37 INFO - Output received: 20:29:37 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) 20:29:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:37 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 0x107a41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb01a45aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107855d30>, '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 0x1076bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10784db20>, '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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 20:29:37 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 20:29:37 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 20:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8PdnjwkW8/Render', 20:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:29:37 INFO - 'HOME': '/Users/cltbld', 20:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:29:37 INFO - 'LOGNAME': 'cltbld', 20:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:29:37 INFO - 'MOZ_NO_REMOTE': '1', 20:29:37 INFO - 'NO_EM_RESTART': '1', 20:29:37 INFO - 'PAGER': '/bin/cat', 20:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:29:37 INFO - 'PWD': '/builds/slave/test', 20:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:29:37 INFO - 'SHELL': '/bin/bash', 20:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rNu3tqRKQ2/Listeners', 20:29:37 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 20:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:29:37 INFO - 'USER': 'cltbld', 20:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:29:37 INFO - 'XPC_FLAGS': '0x0', 20:29:37 INFO - 'XPC_SERVICE_NAME': '0', 20:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:29:37 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 20:29:37 INFO - Cleaning up... 20:29:37 INFO - Return code: 0 20:29:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:37 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 0x107a41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb01a45aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107855d30>, '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 0x1076bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10784db20>, '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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 20:29:37 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 20:29:37 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 20:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8PdnjwkW8/Render', 20:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:29:37 INFO - 'HOME': '/Users/cltbld', 20:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:29:37 INFO - 'LOGNAME': 'cltbld', 20:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:29:37 INFO - 'MOZ_NO_REMOTE': '1', 20:29:37 INFO - 'NO_EM_RESTART': '1', 20:29:37 INFO - 'PAGER': '/bin/cat', 20:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:29:37 INFO - 'PWD': '/builds/slave/test', 20:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:29:37 INFO - 'SHELL': '/bin/bash', 20:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rNu3tqRKQ2/Listeners', 20:29:37 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 20:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:29:37 INFO - 'USER': 'cltbld', 20:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:29:37 INFO - 'XPC_FLAGS': '0x0', 20:29:37 INFO - 'XPC_SERVICE_NAME': '0', 20:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:29:38 INFO - Downloading/unpacking psutil>=3.1.1 20:29:38 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 20:29:38 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 20:29:38 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 20:29:38 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 20:29:38 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 20:29:38 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 20:29:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:29:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:29:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:29:41 INFO - Installing collected packages: psutil 20:29:41 INFO - Running setup.py install for psutil 20:29:41 INFO - building 'psutil._psutil_osx' extension 20:29:41 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 20:29:42 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 20:29:42 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 20:29:42 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 20:29:42 INFO - building 'psutil._psutil_posix' extension 20:29:42 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 20:29:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:29:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:29:42 INFO - ^ 20:29:42 INFO - 1 warning generated. 20:29:42 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 20:29:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:29:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:29:42 INFO - Successfully installed psutil 20:29:42 INFO - Cleaning up... 20:29:42 INFO - Return code: 0 20:29:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb01a45aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107855d30>, '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 0x1076bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10784db20>, '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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 20:29:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8PdnjwkW8/Render', 20:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:29:42 INFO - 'HOME': '/Users/cltbld', 20:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:29:42 INFO - 'LOGNAME': 'cltbld', 20:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:29:42 INFO - 'MOZ_NO_REMOTE': '1', 20:29:42 INFO - 'NO_EM_RESTART': '1', 20:29:42 INFO - 'PAGER': '/bin/cat', 20:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:29:42 INFO - 'PWD': '/builds/slave/test', 20:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:29:42 INFO - 'SHELL': '/bin/bash', 20:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rNu3tqRKQ2/Listeners', 20:29:42 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 20:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:29:42 INFO - 'USER': 'cltbld', 20:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:29:42 INFO - 'XPC_FLAGS': '0x0', 20:29:42 INFO - 'XPC_SERVICE_NAME': '0', 20:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:29:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:29:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:29:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:29:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:29:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:29:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:29:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:29:45 INFO - Installing collected packages: mozsystemmonitor 20:29:45 INFO - Running setup.py install for mozsystemmonitor 20:29:45 INFO - Successfully installed mozsystemmonitor 20:29:45 INFO - Cleaning up... 20:29:45 INFO - Return code: 0 20:29:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:29: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')]} 20:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:29:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29: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')]} 20:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:29:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107a41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb01a45aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107855d30>, '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 0x1076bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10784db20>, '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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 20:29:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:29:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8PdnjwkW8/Render', 20:29:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:29:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:29:45 INFO - 'HOME': '/Users/cltbld', 20:29:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:29:45 INFO - 'LOGNAME': 'cltbld', 20:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:29:45 INFO - 'MOZ_NO_REMOTE': '1', 20:29:45 INFO - 'NO_EM_RESTART': '1', 20:29:45 INFO - 'PAGER': '/bin/cat', 20:29:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:29:45 INFO - 'PWD': '/builds/slave/test', 20:29:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:29:45 INFO - 'SHELL': '/bin/bash', 20:29:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rNu3tqRKQ2/Listeners', 20:29:45 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 20:29:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:29:45 INFO - 'USER': 'cltbld', 20:29:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:29:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:29:45 INFO - 'XPC_FLAGS': '0x0', 20:29:45 INFO - 'XPC_SERVICE_NAME': '0', 20:29:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:29:46 INFO - Downloading/unpacking jsonschema==2.5.1 20:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:29:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:29:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:29:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:29:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:29: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 20:29: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 20:29: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 20:29: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 20:29: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 20:29: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 20:29:49 INFO - Downloading functools32-3.2.3-2.tar.gz 20:29:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:29:49 INFO - warning: no files found matching '*.txt' 20:29:49 INFO - no previously-included directories found matching 'build' 20:29:49 INFO - no previously-included directories found matching 'dist' 20:29:49 INFO - no previously-included directories found matching '.git*' 20:29:49 INFO - Installing collected packages: jsonschema, functools32 20:29:49 INFO - Running setup.py install for functools32 20:29:49 INFO - warning: no files found matching '*.txt' 20:29:49 INFO - no previously-included directories found matching 'build' 20:29:49 INFO - no previously-included directories found matching 'dist' 20:29:49 INFO - no previously-included directories found matching '.git*' 20:29:49 INFO - Successfully installed jsonschema functools32 20:29:49 INFO - Cleaning up... 20:29:49 INFO - Return code: 0 20:29:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107a41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb01a45aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107855d30>, '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 0x1076bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10784db20>, '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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 20:29:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:29:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8PdnjwkW8/Render', 20:29:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:29:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:29:49 INFO - 'HOME': '/Users/cltbld', 20:29:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:29:49 INFO - 'LOGNAME': 'cltbld', 20:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:29:49 INFO - 'MOZ_NO_REMOTE': '1', 20:29:49 INFO - 'NO_EM_RESTART': '1', 20:29:49 INFO - 'PAGER': '/bin/cat', 20:29:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:29:49 INFO - 'PWD': '/builds/slave/test', 20:29:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:29:49 INFO - 'SHELL': '/bin/bash', 20:29:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rNu3tqRKQ2/Listeners', 20:29:49 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 20:29:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:29:49 INFO - 'USER': 'cltbld', 20:29:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:29:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:29:49 INFO - 'XPC_FLAGS': '0x0', 20:29:49 INFO - 'XPC_SERVICE_NAME': '0', 20:29:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:29:49 INFO - Cleaning up... 20:29:49 INFO - Return code: 0 20:29:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107a41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb01a45aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107855d30>, '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 0x1076bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10784db20>, '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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 20:29:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:29:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8PdnjwkW8/Render', 20:29:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:29:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:29:49 INFO - 'HOME': '/Users/cltbld', 20:29:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:29:49 INFO - 'LOGNAME': 'cltbld', 20:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:29:49 INFO - 'MOZ_NO_REMOTE': '1', 20:29:49 INFO - 'NO_EM_RESTART': '1', 20:29:49 INFO - 'PAGER': '/bin/cat', 20:29:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:29:49 INFO - 'PWD': '/builds/slave/test', 20:29:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:29:49 INFO - 'SHELL': '/bin/bash', 20:29:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rNu3tqRKQ2/Listeners', 20:29:49 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 20:29:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:29:49 INFO - 'USER': 'cltbld', 20:29:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:29:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:29:49 INFO - 'XPC_FLAGS': '0x0', 20:29:49 INFO - 'XPC_SERVICE_NAME': '0', 20:29:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:29:50 INFO - Downloading/unpacking blobuploader==1.2.4 20:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:29:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:29:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:29:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:29:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:29:52 INFO - Downloading blobuploader-1.2.4.tar.gz 20:29:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:29:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:29:53 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 20:29:53 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 20:29:53 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 20:29:53 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 20:29:53 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 20:29:53 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 20:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:29:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:29:53 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 20:29:53 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 20:29:53 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 20:29:53 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 20:29:53 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 20:29:53 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 20:29:54 INFO - Downloading docopt-0.6.1.tar.gz 20:29:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:29:54 INFO - Installing collected packages: blobuploader, requests, docopt 20:29:54 INFO - Running setup.py install for blobuploader 20:29:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:29:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:29:54 INFO - Running setup.py install for requests 20:29:55 INFO - Running setup.py install for docopt 20:29:55 INFO - Successfully installed blobuploader requests docopt 20:29:55 INFO - Cleaning up... 20:29:55 INFO - Return code: 0 20:29:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:29:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:29:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:55 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 0x107a41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb01a45aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107855d30>, '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 0x1076bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10784db20>, '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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 20:29:55 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 20:29:55 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 20:29:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8PdnjwkW8/Render', 20:29:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:29:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:29:55 INFO - 'HOME': '/Users/cltbld', 20:29:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:29:55 INFO - 'LOGNAME': 'cltbld', 20:29:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:29:55 INFO - 'MOZ_NO_REMOTE': '1', 20:29:55 INFO - 'NO_EM_RESTART': '1', 20:29:55 INFO - 'PAGER': '/bin/cat', 20:29:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:29:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:29:55 INFO - 'PWD': '/builds/slave/test', 20:29:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:29:55 INFO - 'SHELL': '/bin/bash', 20:29:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rNu3tqRKQ2/Listeners', 20:29:55 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 20:29:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:29:55 INFO - 'USER': 'cltbld', 20:29:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:29:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:29:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:29:55 INFO - 'XPC_FLAGS': '0x0', 20:29:55 INFO - 'XPC_SERVICE_NAME': '0', 20:29:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:29:55 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-zjC1g9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:29:55 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-pnO7Xk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:29:55 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-E7vXrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:29:55 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-5AC4bU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:29:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-04lWvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:29:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-t21Jkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:29:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-a6J2GV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:29:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-AGsgUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:29:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-9fqGoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:29:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-4r7T1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:29:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-3WghRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:29:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-1Z7Ywa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:29:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-wB7nrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:29:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-pUogDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:29:57 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-B5hNas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:29:57 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-LOdCdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:29:57 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Icqx1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:29:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:29:57 INFO - Running setup.py install for manifestparser 20:29:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:29:57 INFO - Running setup.py install for mozcrash 20:29:57 INFO - Running setup.py install for mozdebug 20:29:57 INFO - Running setup.py install for mozdevice 20:29:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:29:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:29:58 INFO - Running setup.py install for mozfile 20:29:58 INFO - Running setup.py install for mozhttpd 20:29:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:29:58 INFO - Running setup.py install for mozinfo 20:29:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:29:58 INFO - Running setup.py install for mozInstall 20:29:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:29:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:29:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:29:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:29:58 INFO - Running setup.py install for mozleak 20:29:58 INFO - Running setup.py install for mozlog 20:29:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:29:58 INFO - Running setup.py install for moznetwork 20:29:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:29:59 INFO - Running setup.py install for mozprocess 20:29:59 INFO - Running setup.py install for mozprofile 20:29:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:29:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:29:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:29:59 INFO - Running setup.py install for mozrunner 20:29:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:29:59 INFO - Running setup.py install for mozscreenshot 20:29:59 INFO - Running setup.py install for moztest 20:29:59 INFO - Running setup.py install for mozversion 20:29:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:29:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:29:59 INFO - Cleaning up... 20:30:00 INFO - Return code: 0 20:30:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:00 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 0x107a41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb01a45aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107855d30>, '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 0x1076bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10784db20>, '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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 20:30:00 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 20:30:00 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 20:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8PdnjwkW8/Render', 20:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:00 INFO - 'HOME': '/Users/cltbld', 20:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:00 INFO - 'LOGNAME': 'cltbld', 20:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:00 INFO - 'MOZ_NO_REMOTE': '1', 20:30:00 INFO - 'NO_EM_RESTART': '1', 20:30:00 INFO - 'PAGER': '/bin/cat', 20:30:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:00 INFO - 'PWD': '/builds/slave/test', 20:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:00 INFO - 'SHELL': '/bin/bash', 20:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rNu3tqRKQ2/Listeners', 20:30:00 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 20:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:00 INFO - 'USER': 'cltbld', 20:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:00 INFO - 'XPC_FLAGS': '0x0', 20:30:00 INFO - 'XPC_SERVICE_NAME': '0', 20:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:30:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-6LXXwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:30:00 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)) 20:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:30:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-KwA8Fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:30:00 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)) 20:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:30:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-E_eLtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:30:00 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)) 20:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:30:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-IzS9Sk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:30:00 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)) 20:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:30:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Yncick-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:30:00 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)) 20:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:30:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-PeHepz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:30:00 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)) 20:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:30:00 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-zZ0gfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:30:01 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)) 20:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:30:01 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-K9mT3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:30:01 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)) 20:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:30:01 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-HIwDEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:30:01 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)) 20:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:30:01 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-qXUsuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:30:01 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)) 20:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:30:01 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-qqIpgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:30:01 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)) 20:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:30:01 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-i46X9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:30:01 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)) 20:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:30:01 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-iGxOgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:30:01 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)) 20:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:30:01 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-DVt4nx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:30:01 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)) 20:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:30:01 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-QOKKox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:30:01 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)) 20:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:30:01 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-tI63_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:30:01 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)) 20:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:30:01 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-65j3dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:30:02 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)) 20:30:02 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)) 20:30:02 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)) 20:30:02 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)) 20:30:02 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)) 20:30:02 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)) 20:30:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:30:02 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 20:30:02 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 20:30:02 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 20:30:02 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 20:30:02 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 20:30:02 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 20:30:06 INFO - Downloading blessings-1.6.tar.gz 20:30:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:30:06 INFO - Installing collected packages: blessings 20:30:06 INFO - Running setup.py install for blessings 20:30:07 INFO - Successfully installed blessings 20:30:07 INFO - Cleaning up... 20:30:07 INFO - Return code: 0 20:30:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:30:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:30:07 INFO - Reading from file tmpfile_stdout 20:30:07 INFO - Current package versions: 20:30:07 INFO - blessings == 1.6 20:30:07 INFO - blobuploader == 1.2.4 20:30:07 INFO - docopt == 0.6.1 20:30:07 INFO - functools32 == 3.2.3-2 20:30:07 INFO - jsonschema == 2.5.1 20:30:07 INFO - manifestparser == 1.1 20:30:07 INFO - mozInstall == 1.12 20:30:07 INFO - mozcrash == 1.0 20:30:07 INFO - mozdebug == 0.1 20:30:07 INFO - mozdevice == 0.48 20:30:07 INFO - mozfile == 1.2 20:30:07 INFO - mozhttpd == 0.7 20:30:07 INFO - mozinfo == 0.9 20:30:07 INFO - mozleak == 0.1 20:30:07 INFO - mozlog == 3.4 20:30:07 INFO - moznetwork == 0.27 20:30:07 INFO - mozprocess == 0.23 20:30:07 INFO - mozprofile == 0.28 20:30:07 INFO - mozrunner == 6.13 20:30:07 INFO - mozscreenshot == 0.1 20:30:07 INFO - mozsystemmonitor == 0.3 20:30:07 INFO - moztest == 0.7 20:30:07 INFO - mozversion == 1.4 20:30:07 INFO - psutil == 3.1.1 20:30:07 INFO - requests == 1.2.3 20:30:07 INFO - wsgiref == 0.1.2 20:30:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:30:07 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')]} 20:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:07 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')]} 20:30:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:08 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 0x107a41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb01a45aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107855d30>, '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 0x1076bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10784db20>, '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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 20:30:08 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 20:30:08 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 20:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8PdnjwkW8/Render', 20:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:08 INFO - 'HOME': '/Users/cltbld', 20:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:08 INFO - 'LOGNAME': 'cltbld', 20:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:08 INFO - 'MOZ_NO_REMOTE': '1', 20:30:08 INFO - 'NO_EM_RESTART': '1', 20:30:08 INFO - 'PAGER': '/bin/cat', 20:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:08 INFO - 'PWD': '/builds/slave/test', 20:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:08 INFO - 'SHELL': '/bin/bash', 20:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rNu3tqRKQ2/Listeners', 20:30:08 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 20:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:08 INFO - 'USER': 'cltbld', 20:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:08 INFO - 'XPC_FLAGS': '0x0', 20:30:08 INFO - 'XPC_SERVICE_NAME': '0', 20:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:08 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)) 20:30:08 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)) 20:30:08 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)) 20:30:08 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)) 20:30:08 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)) 20:30:08 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)) 20:30:08 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)) 20:30:08 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)) 20:30:08 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)) 20:30:08 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)) 20:30:08 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)) 20:30:08 INFO - Cleaning up... 20:30:08 INFO - Return code: 0 20:30:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:30:08 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')]} 20:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:08 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')]} 20:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:08 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 0x107a41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb01a45aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107855d30>, '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 0x1076bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10784db20>, '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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 20:30:08 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 20:30:08 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 20:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8PdnjwkW8/Render', 20:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:08 INFO - 'HOME': '/Users/cltbld', 20:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:08 INFO - 'LOGNAME': 'cltbld', 20:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:08 INFO - 'MOZ_NO_REMOTE': '1', 20:30:08 INFO - 'NO_EM_RESTART': '1', 20:30:08 INFO - 'PAGER': '/bin/cat', 20:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:08 INFO - 'PWD': '/builds/slave/test', 20:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:08 INFO - 'SHELL': '/bin/bash', 20:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rNu3tqRKQ2/Listeners', 20:30:08 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 20:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:08 INFO - 'USER': 'cltbld', 20:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:08 INFO - 'XPC_FLAGS': '0x0', 20:30:08 INFO - 'XPC_SERVICE_NAME': '0', 20:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:30:08 INFO - Cleaning up... 20:30:08 INFO - Return code: 0 20:30:08 INFO - Running post-action listener: _resource_record_post_action 20:30:08 INFO - Running post-action listener: _start_resource_monitoring 20:30:09 INFO - Starting resource monitoring. 20:30:09 INFO - [mozharness: 2016-12-30 04:30:09.024815Z] Finished create-virtualenv step (success) 20:30:09 INFO - [mozharness: 2016-12-30 04:30:09.025792Z] Running install step. 20:30:09 INFO - Running pre-action listener: _resource_record_pre_action 20:30:09 INFO - Running main action method: install 20:30:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:30:09 INFO - Reading from file tmpfile_stdout 20:30:09 INFO - Detecting whether we're running mozinstall >=1.0... 20:30:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:30:09 INFO - Reading from file tmpfile_stdout 20:30:09 INFO - Output received: 20:30:09 INFO - Usage: mozinstall [options] installer 20:30:09 INFO - Options: 20:30:09 INFO - -h, --help show this help message and exit 20:30:09 INFO - -d DEST, --destination=DEST 20:30:09 INFO - Directory to install application into. [default: 20:30:09 INFO - "/builds/slave/test"] 20:30:09 INFO - --app=APP Application being installed. [default: firefox] 20:30:09 INFO - mkdir: /builds/slave/test/build/application 20:30:09 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'] 20:30:09 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 20:30:40 INFO - Reading from file tmpfile_stdout 20:30:40 INFO - Output received: 20:30:40 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 20:30:40 INFO - Running post-action listener: _resource_record_post_action 20:30:40 INFO - [mozharness: 2016-12-30 04:30:40.521578Z] Finished install step (success) 20:30:40 INFO - [mozharness: 2016-12-30 04:30:40.521744Z] Running run-tests step. 20:30:40 INFO - Running pre-action listener: _resource_record_pre_action 20:30:40 INFO - Running main action method: run_tests 20:30:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:30:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:30:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:30:40 INFO - 2016-12-29 20:30:40.534 screenresolution[1661:7758] starting screenresolution argv=screenresolution get 20:30:40 INFO - 2016-12-29 20:30:40.537 screenresolution[1661:7758] Display 0: 1600x1200x32@60 20:30:40 INFO - 2016-12-29 20:30:40.542 screenresolution[1662:7760] starting screenresolution argv=screenresolution list 20:30:40 INFO - Available Modes on Display 0 20:30:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:30:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:30:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:30:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:30:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:30:40 INFO - Intel Iris: 20:30:40 INFO - Chipset Model: Intel Iris 20:30:40 INFO - Type: GPU 20:30:40 INFO - Bus: Built-In 20:30:40 INFO - VRAM (Dynamic, Max): 1536 MB 20:30:40 INFO - Vendor: Intel (0x8086) 20:30:40 INFO - Device ID: 0x0a2e 20:30:40 INFO - Revision ID: 0x0009 20:30:40 INFO - Displays: 20:30:40 INFO - Display: 20:30:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:30:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:30:40 INFO - Display Serial Number: 1600x1200 60 20:30:40 INFO - Main Display: Yes 20:30:40 INFO - Mirror: Off 20:30:40 INFO - Online: Yes 20:30:40 INFO - Rotation: Supported 20:30:40 INFO - Return code: 0 20:30:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:30:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:30:40 INFO - Python 2.7.3 20:30:40 INFO - Return code: 0 20:30:40 INFO - grabbing minidump binary from tooltool 20:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:40 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 0x107855d30>, '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 0x1076bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10784db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:30:40 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 20:30:40 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 20:30:40 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 20:30:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:30:40 INFO - Return code: 0 20:30:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:30:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:30:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:30:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:30:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:30:40 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/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 20:30:40 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/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0258 --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 20:30:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d8PdnjwkW8/Render', 20:30:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:40 INFO - 'HOME': '/Users/cltbld', 20:30:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:40 INFO - 'LOGNAME': 'cltbld', 20:30:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:30:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:30:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:40 INFO - 'MOZ_NO_REMOTE': '1', 20:30:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:30:40 INFO - 'NO_EM_RESTART': '1', 20:30:40 INFO - 'PAGER': '/bin/cat', 20:30:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:40 INFO - 'PWD': '/builds/slave/test', 20:30:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:30:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:40 INFO - 'SHELL': '/bin/bash', 20:30:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rNu3tqRKQ2/Listeners', 20:30:40 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 20:30:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:40 INFO - 'USER': 'cltbld', 20:30:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:40 INFO - 'XPC_FLAGS': '0x0', 20:30:40 INFO - 'XPC_SERVICE_NAME': '0', 20:30:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:40 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/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 20:30:41 INFO - mozversion application_buildid: 20161229183557 20:30:41 INFO - mozversion application_changeset: d017df016ffdeb2392d517d44cd0eae1917c02cb 20:30:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:30:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:30:41 INFO - mozversion application_name: Firefox 20:30:41 INFO - mozversion application_remotingname: firefox-dev 20:30:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:30:41 INFO - mozversion application_vendor: Mozilla 20:30:41 INFO - mozversion application_version: 52.0a2 20:30:41 INFO - mozversion platform_buildid: 20161229183557 20:30:41 INFO - mozversion platform_changeset: d017df016ffdeb2392d517d44cd0eae1917c02cb 20:30:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:30:41 INFO - mozversion platform_version: 52.0a2 20:30:41 INFO - using testdate: 1483072241 20:30:41 INFO - actual date: 1483072241 20:30:41 INFO - starting webserver on 'localhost:49241' 20:30:41 INFO - SUITE-START | Running 2 tests 20:30:41 INFO - TEST-START | dromaeo_css 20:30:41 INFO - operating with platform_type : mac_ 20:30:41 INFO - Initialising browser for dromaeo_css test... 20:30:41 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpdIvJFl/profile http://localhost:49241/getInfo.html) 20:30:44 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 20:30:44 INFO - PROCESS | 1672 | colorDepth:24 20:30:44 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 20:30:44 INFO - PROCESS | 1672 | __metrics 20:30:44 INFO - PROCESS | 1672 | 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" 20:30:44 INFO - PROCESS | 1672 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:30:44 INFO - TEST-INFO | 1672: exit 0 20:30:44 INFO - Browser initialized. 20:30:44 INFO - Running cycle 1/1 for dromaeo_css test... 20:30:44 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpdIvJFl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:30:57 INFO - PROCESS | 1676 | RSS: Main: 222588928 20:30:57 INFO - PROCESS | 1676 | 20:31:48 INFO - PROCESS | 1676 | 1483072308494 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] 20:34:00 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 20:34:02 INFO - PROCESS | 1676 | RSS: Main: 357761024 20:34:02 INFO - PROCESS | 1676 | 20:37:05 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 20:37:06 INFO - PROCESS | 1676 | RSS: Main: 349810688 20:37:06 INFO - PROCESS | 1676 | 20:40:08 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 20:40:09 INFO - PROCESS | 1676 | RSS: Main: 318541824 20:40:09 INFO - PROCESS | 1676 | 20:43:11 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 20:43:12 INFO - PROCESS | 1676 | RSS: Main: 316416000 20:43:12 INFO - PROCESS | 1676 | 20:46:15 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 20:46:16 INFO - PROCESS | 1676 | RSS: Main: 309157888 20:46:16 INFO - PROCESS | 1676 | 20:49:19 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 20:49:19 INFO - PROCESS | 1676 | RSS: Main: 421273600 20:49:19 INFO - PROCESS | 1676 | 20:49:19 INFO - PROCESS | 1676 | __start_tp_report 20:49:19 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 20:49:19 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 20:49:19 INFO - PROCESS | 1676 | |i|pagename|runs| 20:49:19 INFO - PROCESS | 1676 | |0;dojo.html;2774.361896763743;3192.856321465534;3231.4991176367657;3254.6582608826056;3297.538344631752;5429.077056900326;5504.779808807642;5532.944670553294;5543.750531226095;5568.638038527492;4582.862514124582;5404.864878378033;5523.337199536061;5533.170024496328;5544.833654990357;5059.747012649362;5087.058894104603;5141.177411614142;5143.331366922295;5176.8705782355555;4138.834446622147;4972.801087956477;5036.244563315496;5057.291979122926;5132.101882170624;5059.924101138486;5126.23106534021;5142.922856157161;5150.613703972198;5159.32928719266;5115.616328775338;5142.845714628548;5149.459306772772;5178.093833579109;5182.32629758129;5044.697318160922;5120;5120.539151476348;5149.433562308143;5191.766370513336;5371.758270877781;5423.322084739408;5426.8371948841595;5435.83692489226;5490.203920431555;5817.767289308423;5917.319508256563;5929.436703513159;5954.255718035246;6057.515398768088;6107.725152368154;6115.4190351916495;6115.877682446326;6130.356312587159;6139.478144357735;5450.264214331094;5515.806946756844;5581.3302403711;5593.804216852391;5619.634723742944;5123.436421993578;5136.614753893398;6108.706347746941;6110.528382738247;6112.359997612681;5839.270091238595;5899.911501327483;5914.9112763308585;5920.111983202468;5922.555808314393;5825.242718446575;6046.546509011841;6085.4523092921845;6092.7562897484495;6102.176206212104;5806.071028635398;5846.7076646167525;5894.17481552583;5896.262967129109;5899.997000509924;5956.463918247378;5962.34414214436;5973.910391344133;5979.1629171915965;5994.790182343597;4784.712917224978;4803.255495398219;4818.98801251734;4823.493533179036;4830.855074347775;4228.788560571959;4249.32010878258;4249.38383934334;4269.850555230552;4309.633681137138;5554.72226388679;5556.055470570012;5576.996140694714;5593.133064375028;5607.943920560824;6042.758289668452;6052.636841789506;6060.060690592965;6065.727042283108;6071.726772295257;5354.732263386893;5429.185622156708;5530.032244357221;5534.86162845924;5544.417836127149;5164.147915593885;5190.143626301693;5246.632735708464;5249.580033597303;5302.469753024667;6248.9062664060075;6293.496520278458;6301;6320.778772742932;6321.209848768904;6100.9694951526735;6119.969400152971;6131.785387511416;6136.5090792737365;6155.4767844731905;4634.559716826896;6125.663088530174;6129.114290130661;6132.202813634199;6181.258249009968;4884.731339776346;5908.05471124618;5927.110933360031;5959.046552551571;5985.431384018511;6016.217891674054;6020.367861374492;6032.547558933185;6039.214902062704;6065.696715164312;4358;5668.943310566841;5720.513756330759;5814.3604203538425;5826.504747096375;5765.394633563583;5816.447437493432;5817.447342502455;5835.241418615553;5857.941420585739;4678.953210467988;5668.03643380618;5729.512991395778;5732.111522713986;5736.512396446349;4759.785809638505;5654.349749778664;5813.069908814569;5829.94170058294;5832.825015249549;5642.7460764266525;5678.7728490861555;5699.2590963174525;5723.8569035774435;5766.769329226784 20:49:19 INFO - PROCESS | 1676 | |1;ext.html;507.29992610197934;515.6983164848564;519.2419068160485;520.5861340234513;554.6450271826029;22041.66937495937;28248.57627135592;39725.80822575324;40812;40982.59017409825;41990.790046049726;42394.24437411566;42428.939276518104;42495.36256956148;42541.57458425415;8023.478473899207;9332.25341972644;9443.716688499357;9475.952620236889;9487;8402.285805706522;8617.224449799516;9317.068293170663;11094.889051109507;11356.602518911839;11276.830847537294;11342.48958796852;11360.886391136108;11364.886351136507;11379.089672826154;11265.380404077772;11331.886681133248;11334.263272887236;11350.602728904449;11355.20513564055;11173.994340509313;11234.719132021764;11234.887651123467;11244.66266012021;11250.606228781953;39295;39668.40497392541;39736.20527589461;39777.20445591101;39998.80000599979;3363.7981721096567;3553.7867727936405;3647.835847386874;3766.4350347448103;3788.6400791924725;29858.70141298581;30025;30249;30561.388772224433;30670;3110.937781244385;3145.8584363703458;3161.193895556641;3170.8414579271175;3189.4577921753357;325791;334351.99999999756;334482;337566;337596;33136.834315828266;59514;60005.69997149986;60064.39935600588;60086;34536.30927381438;35435.114122147155;35555.466668;35620.46569301462;35660.751873684814;14181.929090354482;14279.929005324642;14281;14309.284535773379;14352.06711563766;11035.668929931955;12014.579489717818;12089.57686480969;12090;12098.334591597546;7386.298301661441;7495.737649182252;7702.268284512962;7901.525908445512;7922.960385198153;2775.195193393893;2835.560488124344;3134.529820526939;3193.3294008258526;3211.871525139015;833.262562632075;890.1632465482426;945.3760518058186;950.6862735297335;967.2310513142069;28614.190355507337;29319.85340073286;29659.258518537208;29797.957071502395;29932.700672993426;969.8484239963121;970.2626004236831;1021.116734025063;1092.126298960828;1093.2729734726477;14952.476663316731;15222.847771522365;15406.152661603495;15496.790502862435;15576.766348504781;652428;654541;654602;658692;660078;55861.720691397095;56472.15291770627;56588.43411565914;56746.71626641841;56836;1021.8927012663861;1210.1710328425056;1225.4791713521895;1267.1953309648625;1341.8658134186503;29308.853455732584;29700.257493562833;29939.251518712208;30264.69735302619;30596.08211753651;48429.51570484391;48983.510164897896;49091;49114;49207;444731.77634111623;527152;528563;532319;534653;40184.799076005605;40833.59166408417;41066.79466602648;41132.38301425541;41368;44618.884527887065;44635.10729785451;44790;45048.09903801971;45558.772206138754;32662.020139395856;36828;36880.631193688794;39022;39278.01804954899;32748.672513275516;37050.25899482049;37067.44398834074;37155;37212.81393593123;36169.7340593078;36262.09344766402;36958;37233.6276637241;37634.43548346832;31454.685453146092;32760.017199484053;33393;33649.663503365635;33721.66278337185;24217;24631.87684061568;25063.248102556954;25239.11663091783;26757.331066723487 20:49:19 INFO - PROCESS | 1676 | |2;jquery.html;319.66914243757714;351.29740518962075;359.193610344776;375.7050715188578;376.2625254501176;14713.632159196011;15214.77177842332;16721.498355049353;19179.616407671874;19919;18557.81442185581;20034.398968030946;20052.39842804718;20078.598428031466;20105.396838094843;5576.191452239433;5803.6517808931385;5809.128630705407;5836.15375770513;5837.562182836305;4055.8174882130374;4329.67527435442;6104.664243466607;6313.715882785285;6419.101325814389;6261;6321.589096708699;6403.775867844626;6408.327125651809;6431.549791514596;6395.712192951305;6427.453666438335;6468.967655161741;6473.417392434656;6480.611163330168;5402.78388864445;5550.417206193372;5622.240997465331;5623.9437605623825;5656.858578535528;20972.659569150826;23275.418114547272;24693.506129877303;24816.751832481805;24868.75656217182;2164.9025793839314;2204.647256438962;2608.699999500063;3049.725524702765;3072.5698402223707;14218.573442796733;15111.924440377838;15113.622159445991;15596.986195897343;15768.684626307524;1528.9006214596016;1824.4253060285967;1860.7860096088987;1861.655593715167;1890.2439024390217;108915.45542272239;108958;108964;109365;109792;10353;10394.324368915995;10431.94784026075;10497.9475102624;10772.407517586607;13161.81543661075;13401.86598134006;13413.463461461628;13442.529511467052;13487.123336983064;7200.603966781878;8273.296769774517;8420.62107205165;8544.914550854537;8585.270252028648;8372.790680233044;8412.537310447984;8420.4947703138;8574.485530868043;8616.913830861611;6354.966410748516;6413.230412350548;6485.221773387224;6488.480921526267;6507.934920650828;1734.913254337278;1795.3267524678245;1803.4769916724265;1804.3323970131135;1807.1054827860294;883.0595415882065;917.5687426909342;944.5891037398753;957.2055194188806;960.4093482508207;20649.89675051645;21609.67585486219;21690.457738556663;21724.456888577595;21736.913154342536;905.7192270396198;945.1210374351781;951.1344676344631;953.1516949914582;960.6589660670361;10677.572897084185;10866.293690910225;11203.991640752209;11295.378754168436;11537.365444900443;600692;608125;609595;615504;619276;81820;82781.17218827855;108460.45769771258;112320.87679123267;113200;871.7864123289835;943.8914524829595;954.1651055326589;980.3431700760508;983.0611765763822;14398.568042958728;15473.84526154724;18865.15106820169;19481.805181948;20117.798822012177;40369.99075023148;48478.06087756591;50608.24087638762;50861.491385085676;51343.74328128336;72453.55092898218;73200.63399682968;74516.88224676743;76165.23834761581;77740.22259777556;6457.257415397289;7780.649870755717;7814.609269536614;8136.0643525995265;8377.246047855479;8837;11068.501917413896;12448.25310481374;12836.229826210458;12932.224066556037;10594.258401911793;10770.838437423288;10954.233203675985;11048.558057677603;11165.330080195214;10776.568937242422;11019;11027.503762330554;11052.557897684002;11158.995690387905;8554.016288126944;10746.623868164577;10810.783784324427;11280.548778048786;11344.035756960751;7656.234376562299;7938.444308898322;8223.136570659995;8258.876116858371;8355.37334699912;10228.693139205836;10803.351798891777;10895.182861285277;10933.507992140532;11003.394813285026 20:49:19 INFO - PROCESS | 1676 | |3;mootools.html;1452.8183977002875;1483.599428154398;1631.8857679962407;1794.1477798045937;1815.4644379907918;7492.325690687837;7775.650095745705;8042.517448953067;8501.48991060536;8512.787180320494;6681;7654.42591805614;7987.121416644164;8242.134575869537;8307.169283071706;2320.5590937721863;2340;2341.5785158671433;2341.882905854709;2345.812335013203;2039.5003224210088;2322.674825524428;2350.9882450587806;2372.240882917467;2378.7740164684415;2103.2533450625037;2134.6050980568643;2135.3593921823467;2135.765065842756;2135.829133669303;2241.1035585765662;2314.328844635054;2315.976840231593;2318.9072437102495;2324.941876453085;2216.9224077157382;2306.3657494189065;2337.5324935013064;2342.4846533762543;2343.9179628712914;6093.6039157454625;7216.603086830222;8217.30152937001;8360.247577717973;8373.706920257822;5017.84946451607;5180.533751962342;5185.248139019859;5187.844364669066;5190.169572868361;3204.070819462353;3312.5528053712924;3314.5359649649067;3365.158710322419;3453.671901169385;980.2354163752285;1021.8927012663638;1045.00724311904;1046.905778479933;1731.740238964153;10709.303895246783;10987.230893837355;11013.229073964825;11021.889781102247;11035.558577656884;4273.380359847867;4460.107978404268;4486.282194848808;4536.206163921366;4551.522090180483;8648.394612377198;8653.610587523577;8666.523341216167;8682.739517814476;8742.994555626057;5121.974390128025;5122.9743851281255;5132.538071573576;5144.099782538041;5146.485351464824;4678.017616300547;4731.172044892199;4734.73958932262;4743.786529606176;4804.903901921942;3449.6550344965804;3451.87918422854;3497.405441075023;3557.0929413000035;3579.982100089483;1082.9241953063367;1084.1868598551087;1085.1535802074395;1094.7755710079414;1098.9505472253768;755.3730403764862;844.6832437835811;881.9559022048871;890.701614958996;903.9864402033975;5242.7378631068295;5478.561715062865;5614.214010038598;5737.655740655574;5957.582969192202;841.1588411588411;965.7054598347571;981.1513041219437;985.1675334342469;1007.3049595778889;3497.5802903651725;3591.114829033641;3685.539307586552;3757.7933213672964;3782.2813665403487;9712.125908668253;10031.147352475122;10049.296549241482;10206.642767503101;10215.387076775418;9487.952560237154;10064.993500649876;10065.345752526062;10116.988301169824;10159.796804063877;6598.736050557924;7695.961520192363;7752.457327987099;7765.107012693499;7767.572783496849;5410.810621628224;5587.189857470563;5604.159376093618;5638.351589567253;5789.8263052108505;8914.242289405214;9168.954155229181;9223.907760922573;9308.95345523268;9367.531623418938;7654;8110.229528194812;8286.04710458305;9433.858492122761;9496;6834.179898412222;6901;6977.162740471176;6977.546459480118;6985.895211571932;6549.4760419167305;7645.235476452533;7838.725644602411;7845.0585929688805;7850.293473587404;6908.274631163857;6970.930290697028;6991.755288564876;7037.7184912602925;7147.785566433015;6897.241303456716;6941.618210998444;6956.617386043817;6969.372756451944;7013.964930175316;4989.451160372429;6348.9047664286;6425.742970281136;6453.548251622341;6512.05575191604;4699.389079419653;4701.083288758796;4799.904001920012;4830.806767729251;4843.297721830244;4792.7363994980615;4813.470518242921;4866.732329721899;4887;4981.1283025469675 20:49:19 INFO - PROCESS | 1676 | |4;prototype.html;299.47591714499623;306.2237228625435;307.40977323538806;315.56767228896416;316.8225793555608;5251.085647465655;5267.155735902613;5286.599051251412;5291.708956007418;5296.655717378372;4044.61576150266;5183.196604526295;5216.374035115782;5221.634485586011;5264.763085661145;3767.321882061221;3916.4321173429835;3949.940750888739;3974.82113304902;4043.1711499142607;3984.940225896614;4009.659178969791;4017.236725022251;4055.600817717883;4065.715399921992;3773.981025123215;3943.9377441235574;3952.0712632531477;3995.2209319182625;4007.799610019502;3258.1691668624467;3452.844621991991;3580.874669386572;3726.347889119393;4010.1177740896956;2894.3921776426973;3500.334936362083;3503.4919936609303;3506.929861402783;3510.3330367230146;4660.135945621744;5108.923366149511;5121.206213036985;5134.255532947702;5156.510131537498;3959.8614048508166;4178.686598505094;4507.377344156102;4528.773561321921;4586;4320.71915325506;5434.266374039533;5437;5438.525470910726;5505.972470137664;3286.342731453719;3850.1337199130253;3870.4968354114067;3910.6284902934176;3910.960890391088;5250.897311564576;5566.133441277745;5731.656100633933;5773.624714393551;5837.299524057098;3245.4807230842953;3252.2032102135126;3416.299658570037;3912.315344814646;3926.0381206604566;3890.3775395936514;3954.9209015819524;3990.7206495545033;3997.620820816872;4006.198760247904;4288.978555107205;4574.81700731967;4590.334401511762;4593.356930029799;4656;4592.609628181575;5168.689878607295;5239.764210610531;5548.334199896039;5581.916271255935;1152.6311580294393;1182.1016027818748;1208.3958020989505;1253.655244807667;1262.6843289177705;789.1792535762743;812.8211793405442;847.9236868681849;862.9870551941726;867.932443094984;4545;4743.881402964885;4984.925226121611;5208.427073021964;5227.843164705065;4673.415823022122;5645.774169033275;5654.491095801398;5677.744501497443;5695.145728140812;5041.764767631954;5166.354205724285;5200.869978250498;5222.190560463135;5247.212918062245;2337.6610391493477;2626.960595591068;2731.904383346613;2763.709810469912;2839.886404543795;4556.726596404226;5280.894382112413;5549.38956714761;5553.584900004506;5619.213310136584;5049.8737531562;5460.372057213392;5469;5473.278170843864;5530.695811730394;4949.901001980013;5140.794368225229;5544.560393426879;5547.667139971614;5549.417311182348;3999.9400158958483;4573.977130114328;4949.876253093701;5208.921866172086;5350.464953504618;4679.2045352289515;4717.669763116549;4911.789694637356;5021.472745361684;5076.162433198481;4619.838305659286;5455.945440545703;5507.807226747205;5521.4478552144465;5574.442555744393;3438.7420943428842;3554.591222009502;3569.2504574039745;3578.284343131332;3625.3111908737255;4209.7895105245225;5015.598752099896;5213.478652134756;5324.680519168863;5390.380106287827;4434.800433980543;4896.996115796324;4950.8762280943265;5129.1018825692;5163.993021360799;4623.468301145412;4755.5719985201495;4980.526849949249;5103.693778373309;5141.588672906233;4378.985666593403;4744.928826067543;4839.2983017461565;5173.017126745906;5191.76637051326;4176.644985176294;4401.449818772653;5011.4236862761245;5204.765785539736;5387.568994480511;5398.894431171768;5422.5119739223655;5449.918251226314;5457;5481.8753310612565 20:49:19 INFO - PROCESS | 1676 | |5;yui.html;687.0415770000845;696.5924933913661;753.819083419979;756.7124492692776;788.2511613966733;14152.858471415282;14631.341589628466;14693.191874446915;14700;14702.558923232293;12811.935940320309;12848.35758212088;12848.93575532121;12858.614241572745;12917.806232906514;652.7258551408408;674.7705780034787;679.6363945289271;810.8297257575916;834.4284165346756;814.4624547798454;824.7154731617583;828.4449418889359;831.8377916306323;832.3507664022072;900.3517467423445;904.3533873280603;905.8731777551116;906.2342320738965;908.1871724806327;701.6772284748971;717.827721346873;865.9870101948476;887.2369762004638;890.3011136258039;662.6686656671665;667.0194813623965;667.3126679520102;668.381746884135;670.2627110178813;14255.643608909757;14278.357473913595;14278.714425711532;14278.785818212735;14282.714345713131;11088.833667494993;12990.935045324808;13055.934720326433;13205.669858253525;13212.735745285137;11241;11783.179947921839;11792;12123.33321667308;12170.878291217152;4362.389265502832;4403.8238470461465;4439.800208990571;4558.270676691746;4580.450345958473;87818;89790;90302.74243143843;91295;92063;9786.363886347506;10284.434356110485;10297.30242213157;10490.160787137163;10741.51663175159;20213.191472341237;20219;20223.4944126398;20237.49406264855;20253.797462025486;7203.963980180066;7400.148982866932;7471.252874712485;7553.622318884144;7558.168601453835;3970.563238043813;4171.249175148502;4174.165166966558;4219.113986062961;4332.003639162947;3237.902862914116;3330.8667653293596;3336.9499457508155;3339.7328213743326;3342.5821772278464;654.201873714068;657.8355411147213;667.4060086522999;668.9899651505232;703.5462126928103;581.44472029212;588.9793857214977;619.8605313804358;644.8323435906698;646.5732616473199;8733;8798.560071996375;9006.189442950035;9023.278137748965;9129.497877616663;575.5740751843606;641.3682522715122;641.6438876423537;655.8524332025352;675.3550359406755;4400.44994375703;4405.867823965286;4441.356003379557;4452.443444569429;4497.707649002804;74099;77939.61030194812;106642.46678766712;112427;115682.42158789153;75616.62191689006;96034.0396596039;96411.51794240985;98088.01911980931;98207.50896245473;67900;69011.65494172498;72165;84275;88000;9268.397554158959;9539.33224674266;9570.47362395075;9590.66432674853;9620.13418792284;41522.96192595088;49960;50412.24381634205;51795.48204518057;52126.73936630293;60640;67199.66400167967;73428.63285683538;73477.53044938964;73704;11954.701132471759;12061.758764824479;12178.756424871275;12268.570600028956;12303.630891073282;13322.933385333012;13381.464741410624;13591.79612305836;13749.793753093913;13976.371063302378;12777.872221277668;12824.615261542169;13281.468741250628;13369.933150334575;13462.124961877838;10459.94770026145;10677.9466102669;10869.67390978272;11121.666350009513;11243.4378281084;10662.89337106619;10825.729356766144;12411.069169812119;12519.749605008032;13124.671883202996;8465.153484651732;8947.418417803083;9064.274858011475;9435.528223588655;9712.902870971482;9081.409708368936;9607.423554586747;9629.614815407305;9687.951560242154;9750.951245243728 20:49:19 INFO - PROCESS | 1676 | __end_tp_report 20:49:19 INFO - PROCESS | 1676 | __start_cc_report 20:49:19 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1627 20:49:19 INFO - PROCESS | 1676 | __end_cc_report 20:49:19 INFO - PROCESS | 1676 | __startTimestamp1483073359549__endTimestamp 20:49:19 INFO - PROCESS | 1676 | 20:49:19 INFO - PROCESS | 1676 | ------- Summary: start ------- 20:49:19 INFO - PROCESS | 1676 | Number of tests: 6 20:49:19 INFO - PROCESS | 1676 | 20:49:19 INFO - PROCESS | 1676 | [#0] dojo.html Cycles:165 Average:5498.41 Median:5642.75 stddev:647.61 (11.5%) stddev-sans-first:613.33 20:49:19 INFO - PROCESS | 1676 | Values: 2774.4 3192.9 3231.5 3254.7 3297.5 5429.1 5504.8 5532.9 5543.8 5568.6 4582.9 5404.9 5523.3 5533.2 5544.8 5059.7 5087.1 5141.2 5143.3 5176.9 4138.8 4972.8 5036.2 5057.3 5132.1 5059.9 5126.2 5142.9 5150.6 5159.3 5115.6 5142.8 5149.5 5178.1 5182.3 5044.7 5120.0 5120.5 5149.4 5191.8 5371.8 5423.3 5426.8 5435.8 5490.2 5817.8 5917.3 5929.4 5954.3 6057.5 6107.7 6115.4 6115.9 6130.4 6139.5 5450.3 5515.8 5581.3 5593.8 5619.6 5123.4 5136.6 6108.7 6110.5 6112.4 5839.3 5899.9 5914.9 5920.1 5922.6 5825.2 6046.5 6085.5 6092.8 6102.2 5806.1 5846.7 5894.2 5896.3 5900.0 5956.5 5962.3 5973.9 5979.2 5994.8 4784.7 4803.3 4819.0 4823.5 4830.9 4228.8 4249.3 4249.4 4269.9 4309.6 5554.7 5556.1 5577.0 5593.1 5607.9 6042.8 6052.6 6060.1 6065.7 6071.7 5354.7 5429.2 5530.0 5534.9 5544.4 5164.1 5190.1 5246.6 5249.6 5302.5 6248.9 6293.5 6301.0 6320.8 6321.2 6101.0 6120.0 6131.8 6136.5 6155.5 4634.6 6125.7 6129.1 6132.2 6181.3 4884.7 5908.1 5927.1 5959.0 5985.4 6016.2 6020.4 6032.5 6039.2 6065.7 4358.0 5668.9 5720.5 5814.4 5826.5 5765.4 5816.4 5817.4 5835.2 5857.9 4679.0 5668.0 5729.5 5732.1 5736.5 4759.8 5654.3 5813.1 5829.9 5832.8 5642.7 5678.8 5699.3 5723.9 5766.8 20:49:19 INFO - PROCESS | 1676 | 20:49:19 INFO - PROCESS | 1676 | [#1] ext.html Cycles:180 Average:63181.82 Median:35640.61 stddev:138935.86 (389.8%) stddev-sans-first:139245.92 20:49:19 INFO - PROCESS | 1676 | Values: 507.3 515.7 519.2 520.6 554.6 22041.7 28248.6 39725.8 40812.0 40982.6 41990.8 42394.2 42428.9 42495.4 42541.6 8023.5 9332.3 9443.7 9476.0 9487.0 8402.3 8617.2 9317.1 11094.9 11356.6 11276.8 11342.5 11360.9 11364.9 11379.1 11265.4 11331.9 11334.3 11350.6 11355.2 11174.0 11234.7 11234.9 11244.7 11250.6 39295.0 39668.4 39736.2 39777.2 39998.8 3363.8 3553.8 3647.8 3766.4 3788.6 29858.7 30025.0 30249.0 30561.4 30670.0 3110.9 3145.9 3161.2 3170.8 3189.5 325791.0 334352.0 334482.0 337566.0 337596.0 33136.8 59514.0 60005.7 60064.4 60086.0 34536.3 35435.1 35555.5 35620.5 35660.8 14181.9 14279.9 14281.0 14309.3 14352.1 11035.7 12014.6 12089.6 12090.0 12098.3 7386.3 7495.7 7702.3 7901.5 7923.0 2775.2 2835.6 3134.5 3193.3 3211.9 833.3 890.2 945.4 950.7 967.2 28614.2 29319.9 29659.3 29798.0 29932.7 969.8 970.3 1021.1 1092.1 1093.3 14952.5 15222.8 15406.2 15496.8 15576.8 652428.0 654541.0 654602.0 658692.0 660078.0 55861.7 56472.2 56588.4 56746.7 56836.0 1021.9 1210.2 1225.5 1267.2 1341.9 29308.9 29700.3 29939.3 30264.7 30596.1 48429.5 48983.5 49091.0 49114.0 49207.0 444731.8 527152.0 528563.0 532319.0 534653.0 40184.8 40833.6 41066.8 41132.4 41368.0 44618.9 44635.1 44790.0 45048.1 45558.8 32662.0 36828.0 36880.6 39022.0 39278.0 32748.7 37050.3 37067.4 37155.0 37212.8 36169.7 36262.1 36958.0 37233.6 37634.4 31454.7 32760.0 33393.0 33649.7 33721.7 24217.0 24631.9 25063.2 25239.1 26757.3 20:49:19 INFO - PROCESS | 1676 | 20:49:19 INFO - PROCESS | 1676 | [#2] jquery.html Cycles:180 Average:34039.37 Median:1696.12 stddev:100975.28 (5953.3%) stddev-sans-first:101226.80 20:49:19 INFO - PROCESS | 1676 | Values: 319.7 351.3 359.2 375.7 376.3 14713.6 15214.8 16721.5 19179.6 19919.0 18557.8 20034.4 20052.4 20078.6 20105.4 5576.2 5803.7 5809.1 5836.2 5837.6 4055.8 4329.7 6104.7 6313.7 6419.1 6261.0 6321.6 6403.8 6408.3 6431.5 6395.7 6427.5 6469.0 6473.4 6480.6 5402.8 5550.4 5622.2 5623.9 5656.9 20972.7 23275.4 24693.5 24816.8 24868.8 2164.9 2204.6 2608.7 3049.7 3072.6 14218.6 15111.9 15113.6 15597.0 15768.7 1528.9 1824.4 1860.8 1861.7 1890.2 108915.5 108958.0 108964.0 109365.0 109792.0 10353.0 10394.3 10431.9 10497.9 10772.4 13161.8 13401.9 13413.5 13442.5 13487.1 7200.6 8273.3 8420.6 8544.9 8585.3 8372.8 8412.5 8420.5 8574.5 8616.9 6355.0 6413.2 6485.2 6488.5 6507.9 1734.9 1795.3 1803.5 1804.3 1807.1 883.1 917.6 944.6 957.2 960.4 20649.9 21609.7 21690.5 21724.5 21736.9 905.7 945.1 951.1 953.2 960.7 10677.6 10866.3 11204.0 11295.4 11537.4 600692.0 608125.0 609595.0 615504.0 619276.0 81820.0 82781.2 108460.5 112320.9 113200.0 871.8 943.9 954.2 980.3 983.1 14398.6 15473.8 18865.2 19481.8 20117.8 40370.0 48478.1 50608.2 50861.5 51343.7 72453.6 73200.6 74516.9 76165.2 77740.2 6457.3 7780.6 7814.6 8136.1 8377.2 8837.0 11068.5 12448.3 12836.2 12932.2 10594.3 10770.8 10954.2 11048.6 11165.3 10776.6 11019.0 11027.5 11052.6 11159.0 8554.0 10746.6 10810.8 11280.5 11344.0 7656.2 7938.4 8223.1 8258.9 8355.4 10228.7 10803.4 10895.2 10933.5 11003.4 20:49:19 INFO - PROCESS | 1676 | 20:49:19 INFO - PROCESS | 1676 | [#3] mootools.html Cycles:180 Average:5304.54 Median:5122.47 stddev:2891.65 (56.5%) stddev-sans-first:2885.27 20:49:19 INFO - PROCESS | 1676 | Values: 1452.8 1483.6 1631.9 1794.1 1815.5 7492.3 7775.7 8042.5 8501.5 8512.8 6681.0 7654.4 7987.1 8242.1 8307.2 2320.6 2340.0 2341.6 2341.9 2345.8 2039.5 2322.7 2351.0 2372.2 2378.8 2103.3 2134.6 2135.4 2135.8 2135.8 2241.1 2314.3 2316.0 2318.9 2324.9 2216.9 2306.4 2337.5 2342.5 2343.9 6093.6 7216.6 8217.3 8360.2 8373.7 5017.8 5180.5 5185.2 5187.8 5190.2 3204.1 3312.6 3314.5 3365.2 3453.7 980.2 1021.9 1045.0 1046.9 1731.7 10709.3 10987.2 11013.2 11021.9 11035.6 4273.4 4460.1 4486.3 4536.2 4551.5 8648.4 8653.6 8666.5 8682.7 8743.0 5122.0 5123.0 5132.5 5144.1 5146.5 4678.0 4731.2 4734.7 4743.8 4804.9 3449.7 3451.9 3497.4 3557.1 3580.0 1082.9 1084.2 1085.2 1094.8 1099.0 755.4 844.7 882.0 890.7 904.0 5242.7 5478.6 5614.2 5737.7 5957.6 841.2 965.7 981.2 985.2 1007.3 3497.6 3591.1 3685.5 3757.8 3782.3 9712.1 10031.1 10049.3 10206.6 10215.4 9488.0 10065.0 10065.3 10117.0 10159.8 6598.7 7696.0 7752.5 7765.1 7767.6 5410.8 5587.2 5604.2 5638.4 5789.8 8914.2 9169.0 9223.9 9309.0 9367.5 7654.0 8110.2 8286.0 9433.9 9496.0 6834.2 6901.0 6977.2 6977.5 6985.9 6549.5 7645.2 7838.7 7845.1 7850.3 6908.3 6970.9 6991.8 7037.7 7147.8 6897.2 6941.6 6956.6 6969.4 7014.0 4989.5 6348.9 6425.7 6453.5 6512.1 4699.4 4701.1 4799.9 4830.8 4843.3 4792.7 4813.5 4866.7 4887.0 4981.1 20:49:19 INFO - PROCESS | 1676 | 20:49:19 INFO - PROCESS | 1676 | [#4] prototype.html Cycles:180 Average:4325.03 Median:4797.44 stddev:1317.20 (27.5%) stddev-sans-first:1285.77 20:49:19 INFO - PROCESS | 1676 | Values: 299.5 306.2 307.4 315.6 316.8 5251.1 5267.2 5286.6 5291.7 5296.7 4044.6 5183.2 5216.4 5221.6 5264.8 3767.3 3916.4 3949.9 3974.8 4043.2 3984.9 4009.7 4017.2 4055.6 4065.7 3774.0 3943.9 3952.1 3995.2 4007.8 3258.2 3452.8 3580.9 3726.3 4010.1 2894.4 3500.3 3503.5 3506.9 3510.3 4660.1 5108.9 5121.2 5134.3 5156.5 3959.9 4178.7 4507.4 4528.8 4586.0 4320.7 5434.3 5437.0 5438.5 5506.0 3286.3 3850.1 3870.5 3910.6 3911.0 5250.9 5566.1 5731.7 5773.6 5837.3 3245.5 3252.2 3416.3 3912.3 3926.0 3890.4 3954.9 3990.7 3997.6 4006.2 4289.0 4574.8 4590.3 4593.4 4656.0 4592.6 5168.7 5239.8 5548.3 5581.9 1152.6 1182.1 1208.4 1253.7 1262.7 789.2 812.8 847.9 863.0 867.9 4545.0 4743.9 4984.9 5208.4 5227.8 4673.4 5645.8 5654.5 5677.7 5695.1 5041.8 5166.4 5200.9 5222.2 5247.2 2337.7 2627.0 2731.9 2763.7 2839.9 4556.7 5280.9 5549.4 5553.6 5619.2 5049.9 5460.4 5469.0 5473.3 5530.7 4949.9 5140.8 5544.6 5547.7 5549.4 3999.9 4574.0 4949.9 5208.9 5350.5 4679.2 4717.7 4911.8 5021.5 5076.2 4619.8 5455.9 5507.8 5521.4 5574.4 3438.7 3554.6 3569.3 3578.3 3625.3 4209.8 5015.6 5213.5 5324.7 5390.4 4434.8 4897.0 4950.9 5129.1 5164.0 4623.5 4755.6 4980.5 5103.7 5141.6 4379.0 4744.9 4839.3 5173.0 5191.8 4176.6 4401.4 5011.4 5204.8 5387.6 5398.9 5422.5 5449.9 5457.0 5481.9 20:49:19 INFO - PROCESS | 1676 | 20:49:19 INFO - PROCESS | 1676 | [#5] yui.html Cycles:180 Average:19490.39 Median:30629.93 stddev:28365.91 (92.6%) stddev-sans-first:28410.34 20:49:19 INFO - PROCESS | 1676 | Values: 687.0 696.6 753.8 756.7 788.3 14152.9 14631.3 14693.2 14700.0 14702.6 12811.9 12848.4 12848.9 12858.6 12917.8 652.7 674.8 679.6 810.8 834.4 814.5 824.7 828.4 831.8 832.4 900.4 904.4 905.9 906.2 908.2 701.7 717.8 866.0 887.2 890.3 662.7 667.0 667.3 668.4 670.3 14255.6 14278.4 14278.7 14278.8 14282.7 11088.8 12990.9 13055.9 13205.7 13212.7 11241.0 11783.2 11792.0 12123.3 12170.9 4362.4 4403.8 4439.8 4558.3 4580.5 87818.0 89790.0 90302.7 91295.0 92063.0 9786.4 10284.4 10297.3 10490.2 10741.5 20213.2 20219.0 20223.5 20237.5 20253.8 7204.0 7400.1 7471.3 7553.6 7558.2 3970.6 4171.2 4174.2 4219.1 4332.0 3237.9 3330.9 3336.9 3339.7 3342.6 654.2 657.8 667.4 669.0 703.5 581.4 589.0 619.9 644.8 646.6 8733.0 8798.6 9006.2 9023.3 9129.5 575.6 641.4 641.6 655.9 675.4 4400.4 4405.9 4441.4 4452.4 4497.7 74099.0 77939.6 106642.5 112427.0 115682.4 75616.6 96034.0 96411.5 98088.0 98207.5 67900.0 69011.7 72165.0 84275.0 88000.0 9268.4 9539.3 9570.5 9590.7 9620.1 41523.0 49960.0 50412.2 51795.5 52126.7 60640.0 67199.7 73428.6 73477.5 73704.0 11954.7 12061.8 12178.8 12268.6 12303.6 13322.9 13381.5 13591.8 13749.8 13976.4 12777.9 12824.6 13281.5 13369.9 13462.1 10459.9 10677.9 10869.7 11121.7 11243.4 10662.9 10825.7 12411.1 12519.7 13124.7 8465.2 8947.4 9064.3 9435.5 9712.9 9081.4 9607.4 9629.6 9688.0 9751.0 20:49:19 INFO - PROCESS | 1676 | -------- Summary: end -------- 20:49:19 INFO - PROCESS | 1676 | 20:49:20 INFO - TEST-INFO | 1676: exit 0 20:49:20 INFO - TEST-OK | dromaeo_css | took 1119185ms 20:49:20 INFO - TEST-START | kraken 20:49:20 INFO - operating with platform_type : mac_ 20:49:20 INFO - Initialising browser for kraken test... 20:49:20 INFO - TEST-INFO | started process 1793 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpopscYO/profile http://localhost:49241/getInfo.html) 20:49:22 INFO - PROCESS | 1793 | __metrics Screen width/height:1600/1200 20:49:22 INFO - PROCESS | 1793 | colorDepth:24 20:49:22 INFO - PROCESS | 1793 | Browser inner width/height: 1024/689 20:49:22 INFO - PROCESS | 1793 | __metrics 20:49:23 INFO - PROCESS | 1793 | 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" 20:49:23 INFO - PROCESS | 1793 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:49:24 INFO - TEST-INFO | 1793: exit 0 20:49:24 INFO - Browser initialized. 20:49:24 INFO - Running cycle 1/1 for kraken test... 20:49:24 INFO - TEST-INFO | started process 1794 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpopscYO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:49:36 INFO - PROCESS | 1794 | RSS: Main: 222420992 20:49:36 INFO - PROCESS | 1794 | 20:49:39 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 20:49:39 INFO - PROCESS | 1794 | RSS: Main: 273932288 20:49:39 INFO - PROCESS | 1794 | 20:49:42 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 20:49:43 INFO - PROCESS | 1794 | RSS: Main: 284901376 20:49:43 INFO - PROCESS | 1794 | 20:49:46 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 20:49:47 INFO - PROCESS | 1794 | RSS: Main: 380194816 20:49:47 INFO - PROCESS | 1794 | 20:49:49 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 20:49:49 INFO - PROCESS | 1794 | RSS: Main: 282337280 20:49:49 INFO - PROCESS | 1794 | 20:49:51 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:49:52 INFO - PROCESS | 1794 | RSS: Main: 284622848 20:49:52 INFO - PROCESS | 1794 | 20:49:55 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 20:49:55 INFO - PROCESS | 1794 | RSS: Main: 324157440 20:49:55 INFO - PROCESS | 1794 | 20:49:58 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 20:49:59 INFO - PROCESS | 1794 | RSS: Main: 325181440 20:49:59 INFO - PROCESS | 1794 | 20:50:02 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 20:50:02 INFO - PROCESS | 1794 | RSS: Main: 324534272 20:50:02 INFO - PROCESS | 1794 | 20:50:04 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:50:04 INFO - PROCESS | 1794 | RSS: Main: 299470848 20:50:04 INFO - PROCESS | 1794 | 20:50:06 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:50:07 INFO - PROCESS | 1794 | RSS: Main: 300883968 20:50:07 INFO - PROCESS | 1794 | 20:50:09 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:50:10 INFO - PROCESS | 1794 | RSS: Main: 303575040 20:50:10 INFO - PROCESS | 1794 | 20:50:13 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:50:13 INFO - PROCESS | 1794 | RSS: Main: 315113472 20:50:13 INFO - PROCESS | 1794 | 20:50:16 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:50:17 INFO - PROCESS | 1794 | RSS: Main: 314683392 20:50:17 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 20:50:19 INFO - PROCESS | 1794 | RSS: Main: 358662144 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | __start_tp_report 20:50:19 INFO - PROCESS | 1794 | _x_x_mozilla_page_load 20:50:19 INFO - PROCESS | 1794 | _x_x_mozilla_page_load_details 20:50:19 INFO - PROCESS | 1794 | |i|pagename|runs| 20:50:19 INFO - PROCESS | 1794 | |0;ai-astar;116;115;115;114;114;122;170;162;115;114 20:50:19 INFO - PROCESS | 1794 | |1;audio-beat-detection;159;142;146;141;114;110;109;109;143;141 20:50:19 INFO - PROCESS | 1794 | |2;audio-dft;202;262;201;182;182;187;183;184;182;184 20:50:19 INFO - PROCESS | 1794 | |3;audio-fft;79;59;58;87;58;58;59;57;85;65 20:50:19 INFO - PROCESS | 1794 | |4;audio-oscillator;86;70;95;69;68;68;90;67;68;91 20:50:19 INFO - PROCESS | 1794 | |5;imaging-gaussian-blur;87;84;87;87;89;83;86;83;86;83 20:50:19 INFO - PROCESS | 1794 | |6;imaging-darkroom;93;92;110;152;104;103;151;129;93;92 20:50:19 INFO - PROCESS | 1794 | |7;imaging-desaturate;81;83;82;80;81;83;82;83;81;80 20:50:19 INFO - PROCESS | 1794 | |8;json-parse-financial;56;54;53;55;58;60;59;57;57;57 20:50:19 INFO - PROCESS | 1794 | |9;json-stringify-tinderbox;64;75;80;71;71;70;70;71;70;105 20:50:19 INFO - PROCESS | 1794 | |10;stanford-crypto-aes;81;80;93;83;84;83;83;82;84;83 20:50:19 INFO - PROCESS | 1794 | |11;stanford-crypto-ccm;140;145;152;149;140;141;144;144;212;169 20:50:19 INFO - PROCESS | 1794 | |12;stanford-crypto-pbkdf2;165;168;164;168;167;167;149;209;171;166 20:50:19 INFO - PROCESS | 1794 | |13;stanford-crypto-sha256-iterative;68;69;67;68;64;88;87;88;73;66 20:50:19 INFO - PROCESS | 1794 | __end_tp_report 20:50:19 INFO - PROCESS | 1794 | __start_cc_report 20:50:19 INFO - PROCESS | 1794 | _x_x_mozilla_cycle_collect,1999 20:50:19 INFO - PROCESS | 1794 | __end_cc_report 20:50:19 INFO - PROCESS | 1794 | __startTimestamp1483073419479__endTimestamp 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | ------- Summary: start ------- 20:50:19 INFO - PROCESS | 1794 | Number of tests: 14 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | [#0] ai-astar Cycles:10 Average:125.70 Median:115.50 stddev:21.45 (18.6%) stddev-sans-first:22.47 20:50:19 INFO - PROCESS | 1794 | Values: 116.0 115.0 115.0 114.0 114.0 122.0 170.0 162.0 115.0 114.0 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:141.50 stddev:18.77 (13.3%) stddev-sans-first:17.04 20:50:19 INFO - PROCESS | 1794 | Values: 159.0 142.0 146.0 141.0 114.0 110.0 109.0 109.0 143.0 141.0 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | [#2] audio-dft Cycles:10 Average:194.90 Median:185.50 stddev:24.79 (13.4%) stddev-sans-first:26.16 20:50:19 INFO - PROCESS | 1794 | Values: 202.0 262.0 201.0 182.0 182.0 187.0 183.0 184.0 182.0 184.0 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | [#3] audio-fft Cycles:10 Average:66.50 Median:62.00 stddev:12.20 (19.7%) stddev-sans-first:12.08 20:50:19 INFO - PROCESS | 1794 | Values: 79.0 59.0 58.0 87.0 58.0 58.0 59.0 57.0 85.0 65.0 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:78.00 stddev:11.67 (15.0%) stddev-sans-first:11.94 20:50:19 INFO - PROCESS | 1794 | Values: 86.0 70.0 95.0 69.0 68.0 68.0 90.0 67.0 68.0 91.0 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.50 stddev:2.12 (2.5%) stddev-sans-first:2.18 20:50:19 INFO - PROCESS | 1794 | Values: 87.0 84.0 87.0 87.0 89.0 83.0 86.0 83.0 86.0 83.0 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | [#6] imaging-darkroom Cycles:10 Average:111.90 Median:122.00 stddev:23.76 (19.5%) stddev-sans-first:24.20 20:50:19 INFO - PROCESS | 1794 | Values: 93.0 92.0 110.0 152.0 104.0 103.0 151.0 129.0 93.0 92.0 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | [#7] imaging-desaturate Cycles:10 Average:81.60 Median:82.00 stddev:1.17 (1.4%) stddev-sans-first:1.22 20:50:19 INFO - PROCESS | 1794 | Values: 81.0 83.0 82.0 80.0 81.0 83.0 82.0 83.0 81.0 80.0 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:2.17 (3.8%) stddev-sans-first:2.29 20:50:19 INFO - PROCESS | 1794 | Values: 56.0 54.0 53.0 55.0 58.0 60.0 59.0 57.0 57.0 57.0 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | [#9] json-stringify-tinderbox Cycles:10 Average:74.70 Median:71.00 stddev:11.39 (16.0%) stddev-sans-first:11.41 20:50:19 INFO - PROCESS | 1794 | Values: 64.0 75.0 80.0 71.0 71.0 70.0 70.0 71.0 70.0 105.0 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:83.00 stddev:3.53 (4.3%) stddev-sans-first:3.62 20:50:19 INFO - PROCESS | 1794 | Values: 81.0 80.0 93.0 83.0 84.0 83.0 83.0 82.0 84.0 83.0 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | [#11] stanford-crypto-ccm Cycles:10 Average:153.60 Median:147.00 stddev:22.26 (15.1%) stddev-sans-first:23.06 20:50:19 INFO - PROCESS | 1794 | Values: 140.0 145.0 152.0 149.0 140.0 141.0 144.0 144.0 212.0 169.0 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.40 Median:167.50 stddev:15.14 (9.0%) stddev-sans-first:15.97 20:50:19 INFO - PROCESS | 1794 | Values: 165.0 168.0 164.0 168.0 167.0 167.0 149.0 209.0 171.0 166.0 20:50:19 INFO - PROCESS | 1794 | 20:50:19 INFO - PROCESS | 1794 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.80 Median:71.00 stddev:9.84 (13.9%) stddev-sans-first:10.21 20:50:19 INFO - PROCESS | 1794 | Values: 68.0 69.0 67.0 68.0 64.0 88.0 87.0 88.0 73.0 66.0 20:50:19 INFO - PROCESS | 1794 | -------- Summary: end -------- 20:50:19 INFO - PROCESS | 1794 | 20:50:20 INFO - TEST-INFO | 1794: exit 0 20:50:20 INFO - TEST-OK | kraken | took 60232ms 20:50:20 INFO - SUITE-END | took 1179s 20:50:20 INFO - Completed test suite (00:19:39) 20:50:20 INFO - javascript benchmark 20:50:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2774.361896763743, 3192.856321465534, 3231.4991176367657, 3254.6582608826056, 3297.538344631752, 5429.077056900326, 5504.779808807642, 5532.944670553294, 5543.750531226095, 5568.638038527492, 4582.862514124582, 5404.864878378033, 5523.337199536061, 5533.170024496328, 5544.833654990357, 5059.747012649362, 5087.058894104603, 5141.177411614142, 5143.331366922295, 5176.8705782355555, 4138.834446622147, 4972.801087956477, 5036.244563315496, 5057.291979122926, 5132.101882170624, 5059.924101138486, 5126.23106534021, 5142.922856157161, 5150.613703972198, 5159.32928719266, 5115.616328775338, 5142.845714628548, 5149.459306772772, 5178.093833579109, 5182.32629758129, 5044.697318160922, 5120.0, 5120.539151476348, 5149.433562308143, 5191.766370513336, 5371.758270877781, 5423.322084739408, 5426.8371948841595, 5435.83692489226, 5490.203920431555, 5817.767289308423, 5917.319508256563, 5929.436703513159, 5954.255718035246, 6057.515398768088, 6107.725152368154, 6115.4190351916495, 6115.877682446326, 6130.356312587159, 6139.478144357735, 5450.264214331094, 5515.806946756844, 5581.3302403711, 5593.804216852391, 5619.634723742944, 5123.436421993578, 5136.614753893398, 6108.706347746941, 6110.528382738247, 6112.359997612681, 5839.270091238595, 5899.911501327483, 5914.9112763308585, 5920.111983202468, 5922.555808314393, 5825.242718446575, 6046.546509011841, 6085.4523092921845, 6092.7562897484495, 6102.176206212104, 5806.071028635398, 5846.7076646167525, 5894.17481552583, 5896.262967129109, 5899.997000509924, 5956.463918247378, 5962.34414214436, 5973.910391344133, 5979.1629171915965, 5994.790182343597, 4784.712917224978, 4803.255495398219, 4818.98801251734, 4823.493533179036, 4830.855074347775, 4228.788560571959, 4249.32010878258, 4249.38383934334, 4269.850555230552, 4309.633681137138, 5554.72226388679, 5556.055470570012, 5576.996140694714, 5593.133064375028, 5607.943920560824, 6042.758289668452, 6052.636841789506, 6060.060690592965, 6065.727042283108, 6071.726772295257, 5354.732263386893, 5429.185622156708, 5530.032244357221, 5534.86162845924, 5544.417836127149, 5164.147915593885, 5190.143626301693, 5246.632735708464, 5249.580033597303, 5302.469753024667, 6248.9062664060075, 6293.496520278458, 6301.0, 6320.778772742932, 6321.209848768904, 6100.9694951526735, 6119.969400152971, 6131.785387511416, 6136.5090792737365, 6155.4767844731905, 4634.559716826896, 6125.663088530174, 6129.114290130661, 6132.202813634199, 6181.258249009968, 4884.731339776346, 5908.05471124618, 5927.110933360031, 5959.046552551571, 5985.431384018511, 6016.217891674054, 6020.367861374492, 6032.547558933185, 6039.214902062704, 6065.696715164312, 4358.0, 5668.943310566841, 5720.513756330759, 5814.3604203538425, 5826.504747096375, 5765.394633563583, 5816.447437493432, 5817.447342502455, 5835.241418615553, 5857.941420585739, 4678.953210467988, 5668.03643380618, 5729.512991395778, 5732.111522713986, 5736.512396446349, 4759.785809638505, 5654.349749778664, 5813.069908814569, 5829.94170058294, 5832.825015249549, 5642.7460764266525, 5678.7728490861555, 5699.2590963174525, 5723.8569035774435, 5766.769329226784], "value": 5458.676810242279, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.29992610197934, 515.6983164848564, 519.2419068160485, 520.5861340234513, 554.6450271826029, 22041.66937495937, 28248.57627135592, 39725.80822575324, 40812.0, 40982.59017409825, 41990.790046049726, 42394.24437411566, 42428.939276518104, 42495.36256956148, 42541.57458425415, 8023.478473899207, 9332.25341972644, 9443.716688499357, 9475.952620236889, 9487.0, 8402.285805706522, 8617.224449799516, 9317.068293170663, 11094.889051109507, 11356.602518911839, 11276.830847537294, 11342.48958796852, 11360.886391136108, 11364.886351136507, 11379.089672826154, 11265.380404077772, 11331.886681133248, 11334.263272887236, 11350.602728904449, 11355.20513564055, 11173.994340509313, 11234.719132021764, 11234.887651123467, 11244.66266012021, 11250.606228781953, 39295.0, 39668.40497392541, 39736.20527589461, 39777.20445591101, 39998.80000599979, 3363.7981721096567, 3553.7867727936405, 3647.835847386874, 3766.4350347448103, 3788.6400791924725, 29858.70141298581, 30025.0, 30249.0, 30561.388772224433, 30670.0, 3110.937781244385, 3145.8584363703458, 3161.193895556641, 3170.8414579271175, 3189.4577921753357, 325791.0, 334351.99999999756, 334482.0, 337566.0, 337596.0, 33136.834315828266, 59514.0, 60005.69997149986, 60064.39935600588, 60086.0, 34536.30927381438, 35435.114122147155, 35555.466668, 35620.46569301462, 35660.751873684814, 14181.929090354482, 14279.929005324642, 14281.0, 14309.284535773379, 14352.06711563766, 11035.668929931955, 12014.579489717818, 12089.57686480969, 12090.0, 12098.334591597546, 7386.298301661441, 7495.737649182252, 7702.268284512962, 7901.525908445512, 7922.960385198153, 2775.195193393893, 2835.560488124344, 3134.529820526939, 3193.3294008258526, 3211.871525139015, 833.262562632075, 890.1632465482426, 945.3760518058186, 950.6862735297335, 967.2310513142069, 28614.190355507337, 29319.85340073286, 29659.258518537208, 29797.957071502395, 29932.700672993426, 969.8484239963121, 970.2626004236831, 1021.116734025063, 1092.126298960828, 1093.2729734726477, 14952.476663316731, 15222.847771522365, 15406.152661603495, 15496.790502862435, 15576.766348504781, 652428.0, 654541.0, 654602.0, 658692.0, 660078.0, 55861.720691397095, 56472.15291770627, 56588.43411565914, 56746.71626641841, 56836.0, 1021.8927012663861, 1210.1710328425056, 1225.4791713521895, 1267.1953309648625, 1341.8658134186503, 29308.853455732584, 29700.257493562833, 29939.251518712208, 30264.69735302619, 30596.08211753651, 48429.51570484391, 48983.510164897896, 49091.0, 49114.0, 49207.0, 444731.77634111623, 527152.0, 528563.0, 532319.0, 534653.0, 40184.799076005605, 40833.59166408417, 41066.79466602648, 41132.38301425541, 41368.0, 44618.884527887065, 44635.10729785451, 44790.0, 45048.09903801971, 45558.772206138754, 32662.020139395856, 36828.0, 36880.631193688794, 39022.0, 39278.01804954899, 32748.672513275516, 37050.25899482049, 37067.44398834074, 37155.0, 37212.81393593123, 36169.7340593078, 36262.09344766402, 36958.0, 37233.6276637241, 37634.43548346832, 31454.685453146092, 32760.017199484053, 33393.0, 33649.663503365635, 33721.66278337185, 24217.0, 24631.87684061568, 25063.248102556954, 25239.11663091783, 26757.331066723487], "value": 18448.465441167584, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.66914243757714, 351.29740518962075, 359.193610344776, 375.7050715188578, 376.2625254501176, 14713.632159196011, 15214.77177842332, 16721.498355049353, 19179.616407671874, 19919.0, 18557.81442185581, 20034.398968030946, 20052.39842804718, 20078.598428031466, 20105.396838094843, 5576.191452239433, 5803.6517808931385, 5809.128630705407, 5836.15375770513, 5837.562182836305, 4055.8174882130374, 4329.67527435442, 6104.664243466607, 6313.715882785285, 6419.101325814389, 6261.0, 6321.589096708699, 6403.775867844626, 6408.327125651809, 6431.549791514596, 6395.712192951305, 6427.453666438335, 6468.967655161741, 6473.417392434656, 6480.611163330168, 5402.78388864445, 5550.417206193372, 5622.240997465331, 5623.9437605623825, 5656.858578535528, 20972.659569150826, 23275.418114547272, 24693.506129877303, 24816.751832481805, 24868.75656217182, 2164.9025793839314, 2204.647256438962, 2608.699999500063, 3049.725524702765, 3072.5698402223707, 14218.573442796733, 15111.924440377838, 15113.622159445991, 15596.986195897343, 15768.684626307524, 1528.9006214596016, 1824.4253060285967, 1860.7860096088987, 1861.655593715167, 1890.2439024390217, 108915.45542272239, 108958.0, 108964.0, 109365.0, 109792.0, 10353.0, 10394.324368915995, 10431.94784026075, 10497.9475102624, 10772.407517586607, 13161.81543661075, 13401.86598134006, 13413.463461461628, 13442.529511467052, 13487.123336983064, 7200.603966781878, 8273.296769774517, 8420.62107205165, 8544.914550854537, 8585.270252028648, 8372.790680233044, 8412.537310447984, 8420.4947703138, 8574.485530868043, 8616.913830861611, 6354.966410748516, 6413.230412350548, 6485.221773387224, 6488.480921526267, 6507.934920650828, 1734.913254337278, 1795.3267524678245, 1803.4769916724265, 1804.3323970131135, 1807.1054827860294, 883.0595415882065, 917.5687426909342, 944.5891037398753, 957.2055194188806, 960.4093482508207, 20649.89675051645, 21609.67585486219, 21690.457738556663, 21724.456888577595, 21736.913154342536, 905.7192270396198, 945.1210374351781, 951.1344676344631, 953.1516949914582, 960.6589660670361, 10677.572897084185, 10866.293690910225, 11203.991640752209, 11295.378754168436, 11537.365444900443, 600692.0, 608125.0, 609595.0, 615504.0, 619276.0, 81820.0, 82781.17218827855, 108460.45769771258, 112320.87679123267, 113200.0, 871.7864123289835, 943.8914524829595, 954.1651055326589, 980.3431700760508, 983.0611765763822, 14398.568042958728, 15473.84526154724, 18865.15106820169, 19481.805181948, 20117.798822012177, 40369.99075023148, 48478.06087756591, 50608.24087638762, 50861.491385085676, 51343.74328128336, 72453.55092898218, 73200.63399682968, 74516.88224676743, 76165.23834761581, 77740.22259777556, 6457.257415397289, 7780.649870755717, 7814.609269536614, 8136.0643525995265, 8377.246047855479, 8837.0, 11068.501917413896, 12448.25310481374, 12836.229826210458, 12932.224066556037, 10594.258401911793, 10770.838437423288, 10954.233203675985, 11048.558057677603, 11165.330080195214, 10776.568937242422, 11019.0, 11027.503762330554, 11052.557897684002, 11158.995690387905, 8554.016288126944, 10746.623868164577, 10810.783784324427, 11280.548778048786, 11344.035756960751, 7656.234376562299, 7938.444308898322, 8223.136570659995, 8258.876116858371, 8355.37334699912, 10228.693139205836, 10803.351798891777, 10895.182861285277, 10933.507992140532, 11003.394813285026], "value": 9466.682460382004, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1452.8183977002875, 1483.599428154398, 1631.8857679962407, 1794.1477798045937, 1815.4644379907918, 7492.325690687837, 7775.650095745705, 8042.517448953067, 8501.48991060536, 8512.787180320494, 6681.0, 7654.42591805614, 7987.121416644164, 8242.134575869537, 8307.169283071706, 2320.5590937721863, 2340.0, 2341.5785158671433, 2341.882905854709, 2345.812335013203, 2039.5003224210088, 2322.674825524428, 2350.9882450587806, 2372.240882917467, 2378.7740164684415, 2103.2533450625037, 2134.6050980568643, 2135.3593921823467, 2135.765065842756, 2135.829133669303, 2241.1035585765662, 2314.328844635054, 2315.976840231593, 2318.9072437102495, 2324.941876453085, 2216.9224077157382, 2306.3657494189065, 2337.5324935013064, 2342.4846533762543, 2343.9179628712914, 6093.6039157454625, 7216.603086830222, 8217.30152937001, 8360.247577717973, 8373.706920257822, 5017.84946451607, 5180.533751962342, 5185.248139019859, 5187.844364669066, 5190.169572868361, 3204.070819462353, 3312.5528053712924, 3314.5359649649067, 3365.158710322419, 3453.671901169385, 980.2354163752285, 1021.8927012663638, 1045.00724311904, 1046.905778479933, 1731.740238964153, 10709.303895246783, 10987.230893837355, 11013.229073964825, 11021.889781102247, 11035.558577656884, 4273.380359847867, 4460.107978404268, 4486.282194848808, 4536.206163921366, 4551.522090180483, 8648.394612377198, 8653.610587523577, 8666.523341216167, 8682.739517814476, 8742.994555626057, 5121.974390128025, 5122.9743851281255, 5132.538071573576, 5144.099782538041, 5146.485351464824, 4678.017616300547, 4731.172044892199, 4734.73958932262, 4743.786529606176, 4804.903901921942, 3449.6550344965804, 3451.87918422854, 3497.405441075023, 3557.0929413000035, 3579.982100089483, 1082.9241953063367, 1084.1868598551087, 1085.1535802074395, 1094.7755710079414, 1098.9505472253768, 755.3730403764862, 844.6832437835811, 881.9559022048871, 890.701614958996, 903.9864402033975, 5242.7378631068295, 5478.561715062865, 5614.214010038598, 5737.655740655574, 5957.582969192202, 841.1588411588411, 965.7054598347571, 981.1513041219437, 985.1675334342469, 1007.3049595778889, 3497.5802903651725, 3591.114829033641, 3685.539307586552, 3757.7933213672964, 3782.2813665403487, 9712.125908668253, 10031.147352475122, 10049.296549241482, 10206.642767503101, 10215.387076775418, 9487.952560237154, 10064.993500649876, 10065.345752526062, 10116.988301169824, 10159.796804063877, 6598.736050557924, 7695.961520192363, 7752.457327987099, 7765.107012693499, 7767.572783496849, 5410.810621628224, 5587.189857470563, 5604.159376093618, 5638.351589567253, 5789.8263052108505, 8914.242289405214, 9168.954155229181, 9223.907760922573, 9308.95345523268, 9367.531623418938, 7654.0, 8110.229528194812, 8286.04710458305, 9433.858492122761, 9496.0, 6834.179898412222, 6901.0, 6977.162740471176, 6977.546459480118, 6985.895211571932, 6549.4760419167305, 7645.235476452533, 7838.725644602411, 7845.0585929688805, 7850.293473587404, 6908.274631163857, 6970.930290697028, 6991.755288564876, 7037.7184912602925, 7147.785566433015, 6897.241303456716, 6941.618210998444, 6956.617386043817, 6969.372756451944, 7013.964930175316, 4989.451160372429, 6348.9047664286, 6425.742970281136, 6453.548251622341, 6512.05575191604, 4699.389079419653, 4701.083288758796, 4799.904001920012, 4830.806767729251, 4843.297721830244, 4792.7363994980615, 4813.470518242921, 4866.732329721899, 4887.0, 4981.1283025469675], "value": 4322.391998058533, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.47591714499623, 306.2237228625435, 307.40977323538806, 315.56767228896416, 316.8225793555608, 5251.085647465655, 5267.155735902613, 5286.599051251412, 5291.708956007418, 5296.655717378372, 4044.61576150266, 5183.196604526295, 5216.374035115782, 5221.634485586011, 5264.763085661145, 3767.321882061221, 3916.4321173429835, 3949.940750888739, 3974.82113304902, 4043.1711499142607, 3984.940225896614, 4009.659178969791, 4017.236725022251, 4055.600817717883, 4065.715399921992, 3773.981025123215, 3943.9377441235574, 3952.0712632531477, 3995.2209319182625, 4007.799610019502, 3258.1691668624467, 3452.844621991991, 3580.874669386572, 3726.347889119393, 4010.1177740896956, 2894.3921776426973, 3500.334936362083, 3503.4919936609303, 3506.929861402783, 3510.3330367230146, 4660.135945621744, 5108.923366149511, 5121.206213036985, 5134.255532947702, 5156.510131537498, 3959.8614048508166, 4178.686598505094, 4507.377344156102, 4528.773561321921, 4586.0, 4320.71915325506, 5434.266374039533, 5437.0, 5438.525470910726, 5505.972470137664, 3286.342731453719, 3850.1337199130253, 3870.4968354114067, 3910.6284902934176, 3910.960890391088, 5250.897311564576, 5566.133441277745, 5731.656100633933, 5773.624714393551, 5837.299524057098, 3245.4807230842953, 3252.2032102135126, 3416.299658570037, 3912.315344814646, 3926.0381206604566, 3890.3775395936514, 3954.9209015819524, 3990.7206495545033, 3997.620820816872, 4006.198760247904, 4288.978555107205, 4574.81700731967, 4590.334401511762, 4593.356930029799, 4656.0, 4592.609628181575, 5168.689878607295, 5239.764210610531, 5548.334199896039, 5581.916271255935, 1152.6311580294393, 1182.1016027818748, 1208.3958020989505, 1253.655244807667, 1262.6843289177705, 789.1792535762743, 812.8211793405442, 847.9236868681849, 862.9870551941726, 867.932443094984, 4545.0, 4743.881402964885, 4984.925226121611, 5208.427073021964, 5227.843164705065, 4673.415823022122, 5645.774169033275, 5654.491095801398, 5677.744501497443, 5695.145728140812, 5041.764767631954, 5166.354205724285, 5200.869978250498, 5222.190560463135, 5247.212918062245, 2337.6610391493477, 2626.960595591068, 2731.904383346613, 2763.709810469912, 2839.886404543795, 4556.726596404226, 5280.894382112413, 5549.38956714761, 5553.584900004506, 5619.213310136584, 5049.8737531562, 5460.372057213392, 5469.0, 5473.278170843864, 5530.695811730394, 4949.901001980013, 5140.794368225229, 5544.560393426879, 5547.667139971614, 5549.417311182348, 3999.9400158958483, 4573.977130114328, 4949.876253093701, 5208.921866172086, 5350.464953504618, 4679.2045352289515, 4717.669763116549, 4911.789694637356, 5021.472745361684, 5076.162433198481, 4619.838305659286, 5455.945440545703, 5507.807226747205, 5521.4478552144465, 5574.442555744393, 3438.7420943428842, 3554.591222009502, 3569.2504574039745, 3578.284343131332, 3625.3111908737255, 4209.7895105245225, 5015.598752099896, 5213.478652134756, 5324.680519168863, 5390.380106287827, 4434.800433980543, 4896.996115796324, 4950.8762280943265, 5129.1018825692, 5163.993021360799, 4623.468301145412, 4755.5719985201495, 4980.526849949249, 5103.693778373309, 5141.588672906233, 4378.985666593403, 4744.928826067543, 4839.2983017461565, 5173.017126745906, 5191.76637051326, 4176.644985176294, 4401.449818772653, 5011.4236862761245, 5204.765785539736, 5387.568994480511, 5398.894431171768, 5422.5119739223655, 5449.918251226314, 5457.0, 5481.8753310612565], "value": 3906.862703304975, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.0415770000845, 696.5924933913661, 753.819083419979, 756.7124492692776, 788.2511613966733, 14152.858471415282, 14631.341589628466, 14693.191874446915, 14700.0, 14702.558923232293, 12811.935940320309, 12848.35758212088, 12848.93575532121, 12858.614241572745, 12917.806232906514, 652.7258551408408, 674.7705780034787, 679.6363945289271, 810.8297257575916, 834.4284165346756, 814.4624547798454, 824.7154731617583, 828.4449418889359, 831.8377916306323, 832.3507664022072, 900.3517467423445, 904.3533873280603, 905.8731777551116, 906.2342320738965, 908.1871724806327, 701.6772284748971, 717.827721346873, 865.9870101948476, 887.2369762004638, 890.3011136258039, 662.6686656671665, 667.0194813623965, 667.3126679520102, 668.381746884135, 670.2627110178813, 14255.643608909757, 14278.357473913595, 14278.714425711532, 14278.785818212735, 14282.714345713131, 11088.833667494993, 12990.935045324808, 13055.934720326433, 13205.669858253525, 13212.735745285137, 11241.0, 11783.179947921839, 11792.0, 12123.33321667308, 12170.878291217152, 4362.389265502832, 4403.8238470461465, 4439.800208990571, 4558.270676691746, 4580.450345958473, 87818.0, 89790.0, 90302.74243143843, 91295.0, 92063.0, 9786.363886347506, 10284.434356110485, 10297.30242213157, 10490.160787137163, 10741.51663175159, 20213.191472341237, 20219.0, 20223.4944126398, 20237.49406264855, 20253.797462025486, 7203.963980180066, 7400.148982866932, 7471.252874712485, 7553.622318884144, 7558.168601453835, 3970.563238043813, 4171.249175148502, 4174.165166966558, 4219.113986062961, 4332.003639162947, 3237.902862914116, 3330.8667653293596, 3336.9499457508155, 3339.7328213743326, 3342.5821772278464, 654.201873714068, 657.8355411147213, 667.4060086522999, 668.9899651505232, 703.5462126928103, 581.44472029212, 588.9793857214977, 619.8605313804358, 644.8323435906698, 646.5732616473199, 8733.0, 8798.560071996375, 9006.189442950035, 9023.278137748965, 9129.497877616663, 575.5740751843606, 641.3682522715122, 641.6438876423537, 655.8524332025352, 675.3550359406755, 4400.44994375703, 4405.867823965286, 4441.356003379557, 4452.443444569429, 4497.707649002804, 74099.0, 77939.61030194812, 106642.46678766712, 112427.0, 115682.42158789153, 75616.62191689006, 96034.0396596039, 96411.51794240985, 98088.01911980931, 98207.50896245473, 67900.0, 69011.65494172498, 72165.0, 84275.0, 88000.0, 9268.397554158959, 9539.33224674266, 9570.47362395075, 9590.66432674853, 9620.13418792284, 41522.96192595088, 49960.0, 50412.24381634205, 51795.48204518057, 52126.73936630293, 60640.0, 67199.66400167967, 73428.63285683538, 73477.53044938964, 73704.0, 11954.701132471759, 12061.758764824479, 12178.756424871275, 12268.570600028956, 12303.630891073282, 13322.933385333012, 13381.464741410624, 13591.79612305836, 13749.793753093913, 13976.371063302378, 12777.872221277668, 12824.615261542169, 13281.468741250628, 13369.933150334575, 13462.124961877838, 10459.94770026145, 10677.9466102669, 10869.67390978272, 11121.666350009513, 11243.4378281084, 10662.89337106619, 10825.729356766144, 12411.069169812119, 12519.749605008032, 13124.671883202996, 8465.153484651732, 8947.418417803083, 9064.274858011475, 9435.528223588655, 9712.902870971482, 9081.409708368936, 9607.423554586747, 9629.614815407305, 9687.951560242154, 9750.951245243728], "value": 7100.520776270997, "unit": "score"}], "name": "dromaeo_css", "value": 6966.627852798329, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 115.0, 114.0, 114.0, 122.0, 170.0, 162.0, 115.0, 114.0], "value": 125.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 142.0, 146.0, 141.0, 114.0, 110.0, 109.0, 109.0, 143.0, 141.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 262.0, 201.0, 182.0, 182.0, 187.0, 183.0, 184.0, 182.0, 184.0], "value": 194.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 58.0, 87.0, 58.0, 58.0, 59.0, 57.0, 85.0, 65.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 95.0, 69.0, 68.0, 68.0, 90.0, 67.0, 68.0, 91.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 87.0, 89.0, 83.0, 86.0, 83.0, 86.0, 83.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 110.0, 152.0, 104.0, 103.0, 151.0, 129.0, 93.0, 92.0], "value": 111.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 82.0, 80.0, 81.0, 83.0, 82.0, 83.0, 81.0, 80.0], "value": 81.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 53.0, 55.0, 58.0, 60.0, 59.0, 57.0, 57.0, 57.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 80.0, 71.0, 71.0, 70.0, 70.0, 71.0, 70.0, 105.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 93.0, 83.0, 84.0, 83.0, 83.0, 82.0, 84.0, 83.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 145.0, 152.0, 149.0, 140.0, 141.0, 144.0, 144.0, 212.0, 169.0], "value": 153.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 168.0, 164.0, 168.0, 167.0, 167.0, 149.0, 209.0, 171.0, 166.0], "value": 169.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 68.0, 64.0, 88.0, 87.0, 88.0, 73.0, 66.0], "value": 73.8, "unit": "score"}], "name": "kraken", "value": 1486.4, "alertThreshold": 2.0}]} 20:50:20 INFO - Return code: 0 20:50:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:50:20 INFO - # TBPL SUCCESS # 20:50:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:50:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:50:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:50:20 INFO - 2016-12-29 20:50:20.818 screenresolution[1804:10139] starting screenresolution argv=screenresolution get 20:50:20 INFO - 2016-12-29 20:50:20.821 screenresolution[1804:10139] Display 0: 1600x1200x32@60 20:50:20 INFO - 2016-12-29 20:50:20.827 screenresolution[1805:10142] starting screenresolution argv=screenresolution list 20:50:20 INFO - Available Modes on Display 0 20:50:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:50:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:50:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:50:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:50:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:50:21 INFO - Intel Iris: 20:50:21 INFO - Chipset Model: Intel Iris 20:50:21 INFO - Type: GPU 20:50:21 INFO - Bus: Built-In 20:50:21 INFO - VRAM (Dynamic, Max): 1536 MB 20:50:21 INFO - Vendor: Intel (0x8086) 20:50:21 INFO - Device ID: 0x0a2e 20:50:21 INFO - Revision ID: 0x0009 20:50:21 INFO - Displays: 20:50:21 INFO - Display: 20:50:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:50:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:50:21 INFO - Display Serial Number: 1600x1200 60 20:50:21 INFO - Main Display: Yes 20:50:21 INFO - Mirror: Off 20:50:21 INFO - Online: Yes 20:50:21 INFO - Rotation: Supported 20:50:21 INFO - Return code: 0 20:50:21 INFO - Running post-action listener: _resource_record_post_action 20:50:21 INFO - [mozharness: 2016-12-30 04:50:21.071486Z] Finished run-tests step (success) 20:50:21 INFO - Running post-run listener: _resource_record_post_run 20:50:21 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32381952; Write bytes: 662914560; Read time: 199; Write time: 1995 20:50:21 INFO - TinderboxPrint: CPU usage
53.5% 20:50:21 INFO - TinderboxPrint: I/O read bytes / time
32,381,952 / 199 20:50:21 INFO - TinderboxPrint: I/O write bytes / time
662,914,560 / 1,995 20:50:21 INFO - TinderboxPrint: CPU idle
2,251.8 (46.5%) 20:50:21 INFO - TinderboxPrint: CPU system
973.7 (20.1%) 20:50:21 INFO - TinderboxPrint: CPU user
1,620.0 (33.4%) 20:50:21 INFO - TinderboxPrint: Swap in / out
377,503,744 / 0 20:50:21 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345215488; Write bytes: 344966656; Read time: 27086; Write time: 749 20:50:21 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 30415872; Write bytes: 311033856; Read time: 182; Write time: 1217 20:50:21 INFO - Running post-run listener: _upload_blobber_files 20:50:21 INFO - Blob upload gear active. 20:50:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:50:21 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 20:50:21 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'] 20:50:21 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 20:50:21 INFO - (blobuploader) - INFO - Open directory for files ... 20:50:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:50:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:50:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:50:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:50:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:50:23 INFO - (blobuploader) - INFO - Done attempting. 20:50:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:50:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:50:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:50:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:50:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:50:24 INFO - (blobuploader) - INFO - Done attempting. 20:50:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:50:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:50:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:50:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:50:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:50:26 INFO - (blobuploader) - INFO - Done attempting. 20:50:26 INFO - (blobuploader) - INFO - Iteration through files over. 20:50:26 INFO - Return code: 0 20:50:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:50:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:50:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/619e7876a0bd4d76fe99b19388fb3454439c2f0e10163962d9dd0bbb7694dfa37e548b1061caa36c46a185d4449d1ed05802d3bc6002fad4e8d3e833e79da9ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38db18a5a6e636f67873a8f1f0dea797fb2d23b4161e1292a8d9c8ab46a2c806f7c5b13d9a029c57b35c6ca7de746480c172cc9a3208cd442669f4f137dbdf45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/237af93ea41cc21a404d40a2de963fd598e1eb7d09d6b375f2d5e48031c6cd3a20d072814a7999cd49cfb2a40708c5af05672de56e116d89f480b7e580e88595"} 20:50:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:50:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:50:26 INFO - Contents: 20:50:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/619e7876a0bd4d76fe99b19388fb3454439c2f0e10163962d9dd0bbb7694dfa37e548b1061caa36c46a185d4449d1ed05802d3bc6002fad4e8d3e833e79da9ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38db18a5a6e636f67873a8f1f0dea797fb2d23b4161e1292a8d9c8ab46a2c806f7c5b13d9a029c57b35c6ca7de746480c172cc9a3208cd442669f4f137dbdf45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/237af93ea41cc21a404d40a2de963fd598e1eb7d09d6b375f2d5e48031c6cd3a20d072814a7999cd49cfb2a40708c5af05672de56e116d89f480b7e580e88595"} 20:50:26 INFO - Running post-run listener: copy_logs_to_upload_dir 20:50:26 INFO - Copying logs to upload dir... 20:50:26 INFO - mkdir: /builds/slave/test/build/upload/logs 20:50:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.181078 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-29 20:50:26.332401) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 20:50:26.335955) ========= 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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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/619e7876a0bd4d76fe99b19388fb3454439c2f0e10163962d9dd0bbb7694dfa37e548b1061caa36c46a185d4449d1ed05802d3bc6002fad4e8d3e833e79da9ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38db18a5a6e636f67873a8f1f0dea797fb2d23b4161e1292a8d9c8ab46a2c806f7c5b13d9a029c57b35c6ca7de746480c172cc9a3208cd442669f4f137dbdf45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/237af93ea41cc21a404d40a2de963fd598e1eb7d09d6b375f2d5e48031c6cd3a20d072814a7999cd49cfb2a40708c5af05672de56e116d89f480b7e580e88595"} build_url:https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011246 build_url: 'https://queue.taskcluster.net/v1/task/BLt0VUvDQN6o1oPJYFOdKA/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/619e7876a0bd4d76fe99b19388fb3454439c2f0e10163962d9dd0bbb7694dfa37e548b1061caa36c46a185d4449d1ed05802d3bc6002fad4e8d3e833e79da9ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38db18a5a6e636f67873a8f1f0dea797fb2d23b4161e1292a8d9c8ab46a2c806f7c5b13d9a029c57b35c6ca7de746480c172cc9a3208cd442669f4f137dbdf45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/237af93ea41cc21a404d40a2de963fd598e1eb7d09d6b375f2d5e48031c6cd3a20d072814a7999cd49cfb2a40708c5af05672de56e116d89f480b7e580e88595"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 20:50:26.370698) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 20:50:26.371031) ========= 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.d8PdnjwkW8/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.rNu3tqRKQ2/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.005119 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 20:50:26.421140) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 20:50:26.421699) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 20:50:26.422262) ========= ========= Total master_lag: 0.68 =========