builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0293 starttime: 1483993389.93 results: success (0) buildid: 20170109105308 builduid: 756eba2d5ee24e2f89eae24097c91f4a revision: 1f3bb12e7ea66181f264cb8f5f4ab125f6b56fbf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:09.929836) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:09.930466) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:09.930751) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:09.996506) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:09.996804) ========= 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.bZBpBCFdYz/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.XqCdHarHCS/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-09 12:23:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2017-01-09 12:23:10 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.154848 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:10.181556) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:10.181838) ========= 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.bZBpBCFdYz/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.XqCdHarHCS/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020197 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:10.250443) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 12:23:10.250752) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1f3bb12e7ea66181f264cb8f5f4ab125f6b56fbf --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 1f3bb12e7ea66181f264cb8f5f4ab125f6b56fbf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bZBpBCFdYz/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.XqCdHarHCS/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-09 12:23:10,329 truncating revision to first 12 chars 2017-01-09 12:23:10,330 Setting DEBUG logging. 2017-01-09 12:23:10,330 attempt 1/10 2017-01-09 12:23:10,330 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1f3bb12e7ea6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 12:23:11,313 unpacking tar archive at: mozilla-aurora-1f3bb12e7ea6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.248111 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 12:23:11.525857) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:11.526608) ========= script_repo_revision: 1f3bb12e7ea66181f264cb8f5f4ab125f6b56fbf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:11.527525) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:11.528235) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:11.550119) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-09 12:23:11.550751) ========= /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.bZBpBCFdYz/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.XqCdHarHCS/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:23:11 INFO - MultiFileLogger online at 20170109 12:23:11 in /builds/slave/test 12:23:11 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 12:23:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:23:11 INFO - {'append_to_log': False, 12:23:11 INFO - 'base_work_dir': '/builds/slave/test', 12:23:11 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:23:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:23:11 INFO - 'branch': 'Mozilla-Aurora', 12:23:11 INFO - 'buildbot_json_path': 'buildprops.json', 12:23:11 INFO - 'config_files': ('talos/mac_config.py',), 12:23:11 INFO - 'default_actions': ('clobber', 12:23:11 INFO - 'read-buildbot-config', 12:23:11 INFO - 'download-and-extract', 12:23:11 INFO - 'populate-webroot', 12:23:11 INFO - 'create-virtualenv', 12:23:11 INFO - 'install', 12:23:11 INFO - 'run-tests'), 12:23:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:23:11 INFO - 'download_minidump_stackwalk': True, 12:23:11 INFO - 'download_symbols': 'ondemand', 12:23:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:23:11 INFO - 'tooltool.py': '/tools/tooltool.py', 12:23:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:23:11 INFO - '/tools/misc-python/virtualenv.py')}, 12:23:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:23:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:23:11 INFO - 'installer_path': 'installer.exe', 12:23:11 INFO - 'log_level': 'info', 12:23:11 INFO - 'log_name': 'talos', 12:23:11 INFO - 'log_to_console': True, 12:23:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:23:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:23:11 INFO - 'opt_config_files': (), 12:23:11 INFO - 'pip_index': False, 12:23:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:23:11 INFO - 'cmd': ('bash', 12:23:11 INFO - '-c', 12:23:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:23:11 INFO - 'enabled': True, 12:23:11 INFO - 'halt_on_failure': False, 12:23:11 INFO - 'name': 'check_screen_resolution'},), 12:23:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:23:11 INFO - 'cmd': ('bash', 12:23:11 INFO - '-c', 12:23:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:23:11 INFO - 'enabled': True, 12:23:11 INFO - 'halt_on_failure': False, 12:23:11 INFO - 'name': 'check_screen_resolution'},), 12:23:11 INFO - 'run_cmd_checks_enabled': True, 12:23:11 INFO - 'sps_profile': False, 12:23:11 INFO - 'sps_profile_interval': 0, 12:23:11 INFO - 'suite': 'dromaeojs', 12:23:11 INFO - 'system_bits': '32', 12:23:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:23:11 INFO - 'title': 't-yosemite-r7-0293', 12:23:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:23:11 INFO - 'use_talos_json': True, 12:23:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:23:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:23:11 INFO - 'work_dir': 'build'} 12:23:11 INFO - [mozharness: 2017-01-09 20:23:11.748588Z] Running clobber step. 12:23:11 INFO - Running pre-action listener: _resource_record_pre_action 12:23:11 INFO - Running main action method: clobber 12:23:11 INFO - rmtree: /builds/slave/test/build 12:23:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:23:12 INFO - Running post-action listener: _resource_record_post_action 12:23:12 INFO - [mozharness: 2017-01-09 20:23:12.103911Z] Finished clobber step (success) 12:23:12 INFO - [mozharness: 2017-01-09 20:23:12.104068Z] Running read-buildbot-config step. 12:23:12 INFO - Running pre-action listener: _resource_record_pre_action 12:23:12 INFO - Running main action method: read_buildbot_config 12:23:12 INFO - Using buildbot properties: 12:23:12 INFO - { 12:23:12 INFO - "project": "", 12:23:12 INFO - "product": "firefox", 12:23:12 INFO - "script_repo_revision": "production", 12:23:12 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:23:12 INFO - "repository": "", 12:23:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:23:12 INFO - "buildid": "20170109105308", 12:23:12 INFO - "pgo_build": "False", 12:23:12 INFO - "basedir": "/builds/slave/test", 12:23:12 INFO - "buildnumber": 58, 12:23:12 INFO - "slavename": "t-yosemite-r7-0293", 12:23:12 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:23:12 INFO - "platform": "yosemite_r7", 12:23:12 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:23:12 INFO - "branch": "mozilla-aurora", 12:23:12 INFO - "repo_path": "releases/mozilla-aurora", 12:23:12 INFO - "revision": "1f3bb12e7ea66181f264cb8f5f4ab125f6b56fbf", 12:23:12 INFO - "stage_platform": "macosx64", 12:23:12 INFO - "builduid": "756eba2d5ee24e2f89eae24097c91f4a", 12:23:12 INFO - "slavebuilddir": "test" 12:23:12 INFO - } 12:23:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 12:23:12 INFO - Running post-action listener: _resource_record_post_action 12:23:12 INFO - [mozharness: 2017-01-09 20:23:12.107293Z] Finished read-buildbot-config step (success) 12:23:12 INFO - [mozharness: 2017-01-09 20:23:12.107425Z] Running download-and-extract step. 12:23:12 INFO - Running pre-action listener: _resource_record_pre_action 12:23:12 INFO - Running main action method: download_and_extract 12:23:12 INFO - mkdir: /builds/slave/test/build/tests 12:23: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')]} 12:23:12 INFO - trying https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 12:23:12 INFO - Downloading https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/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 12:23:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/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 12:23:13 INFO - Downloaded 1199 bytes. 12:23:13 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 12:23:13 INFO - Using the following test package requirements: 12:23:13 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:23:13 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:13 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 12:23:13 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:13 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 12:23:13 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:13 INFO - u'jsshell-mac.zip'], 12:23:13 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:13 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 12:23:13 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:23:13 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:13 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 12:23:13 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:13 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 12:23:13 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:13 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 12:23:13 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:13 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 12:23:13 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:23:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:23:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:23:13 INFO - Fetch https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:23:14 INFO - Http code: 200 12:23:14 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 12:23:14 INFO - x-amz-cf-id: rFYF74BAPTq3k09HGMyUPox-VfmjiaGMDBv32Rb0zDYwcHBwkw9EJA== 12:23:14 INFO - x-amz-version-id: AKwtWn1UBDH.BkbjT7hj3yOr830WJ_71 12:23:14 INFO - Expected file size: 16270844 12:23:14 INFO - Obtained file size: 16270844 12:23: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 12:23:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:23:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:23:21 INFO - Fetch https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:23:21 INFO - Http code: 200 12:23:21 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 12:23:21 INFO - x-amz-cf-id: HQRFqzv889MIFo4ImGkOQwDeQ9QJqYmvo1_NiavjRgqRzE0yDI31SQ== 12:23:21 INFO - x-amz-version-id: AKwtWn1UBDH.BkbjT7hj3yOr830WJ_71 12:23:21 INFO - Expected file size: 16270844 12:23:21 INFO - Obtained file size: 16270844 12:23:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 12:23:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:23:28 INFO - Fetch https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 12:23:28 INFO - Http code: 200 12:23:28 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 12:23:28 INFO - x-amz-cf-id: QVtfMhBrs8YDOh_hdZPUs7nI7DtX3fq-8MMOMsrL8qJ95bZjZOaixw== 12:23:28 INFO - x-amz-version-id: hj3QKO1nC8qE1wZv9RflNYOciDzr3Dmf 12:23:29 INFO - Expected file size: 14409038 12:23:29 INFO - Obtained file size: 14409038 12:23: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')]} 12:23:29 INFO - trying https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:23:29 INFO - Downloading https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 12:23:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/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 12:23:32 INFO - Downloaded 109876819 bytes. 12:23:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:23:32 INFO - mkdir: /builds/slave/test/properties 12:23:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:23:32 INFO - Writing to file /builds/slave/test/properties/build_url 12:23:32 INFO - Contents: 12:23:32 INFO - build_url:https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:23:33 INFO - Running post-action listener: _resource_record_post_action 12:23:33 INFO - Running post-action listener: set_extra_try_arguments 12:23:33 INFO - [mozharness: 2017-01-09 20:23:33.189058Z] Finished download-and-extract step (success) 12:23:33 INFO - [mozharness: 2017-01-09 20:23:33.189200Z] Running populate-webroot step. 12:23:33 INFO - Running pre-action listener: _resource_record_pre_action 12:23:33 INFO - Running main action method: populate_webroot 12:23:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:23:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:23:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:23:33 INFO - '--tppagecycles', 12:23:33 INFO - '7'], 12:23:33 INFO - 'tests': ['tsvgm']}}, 12:23:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:23:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:23:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:23:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:23:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:23:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:23:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:33 INFO - 'talos_options': ['--disable-e10s'], 12:23:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:33 INFO - 'talos_options': ['--disable-e10s'], 12:23:33 INFO - 'tests': ['damp', 'tps']}, 12:23:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:33 INFO - 'tests': ['damp', 'tps']}, 12:23:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:23:33 INFO - 'tests': ['dromaeo_dom']}, 12:23:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:23:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:23:33 INFO - 'tests': ['basic_compositor_video']}, 12:23:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:23:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:23:33 INFO - 'tests': ['a11yr', 12:23:33 INFO - 'ts_paint', 12:23:33 INFO - 'tpaint', 12:23:33 INFO - 'sessionrestore', 12:23:33 INFO - 'sessionrestore_no_auto_restore', 12:23:33 INFO - 'tabpaint']}, 12:23:33 INFO - 'other-e10s': {'tests': ['a11yr', 12:23:33 INFO - 'ts_paint', 12:23:33 INFO - 'tpaint', 12:23:33 INFO - 'sessionrestore', 12:23:33 INFO - 'sessionrestore_no_auto_restore', 12:23:33 INFO - 'tabpaint']}, 12:23:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:23:33 INFO - 'ts_paint', 12:23:33 INFO - 'tpaint', 12:23:33 INFO - 'sessionrestore', 12:23:33 INFO - 'sessionrestore_no_auto_restore', 12:23:33 INFO - 'tabpaint']}, 12:23:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:23:33 INFO - 'ts_paint', 12:23:33 INFO - 'tpaint', 12:23:33 INFO - 'sessionrestore', 12:23:33 INFO - 'sessionrestore_no_auto_restore', 12:23:33 INFO - 'tabpaint']}, 12:23:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:23:33 INFO - 'tests': ['a11yr', 12:23:33 INFO - 'ts_paint', 12:23:33 INFO - 'tpaint', 12:23:33 INFO - 'sessionrestore', 12:23:33 INFO - 'sessionrestore_no_auto_restore', 12:23:33 INFO - 'tabpaint']}, 12:23:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:23:33 INFO - 'tests': ['a11yr', 12:23:33 INFO - 'ts_paint', 12:23:33 INFO - 'tpaint', 12:23:33 INFO - 'sessionrestore', 12:23:33 INFO - 'sessionrestore_no_auto_restore', 12:23:33 INFO - 'tabpaint']}, 12:23:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:23:33 INFO - 'tests': ['tsvgx', 12:23:33 INFO - 'tsvgr_opacity', 12:23:33 INFO - 'tart', 12:23:33 INFO - 'tscrollx', 12:23:33 INFO - 'cart']}, 12:23:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:23:33 INFO - 'tsvgr_opacity', 12:23:33 INFO - 'tart', 12:23:33 INFO - 'tscrollx', 12:23:33 INFO - 'cart']}, 12:23:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:33 INFO - 'talos_options': ['--disable-e10s'], 12:23:33 INFO - 'tests': ['tp5o']}, 12:23:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:33 INFO - 'tests': ['tp5o']}, 12:23:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:33 INFO - 'talos_options': ['--disable-e10s', 12:23:33 INFO - '--xperf_path', 12:23:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:33 INFO - 'tests': ['tp5n']}, 12:23:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:33 INFO - 'talos_options': ['--xperf_path', 12:23:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:33 INFO - 'tests': ['tp5n']}}, 12:23:33 INFO - 'talos.zip': {'path': '', 12:23:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:23:33 INFO - Running post-action listener: _resource_record_post_action 12:23:33 INFO - [mozharness: 2017-01-09 20:23:33.207854Z] Finished populate-webroot step (success) 12:23:33 INFO - [mozharness: 2017-01-09 20:23:33.207990Z] Running create-virtualenv step. 12:23:33 INFO - Running pre-action listener: _resource_record_pre_action 12:23:33 INFO - Running main action method: create_virtualenv 12:23:33 INFO - Creating virtualenv /builds/slave/test/build/venv 12:23: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 12:23:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:23:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:23:33 INFO - Using real prefix '/tools/python27' 12:23:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:23:34 INFO - Installing distribute.............................................................................................................................................................................................done. 12:23:36 INFO - Installing pip.................done. 12:23:36 INFO - Return code: 0 12:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:23:37 INFO - Reading from file tmpfile_stdout 12:23:37 INFO - Output received: 12:23: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) 12:23:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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 0x10f6d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f64d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b05a45b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4ebd30>, '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 0x10f351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4e3b20>, '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.bZBpBCFdYz/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.XqCdHarHCS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:23: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 12:23: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 12:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZBpBCFdYz/Render', 12:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:37 INFO - 'HOME': '/Users/cltbld', 12:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:37 INFO - 'LOGNAME': 'cltbld', 12:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:37 INFO - 'MOZ_NO_REMOTE': '1', 12:23:37 INFO - 'NO_EM_RESTART': '1', 12:23:37 INFO - 'PAGER': '/bin/cat', 12:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:37 INFO - 'PWD': '/builds/slave/test', 12:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:37 INFO - 'SHELL': '/bin/bash', 12:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XqCdHarHCS/Listeners', 12:23:37 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 12:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:37 INFO - 'USER': 'cltbld', 12:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:37 INFO - 'XPC_FLAGS': '0x0', 12:23:37 INFO - 'XPC_SERVICE_NAME': '0', 12:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23: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 12:23:37 INFO - Cleaning up... 12:23:37 INFO - Return code: 0 12:23:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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 0x10f6d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f64d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b05a45b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4ebd30>, '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 0x10f351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4e3b20>, '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.bZBpBCFdYz/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.XqCdHarHCS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:23: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 12:23: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 12:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZBpBCFdYz/Render', 12:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:37 INFO - 'HOME': '/Users/cltbld', 12:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:37 INFO - 'LOGNAME': 'cltbld', 12:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:37 INFO - 'MOZ_NO_REMOTE': '1', 12:23:37 INFO - 'NO_EM_RESTART': '1', 12:23:37 INFO - 'PAGER': '/bin/cat', 12:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:37 INFO - 'PWD': '/builds/slave/test', 12:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:37 INFO - 'SHELL': '/bin/bash', 12:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XqCdHarHCS/Listeners', 12:23:37 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 12:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:37 INFO - 'USER': 'cltbld', 12:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:37 INFO - 'XPC_FLAGS': '0x0', 12:23:37 INFO - 'XPC_SERVICE_NAME': '0', 12:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:37 INFO - Downloading/unpacking psutil>=3.1.1 12:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:23:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:41 INFO - Installing collected packages: psutil 12:23:41 INFO - Running setup.py install for psutil 12:23:41 INFO - building 'psutil._psutil_osx' extension 12:23: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 12:23: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 12:23: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 12:23: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 12:23:42 INFO - building 'psutil._psutil_posix' extension 12:23: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 12:23:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:23:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:23:42 INFO - ^ 12:23:42 INFO - 1 warning generated. 12:23: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 12:23:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:42 INFO - Successfully installed psutil 12:23:42 INFO - Cleaning up... 12:23:42 INFO - Return code: 0 12:23:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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 0x10f6d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f64d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b05a45b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4ebd30>, '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 0x10f351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4e3b20>, '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.bZBpBCFdYz/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.XqCdHarHCS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:23: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 12:23: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 12:23:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZBpBCFdYz/Render', 12:23:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:42 INFO - 'HOME': '/Users/cltbld', 12:23:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:42 INFO - 'LOGNAME': 'cltbld', 12:23:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:42 INFO - 'MOZ_NO_REMOTE': '1', 12:23:42 INFO - 'NO_EM_RESTART': '1', 12:23:42 INFO - 'PAGER': '/bin/cat', 12:23:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:42 INFO - 'PWD': '/builds/slave/test', 12:23:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:42 INFO - 'SHELL': '/bin/bash', 12:23:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XqCdHarHCS/Listeners', 12:23:42 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 12:23:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:42 INFO - 'USER': 'cltbld', 12:23:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:42 INFO - 'XPC_FLAGS': '0x0', 12:23:42 INFO - 'XPC_SERVICE_NAME': '0', 12:23:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:23:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:23:45 INFO - Installing collected packages: mozsystemmonitor 12:23:45 INFO - Running setup.py install for mozsystemmonitor 12:23:45 INFO - Successfully installed mozsystemmonitor 12:23:45 INFO - Cleaning up... 12:23:45 INFO - Return code: 0 12:23:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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 0x10f6d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f64d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b05a45b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4ebd30>, '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 0x10f351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4e3b20>, '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.bZBpBCFdYz/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.XqCdHarHCS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:23: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 12:23: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 12:23:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZBpBCFdYz/Render', 12:23:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:45 INFO - 'HOME': '/Users/cltbld', 12:23:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:45 INFO - 'LOGNAME': 'cltbld', 12:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:45 INFO - 'MOZ_NO_REMOTE': '1', 12:23:45 INFO - 'NO_EM_RESTART': '1', 12:23:45 INFO - 'PAGER': '/bin/cat', 12:23:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:45 INFO - 'PWD': '/builds/slave/test', 12:23:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:45 INFO - 'SHELL': '/bin/bash', 12:23:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XqCdHarHCS/Listeners', 12:23:45 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 12:23:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:45 INFO - 'USER': 'cltbld', 12:23:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:45 INFO - 'XPC_FLAGS': '0x0', 12:23:45 INFO - 'XPC_SERVICE_NAME': '0', 12:23:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:46 INFO - Downloading/unpacking jsonschema==2.5.1 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:23:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23:49 INFO - Downloading functools32-3.2.3-2.tar.gz 12:23:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:23:49 INFO - warning: no files found matching '*.txt' 12:23:49 INFO - no previously-included directories found matching 'build' 12:23:49 INFO - no previously-included directories found matching 'dist' 12:23:49 INFO - no previously-included directories found matching '.git*' 12:23:49 INFO - Installing collected packages: jsonschema, functools32 12:23:49 INFO - Running setup.py install for functools32 12:23:49 INFO - warning: no files found matching '*.txt' 12:23:49 INFO - no previously-included directories found matching 'build' 12:23:49 INFO - no previously-included directories found matching 'dist' 12:23:49 INFO - no previously-included directories found matching '.git*' 12:23:49 INFO - Successfully installed jsonschema functools32 12:23:49 INFO - Cleaning up... 12:23:49 INFO - Return code: 0 12:23:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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 0x10f6d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f64d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b05a45b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4ebd30>, '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 0x10f351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4e3b20>, '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.bZBpBCFdYz/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.XqCdHarHCS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:23: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 12:23: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 12:23:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZBpBCFdYz/Render', 12:23:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:49 INFO - 'HOME': '/Users/cltbld', 12:23:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:49 INFO - 'LOGNAME': 'cltbld', 12:23:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:49 INFO - 'MOZ_NO_REMOTE': '1', 12:23:49 INFO - 'NO_EM_RESTART': '1', 12:23:49 INFO - 'PAGER': '/bin/cat', 12:23:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:49 INFO - 'PWD': '/builds/slave/test', 12:23:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:49 INFO - 'SHELL': '/bin/bash', 12:23:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XqCdHarHCS/Listeners', 12:23:49 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 12:23:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:49 INFO - 'USER': 'cltbld', 12:23:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:49 INFO - 'XPC_FLAGS': '0x0', 12:23:49 INFO - 'XPC_SERVICE_NAME': '0', 12:23:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:23:49 INFO - Cleaning up... 12:23:49 INFO - Return code: 0 12:23:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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 0x10f6d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f64d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b05a45b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4ebd30>, '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 0x10f351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4e3b20>, '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.bZBpBCFdYz/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.XqCdHarHCS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:23: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 12:23: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 12:23:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZBpBCFdYz/Render', 12:23:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:49 INFO - 'HOME': '/Users/cltbld', 12:23:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:49 INFO - 'LOGNAME': 'cltbld', 12:23:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:49 INFO - 'MOZ_NO_REMOTE': '1', 12:23:49 INFO - 'NO_EM_RESTART': '1', 12:23:49 INFO - 'PAGER': '/bin/cat', 12:23:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:49 INFO - 'PWD': '/builds/slave/test', 12:23:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:49 INFO - 'SHELL': '/bin/bash', 12:23:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XqCdHarHCS/Listeners', 12:23:49 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 12:23:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:49 INFO - 'USER': 'cltbld', 12:23:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:49 INFO - 'XPC_FLAGS': '0x0', 12:23:49 INFO - 'XPC_SERVICE_NAME': '0', 12:23:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:50 INFO - Downloading/unpacking blobuploader==1.2.4 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23:52 INFO - Downloading blobuploader-1.2.4.tar.gz 12:23:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:23:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:23:52 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 12:23:52 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 12:23:52 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 12:23:52 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 12:23:52 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 12:23:52 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 12:23:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:23:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23:53 INFO - Downloading docopt-0.6.1.tar.gz 12:23:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:23:54 INFO - Installing collected packages: blobuploader, requests, docopt 12:23:54 INFO - Running setup.py install for blobuploader 12:23:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:23:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:23:54 INFO - Running setup.py install for requests 12:23:54 INFO - Running setup.py install for docopt 12:23:54 INFO - Successfully installed blobuploader requests docopt 12:23:54 INFO - Cleaning up... 12:23:54 INFO - Return code: 0 12:23:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:54 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')]} 12:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:54 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')]} 12:23:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:54 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 0x10f6d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f64d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b05a45b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4ebd30>, '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 0x10f351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4e3b20>, '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.bZBpBCFdYz/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.XqCdHarHCS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:23:54 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 12:23:54 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 12:23:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZBpBCFdYz/Render', 12:23:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:54 INFO - 'HOME': '/Users/cltbld', 12:23:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:54 INFO - 'LOGNAME': 'cltbld', 12:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:54 INFO - 'MOZ_NO_REMOTE': '1', 12:23:54 INFO - 'NO_EM_RESTART': '1', 12:23:54 INFO - 'PAGER': '/bin/cat', 12:23:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:54 INFO - 'PWD': '/builds/slave/test', 12:23:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:54 INFO - 'SHELL': '/bin/bash', 12:23:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XqCdHarHCS/Listeners', 12:23:54 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 12:23:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:54 INFO - 'USER': 'cltbld', 12:23:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:54 INFO - 'XPC_FLAGS': '0x0', 12:23:54 INFO - 'XPC_SERVICE_NAME': '0', 12:23:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:55 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-4ZzKjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:23:55 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-l72Mn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:23:55 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-KQFaIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:23:55 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-tI4J9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:23:55 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-9Pukrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:23:55 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-GqD2iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:23:55 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-faLc_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:23:55 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-2gLF8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:23:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-AWf3fU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:23:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-cO_B27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:23:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-AoxF1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:23:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-q3kHxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:23:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-RAYXVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:23:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-MKR__a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-CV5n7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:23:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-epZL8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:23:56 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-lSjhZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:23:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:23:57 INFO - Running setup.py install for manifestparser 12:23:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:23:57 INFO - Running setup.py install for mozcrash 12:23:57 INFO - Running setup.py install for mozdebug 12:23:57 INFO - Running setup.py install for mozdevice 12:23:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:23:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:23:57 INFO - Running setup.py install for mozfile 12:23:57 INFO - Running setup.py install for mozhttpd 12:23:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:23:57 INFO - Running setup.py install for mozinfo 12:23:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:23:58 INFO - Running setup.py install for mozInstall 12:23:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:23:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:23:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:23:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:23:58 INFO - Running setup.py install for mozleak 12:23:58 INFO - Running setup.py install for mozlog 12:23:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:23:58 INFO - Running setup.py install for moznetwork 12:23:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:23:58 INFO - Running setup.py install for mozprocess 12:23:58 INFO - Running setup.py install for mozprofile 12:23:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Running setup.py install for mozrunner 12:23:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Running setup.py install for mozscreenshot 12:23:59 INFO - Running setup.py install for moztest 12:23:59 INFO - Running setup.py install for mozversion 12:23:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:23:59 INFO - Cleaning up... 12:23:59 INFO - Return code: 0 12:23:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:59 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')]} 12:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:59 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')]} 12:23:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:59 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 0x10f6d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f64d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b05a45b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4ebd30>, '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 0x10f351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4e3b20>, '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.bZBpBCFdYz/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.XqCdHarHCS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:23:59 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 12:23:59 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 12:23:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZBpBCFdYz/Render', 12:23:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:59 INFO - 'HOME': '/Users/cltbld', 12:23:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:59 INFO - 'LOGNAME': 'cltbld', 12:23:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:59 INFO - 'MOZ_NO_REMOTE': '1', 12:23:59 INFO - 'NO_EM_RESTART': '1', 12:23:59 INFO - 'PAGER': '/bin/cat', 12:23:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:59 INFO - 'PWD': '/builds/slave/test', 12:23:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:59 INFO - 'SHELL': '/bin/bash', 12:23:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XqCdHarHCS/Listeners', 12:23:59 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 12:23:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:59 INFO - 'USER': 'cltbld', 12:23:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:59 INFO - 'XPC_FLAGS': '0x0', 12:23:59 INFO - 'XPC_SERVICE_NAME': '0', 12:23:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:59 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-UPPObO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:24: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)) 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:24:00 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-DahXV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:24: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)) 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:24:00 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-XzMmbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:24: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)) 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:24:00 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-zkUldp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:24: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)) 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:24:00 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-pLBzGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:24: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)) 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:24:00 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-anVHPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:24: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)) 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:24:00 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-SwiqRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:24:00 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)) 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:24:00 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-x1zzdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:24:00 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)) 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:24:00 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Ag63Ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:24:00 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)) 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:24:00 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-CvWjDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:24: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)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:24:01 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-bJPgNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:24: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)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:24:01 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-cF5FXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:24: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)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:24:01 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ZUHWDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:24: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)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:24:01 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-rCSXam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:24: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)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:24:01 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-OPjsJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:24: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)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:24:01 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-U7Myqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:24:01 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-qITxGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:24:01 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)) 12:24:01 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)) 12:24:01 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)) 12:24:01 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)) 12:24:01 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)) 12:24:01 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)) 12:24:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:24:01 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 12:24:01 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 12:24:01 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 12:24:01 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 12:24:01 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 12:24:01 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 12:24:04 INFO - Downloading blessings-1.6.tar.gz 12:24:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:24:04 INFO - Installing collected packages: blessings 12:24:04 INFO - Running setup.py install for blessings 12:24:04 INFO - Successfully installed blessings 12:24:04 INFO - Cleaning up... 12:24:04 INFO - Return code: 0 12:24:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:24:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:24:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:24:05 INFO - Reading from file tmpfile_stdout 12:24:05 INFO - Current package versions: 12:24:05 INFO - blessings == 1.6 12:24:05 INFO - blobuploader == 1.2.4 12:24:05 INFO - docopt == 0.6.1 12:24:05 INFO - functools32 == 3.2.3-2 12:24:05 INFO - jsonschema == 2.5.1 12:24:05 INFO - manifestparser == 1.1 12:24:05 INFO - mozInstall == 1.12 12:24:05 INFO - mozcrash == 1.0 12:24:05 INFO - mozdebug == 0.1 12:24:05 INFO - mozdevice == 0.48 12:24:05 INFO - mozfile == 1.2 12:24:05 INFO - mozhttpd == 0.7 12:24:05 INFO - mozinfo == 0.9 12:24:05 INFO - mozleak == 0.1 12:24:05 INFO - mozlog == 3.4 12:24:05 INFO - moznetwork == 0.27 12:24:05 INFO - mozprocess == 0.23 12:24:05 INFO - mozprofile == 0.28 12:24:05 INFO - mozrunner == 6.13 12:24:05 INFO - mozscreenshot == 0.1 12:24:05 INFO - mozsystemmonitor == 0.3 12:24:05 INFO - moztest == 0.8 12:24:05 INFO - mozversion == 1.4 12:24:05 INFO - psutil == 3.1.1 12:24:05 INFO - requests == 1.2.3 12:24:05 INFO - wsgiref == 0.1.2 12:24:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:24:05 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')]} 12:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:05 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')]} 12:24:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:05 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 0x10f6d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f64d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b05a45b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4ebd30>, '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 0x10f351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4e3b20>, '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.bZBpBCFdYz/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.XqCdHarHCS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:24:05 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 12:24:05 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 12:24:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZBpBCFdYz/Render', 12:24:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:24:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:24:05 INFO - 'HOME': '/Users/cltbld', 12:24:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:24:05 INFO - 'LOGNAME': 'cltbld', 12:24:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:05 INFO - 'MOZ_NO_REMOTE': '1', 12:24:05 INFO - 'NO_EM_RESTART': '1', 12:24:05 INFO - 'PAGER': '/bin/cat', 12:24:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:24:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:05 INFO - 'PWD': '/builds/slave/test', 12:24:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:24:05 INFO - 'SHELL': '/bin/bash', 12:24:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XqCdHarHCS/Listeners', 12:24:05 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 12:24:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:24:05 INFO - 'USER': 'cltbld', 12:24:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:24:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:24:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:05 INFO - 'XPC_FLAGS': '0x0', 12:24:05 INFO - 'XPC_SERVICE_NAME': '0', 12:24:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:24:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:05 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)) 12:24:05 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)) 12:24:05 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)) 12:24:05 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)) 12:24:05 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)) 12:24:05 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)) 12:24:05 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)) 12:24:05 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)) 12:24:05 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)) 12:24:05 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)) 12:24:05 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)) 12:24:05 INFO - Cleaning up... 12:24:05 INFO - Return code: 0 12:24:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:24:05 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')]} 12:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:05 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')]} 12:24:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:05 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 0x10f6d7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f64d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b05a45b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4ebd30>, '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 0x10f351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4e3b20>, '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.bZBpBCFdYz/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.XqCdHarHCS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:24:05 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 12:24:05 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 12:24:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZBpBCFdYz/Render', 12:24:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:24:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:24:05 INFO - 'HOME': '/Users/cltbld', 12:24:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:24:05 INFO - 'LOGNAME': 'cltbld', 12:24:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:05 INFO - 'MOZ_NO_REMOTE': '1', 12:24:05 INFO - 'NO_EM_RESTART': '1', 12:24:05 INFO - 'PAGER': '/bin/cat', 12:24:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:24:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:05 INFO - 'PWD': '/builds/slave/test', 12:24:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:24:05 INFO - 'SHELL': '/bin/bash', 12:24:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XqCdHarHCS/Listeners', 12:24:05 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 12:24:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:24:05 INFO - 'USER': 'cltbld', 12:24:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:24:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:24:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:05 INFO - 'XPC_FLAGS': '0x0', 12:24:05 INFO - 'XPC_SERVICE_NAME': '0', 12:24:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:24:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:24:05 INFO - Cleaning up... 12:24:05 INFO - Return code: 0 12:24:05 INFO - Running post-action listener: _resource_record_post_action 12:24:05 INFO - Running post-action listener: _start_resource_monitoring 12:24:05 INFO - Starting resource monitoring. 12:24:05 INFO - [mozharness: 2017-01-09 20:24:05.688433Z] Finished create-virtualenv step (success) 12:24:05 INFO - [mozharness: 2017-01-09 20:24:05.689525Z] Running install step. 12:24:05 INFO - Running pre-action listener: _resource_record_pre_action 12:24:05 INFO - Running main action method: install 12:24:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:24:05 INFO - Reading from file tmpfile_stdout 12:24:05 INFO - Detecting whether we're running mozinstall >=1.0... 12:24:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:24:06 INFO - Reading from file tmpfile_stdout 12:24:06 INFO - Output received: 12:24:06 INFO - Usage: mozinstall [options] installer 12:24:06 INFO - Options: 12:24:06 INFO - -h, --help show this help message and exit 12:24:06 INFO - -d DEST, --destination=DEST 12:24:06 INFO - Directory to install application into. [default: 12:24:06 INFO - "/builds/slave/test"] 12:24:06 INFO - --app=APP Application being installed. [default: firefox] 12:24:06 INFO - mkdir: /builds/slave/test/build/application 12:24:06 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'] 12:24:06 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 12:24:37 INFO - Reading from file tmpfile_stdout 12:24:37 INFO - Output received: 12:24:37 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:24:37 INFO - Running post-action listener: _resource_record_post_action 12:24:37 INFO - [mozharness: 2017-01-09 20:24:37.382761Z] Finished install step (success) 12:24:37 INFO - [mozharness: 2017-01-09 20:24:37.382913Z] Running run-tests step. 12:24:37 INFO - Running pre-action listener: _resource_record_pre_action 12:24:37 INFO - Running main action method: run_tests 12:24:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:24:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:24:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:24:37 INFO - 2017-01-09 12:24:37.397 screenresolution[2585:295323] starting screenresolution argv=screenresolution get 12:24:37 INFO - 2017-01-09 12:24:37.400 screenresolution[2585:295323] Display 0: 1600x1200x32@60 12:24:37 INFO - 2017-01-09 12:24:37.406 screenresolution[2586:295325] starting screenresolution argv=screenresolution list 12:24:37 INFO - Available Modes on Display 0 12:24:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:24:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:24:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:24:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:24:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:24:37 INFO - Intel Iris: 12:24:37 INFO - Chipset Model: Intel Iris 12:24:37 INFO - Type: GPU 12:24:37 INFO - Bus: Built-In 12:24:37 INFO - VRAM (Dynamic, Max): 1536 MB 12:24:37 INFO - Vendor: Intel (0x8086) 12:24:37 INFO - Device ID: 0x0a2e 12:24:37 INFO - Revision ID: 0x0009 12:24:37 INFO - Displays: 12:24:37 INFO - Display: 12:24:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:24:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:24:37 INFO - Display Serial Number: 1600x1200 60 12:24:37 INFO - Main Display: Yes 12:24:37 INFO - Mirror: Off 12:24:37 INFO - Online: Yes 12:24:37 INFO - Rotation: Supported 12:24:37 INFO - Return code: 0 12:24:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:24:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:24:37 INFO - Python 2.7.3 12:24:37 INFO - Return code: 0 12:24:37 INFO - grabbing minidump binary from tooltool 12:24: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')]} 12:24:37 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 0x10f4ebd30>, '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 0x10f351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4e3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:24:37 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 12:24:37 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 12:24:37 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 12:24:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:24:37 INFO - Return code: 0 12:24:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:24:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:24:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:24:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:24:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:24:37 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/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0293', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:24:37 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/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0293 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:24:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZBpBCFdYz/Render', 12:24:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:24:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:24:37 INFO - 'HOME': '/Users/cltbld', 12:24:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:24:37 INFO - 'LOGNAME': 'cltbld', 12:24:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:24:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:37 INFO - 'MOZ_NO_REMOTE': '1', 12:24:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:24:37 INFO - 'NO_EM_RESTART': '1', 12:24:37 INFO - 'PAGER': '/bin/cat', 12:24:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:37 INFO - 'PWD': '/builds/slave/test', 12:24:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:24:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:24:37 INFO - 'SHELL': '/bin/bash', 12:24:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XqCdHarHCS/Listeners', 12:24:37 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 12:24:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:24:37 INFO - 'USER': 'cltbld', 12:24:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:24:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:37 INFO - 'XPC_FLAGS': '0x0', 12:24:37 INFO - 'XPC_SERVICE_NAME': '0', 12:24:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:24:37 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/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0293', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:24:37 INFO - mozversion application_buildid: 20170109105308 12:24:37 INFO - mozversion application_changeset: 1f3bb12e7ea66181f264cb8f5f4ab125f6b56fbf 12:24:37 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:24:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:24:37 INFO - mozversion application_name: Firefox 12:24:37 INFO - mozversion application_remotingname: firefox-dev 12:24:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:24:37 INFO - mozversion application_vendor: Mozilla 12:24:37 INFO - mozversion application_version: 52.0a2 12:24:37 INFO - mozversion platform_buildid: 20170109105308 12:24:37 INFO - mozversion platform_changeset: 1f3bb12e7ea66181f264cb8f5f4ab125f6b56fbf 12:24:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:24:37 INFO - mozversion platform_version: 52.0a2 12:24:37 INFO - using testdate: 1483993477 12:24:37 INFO - actual date: 1483993477 12:24:37 INFO - starting webserver on 'localhost:54771' 12:24:37 INFO - SUITE-START | Running 2 tests 12:24:37 INFO - TEST-START | dromaeo_css 12:24:37 INFO - operating with platform_type : mac_ 12:24:37 INFO - Initialising browser for dromaeo_css test... 12:24:38 INFO - TEST-INFO | started process 2596 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmp4062wf/profile http://localhost:54771/getInfo.html) 12:24:40 INFO - PROCESS | 2596 | __metrics Screen width/height:1600/1200 12:24:40 INFO - PROCESS | 2596 | colorDepth:24 12:24:40 INFO - PROCESS | 2596 | Browser inner width/height: 1024/689 12:24:40 INFO - PROCESS | 2596 | __metrics 12:24:40 INFO - PROCESS | 2596 | 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" 12:24:40 INFO - PROCESS | 2596 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:24:41 INFO - TEST-INFO | 2596: exit 0 12:24:41 INFO - Browser initialized. 12:24:41 INFO - Running cycle 1/1 for dromaeo_css test... 12:24:41 INFO - TEST-INFO | started process 2599 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmp4062wf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:24:54 INFO - PROCESS | 2599 | RSS: Main: 223264768 12:24:54 INFO - PROCESS | 2599 | 12:27:58 INFO - PROCESS | 2599 | Cycle 1(1): loaded http://localhost:54771/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54771/tests/dromaeo/cssquery-ext.html) 12:28:00 INFO - PROCESS | 2599 | RSS: Main: 419016704 12:28:00 INFO - PROCESS | 2599 | 12:31:03 INFO - PROCESS | 2599 | Cycle 1(1): loaded http://localhost:54771/tests/dromaeo/cssquery-ext.html (next: http://localhost:54771/tests/dromaeo/cssquery-jquery.html) 12:31:04 INFO - PROCESS | 2599 | RSS: Main: 346611712 12:31:04 INFO - PROCESS | 2599 | 12:34:06 INFO - PROCESS | 2599 | Cycle 1(1): loaded http://localhost:54771/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54771/tests/dromaeo/cssquery-mootools.html) 12:34:07 INFO - PROCESS | 2599 | RSS: Main: 328179712 12:34:07 INFO - PROCESS | 2599 | 12:37:09 INFO - PROCESS | 2599 | Cycle 1(1): loaded http://localhost:54771/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54771/tests/dromaeo/cssquery-prototype.html) 12:37:10 INFO - PROCESS | 2599 | RSS: Main: 316956672 12:37:10 INFO - PROCESS | 2599 | 12:40:13 INFO - PROCESS | 2599 | Cycle 1(1): loaded http://localhost:54771/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54771/tests/dromaeo/cssquery-yui.html) 12:40:14 INFO - PROCESS | 2599 | RSS: Main: 312700928 12:40:14 INFO - PROCESS | 2599 | 12:43:17 INFO - PROCESS | 2599 | Cycle 1(1): loaded http://localhost:54771/tests/dromaeo/cssquery-yui.html (next: http://localhost:54771/tests/dromaeo/cssquery-dojo.html) 12:43:17 INFO - PROCESS | 2599 | RSS: Main: 373420032 12:43:17 INFO - PROCESS | 2599 | 12:43:17 INFO - PROCESS | 2599 | __start_tp_report 12:43:17 INFO - PROCESS | 2599 | _x_x_mozilla_page_load 12:43:17 INFO - PROCESS | 2599 | _x_x_mozilla_page_load_details 12:43:17 INFO - PROCESS | 2599 | |i|pagename|runs| 12:43:17 INFO - PROCESS | 2599 | |0;dojo.html;2967.88128474861;3256.2673398485344;3261.972478669219;3282.983585082074;3309.5366648669174;5523.944760552398;5586.217929489874;5622.044252477079;5630.634008789431;5672.376038635747;5578.9163162552595;5634.577406694504;5640.651592511552;5642.915356269649;5710.486056254937;5099.923501147486;5115.411727651312;5130.127878260686;5176.79986005946;5189.870253243661;4154.335306350985;5102.311187989611;5117.437081920985;5231.921521177166;5235.136202526579;5100.974495127519;5136.075506408845;5144.819931302405;5174.689518628894;5196.506331898464;5151.381834179903;5172.991266702995;5173.275741396207;5190.351206099238;5238.292830467876;5072.54347108762;5154.845354639367;5159.7678104485385;5176.482351764831;5209.2446595243855;5472.589555783293;5487.286652735119;5506.421825708323;5537.307836520435;5624.184493248456;6116.052011938156;6122.234720659918;6172.135900973867;6174.536909731788;6175.475084617813;6212.316645169028;6225.221847269091;6229.37706229378;6238.189035425366;6250.499960003189;5613.186088017296;5656.745446454919;5673.234113394721;5733.254676892019;5772.538196944234;5650.915236271377;6258.561900666909;6273.247210334789;6275.843103922347;6289.528285378615;4902.50974902514;6013.639181649028;6081.331053584102;6098.14625952367;6126.203593532901;6127.632342059491;6176.382361763787;6188.938110618927;6197.690115494207;6221.3156552779155;5997.950358687212;6049.758009679652;6107.297660769068;6122.969385153046;6138.877222455615;6092.147099406086;6107.908381374283;6113.150485968067;6119.724612392453;6126.326104128542;4911.9754401227765;4912.361393018956;4915.65590408668;4925.926111108336;4955.430125535538;4368.737875727403;4387.605115539617;4401.713888597231;4408.074304396113;4418.80115394808;5545.140503222007;5628.2683251177905;5641.2384328116;5699.145128230799;5722.227499287626;6115.694215289218;6121.693915304306;6144.784932527429;6155.384461553809;6162.168107305456;5686.829395118153;5706.087026075891;5707.942920570741;5710.743016564182;5738.512226460798;5234.13636749932;5278.287431196816;5325.121354976463;5331.626786125012;5332.626716129911;6142.867137759864;6164.198654174928;6178.289496707936;6188.721507532082;6222.439980401786;6128.014278093809;6144.740328232791;6151.384861513813;6168.9691551541955;6184.5052395809125;4937.580305674058;6227.719752611234;6237.812865614039;6247.96876015617;6248.843778905381;5939.910901336397;5999.040153575407;6024.367441418588;6034.8793024138395;6056.0915862619195;6121.602095863755;6137.131844484677;6177.444030037318;6182.536309776695;6183.072539119168;5870.442307980735;5989.191459153133;6012.488938440107;6014.458698717136;6018.247719035121;5801.8549536261935;5872.853178670567;5874.676892770939;5945.762169513344;5966.522678185822;5780.5953583248765;5785.623934444247;5789.45000224978;5790.971045144747;5791.391903850035;5898.852528686817;5909.202257695328;5910.083936989773;5916.142159386951;5924.348321684697;5734.913976290442;5753.8561535961935;5766.654000759968;5794.333651630117;5880.147378629989 12:43:17 INFO - PROCESS | 2599 | |1;ext.html;498.7057635994763;500.72710372846797;510.91940545745734;530.1782237531825;543.1662398218735;25741.613875791856;29599.40801183975;31221.375572488538;38227;39174.80412597933;40486.59513404865;40851.38722919151;40976.312131908126;41015.38476922848;41141.79429102858;9134.451932884014;9300.767480812967;9305.534723263843;9307.767305817375;9340.766480837965;9233.815323693521;9344.486053267068;9353.485558294291;9363.204127649158;9405.153536181742;9300.62797488103;9343.065693430637;9346.439213647169;9351.485668288242;9368.484733339663;9318.953405232964;9368.7657808555;9382.202512786353;9388.248940084844;9390.014048524943;9048.819023619557;9136;9138.588763505659;9154.588043538057;9158.221551168159;38170.618293817264;38434.615653843386;38447.807760961296;38478.615213847785;38483.42274865879;2805.9298517537227;3249.545063691085;3279.754018448626;3428.708559772422;3860.3244432224246;28371.57442638362;29122.271943201376;29215.70784292151;29470.41059178826;29644.110676679735;3070.3091804343844;3119.7660175486703;3149.322895577439;3179.8728050877935;3190.0429871038596;487265;487796;489807;491027;496553;33105.50341744876;55982;56451.435485644615;56452;56542;34259.828700856335;35152.47271290933;35284.47073293903;35371.64628353735;35448.64551354505;13509.932450337881;13527.256000919848;13915.65210869716;14020.71958560823;14084.85915140856;11378;11383.943080284545;11800.52797888092;11920.821187682192;11926.821097683542;7754.651040703182;7766.689332426753;7789;7851.097123830833;7879.921200787919;3269.8692052318115;3289.786163899339;3298.472244440926;3305.8842940496966;3318.7179089777155;890.3144578674385;929.8605209218671;960.5725452173855;960.8894977077586;1037.305005646219;27900.302492437444;27939.30151746182;28551.144175628517;29247.41505169885;29791.55312670312;836.2181360428019;915.7527467583849;1020.1685626214611;1045.482486169351;1058.5130839813771;13471.461141554424;14645.92677036608;14905.478308259158;15059.376743444509;15118.168500732345;658222.7088864525;664357;667078;668136;678486;54837.90324193494;55253;55293.617659558025;55397.89204215893;55454;1212.9696757580955;1260.6722252214306;1324.966875828112;1341.5237590655177;1364.747521708487;28105.718942811127;28867.278318042216;29569.556456653598;30172.17245240751;30441.695583044773;47092.52907471018;47513.76243118762;47568;47660;47675;541525;544216;544775;545156;827493;38773.41839872348;38977.80511097427;39156.80421597874;39659.20681586294;39957.40063898986;43549.346759799264;43561.56438435575;43965.560344397425;44161.77919110384;44189.77905110583;35818.462723059696;36450.851224255224;36558;36624.90125296245;37517;35942.640573593926;36464.08839779027;36770.448443272835;36997.81501092477;37816.43275350817;35273.29453410866;35604;36304;36467.45298820467;36867.81566092152;30783.846080769454;33108.17229569184;33122.66877331292;33211.83394083111;33338.99983000514;22086.55826883421;24265.63601545943;24515.01939922383;24919.87540062288;25099.247022589352 12:43:17 INFO - PROCESS | 2599 | |2;jquery.html;355.5804151101699;368.6368926607292;380.36099353086803;384.11653197645416;398.1042654028436;14372.2813859307;14895.776563351545;15487.767683484743;19278.13248403822;19834.305799297028;19013.954232517204;19626.705599416022;20271.797282027175;20295.898520507413;20527.178912843505;5675.772969081232;5907.88184236315;5984.970075149618;5999.730012149464;6001.159837622737;4792.305115758213;5525.756704741441;5866.5306775458075;5935.436133567304;5944.7027648617395;5868.6478811271245;5873.941260587403;5877.529797616202;5890.440408161217;5903.911441328383;5616.438356164372;5936.821895343125;6004.819855404301;6035.64198055434;6076.574639775217;5613.915791263134;5813.622114562582;5816.82549523515;5822.446867547618;5842.182094506772;13257.337133143401;13462.326883655778;13468.461261549526;13485.528006519724;20520.692189617166;2128.7977642123974;2200.196928121224;2251.74104977928;2297.517521320525;2318.8840557972035;9932;10482.947585262025;10554.9472252639;11443.828342574869;14159.504417345446;1801.1534578748099;1885.7216818168251;1891.3758459708401;1910.9904450477798;1956.0122138320057;83392.58303708503;94664.63338416525;113982.43008784903;119401;119904;8560.743177704679;10778.407187604756;10880.183986201067;11273.54905803775;11558.826617600742;14235;14388.928055359656;14400.927995359956;14416.567502974718;14421.062630928956;6861.039454476378;8259.339252859756;8365.749027529184;8472.364572657076;8491.448055876348;6567.638779867153;7957.880631790527;8006.279434850775;8018.158093400226;8151.021877374753;4375.409319741858;4444.444444444444;4450.443694538183;4506.2564676828615;4513.661475389359;1844.7601811764384;1912.961740765177;1916.8466522678152;1927.258005667828;1928.5757133430623;696.2636491940456;700.2227527444533;778.5406610100068;780.683823051665;866.3156106675656;17521.03634300126;20211.79788202099;21341.253056143272;21448.57102857934;21453;937.639008981547;954.6610953111684;964.6623681711345;979.8824141103113;987.3335498538457;10649.574017039387;11175.72060698489;11212.66362009141;11299.265547739371;11323.660290191307;430718;567727;568265;614936;616907;72586.35231645328;119690.40154799345;124987;134213.3289333547;134976;924.3390975452584;960.846264597675;987.6543209876486;987.8518222266574;989;18903.43289701311;19370.70943935814;19482.512937176685;19521.414357569294;19606.509837254183;54113;54732.72633636806;54804.90390192094;56149.15776263278;57243.141352878905;75748.62125689337;81424.18575814166;81617.59191204006;82666;86507;7794.986651735238;7954.284114429729;8074.3944204183745;8224;8357.456765310235;8897.555122243732;12135.635930922086;12472.75054498923;12577.87422125767;12967.286799225756;10739.409332486788;10821.9458902705;10836.831515934262;11009.009637869372;11066.28069175501;10485.108765754996;10588.841167382341;10650.254482186172;10695.43697136548;11034.062104721188;10515.579376825148;10799.622013229498;10915.945420272848;10989;11334.94332528332;7895.84208315842;7973.3621310293875;8000.799980000314;8138.9186108138165;8154.836903261783;8228.930239068883;9125.954370228106;9860.85208721884;10708.518116684923;10809.567617295535 12:43:17 INFO - PROCESS | 2599 | |3;mootools.html;1450.361840790052;1479.6744716162445;1629.9348026078958;1793.085526381545;1829.2500074969257;7967.362610991121;8202.671893124276;8356.247937685606;8391.748247552569;8395.45429547079;7437.776866694008;7660.4254680898875;7668.233176682329;7945.602719864012;8198.46709963852;2301.73530044045;2333.8366314358;2338.801201897832;2343.5195784864145;2347.7065366829147;2321.7562155973633;2350.9412264693347;2366.8343215974887;2372.727136379321;2376.8811559422047;2041.652919003773;2120.236714782677;2130.1160018592263;2242.383344580237;2307.5846347657416;2302.343832007878;2314.051238992005;2318.431984163891;2319.3158018384447;2322.059565875823;2313.086330899302;2331.6852224949585;2343.6601692754457;2343.718753749544;2353.6116540770754;8219.219174178443;8302.377321700897;8334.083250842463;8350.039745429232;8355.373346999;5021.9748901255625;5188.325517682677;5190.4550022247495;5193.350831146106;5205.817796377147;3312.287858110518;3518.190816112283;3536.398812201906;3575.3564358415733;3584.408572585546;984.0503913723292;1018.256672628985;1070.3417398300066;1073;1087.9945600272026;10752.247342686014;10782.838257426145;10786.622468213574;10798.622048228353;10804.297720648212;4447.2439685253585;4590.931136032962;4656.650751193674;4698.976505117453;4742.051589682077;9307;9341.159295663423;9370.922343930535;9385.06149385056;9388.90611093894;5135.87160320995;5145.588352931756;5150.845474635692;5151.639385243072;5160.509751573594;4382.539833317455;4454.933176002362;4753.524647535288;4795.112904112681;4819.277108433693;3766.7928263945746;3796.5064541609413;3854.1713531590844;3905.082305658168;3912.7456715313415;1090.907272881814;1248.4132457744845;1254.4543123741203;1259.2822091407993;1269.8412698412699;760.6615056299894;819.2135549872071;825.6573521988428;899.5592159841631;901.8647202919615;5318.70747108905;5426.728663566806;5743.4543718346695;5754.367019627837;5818.738156782955;791.6041979010495;865.251557402842;924.8890133183926;950.1543626172712;959.0840747086655;3761.6614504694708;3785.545734511821;3786.545614526219;3808.0479880029993;3850.4416859555768;9761.707148785406;10218.335808172445;10221.846672299922;10225.181985441146;10228.181745460346;10083.798324033478;10112.443815590213;10164;10176.491175441199;10269.640562580424;7279.6360181990685;7599.696012159452;7789.493682910593;7798.883016754753;7920.960395198103;5578.107502799533;5630.791161796132;5757.827265182052;5862.824115276549;5909.970450147722;9030.187283144549;9296.953515232652;9350.906490935004;9355.251579873458;9367.812643746951;8992;9258.95370523143;9477.620895164117;9575.712728618153;9650.71047868565;7142.392896603847;7226.783196504113;7294.197638259893;7298.124225093023;7306.123265208209;7761.61191940412;7777.02787151606;7853.450258481851;7872.252136047066;7906.723264685708;7034.507584469038;7088.043114179726;7129.322714342129;7151.213366529573;7268.018817459575;6905.89641155373;6965.129358830146;6991.8951215730785;7000.369966703021;7039.507234493536;6358.237011558643;6578.5395022347975;6628.436582890508;6665.966670166618;6723.7646682365885;4041.7372870763306;4742.07529531738;4809.422869255712;4828.396450443694;4919.778609962489;4764.118638051971;4859.975700121477;4870.95129048705;4940.333055037524;4961.355023846877 12:43:17 INFO - PROCESS | 2599 | |4;prototype.html;247.80918692606693;280.35292655648067;295.95856580078794;304.98627561759724;309.6856690459184;4059.4925634295714;4984.501549845014;5005.824796132136;5019.573336266417;5084.38987321521;4335.891602709934;4760.071786001731;5137.178051511759;5160.767765450554;5198.350206224222;3456.792592444461;3956.8830675588492;4018.3570628699413;4024.6579040781567;4026.5973402659793;3315.7678962472637;3846.884403522975;3988.5811989741096;4000.5199376074765;4003.83984640614;3926.7054970877157;3958.6635136013474;3960.2871483133026;3977.482927219472;3981.223661386031;3921.862734804297;3934.3114954883067;3958.6239307265764;3959.762414255125;3970;3063.754899608026;3345.8494367753265;3447.1037530242065;3455.87904423344;3457.6633548625696;4865.10260998211;5044.066847633163;5081.568066714333;5113.744312784346;5131.615128865313;4316.82732690692;4472.93290600638;4473.284274516094;4478.955210447919;4507.309758840435;4489.528599497071;4535.795889184961;5103.239382413061;5446.400895901447;5454.627237151852;2963.4962056450454;3116.56368108465;3464.34177506273;3633.7638053526434;3771.510253449899;4946.703197808179;4952.935118949407;5158.174692049329;5673.631213971079;5720.513756330717;3790.639889210521;3801.201747633028;3811.3330167220624;3815.8361699681864;3826.617338266207;3163.940080073155;3869.6323849234277;3873.7869417182324;3885.3977633466857;3890;4199.202151591249;4428.247197976351;4451.375248034444;4469.351943968171;4492.348609451611;4782.4676135087375;5418.45815418455;5421.945780542223;5464.644798088111;5477.561795056386;1238.585074000202;1253.2856271925098;1256.497401039573;1262.3246563088887;1268.6828292926768;714.7212587091038;839.7102999465175;855.153398135841;859.2052351574894;875.92992560595;4270.551592082848;4785.076237485228;4793.472718001017;4853.611711063107;4962.280469331999;4515.661325400608;4601.907961840744;4848.127337079289;4933.333999910038;4995.825146119869;4134;4552.658550608718;4680.321353403806;4856.902861942812;4866.75897646098;2581.6256642787066;2583.2929745814818;2720.6871209811125;2745.409736906575;2805.6492938382703;4887.560119589254;5057.393112826484;5551.972240138854;5553.583481238923;5565.888682226333;4785.712857228507;5344.225087362389;5480.671159730429;5481.972590137104;5808.82573522795;5859.179714840011;5874.265716785402;5882.647041177457;5894.557908156906;5927.792527261525;5164.793408263778;5198.8960220794615;5654.204440448573;5656;5675.659460432387;5424.186372044225;5562.527185189142;5588.217649529151;5629.971850140723;5649.717514124195;4629.560191781776;5865.853353666193;5872.794452194153;5880.558958078076;5884.911726324023;3797.7911214883447;3850.96149038506;3855.1133239354544;3855.5373355196434;3873.3802591585213;4464.17412778624;4532.413655220646;4841.273808928689;5373.973130134481;5632.746526406403;5003.199488081889;5464.644798088111;5470.72646367672;5474.315710536183;5501.367342755634;5114.769835357492;5475.6440831345835;5503.871706300119;5511.283533140666;5530.336359636869;5393.8921221574565;5419.512243898068;5423.376311724044;5437.184422336523;5443.319585051869;4567.474740404896;5215.322008138917;5509.807156749494;5529.142982837667;5538.667679939216;4953.439666505039;5302.8939421212135;5385.2191432241325;5389.434109418611;5395.946040539544 12:43:17 INFO - PROCESS | 2599 | |5;yui.html;731.3637135691948;737.6274981134526;754.8109238467479;761.3984951888009;787.8030492376904;14133.434662613508;14211.786823197648;14581;14685.265736713149;14914.17972011539;12911.741765164714;13212.603621891358;13366.866331336709;13371;13387.464501419956;640.1549954060654;794.0352471746827;799.2447137455107;801.4389927051059;806.3589446390105;804.01508152513;805.9758207253763;808.1030056637128;809.720646376999;810.4448452809814;850.086157380815;851.3997631669689;851.6678495386805;851.8849955256023;852.0031563071191;836.1053672570353;843.995780021099;846.0608724316035;847.7160151349306;848.4781859156606;622.8723111762077;623.8066199478176;624.44736408279;624.597134848025;626.1640709652573;12364.56724014655;12513.436895339732;12538.623841284685;12560.74878502434;12565.120441569094;11330.660080197607;11334.546618135264;11379.772404551946;11384.089272858151;11603.883961160365;9896.653617123438;10077.193824494023;10140.492975351202;10332.535035923327;10417.062464378167;3338.8163650999186;3345;3589.1206654369585;3608.1340478285283;3621.1128273573145;90593;94474;94626;95319;96573.5171324139;9733.756656083513;9841.458719770339;10048.949755251324;10180.185585153169;10446.529906154241;19138.808611913704;19315.710264346046;19400.902995485216;19414.805851941583;19495.707564386546;6513.804585862432;7432.145303290083;7455.664495097625;7471.4396420268695;7549.924500755032;3994.78028708418;4041.777702226347;4056.492938382702;4070.3690927906223;4081.6734661227624;3258.5600943872746;3361.0421030006332;3390.627031026585;3400.2009527760956;3408.812515311632;643.3566433566433;645.5577929118568;648.9059086432441;651.1827656291418;655.8688262347549;596.639033384796;596.8686888884439;611.4987705183763;611.7063809371526;629.8960671489153;8570.528620925908;8714.869276960851;8716.43343182691;8812.911870881337;9068.727938161866;525.653068974475;540.753956949587;623.975040998355;626.5614070150913;630.9053641953743;4105.220008198433;4275.315949448073;4284.271673815459;4293.162833247487;4371.934420983688;71932;76062.61968690231;82911.92720182044;102997.48501257446;122738;70786.64606676933;81747;102406.48796755969;105192;106561.46719266509;84413.57793210994;85296.57351713201;85593.57203213945;87464.5626771862;89493.10506894978;8126.674933002851;8283.50298982063;8310.501369917823;8374.204450577185;8508.957455212685;42827.57172428236;54902.45097548974;55747.16379254396;56893.71553142208;58703;77106;78158.6092069536;78681.60659196667;82875.5856220715;85187.57406213177;10532.157427405942;10656.57373705043;10677.679669609925;10805.405702686428;11163.106951444028;11854.170208085352;12757.808632870328;13652.795208071688;13723.794143088062;15009.399624014917;12765.93617031909;12938.870611293767;13339.733205336033;13419;13812.37844296989;11990.820137698116;12949.611511654666;13477.326133693472;13534.526291580141;13682.384292706653;12533.435995380412;13176.341182940623;13224.669383265495;13704.862951370358;13716.931415342859;10255.84616230772;10652.680419587425;11306.660800176009;11700.70748231267;11849.289042657123;11164.609238676607;11314.717132071764;11422.371769552754;11532.82700759506;11554.537818487166 12:43:17 INFO - PROCESS | 2599 | __end_tp_report 12:43:17 INFO - PROCESS | 2599 | __start_cc_report 12:43:17 INFO - PROCESS | 2599 | _x_x_mozilla_cycle_collect,2508 12:43:17 INFO - PROCESS | 2599 | __end_cc_report 12:43:17 INFO - PROCESS | 2599 | __startTimestamp1483994597324__endTimestamp 12:43:17 INFO - PROCESS | 2599 | 12:43:17 INFO - PROCESS | 2599 | ------- Summary: start ------- 12:43:17 INFO - PROCESS | 2599 | Number of tests: 6 12:43:17 INFO - PROCESS | 2599 | 12:43:17 INFO - PROCESS | 2599 | [#0] dojo.html Cycles:165 Average:5636.33 Median:5785.62 stddev:641.23 (11.1%) stddev-sans-first:608.07 12:43:17 INFO - PROCESS | 2599 | Values: 2967.9 3256.3 3262.0 3283.0 3309.5 5523.9 5586.2 5622.0 5630.6 5672.4 5578.9 5634.6 5640.7 5642.9 5710.5 5099.9 5115.4 5130.1 5176.8 5189.9 4154.3 5102.3 5117.4 5231.9 5235.1 5101.0 5136.1 5144.8 5174.7 5196.5 5151.4 5173.0 5173.3 5190.4 5238.3 5072.5 5154.8 5159.8 5176.5 5209.2 5472.6 5487.3 5506.4 5537.3 5624.2 6116.1 6122.2 6172.1 6174.5 6175.5 6212.3 6225.2 6229.4 6238.2 6250.5 5613.2 5656.7 5673.2 5733.3 5772.5 5650.9 6258.6 6273.2 6275.8 6289.5 4902.5 6013.6 6081.3 6098.1 6126.2 6127.6 6176.4 6188.9 6197.7 6221.3 5998.0 6049.8 6107.3 6123.0 6138.9 6092.1 6107.9 6113.2 6119.7 6126.3 4912.0 4912.4 4915.7 4925.9 4955.4 4368.7 4387.6 4401.7 4408.1 4418.8 5545.1 5628.3 5641.2 5699.1 5722.2 6115.7 6121.7 6144.8 6155.4 6162.2 5686.8 5706.1 5707.9 5710.7 5738.5 5234.1 5278.3 5325.1 5331.6 5332.6 6142.9 6164.2 6178.3 6188.7 6222.4 6128.0 6144.7 6151.4 6169.0 6184.5 4937.6 6227.7 6237.8 6248.0 6248.8 5939.9 5999.0 6024.4 6034.9 6056.1 6121.6 6137.1 6177.4 6182.5 6183.1 5870.4 5989.2 6012.5 6014.5 6018.2 5801.9 5872.9 5874.7 5945.8 5966.5 5780.6 5785.6 5789.5 5791.0 5791.4 5898.9 5909.2 5910.1 5916.1 5924.3 5734.9 5753.9 5766.7 5794.3 5880.1 12:43:17 INFO - PROCESS | 2599 | 12:43:17 INFO - PROCESS | 2599 | [#1] ext.html Cycles:180 Average:69577.70 Median:38463.21 stddev:159564.93 (414.9%) stddev-sans-first:159928.25 12:43:17 INFO - PROCESS | 2599 | Values: 498.7 500.7 510.9 530.2 543.2 25741.6 29599.4 31221.4 38227.0 39174.8 40486.6 40851.4 40976.3 41015.4 41141.8 9134.5 9300.8 9305.5 9307.8 9340.8 9233.8 9344.5 9353.5 9363.2 9405.2 9300.6 9343.1 9346.4 9351.5 9368.5 9319.0 9368.8 9382.2 9388.2 9390.0 9048.8 9136.0 9138.6 9154.6 9158.2 38170.6 38434.6 38447.8 38478.6 38483.4 2805.9 3249.5 3279.8 3428.7 3860.3 28371.6 29122.3 29215.7 29470.4 29644.1 3070.3 3119.8 3149.3 3179.9 3190.0 487265.0 487796.0 489807.0 491027.0 496553.0 33105.5 55982.0 56451.4 56452.0 56542.0 34259.8 35152.5 35284.5 35371.6 35448.6 13509.9 13527.3 13915.7 14020.7 14084.9 11378.0 11383.9 11800.5 11920.8 11926.8 7754.7 7766.7 7789.0 7851.1 7879.9 3269.9 3289.8 3298.5 3305.9 3318.7 890.3 929.9 960.6 960.9 1037.3 27900.3 27939.3 28551.1 29247.4 29791.6 836.2 915.8 1020.2 1045.5 1058.5 13471.5 14645.9 14905.5 15059.4 15118.2 658222.7 664357.0 667078.0 668136.0 678486.0 54837.9 55253.0 55293.6 55397.9 55454.0 1213.0 1260.7 1325.0 1341.5 1364.7 28105.7 28867.3 29569.6 30172.2 30441.7 47092.5 47513.8 47568.0 47660.0 47675.0 541525.0 544216.0 544775.0 545156.0 827493.0 38773.4 38977.8 39156.8 39659.2 39957.4 43549.3 43561.6 43965.6 44161.8 44189.8 35818.5 36450.9 36558.0 36624.9 37517.0 35942.6 36464.1 36770.4 36997.8 37816.4 35273.3 35604.0 36304.0 36467.5 36867.8 30783.8 33108.2 33122.7 33211.8 33339.0 22086.6 24265.6 24515.0 24919.9 25099.2 12:43:17 INFO - PROCESS | 2599 | 12:43:17 INFO - PROCESS | 2599 | [#2] jquery.html Cycles:180 Average:32992.36 Median:215558.05 stddev:94185.73 (43.7%) stddev-sans-first:94418.07 12:43:17 INFO - PROCESS | 2599 | Values: 355.6 368.6 380.4 384.1 398.1 14372.3 14895.8 15487.8 19278.1 19834.3 19014.0 19626.7 20271.8 20295.9 20527.2 5675.8 5907.9 5985.0 5999.7 6001.2 4792.3 5525.8 5866.5 5935.4 5944.7 5868.6 5873.9 5877.5 5890.4 5903.9 5616.4 5936.8 6004.8 6035.6 6076.6 5613.9 5813.6 5816.8 5822.4 5842.2 13257.3 13462.3 13468.5 13485.5 20520.7 2128.8 2200.2 2251.7 2297.5 2318.9 9932.0 10482.9 10554.9 11443.8 14159.5 1801.2 1885.7 1891.4 1911.0 1956.0 83392.6 94664.6 113982.4 119401.0 119904.0 8560.7 10778.4 10880.2 11273.5 11558.8 14235.0 14388.9 14400.9 14416.6 14421.1 6861.0 8259.3 8365.7 8472.4 8491.4 6567.6 7957.9 8006.3 8018.2 8151.0 4375.4 4444.4 4450.4 4506.3 4513.7 1844.8 1913.0 1916.8 1927.3 1928.6 696.3 700.2 778.5 780.7 866.3 17521.0 20211.8 21341.3 21448.6 21453.0 937.6 954.7 964.7 979.9 987.3 10649.6 11175.7 11212.7 11299.3 11323.7 430718.0 567727.0 568265.0 614936.0 616907.0 72586.4 119690.4 124987.0 134213.3 134976.0 924.3 960.8 987.7 987.9 989.0 18903.4 19370.7 19482.5 19521.4 19606.5 54113.0 54732.7 54804.9 56149.2 57243.1 75748.6 81424.2 81617.6 82666.0 86507.0 7795.0 7954.3 8074.4 8224.0 8357.5 8897.6 12135.6 12472.8 12577.9 12967.3 10739.4 10821.9 10836.8 11009.0 11066.3 10485.1 10588.8 10650.3 10695.4 11034.1 10515.6 10799.6 10915.9 10989.0 11334.9 7895.8 7973.4 8000.8 8138.9 8154.8 8228.9 9126.0 9860.9 10708.5 10809.6 12:43:17 INFO - PROCESS | 2599 | 12:43:17 INFO - PROCESS | 2599 | [#3] mootools.html Cycles:180 Average:5436.95 Median:5148.22 stddev:2968.53 (57.7%) stddev-sans-first:2961.73 12:43:17 INFO - PROCESS | 2599 | Values: 1450.4 1479.7 1629.9 1793.1 1829.3 7967.4 8202.7 8356.2 8391.7 8395.5 7437.8 7660.4 7668.2 7945.6 8198.5 2301.7 2333.8 2338.8 2343.5 2347.7 2321.8 2350.9 2366.8 2372.7 2376.9 2041.7 2120.2 2130.1 2242.4 2307.6 2302.3 2314.1 2318.4 2319.3 2322.1 2313.1 2331.7 2343.7 2343.7 2353.6 8219.2 8302.4 8334.1 8350.0 8355.4 5022.0 5188.3 5190.5 5193.4 5205.8 3312.3 3518.2 3536.4 3575.4 3584.4 984.1 1018.3 1070.3 1073.0 1088.0 10752.2 10782.8 10786.6 10798.6 10804.3 4447.2 4590.9 4656.7 4699.0 4742.1 9307.0 9341.2 9370.9 9385.1 9388.9 5135.9 5145.6 5150.8 5151.6 5160.5 4382.5 4454.9 4753.5 4795.1 4819.3 3766.8 3796.5 3854.2 3905.1 3912.7 1090.9 1248.4 1254.5 1259.3 1269.8 760.7 819.2 825.7 899.6 901.9 5318.7 5426.7 5743.5 5754.4 5818.7 791.6 865.3 924.9 950.2 959.1 3761.7 3785.5 3786.5 3808.0 3850.4 9761.7 10218.3 10221.8 10225.2 10228.2 10083.8 10112.4 10164.0 10176.5 10269.6 7279.6 7599.7 7789.5 7798.9 7921.0 5578.1 5630.8 5757.8 5862.8 5910.0 9030.2 9297.0 9350.9 9355.3 9367.8 8992.0 9259.0 9477.6 9575.7 9650.7 7142.4 7226.8 7294.2 7298.1 7306.1 7761.6 7777.0 7853.5 7872.3 7906.7 7034.5 7088.0 7129.3 7151.2 7268.0 6905.9 6965.1 6991.9 7000.4 7039.5 6358.2 6578.5 6628.4 6666.0 6723.8 4041.7 4742.1 4809.4 4828.4 4919.8 4764.1 4860.0 4871.0 4940.3 4961.4 12:43:17 INFO - PROCESS | 2599 | 12:43:17 INFO - PROCESS | 2599 | [#4] prototype.html Cycles:180 Average:4367.95 Median:4850.87 stddev:1346.20 (27.8%) stddev-sans-first:1313.98 12:43:17 INFO - PROCESS | 2599 | Values: 247.8 280.4 296.0 305.0 309.7 4059.5 4984.5 5005.8 5019.6 5084.4 4335.9 4760.1 5137.2 5160.8 5198.4 3456.8 3956.9 4018.4 4024.7 4026.6 3315.8 3846.9 3988.6 4000.5 4003.8 3926.7 3958.7 3960.3 3977.5 3981.2 3921.9 3934.3 3958.6 3959.8 3970.0 3063.8 3345.8 3447.1 3455.9 3457.7 4865.1 5044.1 5081.6 5113.7 5131.6 4316.8 4472.9 4473.3 4479.0 4507.3 4489.5 4535.8 5103.2 5446.4 5454.6 2963.5 3116.6 3464.3 3633.8 3771.5 4946.7 4952.9 5158.2 5673.6 5720.5 3790.6 3801.2 3811.3 3815.8 3826.6 3163.9 3869.6 3873.8 3885.4 3890.0 4199.2 4428.2 4451.4 4469.4 4492.3 4782.5 5418.5 5421.9 5464.6 5477.6 1238.6 1253.3 1256.5 1262.3 1268.7 714.7 839.7 855.2 859.2 875.9 4270.6 4785.1 4793.5 4853.6 4962.3 4515.7 4601.9 4848.1 4933.3 4995.8 4134.0 4552.7 4680.3 4856.9 4866.8 2581.6 2583.3 2720.7 2745.4 2805.6 4887.6 5057.4 5552.0 5553.6 5565.9 4785.7 5344.2 5480.7 5482.0 5808.8 5859.2 5874.3 5882.6 5894.6 5927.8 5164.8 5198.9 5654.2 5656.0 5675.7 5424.2 5562.5 5588.2 5630.0 5649.7 4629.6 5865.9 5872.8 5880.6 5884.9 3797.8 3851.0 3855.1 3855.5 3873.4 4464.2 4532.4 4841.3 5374.0 5632.7 5003.2 5464.6 5470.7 5474.3 5501.4 5114.8 5475.6 5503.9 5511.3 5530.3 5393.9 5419.5 5423.4 5437.2 5443.3 4567.5 5215.3 5509.8 5529.1 5538.7 4953.4 5302.9 5385.2 5389.4 5395.9 12:43:17 INFO - PROCESS | 2599 | 12:43:17 INFO - PROCESS | 2599 | [#5] yui.html Cycles:180 Average:20092.51 Median:4288.72 stddev:29642.92 (691.2%) stddev-sans-first:29690.42 12:43:17 INFO - PROCESS | 2599 | Values: 731.4 737.6 754.8 761.4 787.8 14133.4 14211.8 14581.0 14685.3 14914.2 12911.7 13212.6 13366.9 13371.0 13387.5 640.2 794.0 799.2 801.4 806.4 804.0 806.0 808.1 809.7 810.4 850.1 851.4 851.7 851.9 852.0 836.1 844.0 846.1 847.7 848.5 622.9 623.8 624.4 624.6 626.2 12364.6 12513.4 12538.6 12560.7 12565.1 11330.7 11334.5 11379.8 11384.1 11603.9 9896.7 10077.2 10140.5 10332.5 10417.1 3338.8 3345.0 3589.1 3608.1 3621.1 90593.0 94474.0 94626.0 95319.0 96573.5 9733.8 9841.5 10048.9 10180.2 10446.5 19138.8 19315.7 19400.9 19414.8 19495.7 6513.8 7432.1 7455.7 7471.4 7549.9 3994.8 4041.8 4056.5 4070.4 4081.7 3258.6 3361.0 3390.6 3400.2 3408.8 643.4 645.6 648.9 651.2 655.9 596.6 596.9 611.5 611.7 629.9 8570.5 8714.9 8716.4 8812.9 9068.7 525.7 540.8 624.0 626.6 630.9 4105.2 4275.3 4284.3 4293.2 4371.9 71932.0 76062.6 82911.9 102997.5 122738.0 70786.6 81747.0 102406.5 105192.0 106561.5 84413.6 85296.6 85593.6 87464.6 89493.1 8126.7 8283.5 8310.5 8374.2 8509.0 42827.6 54902.5 55747.2 56893.7 58703.0 77106.0 78158.6 78681.6 82875.6 85187.6 10532.2 10656.6 10677.7 10805.4 11163.1 11854.2 12757.8 13652.8 13723.8 15009.4 12765.9 12938.9 13339.7 13419.0 13812.4 11990.8 12949.6 13477.3 13534.5 13682.4 12533.4 13176.3 13224.7 13704.9 13716.9 10255.8 10652.7 11306.7 11700.7 11849.3 11164.6 11314.7 11422.4 11532.8 11554.5 12:43:17 INFO - PROCESS | 2599 | -------- Summary: end -------- 12:43:17 INFO - PROCESS | 2599 | 12:43:17 INFO - TEST-INFO | 2599: exit 0 12:43:17 INFO - TEST-OK | dromaeo_css | took 1119991ms 12:43:17 INFO - TEST-START | kraken 12:43:17 INFO - operating with platform_type : mac_ 12:43:17 INFO - Initialising browser for kraken test... 12:43:18 INFO - TEST-INFO | started process 2603 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpoggWrK/profile http://localhost:54771/getInfo.html) 12:43:20 INFO - PROCESS | 2603 | __metrics Screen width/height:1600/1200 12:43:20 INFO - PROCESS | 2603 | colorDepth:24 12:43:20 INFO - PROCESS | 2603 | Browser inner width/height: 1024/689 12:43:20 INFO - PROCESS | 2603 | __metrics 12:43:20 INFO - PROCESS | 2603 | 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" 12:43:20 INFO - PROCESS | 2603 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:43:21 INFO - TEST-INFO | 2603: exit 0 12:43:21 INFO - Browser initialized. 12:43:21 INFO - Running cycle 1/1 for kraken test... 12:43:21 INFO - TEST-INFO | started process 2604 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpoggWrK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:43:33 INFO - PROCESS | 2604 | RSS: Main: 222756864 12:43:33 INFO - PROCESS | 2604 | 12:43:36 INFO - PROCESS | 2604 | Cycle 1(1): loaded http://localhost:54771/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54771/tests/kraken/driver.html?testName=audio-beat-detection) 12:43:37 INFO - PROCESS | 2604 | RSS: Main: 272769024 12:43:37 INFO - PROCESS | 2604 | 12:43:40 INFO - PROCESS | 2604 | Cycle 1(1): loaded http://localhost:54771/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54771/tests/kraken/driver.html?testName=audio-dft) 12:43:40 INFO - PROCESS | 2604 | RSS: Main: 283631616 12:43:40 INFO - PROCESS | 2604 | 12:43:44 INFO - PROCESS | 2604 | Cycle 1(1): loaded http://localhost:54771/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54771/tests/kraken/driver.html?testName=audio-fft) 12:43:44 INFO - PROCESS | 2604 | RSS: Main: 380309504 12:43:44 INFO - PROCESS | 2604 | 12:43:46 INFO - PROCESS | 2604 | Cycle 1(1): loaded http://localhost:54771/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54771/tests/kraken/driver.html?testName=audio-oscillator) 12:43:47 INFO - PROCESS | 2604 | RSS: Main: 281178112 12:43:47 INFO - PROCESS | 2604 | 12:43:49 INFO - PROCESS | 2604 | Cycle 1(1): loaded http://localhost:54771/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54771/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:43:50 INFO - PROCESS | 2604 | RSS: Main: 283480064 12:43:50 INFO - PROCESS | 2604 | 12:43:52 INFO - PROCESS | 2604 | Cycle 1(1): loaded http://localhost:54771/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54771/tests/kraken/driver.html?testName=imaging-darkroom) 12:43:53 INFO - PROCESS | 2604 | RSS: Main: 322822144 12:43:53 INFO - PROCESS | 2604 | 12:43:56 INFO - PROCESS | 2604 | Cycle 1(1): loaded http://localhost:54771/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54771/tests/kraken/driver.html?testName=imaging-desaturate) 12:43:57 INFO - PROCESS | 2604 | RSS: Main: 325246976 12:43:57 INFO - PROCESS | 2604 | 12:44:00 INFO - PROCESS | 2604 | Cycle 1(1): loaded http://localhost:54771/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54771/tests/kraken/driver.html?testName=json-parse-financial) 12:44:00 INFO - PROCESS | 2604 | RSS: Main: 323788800 12:44:00 INFO - PROCESS | 2604 | 12:44:02 INFO - PROCESS | 2604 | Cycle 1(1): loaded http://localhost:54771/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54771/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:44:03 INFO - PROCESS | 2604 | RSS: Main: 298496000 12:44:03 INFO - PROCESS | 2604 | 12:44:04 INFO - PROCESS | 2604 | Cycle 1(1): loaded http://localhost:54771/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54771/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:44:05 INFO - PROCESS | 2604 | RSS: Main: 299323392 12:44:05 INFO - PROCESS | 2604 | 12:44:07 INFO - PROCESS | 2604 | Cycle 1(1): loaded http://localhost:54771/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54771/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:44:08 INFO - PROCESS | 2604 | RSS: Main: 302501888 12:44:08 INFO - PROCESS | 2604 | 12:44:11 INFO - PROCESS | 2604 | Cycle 1(1): loaded http://localhost:54771/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54771/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:44:12 INFO - PROCESS | 2604 | RSS: Main: 312532992 12:44:12 INFO - PROCESS | 2604 | 12:44:15 INFO - PROCESS | 2604 | Cycle 1(1): loaded http://localhost:54771/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54771/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:44:15 INFO - PROCESS | 2604 | RSS: Main: 312647680 12:44:15 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | Cycle 1(1): loaded http://localhost:54771/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54771/tests/kraken/driver.html?testName=ai-astar) 12:44:17 INFO - PROCESS | 2604 | RSS: Main: 356311040 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | __start_tp_report 12:44:17 INFO - PROCESS | 2604 | _x_x_mozilla_page_load 12:44:17 INFO - PROCESS | 2604 | _x_x_mozilla_page_load_details 12:44:17 INFO - PROCESS | 2604 | |i|pagename|runs| 12:44:17 INFO - PROCESS | 2604 | |0;ai-astar;115;114;114;115;114;114;115;115;115;114 12:44:17 INFO - PROCESS | 2604 | |1;audio-beat-detection;142;108;112;144;112;111;135;139;148;109 12:44:17 INFO - PROCESS | 2604 | |2;audio-dft;184;184;184;186;182;199;257;186;223;259 12:44:17 INFO - PROCESS | 2604 | |3;audio-fft;82;60;59;91;63;59;59;59;82;58 12:44:17 INFO - PROCESS | 2604 | |4;audio-oscillator;88;89;128;102;97;68;95;96;99;125 12:44:17 INFO - PROCESS | 2604 | |5;imaging-gaussian-blur;90;86;89;87;88;86;90;88;89;86 12:44:17 INFO - PROCESS | 2604 | |6;imaging-darkroom;136;99;98;99;115;161;159;102;98;97 12:44:17 INFO - PROCESS | 2604 | |7;imaging-desaturate;83;83;79;80;87;131;82;83;80;99 12:44:17 INFO - PROCESS | 2604 | |8;json-parse-financial;57;56;55;56;59;58;59;58;56;55 12:44:17 INFO - PROCESS | 2604 | |9;json-stringify-tinderbox;70;70;68;77;72;70;72;71;74;71 12:44:17 INFO - PROCESS | 2604 | |10;stanford-crypto-aes;84;84;87;82;100;104;70;96;102;102 12:44:17 INFO - PROCESS | 2604 | |11;stanford-crypto-ccm;141;138;140;138;143;168;146;143;194;174 12:44:17 INFO - PROCESS | 2604 | |12;stanford-crypto-pbkdf2;166;166;168;163;153;208;167;163;163;165 12:44:17 INFO - PROCESS | 2604 | |13;stanford-crypto-sha256-iterative;68;67;68;68;67;68;67;68;67;67 12:44:17 INFO - PROCESS | 2604 | __end_tp_report 12:44:17 INFO - PROCESS | 2604 | __start_cc_report 12:44:17 INFO - PROCESS | 2604 | _x_x_mozilla_cycle_collect,2035 12:44:17 INFO - PROCESS | 2604 | __end_cc_report 12:44:17 INFO - PROCESS | 2604 | __startTimestamp1483994657658__endTimestamp 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | ------- Summary: start ------- 12:44:17 INFO - PROCESS | 2604 | Number of tests: 14 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | [#0] ai-astar Cycles:10 Average:114.50 Median:115.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 12:44:17 INFO - PROCESS | 2604 | Values: 115.0 114.0 114.0 115.0 114.0 114.0 115.0 115.0 115.0 114.0 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | [#1] audio-beat-detection Cycles:10 Average:126.00 Median:137.00 stddev:16.81 (12.3%) stddev-sans-first:16.81 12:44:17 INFO - PROCESS | 2604 | Values: 142.0 108.0 112.0 144.0 112.0 111.0 135.0 139.0 148.0 109.0 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | [#2] audio-dft Cycles:10 Average:204.40 Median:192.50 stddev:30.82 (16.0%) stddev-sans-first:31.80 12:44:17 INFO - PROCESS | 2604 | Values: 184.0 184.0 184.0 186.0 182.0 199.0 257.0 186.0 223.0 259.0 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | [#3] audio-fft Cycles:10 Average:67.20 Median:61.50 stddev:12.59 (20.5%) stddev-sans-first:12.17 12:44:17 INFO - PROCESS | 2604 | Values: 82.0 60.0 59.0 91.0 63.0 59.0 59.0 59.0 82.0 58.0 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | [#4] audio-oscillator Cycles:10 Average:98.70 Median:92.00 stddev:17.44 (19.0%) stddev-sans-first:18.06 12:44:17 INFO - PROCESS | 2604 | Values: 88.0 89.0 128.0 102.0 97.0 68.0 95.0 96.0 99.0 125.0 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:88.50 stddev:1.60 (1.8%) stddev-sans-first:1.50 12:44:17 INFO - PROCESS | 2604 | Values: 90.0 86.0 89.0 87.0 88.0 86.0 90.0 88.0 89.0 86.0 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | [#6] imaging-darkroom Cycles:10 Average:116.40 Median:97.50 stddev:25.94 (26.6%) stddev-sans-first:26.53 12:44:17 INFO - PROCESS | 2604 | Values: 136.0 99.0 98.0 99.0 115.0 161.0 159.0 102.0 98.0 97.0 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | [#7] imaging-desaturate Cycles:10 Average:88.70 Median:83.00 stddev:15.94 (19.2%) stddev-sans-first:16.77 12:44:17 INFO - PROCESS | 2604 | Values: 83.0 83.0 79.0 80.0 87.0 131.0 82.0 83.0 80.0 99.0 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.50 stddev:1.52 (2.7%) stddev-sans-first:1.62 12:44:17 INFO - PROCESS | 2604 | Values: 57.0 56.0 55.0 56.0 59.0 58.0 59.0 58.0 56.0 55.0 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.50 stddev:2.51 (3.5%) stddev-sans-first:2.60 12:44:17 INFO - PROCESS | 2604 | Values: 70.0 70.0 68.0 77.0 72.0 70.0 72.0 71.0 74.0 71.0 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:83.00 stddev:11.32 (13.6%) stddev-sans-first:11.71 12:44:17 INFO - PROCESS | 2604 | Values: 84.0 84.0 87.0 82.0 100.0 104.0 70.0 96.0 102.0 102.0 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | [#11] stanford-crypto-ccm Cycles:10 Average:152.50 Median:144.50 stddev:19.31 (13.4%) stddev-sans-first:20.03 12:44:17 INFO - PROCESS | 2604 | Values: 141.0 138.0 140.0 138.0 143.0 168.0 146.0 143.0 194.0 174.0 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.20 Median:166.00 stddev:14.60 (8.8%) stddev-sans-first:15.46 12:44:17 INFO - PROCESS | 2604 | Values: 166.0 166.0 168.0 163.0 153.0 208.0 167.0 163.0 163.0 165.0 12:44:17 INFO - PROCESS | 2604 | 12:44:17 INFO - PROCESS | 2604 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 12:44:17 INFO - PROCESS | 2604 | Values: 68.0 67.0 68.0 68.0 67.0 68.0 67.0 68.0 67.0 67.0 12:44:17 INFO - PROCESS | 2604 | -------- Summary: end -------- 12:44:17 INFO - PROCESS | 2604 | 12:44:18 INFO - TEST-INFO | 2604: exit 0 12:44:18 INFO - TEST-OK | kraken | took 60726ms 12:44:18 INFO - SUITE-END | took 1180s 12:44:18 INFO - Completed test suite (00:19:40) 12:44:18 INFO - javascript benchmark 12:44:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2967.88128474861, 3256.2673398485344, 3261.972478669219, 3282.983585082074, 3309.5366648669174, 5523.944760552398, 5586.217929489874, 5622.044252477079, 5630.634008789431, 5672.376038635747, 5578.9163162552595, 5634.577406694504, 5640.651592511552, 5642.915356269649, 5710.486056254937, 5099.923501147486, 5115.411727651312, 5130.127878260686, 5176.79986005946, 5189.870253243661, 4154.335306350985, 5102.311187989611, 5117.437081920985, 5231.921521177166, 5235.136202526579, 5100.974495127519, 5136.075506408845, 5144.819931302405, 5174.689518628894, 5196.506331898464, 5151.381834179903, 5172.991266702995, 5173.275741396207, 5190.351206099238, 5238.292830467876, 5072.54347108762, 5154.845354639367, 5159.7678104485385, 5176.482351764831, 5209.2446595243855, 5472.589555783293, 5487.286652735119, 5506.421825708323, 5537.307836520435, 5624.184493248456, 6116.052011938156, 6122.234720659918, 6172.135900973867, 6174.536909731788, 6175.475084617813, 6212.316645169028, 6225.221847269091, 6229.37706229378, 6238.189035425366, 6250.499960003189, 5613.186088017296, 5656.745446454919, 5673.234113394721, 5733.254676892019, 5772.538196944234, 5650.915236271377, 6258.561900666909, 6273.247210334789, 6275.843103922347, 6289.528285378615, 4902.50974902514, 6013.639181649028, 6081.331053584102, 6098.14625952367, 6126.203593532901, 6127.632342059491, 6176.382361763787, 6188.938110618927, 6197.690115494207, 6221.3156552779155, 5997.950358687212, 6049.758009679652, 6107.297660769068, 6122.969385153046, 6138.877222455615, 6092.147099406086, 6107.908381374283, 6113.150485968067, 6119.724612392453, 6126.326104128542, 4911.9754401227765, 4912.361393018956, 4915.65590408668, 4925.926111108336, 4955.430125535538, 4368.737875727403, 4387.605115539617, 4401.713888597231, 4408.074304396113, 4418.80115394808, 5545.140503222007, 5628.2683251177905, 5641.2384328116, 5699.145128230799, 5722.227499287626, 6115.694215289218, 6121.693915304306, 6144.784932527429, 6155.384461553809, 6162.168107305456, 5686.829395118153, 5706.087026075891, 5707.942920570741, 5710.743016564182, 5738.512226460798, 5234.13636749932, 5278.287431196816, 5325.121354976463, 5331.626786125012, 5332.626716129911, 6142.867137759864, 6164.198654174928, 6178.289496707936, 6188.721507532082, 6222.439980401786, 6128.014278093809, 6144.740328232791, 6151.384861513813, 6168.9691551541955, 6184.5052395809125, 4937.580305674058, 6227.719752611234, 6237.812865614039, 6247.96876015617, 6248.843778905381, 5939.910901336397, 5999.040153575407, 6024.367441418588, 6034.8793024138395, 6056.0915862619195, 6121.602095863755, 6137.131844484677, 6177.444030037318, 6182.536309776695, 6183.072539119168, 5870.442307980735, 5989.191459153133, 6012.488938440107, 6014.458698717136, 6018.247719035121, 5801.8549536261935, 5872.853178670567, 5874.676892770939, 5945.762169513344, 5966.522678185822, 5780.5953583248765, 5785.623934444247, 5789.45000224978, 5790.971045144747, 5791.391903850035, 5898.852528686817, 5909.202257695328, 5910.083936989773, 5916.142159386951, 5924.348321684697, 5734.913976290442, 5753.8561535961935, 5766.654000759968, 5794.333651630117, 5880.147378629989], "value": 5594.951438525904, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.7057635994763, 500.72710372846797, 510.91940545745734, 530.1782237531825, 543.1662398218735, 25741.613875791856, 29599.40801183975, 31221.375572488538, 38227.0, 39174.80412597933, 40486.59513404865, 40851.38722919151, 40976.312131908126, 41015.38476922848, 41141.79429102858, 9134.451932884014, 9300.767480812967, 9305.534723263843, 9307.767305817375, 9340.766480837965, 9233.815323693521, 9344.486053267068, 9353.485558294291, 9363.204127649158, 9405.153536181742, 9300.62797488103, 9343.065693430637, 9346.439213647169, 9351.485668288242, 9368.484733339663, 9318.953405232964, 9368.7657808555, 9382.202512786353, 9388.248940084844, 9390.014048524943, 9048.819023619557, 9136.0, 9138.588763505659, 9154.588043538057, 9158.221551168159, 38170.618293817264, 38434.615653843386, 38447.807760961296, 38478.615213847785, 38483.42274865879, 2805.9298517537227, 3249.545063691085, 3279.754018448626, 3428.708559772422, 3860.3244432224246, 28371.57442638362, 29122.271943201376, 29215.70784292151, 29470.41059178826, 29644.110676679735, 3070.3091804343844, 3119.7660175486703, 3149.322895577439, 3179.8728050877935, 3190.0429871038596, 487265.0, 487796.0, 489807.0, 491027.0, 496553.0, 33105.50341744876, 55982.0, 56451.435485644615, 56452.0, 56542.0, 34259.828700856335, 35152.47271290933, 35284.47073293903, 35371.64628353735, 35448.64551354505, 13509.932450337881, 13527.256000919848, 13915.65210869716, 14020.71958560823, 14084.85915140856, 11378.0, 11383.943080284545, 11800.52797888092, 11920.821187682192, 11926.821097683542, 7754.651040703182, 7766.689332426753, 7789.0, 7851.097123830833, 7879.921200787919, 3269.8692052318115, 3289.786163899339, 3298.472244440926, 3305.8842940496966, 3318.7179089777155, 890.3144578674385, 929.8605209218671, 960.5725452173855, 960.8894977077586, 1037.305005646219, 27900.302492437444, 27939.30151746182, 28551.144175628517, 29247.41505169885, 29791.55312670312, 836.2181360428019, 915.7527467583849, 1020.1685626214611, 1045.482486169351, 1058.5130839813771, 13471.461141554424, 14645.92677036608, 14905.478308259158, 15059.376743444509, 15118.168500732345, 658222.7088864525, 664357.0, 667078.0, 668136.0, 678486.0, 54837.90324193494, 55253.0, 55293.617659558025, 55397.89204215893, 55454.0, 1212.9696757580955, 1260.6722252214306, 1324.966875828112, 1341.5237590655177, 1364.747521708487, 28105.718942811127, 28867.278318042216, 29569.556456653598, 30172.17245240751, 30441.695583044773, 47092.52907471018, 47513.76243118762, 47568.0, 47660.0, 47675.0, 541525.0, 544216.0, 544775.0, 545156.0, 827493.0, 38773.41839872348, 38977.80511097427, 39156.80421597874, 39659.20681586294, 39957.40063898986, 43549.346759799264, 43561.56438435575, 43965.560344397425, 44161.77919110384, 44189.77905110583, 35818.462723059696, 36450.851224255224, 36558.0, 36624.90125296245, 37517.0, 35942.640573593926, 36464.08839779027, 36770.448443272835, 36997.81501092477, 37816.43275350817, 35273.29453410866, 35604.0, 36304.0, 36467.45298820467, 36867.81566092152, 30783.846080769454, 33108.17229569184, 33122.66877331292, 33211.83394083111, 33338.99983000514, 22086.55826883421, 24265.63601545943, 24515.01939922383, 24919.87540062288, 25099.247022589352], "value": 18172.2009398771, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.5804151101699, 368.6368926607292, 380.36099353086803, 384.11653197645416, 398.1042654028436, 14372.2813859307, 14895.776563351545, 15487.767683484743, 19278.13248403822, 19834.305799297028, 19013.954232517204, 19626.705599416022, 20271.797282027175, 20295.898520507413, 20527.178912843505, 5675.772969081232, 5907.88184236315, 5984.970075149618, 5999.730012149464, 6001.159837622737, 4792.305115758213, 5525.756704741441, 5866.5306775458075, 5935.436133567304, 5944.7027648617395, 5868.6478811271245, 5873.941260587403, 5877.529797616202, 5890.440408161217, 5903.911441328383, 5616.438356164372, 5936.821895343125, 6004.819855404301, 6035.64198055434, 6076.574639775217, 5613.915791263134, 5813.622114562582, 5816.82549523515, 5822.446867547618, 5842.182094506772, 13257.337133143401, 13462.326883655778, 13468.461261549526, 13485.528006519724, 20520.692189617166, 2128.7977642123974, 2200.196928121224, 2251.74104977928, 2297.517521320525, 2318.8840557972035, 9932.0, 10482.947585262025, 10554.9472252639, 11443.828342574869, 14159.504417345446, 1801.1534578748099, 1885.7216818168251, 1891.3758459708401, 1910.9904450477798, 1956.0122138320057, 83392.58303708503, 94664.63338416525, 113982.43008784903, 119401.0, 119904.0, 8560.743177704679, 10778.407187604756, 10880.183986201067, 11273.54905803775, 11558.826617600742, 14235.0, 14388.928055359656, 14400.927995359956, 14416.567502974718, 14421.062630928956, 6861.039454476378, 8259.339252859756, 8365.749027529184, 8472.364572657076, 8491.448055876348, 6567.638779867153, 7957.880631790527, 8006.279434850775, 8018.158093400226, 8151.021877374753, 4375.409319741858, 4444.444444444444, 4450.443694538183, 4506.2564676828615, 4513.661475389359, 1844.7601811764384, 1912.961740765177, 1916.8466522678152, 1927.258005667828, 1928.5757133430623, 696.2636491940456, 700.2227527444533, 778.5406610100068, 780.683823051665, 866.3156106675656, 17521.03634300126, 20211.79788202099, 21341.253056143272, 21448.57102857934, 21453.0, 937.639008981547, 954.6610953111684, 964.6623681711345, 979.8824141103113, 987.3335498538457, 10649.574017039387, 11175.72060698489, 11212.66362009141, 11299.265547739371, 11323.660290191307, 430718.0, 567727.0, 568265.0, 614936.0, 616907.0, 72586.35231645328, 119690.40154799345, 124987.0, 134213.3289333547, 134976.0, 924.3390975452584, 960.846264597675, 987.6543209876486, 987.8518222266574, 989.0, 18903.43289701311, 19370.70943935814, 19482.512937176685, 19521.414357569294, 19606.509837254183, 54113.0, 54732.72633636806, 54804.90390192094, 56149.15776263278, 57243.141352878905, 75748.62125689337, 81424.18575814166, 81617.59191204006, 82666.0, 86507.0, 7794.986651735238, 7954.284114429729, 8074.3944204183745, 8224.0, 8357.456765310235, 8897.555122243732, 12135.635930922086, 12472.75054498923, 12577.87422125767, 12967.286799225756, 10739.409332486788, 10821.9458902705, 10836.831515934262, 11009.009637869372, 11066.28069175501, 10485.108765754996, 10588.841167382341, 10650.254482186172, 10695.43697136548, 11034.062104721188, 10515.579376825148, 10799.622013229498, 10915.945420272848, 10989.0, 11334.94332528332, 7895.84208315842, 7973.3621310293875, 8000.799980000314, 8138.9186108138165, 8154.836903261783, 8228.930239068883, 9125.954370228106, 9860.85208721884, 10708.518116684923, 10809.567617295535], "value": 9181.59832553077, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.361840790052, 1479.6744716162445, 1629.9348026078958, 1793.085526381545, 1829.2500074969257, 7967.362610991121, 8202.671893124276, 8356.247937685606, 8391.748247552569, 8395.45429547079, 7437.776866694008, 7660.4254680898875, 7668.233176682329, 7945.602719864012, 8198.46709963852, 2301.73530044045, 2333.8366314358, 2338.801201897832, 2343.5195784864145, 2347.7065366829147, 2321.7562155973633, 2350.9412264693347, 2366.8343215974887, 2372.727136379321, 2376.8811559422047, 2041.652919003773, 2120.236714782677, 2130.1160018592263, 2242.383344580237, 2307.5846347657416, 2302.343832007878, 2314.051238992005, 2318.431984163891, 2319.3158018384447, 2322.059565875823, 2313.086330899302, 2331.6852224949585, 2343.6601692754457, 2343.718753749544, 2353.6116540770754, 8219.219174178443, 8302.377321700897, 8334.083250842463, 8350.039745429232, 8355.373346999, 5021.9748901255625, 5188.325517682677, 5190.4550022247495, 5193.350831146106, 5205.817796377147, 3312.287858110518, 3518.190816112283, 3536.398812201906, 3575.3564358415733, 3584.408572585546, 984.0503913723292, 1018.256672628985, 1070.3417398300066, 1073.0, 1087.9945600272026, 10752.247342686014, 10782.838257426145, 10786.622468213574, 10798.622048228353, 10804.297720648212, 4447.2439685253585, 4590.931136032962, 4656.650751193674, 4698.976505117453, 4742.051589682077, 9307.0, 9341.159295663423, 9370.922343930535, 9385.06149385056, 9388.90611093894, 5135.87160320995, 5145.588352931756, 5150.845474635692, 5151.639385243072, 5160.509751573594, 4382.539833317455, 4454.933176002362, 4753.524647535288, 4795.112904112681, 4819.277108433693, 3766.7928263945746, 3796.5064541609413, 3854.1713531590844, 3905.082305658168, 3912.7456715313415, 1090.907272881814, 1248.4132457744845, 1254.4543123741203, 1259.2822091407993, 1269.8412698412699, 760.6615056299894, 819.2135549872071, 825.6573521988428, 899.5592159841631, 901.8647202919615, 5318.70747108905, 5426.728663566806, 5743.4543718346695, 5754.367019627837, 5818.738156782955, 791.6041979010495, 865.251557402842, 924.8890133183926, 950.1543626172712, 959.0840747086655, 3761.6614504694708, 3785.545734511821, 3786.545614526219, 3808.0479880029993, 3850.4416859555768, 9761.707148785406, 10218.335808172445, 10221.846672299922, 10225.181985441146, 10228.181745460346, 10083.798324033478, 10112.443815590213, 10164.0, 10176.491175441199, 10269.640562580424, 7279.6360181990685, 7599.696012159452, 7789.493682910593, 7798.883016754753, 7920.960395198103, 5578.107502799533, 5630.791161796132, 5757.827265182052, 5862.824115276549, 5909.970450147722, 9030.187283144549, 9296.953515232652, 9350.906490935004, 9355.251579873458, 9367.812643746951, 8992.0, 9258.95370523143, 9477.620895164117, 9575.712728618153, 9650.71047868565, 7142.392896603847, 7226.783196504113, 7294.197638259893, 7298.124225093023, 7306.123265208209, 7761.61191940412, 7777.02787151606, 7853.450258481851, 7872.252136047066, 7906.723264685708, 7034.507584469038, 7088.043114179726, 7129.322714342129, 7151.213366529573, 7268.018817459575, 6905.89641155373, 6965.129358830146, 6991.8951215730785, 7000.369966703021, 7039.507234493536, 6358.237011558643, 6578.5395022347975, 6628.436582890508, 6665.966670166618, 6723.7646682365885, 4041.7372870763306, 4742.07529531738, 4809.422869255712, 4828.396450443694, 4919.778609962489, 4764.118638051971, 4859.975700121477, 4870.95129048705, 4940.333055037524, 4961.355023846877], "value": 4405.538426467049, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [247.80918692606693, 280.35292655648067, 295.95856580078794, 304.98627561759724, 309.6856690459184, 4059.4925634295714, 4984.501549845014, 5005.824796132136, 5019.573336266417, 5084.38987321521, 4335.891602709934, 4760.071786001731, 5137.178051511759, 5160.767765450554, 5198.350206224222, 3456.792592444461, 3956.8830675588492, 4018.3570628699413, 4024.6579040781567, 4026.5973402659793, 3315.7678962472637, 3846.884403522975, 3988.5811989741096, 4000.5199376074765, 4003.83984640614, 3926.7054970877157, 3958.6635136013474, 3960.2871483133026, 3977.482927219472, 3981.223661386031, 3921.862734804297, 3934.3114954883067, 3958.6239307265764, 3959.762414255125, 3970.0, 3063.754899608026, 3345.8494367753265, 3447.1037530242065, 3455.87904423344, 3457.6633548625696, 4865.10260998211, 5044.066847633163, 5081.568066714333, 5113.744312784346, 5131.615128865313, 4316.82732690692, 4472.93290600638, 4473.284274516094, 4478.955210447919, 4507.309758840435, 4489.528599497071, 4535.795889184961, 5103.239382413061, 5446.400895901447, 5454.627237151852, 2963.4962056450454, 3116.56368108465, 3464.34177506273, 3633.7638053526434, 3771.510253449899, 4946.703197808179, 4952.935118949407, 5158.174692049329, 5673.631213971079, 5720.513756330717, 3790.639889210521, 3801.201747633028, 3811.3330167220624, 3815.8361699681864, 3826.617338266207, 3163.940080073155, 3869.6323849234277, 3873.7869417182324, 3885.3977633466857, 3890.0, 4199.202151591249, 4428.247197976351, 4451.375248034444, 4469.351943968171, 4492.348609451611, 4782.4676135087375, 5418.45815418455, 5421.945780542223, 5464.644798088111, 5477.561795056386, 1238.585074000202, 1253.2856271925098, 1256.497401039573, 1262.3246563088887, 1268.6828292926768, 714.7212587091038, 839.7102999465175, 855.153398135841, 859.2052351574894, 875.92992560595, 4270.551592082848, 4785.076237485228, 4793.472718001017, 4853.611711063107, 4962.280469331999, 4515.661325400608, 4601.907961840744, 4848.127337079289, 4933.333999910038, 4995.825146119869, 4134.0, 4552.658550608718, 4680.321353403806, 4856.902861942812, 4866.75897646098, 2581.6256642787066, 2583.2929745814818, 2720.6871209811125, 2745.409736906575, 2805.6492938382703, 4887.560119589254, 5057.393112826484, 5551.972240138854, 5553.583481238923, 5565.888682226333, 4785.712857228507, 5344.225087362389, 5480.671159730429, 5481.972590137104, 5808.82573522795, 5859.179714840011, 5874.265716785402, 5882.647041177457, 5894.557908156906, 5927.792527261525, 5164.793408263778, 5198.8960220794615, 5654.204440448573, 5656.0, 5675.659460432387, 5424.186372044225, 5562.527185189142, 5588.217649529151, 5629.971850140723, 5649.717514124195, 4629.560191781776, 5865.853353666193, 5872.794452194153, 5880.558958078076, 5884.911726324023, 3797.7911214883447, 3850.96149038506, 3855.1133239354544, 3855.5373355196434, 3873.3802591585213, 4464.17412778624, 4532.413655220646, 4841.273808928689, 5373.973130134481, 5632.746526406403, 5003.199488081889, 5464.644798088111, 5470.72646367672, 5474.315710536183, 5501.367342755634, 5114.769835357492, 5475.6440831345835, 5503.871706300119, 5511.283533140666, 5530.336359636869, 5393.8921221574565, 5419.512243898068, 5423.376311724044, 5437.184422336523, 5443.319585051869, 4567.474740404896, 5215.322008138917, 5509.807156749494, 5529.142982837667, 5538.667679939216, 4953.439666505039, 5302.8939421212135, 5385.2191432241325, 5389.434109418611, 5395.946040539544], "value": 3933.1896314739683, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.3637135691948, 737.6274981134526, 754.8109238467479, 761.3984951888009, 787.8030492376904, 14133.434662613508, 14211.786823197648, 14581.0, 14685.265736713149, 14914.17972011539, 12911.741765164714, 13212.603621891358, 13366.866331336709, 13371.0, 13387.464501419956, 640.1549954060654, 794.0352471746827, 799.2447137455107, 801.4389927051059, 806.3589446390105, 804.01508152513, 805.9758207253763, 808.1030056637128, 809.720646376999, 810.4448452809814, 850.086157380815, 851.3997631669689, 851.6678495386805, 851.8849955256023, 852.0031563071191, 836.1053672570353, 843.995780021099, 846.0608724316035, 847.7160151349306, 848.4781859156606, 622.8723111762077, 623.8066199478176, 624.44736408279, 624.597134848025, 626.1640709652573, 12364.56724014655, 12513.436895339732, 12538.623841284685, 12560.74878502434, 12565.120441569094, 11330.660080197607, 11334.546618135264, 11379.772404551946, 11384.089272858151, 11603.883961160365, 9896.653617123438, 10077.193824494023, 10140.492975351202, 10332.535035923327, 10417.062464378167, 3338.8163650999186, 3345.0, 3589.1206654369585, 3608.1340478285283, 3621.1128273573145, 90593.0, 94474.0, 94626.0, 95319.0, 96573.5171324139, 9733.756656083513, 9841.458719770339, 10048.949755251324, 10180.185585153169, 10446.529906154241, 19138.808611913704, 19315.710264346046, 19400.902995485216, 19414.805851941583, 19495.707564386546, 6513.804585862432, 7432.145303290083, 7455.664495097625, 7471.4396420268695, 7549.924500755032, 3994.78028708418, 4041.777702226347, 4056.492938382702, 4070.3690927906223, 4081.6734661227624, 3258.5600943872746, 3361.0421030006332, 3390.627031026585, 3400.2009527760956, 3408.812515311632, 643.3566433566433, 645.5577929118568, 648.9059086432441, 651.1827656291418, 655.8688262347549, 596.639033384796, 596.8686888884439, 611.4987705183763, 611.7063809371526, 629.8960671489153, 8570.528620925908, 8714.869276960851, 8716.43343182691, 8812.911870881337, 9068.727938161866, 525.653068974475, 540.753956949587, 623.975040998355, 626.5614070150913, 630.9053641953743, 4105.220008198433, 4275.315949448073, 4284.271673815459, 4293.162833247487, 4371.934420983688, 71932.0, 76062.61968690231, 82911.92720182044, 102997.48501257446, 122738.0, 70786.64606676933, 81747.0, 102406.48796755969, 105192.0, 106561.46719266509, 84413.57793210994, 85296.57351713201, 85593.57203213945, 87464.5626771862, 89493.10506894978, 8126.674933002851, 8283.50298982063, 8310.501369917823, 8374.204450577185, 8508.957455212685, 42827.57172428236, 54902.45097548974, 55747.16379254396, 56893.71553142208, 58703.0, 77106.0, 78158.6092069536, 78681.60659196667, 82875.5856220715, 85187.57406213177, 10532.157427405942, 10656.57373705043, 10677.679669609925, 10805.405702686428, 11163.106951444028, 11854.170208085352, 12757.808632870328, 13652.795208071688, 13723.794143088062, 15009.399624014917, 12765.93617031909, 12938.870611293767, 13339.733205336033, 13419.0, 13812.37844296989, 11990.820137698116, 12949.611511654666, 13477.326133693472, 13534.526291580141, 13682.384292706653, 12533.435995380412, 13176.341182940623, 13224.669383265495, 13704.862951370358, 13716.931415342859, 10255.84616230772, 10652.680419587425, 11306.660800176009, 11700.70748231267, 11849.289042657123, 11164.609238676607, 11314.717132071764, 11422.371769552754, 11532.82700759506, 11554.537818487166], "value": 7065.174231856583, "unit": "score"}], "name": "dromaeo_css", "value": 6966.356364921517, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 115.0, 115.0, 115.0, 114.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 108.0, 112.0, 144.0, 112.0, 111.0, 135.0, 139.0, 148.0, 109.0], "value": 126.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 184.0, 186.0, 182.0, 199.0, 257.0, 186.0, 223.0, 259.0], "value": 204.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 59.0, 91.0, 63.0, 59.0, 59.0, 59.0, 82.0, 58.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 128.0, 102.0, 97.0, 68.0, 95.0, 96.0, 99.0, 125.0], "value": 98.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 89.0, 87.0, 88.0, 86.0, 90.0, 88.0, 89.0, 86.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 99.0, 98.0, 99.0, 115.0, 161.0, 159.0, 102.0, 98.0, 97.0], "value": 116.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 79.0, 80.0, 87.0, 131.0, 82.0, 83.0, 80.0, 99.0], "value": 88.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 55.0, 56.0, 59.0, 58.0, 59.0, 58.0, 56.0, 55.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 68.0, 77.0, 72.0, 70.0, 72.0, 71.0, 74.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 87.0, 82.0, 100.0, 104.0, 70.0, 96.0, 102.0, 102.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 138.0, 140.0, 138.0, 143.0, 168.0, 146.0, 143.0, 194.0, 174.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 168.0, 163.0, 153.0, 208.0, 167.0, 163.0, 163.0, 165.0], "value": 168.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 68.0, 67.0, 68.0, 67.0, 68.0, 67.0, 67.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1511.5, "alertThreshold": 2.0}]} 12:44:18 INFO - Return code: 0 12:44:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:44:18 INFO - # TBPL SUCCESS # 12:44:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:44:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:44:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:44:19 INFO - 2017-01-09 12:44:19.010 screenresolution[2608:297351] starting screenresolution argv=screenresolution get 12:44:19 INFO - 2017-01-09 12:44:19.013 screenresolution[2608:297351] Display 0: 1600x1200x32@60 12:44:19 INFO - 2017-01-09 12:44:19.019 screenresolution[2609:297354] starting screenresolution argv=screenresolution list 12:44:19 INFO - Available Modes on Display 0 12:44:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:44:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:44:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:44:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:44:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:44:19 INFO - Intel Iris: 12:44:19 INFO - Chipset Model: Intel Iris 12:44:19 INFO - Type: GPU 12:44:19 INFO - Bus: Built-In 12:44:19 INFO - VRAM (Dynamic, Max): 1536 MB 12:44:19 INFO - Vendor: Intel (0x8086) 12:44:19 INFO - Device ID: 0x0a2e 12:44:19 INFO - Revision ID: 0x0009 12:44:19 INFO - Displays: 12:44:19 INFO - Display: 12:44:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:44:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:44:19 INFO - Display Serial Number: 1600x1200 60 12:44:19 INFO - Main Display: Yes 12:44:19 INFO - Mirror: Off 12:44:19 INFO - Online: Yes 12:44:19 INFO - Rotation: Supported 12:44:19 INFO - Return code: 0 12:44:19 INFO - Running post-action listener: _resource_record_post_action 12:44:19 INFO - [mozharness: 2017-01-09 20:44:19.264888Z] Finished run-tests step (success) 12:44:19 INFO - Running post-run listener: _resource_record_post_run 12:44:19 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 622592; Write bytes: 659786240; Read time: 6; Write time: 1895 12:44:19 INFO - TinderboxPrint: CPU usage
53.5% 12:44:19 INFO - TinderboxPrint: I/O read bytes / time
622,592 / 6 12:44:19 INFO - TinderboxPrint: I/O write bytes / time
659,786,240 / 1,895 12:44:19 INFO - TinderboxPrint: CPU idle
2,259.2 (46.5%) 12:44:19 INFO - TinderboxPrint: CPU system
970.4 (20.0%) 12:44:19 INFO - TinderboxPrint: CPU user
1,624.1 (33.5%) 12:44:19 INFO - TinderboxPrint: Swap in / out
363,331,584 / 0 12:44:19 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 340711936; Write bytes: 335587328; Read time: 26841; Write time: 698 12:44:19 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 622592; Write bytes: 307544576; Read time: 6; Write time: 1143 12:44:19 INFO - Running post-run listener: _upload_blobber_files 12:44:19 INFO - Blob upload gear active. 12:44:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:44:19 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 12:44:19 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'] 12:44:19 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 12:44:20 INFO - (blobuploader) - INFO - Open directory for files ... 12:44:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:44:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:44:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:44:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:44:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:44:21 INFO - (blobuploader) - INFO - Done attempting. 12:44:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:44:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:44:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:44:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:44:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:44:22 INFO - (blobuploader) - INFO - Done attempting. 12:44:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:44:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:44:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:44:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:44:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:44:24 INFO - (blobuploader) - INFO - Done attempting. 12:44:24 INFO - (blobuploader) - INFO - Iteration through files over. 12:44:24 INFO - Return code: 0 12:44:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:44:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:44:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8023154322e3ab122a6d7a29c319804b28a04f8943f31a61b31c93d1e7d5df0058c61d3a508ec5142df0995f90146330975157b0040c1baa359397ce023230e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38cf5cffd8c836dcd305403f2f7a6facc71abb7043f43d5f1b1381a29f10424d489e6f6a7e6eeb37d39e570880eb8a893309602c952478446d9425366337fcbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/abecec6d72952a6a0c273a75cfc6c2cc3d0f33aac78fad08288b04ab12a232a1797471ac6c1170050a86ff265e263a6a30eb14bb3015b081376cd8c6fa0549d1"} 12:44:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:44:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:44:24 INFO - Contents: 12:44:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8023154322e3ab122a6d7a29c319804b28a04f8943f31a61b31c93d1e7d5df0058c61d3a508ec5142df0995f90146330975157b0040c1baa359397ce023230e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38cf5cffd8c836dcd305403f2f7a6facc71abb7043f43d5f1b1381a29f10424d489e6f6a7e6eeb37d39e570880eb8a893309602c952478446d9425366337fcbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/abecec6d72952a6a0c273a75cfc6c2cc3d0f33aac78fad08288b04ab12a232a1797471ac6c1170050a86ff265e263a6a30eb14bb3015b081376cd8c6fa0549d1"} 12:44:24 INFO - Running post-run listener: copy_logs_to_upload_dir 12:44:24 INFO - Copying logs to upload dir... 12:44:24 INFO - mkdir: /builds/slave/test/build/upload/logs 12:44:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.034610 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-09 12:44:24.625585) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 12:44:24.629444) ========= 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.bZBpBCFdYz/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.XqCdHarHCS/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8023154322e3ab122a6d7a29c319804b28a04f8943f31a61b31c93d1e7d5df0058c61d3a508ec5142df0995f90146330975157b0040c1baa359397ce023230e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38cf5cffd8c836dcd305403f2f7a6facc71abb7043f43d5f1b1381a29f10424d489e6f6a7e6eeb37d39e570880eb8a893309602c952478446d9425366337fcbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/abecec6d72952a6a0c273a75cfc6c2cc3d0f33aac78fad08288b04ab12a232a1797471ac6c1170050a86ff265e263a6a30eb14bb3015b081376cd8c6fa0549d1"} build_url:https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010864 build_url: 'https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/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/8023154322e3ab122a6d7a29c319804b28a04f8943f31a61b31c93d1e7d5df0058c61d3a508ec5142df0995f90146330975157b0040c1baa359397ce023230e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38cf5cffd8c836dcd305403f2f7a6facc71abb7043f43d5f1b1381a29f10424d489e6f6a7e6eeb37d39e570880eb8a893309602c952478446d9425366337fcbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/abecec6d72952a6a0c273a75cfc6c2cc3d0f33aac78fad08288b04ab12a232a1797471ac6c1170050a86ff265e263a6a30eb14bb3015b081376cd8c6fa0549d1"}' ========= master_lag: 0.10 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 12:44:24.741665) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 12:44:24.741978) ========= 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.bZBpBCFdYz/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.XqCdHarHCS/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.004878 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 12:44:24.768000) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 12:44:24.768293) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 12:44:24.768577) ========= ========= Total master_lag: 0.27 =========