builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0025 starttime: 1479227898.56 results: success (0) buildid: 20161115060313 builduid: 4a146f9cd6ab4b7c82d3f9f2da6c9d68 revision: f8ba9c9b401f57b0047ddd6932cb830190865b38 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-15 08:38:18.559220) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-15 08:38:18.559646) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-15 08:38:18.562530) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-15 08:38:18.586715) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 08:38:18.587945) ========= 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.B1b4S84xHu/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.BvNxSFSmHE/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-15 08:38:18-- 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% 155M=0s 2016-11-15 08:38:18 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133117 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 08:38:18.740337) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 08:38:18.740680) ========= 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.B1b4S84xHu/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.BvNxSFSmHE/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.020189 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 08:38:18.789832) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-15 08:38:18.790219) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f8ba9c9b401f57b0047ddd6932cb830190865b38 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f8ba9c9b401f57b0047ddd6932cb830190865b38 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.B1b4S84xHu/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.BvNxSFSmHE/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-15 08:38:18,868 truncating revision to first 12 chars 2016-11-15 08:38:18,868 Setting DEBUG logging. 2016-11-15 08:38:18,868 attempt 1/10 2016-11-15 08:38:18,868 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f8ba9c9b401f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-15 08:38:20,409 unpacking tar archive at: mozilla-central-f8ba9c9b401f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.804416 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-15 08:38:20.616655) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-15 08:38:20.616989) ========= script_repo_revision: f8ba9c9b401f57b0047ddd6932cb830190865b38 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-15 08:38:20.617368) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-15 08:38:20.617630) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-15 08:38:20.635746) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-11-15 08:38:20.636040) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.B1b4S84xHu/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.BvNxSFSmHE/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 08:38:20 INFO - MultiFileLogger online at 20161115 08:38:20 in /builds/slave/test 08:38:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:38:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:38:20 INFO - {'append_to_log': False, 08:38:20 INFO - 'base_work_dir': '/builds/slave/test', 08:38:20 INFO - 'blob_upload_branch': 'Firefox', 08:38:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:38:20 INFO - 'branch': 'Firefox', 08:38:20 INFO - 'buildbot_json_path': 'buildprops.json', 08:38:20 INFO - 'config_files': ('talos/mac_config.py',), 08:38:20 INFO - 'default_actions': ('clobber', 08:38:20 INFO - 'read-buildbot-config', 08:38:20 INFO - 'download-and-extract', 08:38:20 INFO - 'populate-webroot', 08:38:20 INFO - 'create-virtualenv', 08:38:20 INFO - 'install', 08:38:20 INFO - 'run-tests'), 08:38:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:38:20 INFO - 'download_minidump_stackwalk': True, 08:38:20 INFO - 'download_symbols': 'ondemand', 08:38:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:38:20 INFO - 'tooltool.py': '/tools/tooltool.py', 08:38:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:38:20 INFO - '/tools/misc-python/virtualenv.py')}, 08:38:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:38:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:38:20 INFO - 'installer_path': 'installer.exe', 08:38:20 INFO - 'log_level': 'info', 08:38:20 INFO - 'log_name': 'talos', 08:38:20 INFO - 'log_to_console': True, 08:38:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:38:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:38:20 INFO - 'opt_config_files': (), 08:38:20 INFO - 'pip_index': False, 08:38:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:38:20 INFO - 'cmd': ('bash', 08:38:20 INFO - '-c', 08:38:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:38:20 INFO - 'enabled': True, 08:38:20 INFO - 'halt_on_failure': False, 08:38:20 INFO - 'name': 'check_screen_resolution'},), 08:38:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:38:20 INFO - 'cmd': ('bash', 08:38:20 INFO - '-c', 08:38:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:38:20 INFO - 'enabled': True, 08:38:20 INFO - 'halt_on_failure': False, 08:38:20 INFO - 'name': 'check_screen_resolution'},), 08:38:20 INFO - 'run_cmd_checks_enabled': True, 08:38:20 INFO - 'sps_profile': False, 08:38:20 INFO - 'sps_profile_interval': 0, 08:38:20 INFO - 'suite': 'dromaeojs-e10s', 08:38:20 INFO - 'system_bits': '32', 08:38:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:38:20 INFO - 'title': 't-yosemite-r7-0025', 08:38:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:38:20 INFO - 'use_talos_json': True, 08:38:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:38:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:38:20 INFO - 'work_dir': 'build'} 08:38:20 INFO - [mozharness: 2016-11-15 16:38:20.841537Z] Running clobber step. 08:38:20 INFO - Running pre-action listener: _resource_record_pre_action 08:38:20 INFO - Running main action method: clobber 08:38:20 INFO - rmtree: /builds/slave/test/build 08:38:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:38:21 INFO - Running post-action listener: _resource_record_post_action 08:38:21 INFO - [mozharness: 2016-11-15 16:38:21.766516Z] Finished clobber step (success) 08:38:21 INFO - [mozharness: 2016-11-15 16:38:21.766644Z] Running read-buildbot-config step. 08:38:21 INFO - Running pre-action listener: _resource_record_pre_action 08:38:21 INFO - Running main action method: read_buildbot_config 08:38:21 INFO - Using buildbot properties: 08:38:21 INFO - { 08:38:21 INFO - "project": "", 08:38:21 INFO - "product": "firefox", 08:38:21 INFO - "script_repo_revision": "production", 08:38:21 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:38:21 INFO - "repository": "", 08:38:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:38:21 INFO - "buildid": "20161115060313", 08:38:21 INFO - "pgo_build": "False", 08:38:21 INFO - "basedir": "/builds/slave/test", 08:38:21 INFO - "buildnumber": 14, 08:38:21 INFO - "slavename": "t-yosemite-r7-0025", 08:38:21 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 08:38:21 INFO - "platform": "yosemite_r7", 08:38:21 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:38:21 INFO - "branch": "mozilla-central", 08:38:21 INFO - "repo_path": "mozilla-central", 08:38:21 INFO - "revision": "f8ba9c9b401f57b0047ddd6932cb830190865b38", 08:38:21 INFO - "stage_platform": "macosx64", 08:38:21 INFO - "builduid": "4a146f9cd6ab4b7c82d3f9f2da6c9d68", 08:38:21 INFO - "slavebuilddir": "test" 08:38:21 INFO - } 08:38:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:38:21 INFO - Running post-action listener: _resource_record_post_action 08:38:21 INFO - [mozharness: 2016-11-15 16:38:21.770028Z] Finished read-buildbot-config step (success) 08:38:21 INFO - [mozharness: 2016-11-15 16:38:21.770161Z] Running download-and-extract step. 08:38:21 INFO - Running pre-action listener: _resource_record_pre_action 08:38:21 INFO - Running main action method: download_and_extract 08:38:21 INFO - mkdir: /builds/slave/test/build/tests 08:38:21 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')]} 08:38:21 INFO - trying https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:38:21 INFO - Downloading https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:38:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:38:23 INFO - Downloaded 1199 bytes. 08:38:23 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:38:23 INFO - Using the following test package requirements: 08:38:23 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:38:23 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:38:23 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:38:23 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:38:23 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:38:23 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:38:23 INFO - u'jsshell-mac.zip'], 08:38:23 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:38:23 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:38:23 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:38:23 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:38:23 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:38:23 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:38:23 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:38:23 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:38:23 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:38:23 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:38:23 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:38:23 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:38:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:38:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:38:23 INFO - Fetch https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:38:23 INFO - Http code: 200 08:38:23 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 08:38:23 INFO - x-amz-cf-id: ua5eiPjGCBHIebTc4i5D2GmIW87xBZVAbkOgHZqOHlpTVI2LeT-VaQ== 08:38:23 INFO - x-amz-version-id: BtvcBPOETEE8fbGR2ERbjj.7el.1OJTj 08:38:24 INFO - Expected file size: 16330861 08:38:24 INFO - Obtained file size: 16330861 08:38:30 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:38:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:38:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:38:30 INFO - Fetch https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:38:31 INFO - Http code: 200 08:38:31 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 08:38:31 INFO - x-amz-cf-id: c94lKWWq8Zprt6SdCsqQNuGxR5RYDzfZQqvvBtL7tONKoIDF1Zyrdw== 08:38:31 INFO - x-amz-version-id: BtvcBPOETEE8fbGR2ERbjj.7el.1OJTj 08:38:31 INFO - Expected file size: 16330861 08:38:31 INFO - Obtained file size: 16330861 08:38:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:38:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:38:38 INFO - Fetch https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:38:38 INFO - Http code: 200 08:38:38 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 08:38:38 INFO - x-amz-cf-id: hOxsHgB87Opgzz1kKIY2XvzzY8nvEKZrmbIHtOL55tVmVX_9JDGcAA== 08:38:38 INFO - x-amz-version-id: zeLkAUf6zEtdTs1b6ZfXgCivuCjxNV16 08:38:39 INFO - Expected file size: 14409038 08:38:39 INFO - Obtained file size: 14409038 08:38:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:38:39 INFO - trying https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:38:39 INFO - Downloading https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:38:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:38:42 INFO - Downloaded 110980325 bytes. 08:38:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:38:42 INFO - mkdir: /builds/slave/test/properties 08:38:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:38:42 INFO - Writing to file /builds/slave/test/properties/build_url 08:38:42 INFO - Contents: 08:38:42 INFO - build_url:https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:38:43 INFO - Running post-action listener: _resource_record_post_action 08:38:43 INFO - Running post-action listener: set_extra_try_arguments 08:38:43 INFO - [mozharness: 2016-11-15 16:38:43.048258Z] Finished download-and-extract step (success) 08:38:43 INFO - [mozharness: 2016-11-15 16:38:43.048388Z] Running populate-webroot step. 08:38:43 INFO - Running pre-action listener: _resource_record_pre_action 08:38:43 INFO - Running main action method: populate_webroot 08:38:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:38:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:38:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:38:43 INFO - '--tppagecycles', 08:38:43 INFO - '7'], 08:38:43 INFO - 'tests': ['tsvgm']}}, 08:38:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:38:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:38:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:38:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:38:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:38:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:38:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:43 INFO - 'talos_options': ['--disable-e10s'], 08:38:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:38:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:38:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:43 INFO - 'talos_options': ['--disable-e10s'], 08:38:43 INFO - 'tests': ['damp', 'tps']}, 08:38:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:43 INFO - 'tests': ['damp', 'tps']}, 08:38:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:38:43 INFO - 'tests': ['dromaeo_dom']}, 08:38:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:38:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:38:43 INFO - 'tests': ['basic_compositor_video']}, 08:38:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:38:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:38:43 INFO - 'tests': ['a11yr', 08:38:43 INFO - 'ts_paint', 08:38:43 INFO - 'tpaint', 08:38:43 INFO - 'sessionrestore', 08:38:43 INFO - 'sessionrestore_no_auto_restore', 08:38:43 INFO - 'tabpaint']}, 08:38:43 INFO - 'other-e10s': {'tests': ['a11yr', 08:38:43 INFO - 'ts_paint', 08:38:43 INFO - 'tpaint', 08:38:43 INFO - 'sessionrestore', 08:38:43 INFO - 'sessionrestore_no_auto_restore', 08:38:43 INFO - 'tabpaint']}, 08:38:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:38:43 INFO - 'ts_paint', 08:38:43 INFO - 'tpaint', 08:38:43 INFO - 'sessionrestore', 08:38:43 INFO - 'sessionrestore_no_auto_restore', 08:38:43 INFO - 'tabpaint']}, 08:38:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:38:43 INFO - 'ts_paint', 08:38:43 INFO - 'tpaint', 08:38:43 INFO - 'sessionrestore', 08:38:43 INFO - 'sessionrestore_no_auto_restore', 08:38:43 INFO - 'tabpaint']}, 08:38:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:38:43 INFO - 'tests': ['a11yr', 08:38:43 INFO - 'ts_paint', 08:38:43 INFO - 'tpaint', 08:38:43 INFO - 'sessionrestore', 08:38:43 INFO - 'sessionrestore_no_auto_restore', 08:38:43 INFO - 'tabpaint']}, 08:38:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:38:43 INFO - 'tests': ['a11yr', 08:38:43 INFO - 'ts_paint', 08:38:43 INFO - 'tpaint', 08:38:43 INFO - 'sessionrestore', 08:38:43 INFO - 'sessionrestore_no_auto_restore', 08:38:43 INFO - 'tabpaint']}, 08:38:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:38:43 INFO - 'tests': ['tsvgx', 08:38:43 INFO - 'tsvgr_opacity', 08:38:43 INFO - 'tart', 08:38:43 INFO - 'tscrollx', 08:38:43 INFO - 'cart']}, 08:38:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:38:43 INFO - 'tsvgr_opacity', 08:38:43 INFO - 'tart', 08:38:43 INFO - 'tscrollx', 08:38:43 INFO - 'cart']}, 08:38:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:43 INFO - 'talos_options': ['--disable-e10s'], 08:38:43 INFO - 'tests': ['tp5o']}, 08:38:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:43 INFO - 'tests': ['tp5o']}, 08:38:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:43 INFO - 'talos_options': ['--disable-e10s', 08:38:43 INFO - '--xperf_path', 08:38:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:38:43 INFO - 'tests': ['tp5n']}, 08:38:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:38:43 INFO - 'talos_options': ['--xperf_path', 08:38:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:38:43 INFO - 'tests': ['tp5n']}}, 08:38:43 INFO - 'talos.zip': {'path': '', 08:38:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:38:43 INFO - Running post-action listener: _resource_record_post_action 08:38:43 INFO - [mozharness: 2016-11-15 16:38:43.067604Z] Finished populate-webroot step (success) 08:38:43 INFO - [mozharness: 2016-11-15 16:38:43.067739Z] Running create-virtualenv step. 08:38:43 INFO - Running pre-action listener: _resource_record_pre_action 08:38:43 INFO - Running main action method: create_virtualenv 08:38:43 INFO - Creating virtualenv /builds/slave/test/build/venv 08:38:43 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 08:38:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:38:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:38:43 INFO - Using real prefix '/tools/python27' 08:38:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:38:43 INFO - Installing distribute.............................................................................................................................................................................................done. 08:38:46 INFO - Installing pip.................done. 08:38:46 INFO - Return code: 0 08:38:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:38:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:38:46 INFO - Reading from file tmpfile_stdout 08:38:46 INFO - Output received: 08:38:46 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) 08:38:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:38:46 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')]} 08:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:47 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')]} 08:38:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:47 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 0x105c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa2b58c2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a80d30>, '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 0x1058a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a78b20>, '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.B1b4S84xHu/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.BvNxSFSmHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 08:38:47 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 08:38:47 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 08:38:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B1b4S84xHu/Render', 08:38:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:38:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:38:47 INFO - 'HOME': '/Users/cltbld', 08:38:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:38:47 INFO - 'LOGNAME': 'cltbld', 08:38:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:47 INFO - 'MOZ_NO_REMOTE': '1', 08:38:47 INFO - 'NO_EM_RESTART': '1', 08:38:47 INFO - 'PAGER': '/bin/cat', 08:38:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:38:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:47 INFO - 'PWD': '/builds/slave/test', 08:38:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:38:47 INFO - 'SHELL': '/bin/bash', 08:38:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvNxSFSmHE/Listeners', 08:38:47 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 08:38:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:38:47 INFO - 'USER': 'cltbld', 08:38:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:38:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:38:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:47 INFO - 'XPC_FLAGS': '0x0', 08:38:47 INFO - 'XPC_SERVICE_NAME': '0', 08:38:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:38:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:47 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 08:38:47 INFO - Cleaning up... 08:38:47 INFO - Return code: 0 08:38:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:38:47 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')]} 08:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:47 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')]} 08:38:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:47 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 0x105c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa2b58c2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a80d30>, '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 0x1058a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a78b20>, '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.B1b4S84xHu/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.BvNxSFSmHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 08:38:47 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 08:38:47 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 08:38:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B1b4S84xHu/Render', 08:38:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:38:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:38:47 INFO - 'HOME': '/Users/cltbld', 08:38:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:38:47 INFO - 'LOGNAME': 'cltbld', 08:38:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:47 INFO - 'MOZ_NO_REMOTE': '1', 08:38:47 INFO - 'NO_EM_RESTART': '1', 08:38:47 INFO - 'PAGER': '/bin/cat', 08:38:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:38:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:47 INFO - 'PWD': '/builds/slave/test', 08:38:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:38:47 INFO - 'SHELL': '/bin/bash', 08:38:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvNxSFSmHE/Listeners', 08:38:47 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 08:38:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:38:47 INFO - 'USER': 'cltbld', 08:38:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:38:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:38:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:47 INFO - 'XPC_FLAGS': '0x0', 08:38:47 INFO - 'XPC_SERVICE_NAME': '0', 08:38:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:38:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:47 INFO - Downloading/unpacking psutil>=3.1.1 08:38:47 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 08:38:47 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 08:38:47 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 08:38:47 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 08:38:47 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 08:38:47 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 08:38:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:38:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:38:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:38:50 INFO - Installing collected packages: psutil 08:38:50 INFO - Running setup.py install for psutil 08:38:51 INFO - building 'psutil._psutil_osx' extension 08:38:51 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 08:38:51 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 08:38:51 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 08:38:51 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 08:38:51 INFO - building 'psutil._psutil_posix' extension 08:38:51 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 08:38:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:38:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:38:51 INFO - ^ 08:38:51 INFO - 1 warning generated. 08:38:51 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 08:38:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:38:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:38:51 INFO - Successfully installed psutil 08:38:51 INFO - Cleaning up... 08:38:51 INFO - Return code: 0 08:38:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:38:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:38:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:38:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:51 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 0x105c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa2b58c2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a80d30>, '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 0x1058a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a78b20>, '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.B1b4S84xHu/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.BvNxSFSmHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 08:38:51 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 08:38:51 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 08:38:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B1b4S84xHu/Render', 08:38:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:38:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:38:51 INFO - 'HOME': '/Users/cltbld', 08:38:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:38:51 INFO - 'LOGNAME': 'cltbld', 08:38:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:51 INFO - 'MOZ_NO_REMOTE': '1', 08:38:51 INFO - 'NO_EM_RESTART': '1', 08:38:51 INFO - 'PAGER': '/bin/cat', 08:38:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:38:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:51 INFO - 'PWD': '/builds/slave/test', 08:38:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:38:51 INFO - 'SHELL': '/bin/bash', 08:38:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvNxSFSmHE/Listeners', 08:38:51 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 08:38:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:38:51 INFO - 'USER': 'cltbld', 08:38:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:38:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:38:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:51 INFO - 'XPC_FLAGS': '0x0', 08:38:51 INFO - 'XPC_SERVICE_NAME': '0', 08:38:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:38:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:38: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 08:38: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 08:38: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 08:38: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 08:38: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 08:38: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 08:38:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:38:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:38:54 INFO - Installing collected packages: mozsystemmonitor 08:38:54 INFO - Running setup.py install for mozsystemmonitor 08:38:54 INFO - Successfully installed mozsystemmonitor 08:38:54 INFO - Cleaning up... 08:38:54 INFO - Return code: 0 08:38:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:38: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')]} 08:38:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38: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')]} 08:38:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:54 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 0x105c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa2b58c2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a80d30>, '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 0x1058a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a78b20>, '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.B1b4S84xHu/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.BvNxSFSmHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 08:38:54 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 08:38:54 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 08:38:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B1b4S84xHu/Render', 08:38:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:38:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:38:54 INFO - 'HOME': '/Users/cltbld', 08:38:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:38:54 INFO - 'LOGNAME': 'cltbld', 08:38:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:54 INFO - 'MOZ_NO_REMOTE': '1', 08:38:54 INFO - 'NO_EM_RESTART': '1', 08:38:54 INFO - 'PAGER': '/bin/cat', 08:38:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:38:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:54 INFO - 'PWD': '/builds/slave/test', 08:38:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:38:54 INFO - 'SHELL': '/bin/bash', 08:38:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvNxSFSmHE/Listeners', 08:38:54 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 08:38:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:38:54 INFO - 'USER': 'cltbld', 08:38:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:38:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:38:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:54 INFO - 'XPC_FLAGS': '0x0', 08:38:54 INFO - 'XPC_SERVICE_NAME': '0', 08:38:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:38:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:55 INFO - Downloading/unpacking jsonschema==2.5.1 08:38:55 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 08:38:55 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 08:38:55 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 08:38:55 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 08:38:55 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 08:38:55 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 08:38:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:38:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:58 INFO - Downloading functools32-3.2.3-2.tar.gz 08:38:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:38:58 INFO - warning: no files found matching '*.txt' 08:38:58 INFO - no previously-included directories found matching 'build' 08:38:58 INFO - no previously-included directories found matching 'dist' 08:38:58 INFO - no previously-included directories found matching '.git*' 08:38:58 INFO - Installing collected packages: jsonschema, functools32 08:38:58 INFO - Running setup.py install for functools32 08:38:58 INFO - warning: no files found matching '*.txt' 08:38:58 INFO - no previously-included directories found matching 'build' 08:38:58 INFO - no previously-included directories found matching 'dist' 08:38:58 INFO - no previously-included directories found matching '.git*' 08:38:58 INFO - Successfully installed jsonschema functools32 08:38:58 INFO - Cleaning up... 08:38:58 INFO - Return code: 0 08:38:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:38:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:58 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 0x105c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa2b58c2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a80d30>, '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 0x1058a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a78b20>, '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.B1b4S84xHu/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.BvNxSFSmHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 08:38:58 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 08:38:58 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 08:38:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B1b4S84xHu/Render', 08:38:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:38:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:38:58 INFO - 'HOME': '/Users/cltbld', 08:38:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:38:58 INFO - 'LOGNAME': 'cltbld', 08:38:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:58 INFO - 'MOZ_NO_REMOTE': '1', 08:38:58 INFO - 'NO_EM_RESTART': '1', 08:38:58 INFO - 'PAGER': '/bin/cat', 08:38:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:38:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:58 INFO - 'PWD': '/builds/slave/test', 08:38:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:38:58 INFO - 'SHELL': '/bin/bash', 08:38:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvNxSFSmHE/Listeners', 08:38:58 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 08:38:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:38:58 INFO - 'USER': 'cltbld', 08:38:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:38:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:38:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:58 INFO - 'XPC_FLAGS': '0x0', 08:38:58 INFO - 'XPC_SERVICE_NAME': '0', 08:38:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:38:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:38:58 INFO - Cleaning up... 08:38:58 INFO - Return code: 0 08:38:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:38:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:38:58 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 0x105c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa2b58c2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a80d30>, '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 0x1058a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a78b20>, '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.B1b4S84xHu/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.BvNxSFSmHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 08:38:58 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 08:38:58 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 08:38:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B1b4S84xHu/Render', 08:38:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:38:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:38:58 INFO - 'HOME': '/Users/cltbld', 08:38:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:38:58 INFO - 'LOGNAME': 'cltbld', 08:38:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:58 INFO - 'MOZ_NO_REMOTE': '1', 08:38:58 INFO - 'NO_EM_RESTART': '1', 08:38:58 INFO - 'PAGER': '/bin/cat', 08:38:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:38:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:58 INFO - 'PWD': '/builds/slave/test', 08:38:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:38:58 INFO - 'SHELL': '/bin/bash', 08:38:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvNxSFSmHE/Listeners', 08:38:58 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 08:38:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:38:58 INFO - 'USER': 'cltbld', 08:38:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:38:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:38:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:58 INFO - 'XPC_FLAGS': '0x0', 08:38:58 INFO - 'XPC_SERVICE_NAME': '0', 08:38:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:38:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:59 INFO - Downloading/unpacking blobuploader==1.2.4 08:38:59 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 08:38:59 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 08:38:59 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 08:38:59 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 08:38:59 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 08:38:59 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 08:39:01 INFO - Downloading blobuploader-1.2.4.tar.gz 08:39:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:39:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:39: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 08:39: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 08:39: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 08:39: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 08:39: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 08:39: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 08:39:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:39:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:39:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:39:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:39:02 INFO - Downloading docopt-0.6.1.tar.gz 08:39:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:39:02 INFO - Installing collected packages: blobuploader, requests, docopt 08:39:02 INFO - Running setup.py install for blobuploader 08:39:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:39:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:39:02 INFO - Running setup.py install for requests 08:39:03 INFO - Running setup.py install for docopt 08:39:03 INFO - Successfully installed blobuploader requests docopt 08:39:03 INFO - Cleaning up... 08:39:03 INFO - Return code: 0 08:39:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:39:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:03 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 0x105c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa2b58c2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a80d30>, '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 0x1058a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a78b20>, '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.B1b4S84xHu/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.BvNxSFSmHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 08:39:03 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 08:39:03 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 08:39:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B1b4S84xHu/Render', 08:39:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:39:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:39:03 INFO - 'HOME': '/Users/cltbld', 08:39:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:39:03 INFO - 'LOGNAME': 'cltbld', 08:39:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:03 INFO - 'MOZ_NO_REMOTE': '1', 08:39:03 INFO - 'NO_EM_RESTART': '1', 08:39:03 INFO - 'PAGER': '/bin/cat', 08:39:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:39:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:03 INFO - 'PWD': '/builds/slave/test', 08:39:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:39:03 INFO - 'SHELL': '/bin/bash', 08:39:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvNxSFSmHE/Listeners', 08:39:03 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 08:39:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:39:03 INFO - 'USER': 'cltbld', 08:39:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:39:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:39:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:03 INFO - 'XPC_FLAGS': '0x0', 08:39:03 INFO - 'XPC_SERVICE_NAME': '0', 08:39:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:39:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:39:03 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-N2B2CS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:39:04 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Y59zrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:39:04 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-3frQ5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:39:04 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-7_ygAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:39:04 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-k3V1Mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:39:04 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-esnWbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:39:04 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-xddWSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:39:04 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-sY45OC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:39:04 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-tggGUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:39:04 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-70cSkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:39:04 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ifJ_QL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:39:05 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-XWaAHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:39:05 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-kL4nQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:39:05 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-tdDcuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:39:05 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-JMcR1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:39:05 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-mb2WGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:39:05 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-GgiplB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:39:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:39:05 INFO - Running setup.py install for manifestparser 08:39:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:39:05 INFO - Running setup.py install for mozcrash 08:39:05 INFO - Running setup.py install for mozdebug 08:39:06 INFO - Running setup.py install for mozdevice 08:39:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:39:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:39:06 INFO - Running setup.py install for mozfile 08:39:06 INFO - Running setup.py install for mozhttpd 08:39:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:39:06 INFO - Running setup.py install for mozinfo 08:39:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:39:06 INFO - Running setup.py install for mozInstall 08:39:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:39:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:39:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:39:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:39:06 INFO - Running setup.py install for mozleak 08:39:06 INFO - Running setup.py install for mozlog 08:39:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:39:07 INFO - Running setup.py install for moznetwork 08:39:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:39:07 INFO - Running setup.py install for mozprocess 08:39:07 INFO - Running setup.py install for mozprofile 08:39:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:39:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:39:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:39:07 INFO - Running setup.py install for mozrunner 08:39:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:39:07 INFO - Running setup.py install for mozscreenshot 08:39:07 INFO - Running setup.py install for moztest 08:39:08 INFO - Running setup.py install for mozversion 08:39:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:39:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:39:08 INFO - Cleaning up... 08:39:08 INFO - Return code: 0 08:39:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:08 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 0x105c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa2b58c2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a80d30>, '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 0x1058a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a78b20>, '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.B1b4S84xHu/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.BvNxSFSmHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 08:39:08 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 08:39:08 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 08:39:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B1b4S84xHu/Render', 08:39:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:39:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:39:08 INFO - 'HOME': '/Users/cltbld', 08:39:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:39:08 INFO - 'LOGNAME': 'cltbld', 08:39:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:08 INFO - 'MOZ_NO_REMOTE': '1', 08:39:08 INFO - 'NO_EM_RESTART': '1', 08:39:08 INFO - 'PAGER': '/bin/cat', 08:39:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:39:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:08 INFO - 'PWD': '/builds/slave/test', 08:39:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:39:08 INFO - 'SHELL': '/bin/bash', 08:39:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvNxSFSmHE/Listeners', 08:39:08 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 08:39:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:39:08 INFO - 'USER': 'cltbld', 08:39:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:39:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:39:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:08 INFO - 'XPC_FLAGS': '0x0', 08:39:08 INFO - 'XPC_SERVICE_NAME': '0', 08:39:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:39:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:39:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-DaIT5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:39:08 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)) 08:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:39:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-lFlXDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:39:08 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)) 08:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:39:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-nGo3VQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:39:08 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)) 08:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:39:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-c4DNoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:39:08 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)) 08:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:39:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-YlqR0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:39:09 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)) 08:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:39:09 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-iKF_6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:39:09 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)) 08:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:39:09 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-dyrJS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:39:09 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)) 08:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:39:09 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-IyNhOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:39:09 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)) 08:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:39:09 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-4lkvGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:39:09 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)) 08:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:39:09 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-kX52bp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:39:09 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)) 08:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:39:09 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-LzcXcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:39:09 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)) 08:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:39:09 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-kl9tcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:39:09 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)) 08:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:39:09 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-e3oD1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:39:09 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)) 08:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:39:09 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-fUQHli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:39:10 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)) 08:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:39:10 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-IWhRBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:39:10 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)) 08:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:39:10 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-rMmMcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:39:10 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-BA4uVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:39:10 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)) 08:39:10 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)) 08:39:10 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)) 08:39:10 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)) 08:39:10 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)) 08:39:10 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)) 08:39:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:39:10 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 08:39:10 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 08:39:10 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 08:39:10 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 08:39:10 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 08:39:10 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 08:39:12 INFO - Downloading blessings-1.6.tar.gz 08:39:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:39:12 INFO - Installing collected packages: blessings 08:39:12 INFO - Running setup.py install for blessings 08:39:13 INFO - Successfully installed blessings 08:39:13 INFO - Cleaning up... 08:39:13 INFO - Return code: 0 08:39:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:39:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:39:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:39:13 INFO - Reading from file tmpfile_stdout 08:39:13 INFO - Current package versions: 08:39:13 INFO - blessings == 1.6 08:39:13 INFO - blobuploader == 1.2.4 08:39:13 INFO - docopt == 0.6.1 08:39:13 INFO - functools32 == 3.2.3-2 08:39:13 INFO - jsonschema == 2.5.1 08:39:13 INFO - manifestparser == 1.1 08:39:13 INFO - mozInstall == 1.12 08:39:13 INFO - mozcrash == 1.0 08:39:13 INFO - mozdebug == 0.1 08:39:13 INFO - mozdevice == 0.48 08:39:13 INFO - mozfile == 1.2 08:39:13 INFO - mozhttpd == 0.7 08:39:13 INFO - mozinfo == 0.9 08:39:13 INFO - mozleak == 0.1 08:39:13 INFO - mozlog == 3.4 08:39:13 INFO - moznetwork == 0.27 08:39:13 INFO - mozprocess == 0.23 08:39:13 INFO - mozprofile == 0.28 08:39:13 INFO - mozrunner == 6.13 08:39:13 INFO - mozscreenshot == 0.1 08:39:13 INFO - mozsystemmonitor == 0.3 08:39:13 INFO - moztest == 0.7 08:39:13 INFO - mozversion == 1.4 08:39:13 INFO - psutil == 3.1.1 08:39:13 INFO - requests == 1.2.3 08:39:13 INFO - wsgiref == 0.1.2 08:39:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:39:13 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')]} 08:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:13 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')]} 08:39:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:13 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 0x105c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa2b58c2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a80d30>, '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 0x1058a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a78b20>, '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.B1b4S84xHu/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.BvNxSFSmHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 08:39:13 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 08:39:13 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 08:39:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B1b4S84xHu/Render', 08:39:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:39:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:39:13 INFO - 'HOME': '/Users/cltbld', 08:39:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:39:13 INFO - 'LOGNAME': 'cltbld', 08:39:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:13 INFO - 'MOZ_NO_REMOTE': '1', 08:39:13 INFO - 'NO_EM_RESTART': '1', 08:39:13 INFO - 'PAGER': '/bin/cat', 08:39:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:39:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:13 INFO - 'PWD': '/builds/slave/test', 08:39:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:39:13 INFO - 'SHELL': '/bin/bash', 08:39:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvNxSFSmHE/Listeners', 08:39:13 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 08:39:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:39:13 INFO - 'USER': 'cltbld', 08:39:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:39:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:39:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:13 INFO - 'XPC_FLAGS': '0x0', 08:39:13 INFO - 'XPC_SERVICE_NAME': '0', 08:39:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:13 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)) 08:39:13 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)) 08:39:13 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)) 08:39:13 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)) 08:39:13 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)) 08:39:13 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)) 08:39:13 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)) 08:39:13 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)) 08:39:13 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)) 08:39:13 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)) 08:39:13 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)) 08:39:13 INFO - Cleaning up... 08:39:13 INFO - Return code: 0 08:39:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:39:13 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')]} 08:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:13 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')]} 08:39:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:13 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 0x105c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa2b58c2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a80d30>, '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 0x1058a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a78b20>, '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.B1b4S84xHu/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.BvNxSFSmHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 08:39:13 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 08:39:13 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 08:39:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B1b4S84xHu/Render', 08:39:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:39:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:39:13 INFO - 'HOME': '/Users/cltbld', 08:39:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:39:13 INFO - 'LOGNAME': 'cltbld', 08:39:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:13 INFO - 'MOZ_NO_REMOTE': '1', 08:39:13 INFO - 'NO_EM_RESTART': '1', 08:39:13 INFO - 'PAGER': '/bin/cat', 08:39:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:39:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:13 INFO - 'PWD': '/builds/slave/test', 08:39:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:39:13 INFO - 'SHELL': '/bin/bash', 08:39:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvNxSFSmHE/Listeners', 08:39:13 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 08:39:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:39:13 INFO - 'USER': 'cltbld', 08:39:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:39:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:39:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:13 INFO - 'XPC_FLAGS': '0x0', 08:39:13 INFO - 'XPC_SERVICE_NAME': '0', 08:39:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:39:13 INFO - Cleaning up... 08:39:13 INFO - Return code: 0 08:39:13 INFO - Running post-action listener: _resource_record_post_action 08:39:13 INFO - Running post-action listener: _start_resource_monitoring 08:39:14 INFO - Starting resource monitoring. 08:39:14 INFO - [mozharness: 2016-11-15 16:39:14.011729Z] Finished create-virtualenv step (success) 08:39:14 INFO - [mozharness: 2016-11-15 16:39:14.012786Z] Running install step. 08:39:14 INFO - Running pre-action listener: _resource_record_pre_action 08:39:14 INFO - Running main action method: install 08:39:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:39:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:39:14 INFO - Reading from file tmpfile_stdout 08:39:14 INFO - Detecting whether we're running mozinstall >=1.0... 08:39:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:39:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:39:14 INFO - Reading from file tmpfile_stdout 08:39:14 INFO - Output received: 08:39:14 INFO - Usage: mozinstall [options] installer 08:39:14 INFO - Options: 08:39:14 INFO - -h, --help show this help message and exit 08:39:14 INFO - -d DEST, --destination=DEST 08:39:14 INFO - Directory to install application into. [default: 08:39:14 INFO - "/builds/slave/test"] 08:39:14 INFO - --app=APP Application being installed. [default: firefox] 08:39:14 INFO - mkdir: /builds/slave/test/build/application 08:39:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:39:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:39:48 INFO - Reading from file tmpfile_stdout 08:39:48 INFO - Output received: 08:39:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:39:48 INFO - Running post-action listener: _resource_record_post_action 08:39:48 INFO - [mozharness: 2016-11-15 16:39:48.245243Z] Finished install step (success) 08:39:48 INFO - [mozharness: 2016-11-15 16:39:48.245427Z] Running run-tests step. 08:39:48 INFO - Running pre-action listener: _resource_record_pre_action 08:39:48 INFO - Running main action method: run_tests 08:39:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:39:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:39:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:39:48 INFO - 2016-11-15 08:39:48.259 screenresolution[1883:13310] starting screenresolution argv=screenresolution get 08:39:48 INFO - 2016-11-15 08:39:48.262 screenresolution[1883:13310] Display 0: 1600x1200x32@60 08:39:48 INFO - 2016-11-15 08:39:48.267 screenresolution[1884:13312] starting screenresolution argv=screenresolution list 08:39:48 INFO - Available Modes on Display 0 08:39:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:39:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:39:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:39:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:39:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:39:48 INFO - Intel Iris: 08:39:48 INFO - Chipset Model: Intel Iris 08:39:48 INFO - Type: GPU 08:39:48 INFO - Bus: Built-In 08:39:48 INFO - VRAM (Dynamic, Max): 1536 MB 08:39:48 INFO - Vendor: Intel (0x8086) 08:39:48 INFO - Device ID: 0x0a2e 08:39:48 INFO - Revision ID: 0x0009 08:39:48 INFO - Displays: 08:39:48 INFO - Display: 08:39:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:39:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:39:48 INFO - Display Serial Number: 1600x1200 60 08:39:48 INFO - Main Display: Yes 08:39:48 INFO - Mirror: Off 08:39:48 INFO - Online: Yes 08:39:48 INFO - Rotation: Supported 08:39:48 INFO - Return code: 0 08:39:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:39:48 INFO - Python 2.7.3 08:39:48 INFO - Return code: 0 08:39:48 INFO - grabbing minidump binary from tooltool 08:39:48 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')]} 08:39:48 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 0x105a80d30>, '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 0x1058a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a78b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:39:48 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 08:39:48 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 08:39:48 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 08:39:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:39:48 INFO - Return code: 0 08:39:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:39:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:39:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:39:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:39:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:39:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0025 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:39:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B1b4S84xHu/Render', 08:39:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:39:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:39:48 INFO - 'HOME': '/Users/cltbld', 08:39:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:39:48 INFO - 'LOGNAME': 'cltbld', 08:39:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:39:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:48 INFO - 'MOZ_NO_REMOTE': '1', 08:39:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:39:48 INFO - 'NO_EM_RESTART': '1', 08:39:48 INFO - 'PAGER': '/bin/cat', 08:39:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:48 INFO - 'PWD': '/builds/slave/test', 08:39:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:39:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:39:48 INFO - 'SHELL': '/bin/bash', 08:39:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvNxSFSmHE/Listeners', 08:39:48 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 08:39:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:39:48 INFO - 'USER': 'cltbld', 08:39:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:39:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:48 INFO - 'XPC_FLAGS': '0x0', 08:39:48 INFO - 'XPC_SERVICE_NAME': '0', 08:39:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:39:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:39:48 INFO - mozversion application_buildid: 20161115060313 08:39:48 INFO - mozversion application_changeset: f8ba9c9b401f57b0047ddd6932cb830190865b38 08:39:48 INFO - mozversion application_display_name: Nightly 08:39:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:39:48 INFO - mozversion application_name: Firefox 08:39:48 INFO - mozversion application_remotingname: firefox 08:39:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:39:48 INFO - mozversion application_vendor: Mozilla 08:39:48 INFO - mozversion application_version: 53.0a1 08:39:48 INFO - mozversion platform_buildid: 20161115060313 08:39:48 INFO - mozversion platform_changeset: f8ba9c9b401f57b0047ddd6932cb830190865b38 08:39:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:39:48 INFO - mozversion platform_version: 53.0a1 08:39:48 INFO - using testdate: 1479227988 08:39:48 INFO - actual date: 1479227988 08:39:48 INFO - starting webserver on 'localhost:49370' 08:39:48 INFO - SUITE-START | Running 2 tests 08:39:48 INFO - TEST-START | dromaeo_css 08:39:48 INFO - operating with platform_type : mac_ 08:39:48 INFO - Initialising browser for dromaeo_css test... 08:39:48 INFO - TEST-INFO | started process 1894 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmp2gPyeT/profile http://localhost:49370/getInfo.html) 08:39:51 INFO - PROCESS | 1894 | 2016-11-15 08:39:51.668 plugin-container[1896:13464] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 08:39:51 INFO - PROCESS | 1894 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:39:51 INFO - PROCESS | 1894 | 2016-11-15 08:39:51.672 plugin-container[1896:13464] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940f, name = 'com.apple.CFPasteboardClient' 08:39:51 INFO - PROCESS | 1894 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:39:51 INFO - PROCESS | 1894 | 2016-11-15 08:39:51.672 plugin-container[1896:13464] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:39:51 INFO - PROCESS | 1894 | __metrics Screen width/height:1600/1200 08:39:51 INFO - PROCESS | 1894 | colorDepth:24 08:39:51 INFO - PROCESS | 1894 | Browser inner width/height: 1024/689 08:39:51 INFO - PROCESS | 1894 | __metrics 08:39:51 ERROR - PROCESS | 1894 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 08:39:52 INFO - TEST-INFO | 1894: exit 0 08:39:52 INFO - Browser initialized. 08:39:52 INFO - Running cycle 1/1 for dromaeo_css test... 08:39:52 INFO - TEST-INFO | started process 1897 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmp2gPyeT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:39:55 INFO - PROCESS | 1897 | 2016-11-15 08:39:55.084 plugin-container[1898:13586] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 08:39:55 INFO - PROCESS | 1897 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:39:55 INFO - PROCESS | 1897 | 2016-11-15 08:39:55.085 plugin-container[1898:13586] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 08:39:55 INFO - PROCESS | 1897 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:39:55 INFO - PROCESS | 1897 | 2016-11-15 08:39:55.085 plugin-container[1898:13586] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:40:04 INFO - PROCESS | 1897 | RSS: Main: 237752320 08:40:04 INFO - PROCESS | 1897 | 08:40:54 INFO - PROCESS | 1897 | 1479228054316 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:43:07 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49370/tests/dromaeo/cssquery-ext.html) 08:43:09 INFO - PROCESS | 1897 | RSS: Main: 251486208 08:43:09 INFO - PROCESS | 1897 | 08:46:11 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-ext.html (next: http://localhost:49370/tests/dromaeo/cssquery-jquery.html) 08:46:12 INFO - PROCESS | 1897 | RSS: Main: 264204288 08:46:12 INFO - PROCESS | 1897 | 08:49:14 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49370/tests/dromaeo/cssquery-mootools.html) 08:49:15 INFO - PROCESS | 1897 | RSS: Main: 267984896 08:49:15 INFO - PROCESS | 1897 | 08:52:17 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49370/tests/dromaeo/cssquery-prototype.html) 08:52:17 INFO - PROCESS | 1897 | RSS: Main: 266690560 08:52:17 INFO - PROCESS | 1897 | 08:55:20 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49370/tests/dromaeo/cssquery-yui.html) 08:55:21 INFO - PROCESS | 1897 | RSS: Main: 271056896 08:55:21 INFO - PROCESS | 1897 | 08:58:24 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-yui.html (next: http://localhost:49370/tests/dromaeo/cssquery-dojo.html) 08:58:24 INFO - PROCESS | 1897 | RSS: Main: 274161664 08:58:24 INFO - PROCESS | 1897 | 08:58:24 INFO - PROCESS | 1897 | __start_tp_report 08:58:24 INFO - PROCESS | 1897 | _x_x_mozilla_page_load 08:58:24 INFO - PROCESS | 1897 | _x_x_mozilla_page_load_details 08:58:24 INFO - PROCESS | 1897 | |i|pagename|runs| 08:58:24 INFO - PROCESS | 1897 | |0;dojo.html;3271.116798464416;3284.9507257391138;3357;3360.2775403288288;3374.8481318340673;5499.890002199953;5512.586556008296;5517.2551705519745;5544.667319960805;5680.744366503507;5534.806281780138;5542.667439953606;5586.581006424514;5625.071863142587;5659.122835960433;5008.574271186953;5083.364579427572;5093.00156442006;5160.819371322004;5210.609204309673;5115.846524604268;5200.843974680765;5205.063088644042;5259.973700131494;5296.2055691646365;5075.847724568269;5092.694438333712;5199.818006369796;5202.869928251786;5262.368515778112;5185.818496352647;5191.325127733408;5205;5211.557017653504;5248.947510524884;5126.71803050832;5127.564157046656;5132.640715149941;5154.149565321717;5157.613179011589;5449.264349312872;5510.173473978895;5555.416681248451;5556.99974004679;5630.831075067755;6105.084237364386;6134.478569321613;6153.938460615382;6194.318624951252;6196.411340922605;6194.194754681863;6204.503639708812;6231.096491008823;6240.31356550779;6246.6876656167005;5567.638103523258;5592;5732.942670573282;5758.596898217084;5819.621724587888;6112.685862051835;6155.72299246535;6161.815145545641;6193.969030154821;6234.750609975641;4701.200795864711;5915.881682366328;6062.969685151546;6076.058210977306;6099.756009759649;4909.361782968192;5026.768441731799;5226.5818734501145;5278.392984806719;5753;5801.723620803416;5993.130996005555;6026.638401695912;6120.510359171256;6137.263528376623;4828.324034635154;5273.578113750891;6141.324454310023;6148.477379422709;6178.845528861814;4898.24077268024;4900.5099490051425;4902.803887844518;4903.53416425439;4963.652544321935;3892.1048158923604;4350.608445239944;4357.455318085239;4396.802143903532;4411.625011874026;5638.887222255532;5682.176084467729;5685.744141513642;5728.312602487645;5807.709614519342;6112.2665280165775;6112.572119951561;6116.052011938156;6123.663198524035;6130.724117414727;5643.351014633287;5668.121441176624;5693.345265294544;5694.288213973254;5720.68536230503;4474.194644963938;5212.661177023481;5289.074411977888;5324.6805191687845;5341.172118321666;6116.724747386467;6141.455323060992;6162.291336496181;6174.073888916609;6176.629402235881;6168.1056246844855;6189.659568723763;6192.040233763774;6200.720967556381;6226.501879849692;5291;6181.474574661204;6278.811635650938;6279.96860015697;6299.6535190564955;5932.436418540232;6001.489873360813;6011.947909115975;6125.111858780363;6134.846628834315;6058.697065146813;6121.969390153021;6137.5302083125;6139.938600613937;6210.720517576629;6001;6015.608985416109;6048.153258543895;6079.179310792986;6104.755809767737;5803.738831752666;6074.42292982166;6078.57449978515;6081.027035674271;6086.817395478142;4818.95181048185;5759.827205183851;5824.242848429677;5834.795782147605;5840.065589505659;5918.940810591839;6001.129836173817;6012.8797424052145;6056.273247210187;6085.969570152298;5893.5874488785375;5916.67458289781;5941.0197317442135;5974.133750606225;5980.461758441761 08:58:24 INFO - PROCESS | 1897 | |1;ext.html;437.9321205213191;469.5046725704382;501.2781594503913;518.2381898609043;539.7328322480373;35543.64456355436;37892.863214103585;37955.912424816655;37981.15141364402;38018.04954876129;38544.42183367252;39145.43418263273;39239;39262;39355.212895742065;7838.647260873246;9263.351565390409;9398.201152902011;9486.003969583198;9511.571979260916;7305.853882922338;7920.287174154325;9416.62333506659;9473.384230025027;9493.81012379752;8072.394570407243;9429.858552121723;9503.477308748015;9505.762355941088;9512.47681377524;9407.576659050324;9477.052294770538;9486.288528360332;9502.572384242725;9507.049295070437;9306.720798375993;9357.766055848659;9400.341976061678;9402.38884472507;9413.905860941371;33606;33838.83080584606;33964.83017584896;33994.6600533994;34006.31987360239;3197.3605278944306;3322.2691007978206;3437.914052148691;3732.6080761520193;3837.3668344723087;24079.759202407928;29072.273193170127;29667.703322966925;29901.850490747405;30247;3129.2020534763487;3244.659310772382;3283.7044665980175;3319.1038419626566;3329.234276116507;495567;496107;502212;503906;505374.47312763566;57083.71458142766;57275;57406.277811665714;57451;57565.84868302694;51461.912285263;51466;59867.10199347013;60031.39968600345;60162.79674406575;15563.922180389025;15582.9220853895;15806.9209653951;15834.366625334858;15854.44509442187;12126.636200913987;12244.387780610934;12256.264624122581;12259.448324825404;12290;14452.988290819541;15611.921940390226;15863.20683965797;15882.60293492672;16040.197990100449;3144.198229451475;3239.7084262416497;3274.0341599228286;6351.110844481684;6421.646809425433;939.4316438554713;957.6353695983336;1018.5620183321244;1020.2042406922612;1041.8749750030045;26174.34564135912;27082.593761093598;27632.309192270353;29188.416231675248;29575;999.2505620784411;1052.0058544675207;1080.3679847289266;1088.1403691083956;1100.8403781451646;14207.573772786833;14230.573082807532;15232.695346093238;15806.288717007763;16141.67716645684;700904;835646;853260;854788;855240;55472.89054218893;56001.43998559962;56079;56231.71884140635;56264.718676407174;1197.2636828350676;1299.27240745183;1329.692982675058;1329.8005299205197;1348.1237195822794;25392.111276105246;29287;29313.413731725672;29551.704482954894;30356.241093971945;43593.56406436022;48953.755231223615;49383;49533.50466495289;49671;488128;569164;592515;593324;594717;25753.742462575883;26197.214083577524;26393.34016649522;26533;26588.878723797843;37373.4393984085;41009;44253.336199957674;44967.32549011702;45444.318335224336;31893.8405307972;37941.62058379381;38133.427998579486;38208.853734388016;38492.230155397294;33369.66630333666;36823;37063.81468092642;37419.81290093532;37870;32693.509597356533;36296.81851590725;36324.455133172494;36920.63079369172;37026.814865925495;27804.72195278021;30329.393412132074;32946;33515.4972675415;33597.32805343928;24241.87879060664;24344.51310973806;24446.958333125633;24495;24769.389989650615 08:58:24 INFO - PROCESS | 1897 | |2;jquery.html;369.17305236270744;371.6580745713943;378.3945686900958;381.19567712127423;397.93036218661746;19094;19894.403167904988;20066.996650167468;20296.69554956673;20413.591728165447;19416.708749368736;19632.70550941737;19772.70340944887;20220.39338819834;20235.49411264719;4760.3573517575205;5772.393898640635;5828.548570425033;5921;5954.583179177438;5854.356020837705;5985.191999080112;5986.10208468731;5990.281166260055;5990.9101363479585;5620.522255608258;5821;5873.941260587403;5885.852853678671;5944.464998150187;4841.612670986313;5818.301803783552;5881.794137205177;5895.646261224319;5917.970410147922;4878.585320247783;5076.670016448956;5118.820841270538;5128.384593848725;5129.794808207667;24618.753812462004;24814.00743970239;25045;25133.24600261995;25204.873975630006;2203.5703037907615;2380.785729284373;2460.274219105368;2492.3395300245447;2781.680106787705;15985.440509582226;16662.83337166625;16704.331826726917;16862.723532323223;17307.134643267786;2011.336259034515;2062.463759422546;2086.467950672584;2089.3209706845337;2093.1731965873546;114029.99999999917;129816;137419;142251;143826.84259736113;17457.82542174562;18980.305908227478;20065;20951.638143520624;21301;34733.82633086869;34824.82587587046;34947.4757878632;34982;34985.47521787175;8870.822583548423;9316.767080823034;9598.800149981253;10370.481475926174;10537.63118290856;7098;7198.532095413886;8661.52361620117;8951.896304368469;8953.910460895439;7831.921680783119;7959.602019899097;9415.105564971316;9815.950920245496;9825.803483930282;3770.3967365221433;3856.151646637735;3859.749116307431;3862.6823905656383;3872.5546562145714;740.0379506641344;812.3907069697726;943.9386439881386;979.8236317462782;990.1583653894277;16576.502704918872;17531.298748049936;19716.21135154578;19942.50143746418;20739.377818665464;862.7109918177481;887.9156480134507;918.1415376622879;946.3580687507169;1009.6920439265947;10040.598376064876;10780.94609526963;10790.622328218475;10853.294535855144;10856.891431085745;499987;514895;582439;604081;604441;130281;136698.76354633097;138040.30979845035;140524;141373.29313353368;850.5922697934927;973.352720440913;985.8422652375585;1024.4877561219391;1024.90263424976;13807.516736914562;16197.109158995898;18330.81669183291;18642.347517837352;19020.809791901906;49458.752706236235;50955.49044509508;52531.47468525266;53081;53093.73453132709;73604.26395735974;78484.21515784998;78966.21033789818;79277;80921.59539202266;7846.843063138819;7886.250806173404;7912.5648089353335;7943.563104029334;8104.675812967415;11737.55521365811;12238.816417753918;12352.62942111738;12579.559715409916;12676.556320528738;10610.469476526298;10929.398883061507;11078;11100.278481898651;11196.496157673088;9221.170094691242;10884.564617415532;10901.890981090088;11067.833982490109;11074.280171788809;10236;11148.609798657008;11299.887001129884;11352.262102963281;11371.943140284246;5713.314402271754;6233.625982441068;6523.706433210612;6580.934190658032;7502.3622992046285;8233.711820086268;11056.778864422828;11171.608993685182;11187.67896138679;11233.831492527783 08:58:24 INFO - PROCESS | 1897 | |3;mootools.html;1444.083007290371;1475.9557213283601;1649.645326254855;1705.7782488276523;1802.3511535847092;7539.9246007539905;7804.843903121934;8075.51546907186;8499.235068843804;8540.444871083382;7974.641141148648;8266.29736472399;8287.461315014534;8353.331733461324;8368.246857782799;2245.5845668551287;2279.794818466338;2288.61093614086;2298.080767692928;2301.7122859642545;2296.5521723263887;2335.8832058397097;2345.4605440748637;2356.3991182248587;2356.5640356534095;2241.551689662066;2242.9775702242932;2244.551089782042;2247.797698207161;2275.601769690306;2250.628646273363;2256.7404748453973;2283.54329134173;2283.6117859963765;2285.691431656738;1938.6607343714934;2299.850509716863;2326.9883650581637;2333.988330058356;2335.859848409101;8294.875576866352;8404.327653787683;8440.409171358007;8453.492790432594;8461.957690211571;4846.224604063333;4988.501149885019;4994.076095922266;5008.599312055063;5011.298418221452;3181.538676891861;3279.442494775894;3529.9294014119573;3530.3998320285614;3558.3594952908466;1027.0756319312604;1047.240750455921;1070.1545778834711;1077.4020418667637;1086.1148164246115;9504.524773761284;10093.39439633617;10737.355758654505;10805.459727013618;10888.183386246063;4399.802008909607;4419.292913133883;4639;4639.721616703008;4642.094791515689;7878.487898286593;9281.025492323344;9363.063693630582;9377.859332110023;9380.48407337603;5130.9230361544605;5136.2552429898205;5159.25190847325;5178.404483484373;5178.663386879841;3674.805688151308;3853.460515527828;3921.843056298399;4336.891577710582;4675.649326300541;3078.415101130778;3152.2277042124824;3672.7245456590863;3814.599467055975;3825.445310429972;1236.6088150830317;1248.4756402311052;1251.6495381293253;1262.2805001149256;1264.2288204195434;793.5318162284165;828.8300898315831;864.386285737121;873.4715497124148;890.5725251879135;4539.910421498883;4561.338605902126;5127.589792816566;5573.247611572467;5631;424.5181718749242;425.8105143211241;426.31789137379945;426.5350767663262;435.27743945051066;2889.5954566360724;3683.042408973686;3774.8867533974026;3790.3556395412847;3825.9808700956874;9826.901730982741;10069.59721611142;10189.337693049929;10199.235057370726;10199.694009179737;10140.594376225015;10173.796524069625;10177.949110254402;10180.236482263712;10198.490075496196;7378;7637.847243055107;7786.961065194638;7970.362371010306;7987.56068416231;4762.690425122356;5523.6409633373705;5668;5707.229524014371;5806.4193580641595;9100.135487128713;9186.173244408035;9267.953660231655;9357.906420935977;9361.578728957076;6792.76225332111;9023.323250756352;9329.813403732023;9366.859497107413;9542.570584323583;5434.728263586884;6558.901616475852;6768.357006084414;6905.930940690529;7234.6020968847215;7768.689252429839;7941.364690824605;7966.0440747110715;7973.800654983672;7984.720534781487;6500.252470965899;6590.934090659224;6682.46540276767;6686.629241005679;7062.117235345582;5589.860253493533;6927.787637163577;6959.89560156588;7072.186698529735;7092.078029856086;5760.049591817303;6455.935440645721;6543.934560654523;6647.767328143492;6681.46548276127;4710.576048155683;4794.376731024944;4800.3759511263315;4818.349522814516;4915.090708218991;4399.714018588781;4758.16732071893;4843.515648435128;4891.192953162688;4899.485554016777 08:58:24 INFO - PROCESS | 1897 | |4;prototype.html;252.57693363615945;293.4146377975937;302.4963435879261;306.0954878334521;307.6523528412893;4547.47704014038;4942.258661200822;5148.948510514893;5353.625246232766;5526.723663816808;5186.392218412295;5357.4374690657505;5378.273933019051;5382.757775900084;5410.864728381793;3194.393065317582;4057.8173982170874;4075.7976396962968;4140.337545992642;4150.783143064119;3145.9685403145904;4068.023674318157;4131.876043718678;4166.708330416872;4176.060386413048;3933.0953880607476;3997.240524300388;4063.817128229237;4086.6730661547153;4087.6729861611147;3472.878449254289;4060.47213862199;4085.754854708697;4087.4890638670167;4093.22228776533;2964.762818974477;3049.9695003049906;3319.3029463812627;3526.7531272810656;3536.169000284931;4741.838249628863;4870.732109733964;5090.261912022736;5296.735163241822;5369.194620806871;4632.513586073447;4640.350350950869;4659.603933665642;4662.8134874604975;4665.206914824488;4445.533219012022;5688.203651488795;5746.454086861741;5755.870327978633;5759.827205183851;3697.96027566252;3814.1799513104816;3846.74996125251;3864.3237433449035;3888.3778595424596;4621.491635920046;4859.611231101539;5030.094582975063;5710.828675139753;5948.300842334594;3769.3403654360927;3857.589006870706;3877.3796192609047;3885.678869184491;3891.8248678809523;3052.435299469561;3147.6065491813524;3660.908477288089;3934.960650393459;3952.063488890423;4084.8366065357645;4550.044490656999;4611.377464042312;4617.39842922521;4657.04404150251;4599.563041511051;4978.327925730053;5067.46791586878;5336.466353364633;5367.892642147135;1006.8691070160833;1108.4346983038708;1238.6098379010582;1242.1243023668474;1266.5060626355728;731.4331393170229;777.9766607001686;868.5613765048736;882.179572997106;887.5207388010402;4528.207563676344;4677.906441871211;4714.939138693767;5059.468755780664;5154.433012368637;4821.63837712173;5195.817756542261;5475.835724928259;5567.944320556823;5600.123958473957;4705.106029854317;5041.42023667283;5082.898342033139;5104.7283958638045;5136.948630513722;2431.538007778516;2514.3588384961718;2841.0766500887294;2853.9429211415654;2911.2721819545113;5204.9219261711105;5332.467078137931;5339.172428273624;5343.381742946006;5786.697992951552;4642.4040200202235;4946.109700253989;5360.088784906575;5662.462066103713;5693.772249110072;4565.474970378449;5675.829725108253;6084.269887613515;6164;6249.500039996789;4736.715797052188;5411.891762164813;5838.32859221195;5884.9705751470965;6025.849353766191;5384.542313903362;5756.071715975195;5901.114832775118;5919.289685237627;5958.165856779968;5157.432682404858;6020.19394181735;6265.467435267871;6290.21372328459;6296.244450665949;3883.7475564088245;4035.6189847989826;4046.1300820323772;4059.878203653895;4067.491563554556;5676.205331253545;5881.852953676193;5925.703714814328;5936.703164841654;5949;4926.064047830901;5077.623380451958;5577.52591029767;5613.158026296088;5711.400302968129;5007.499250074963;5181.404138523967;5255.84371438283;5745.827625171251;5827.776167004977;5412.161115027177;5638.053362646246;5638.605297629127;5643.28785464142;5656.85857853557;5238.947610523846;5348.304720386325;5763.896177894954;5765.884682306247;5777.595568310178;5559.332880054258;5586.413426590149;5609.9158512621525;5651.886962260814;5711.771529138788 08:58:24 INFO - PROCESS | 1897 | |5;yui.html;690.19592175116;691.357037954702;714.4320265389015;723.7937187901449;759.4342215049788;13721.108127971687;14540.636484087903;14571.12573245606;14589.854101458996;14608.78086828697;10777.78444431111;13095.672608184801;13105.148165369244;13117.606471805837;13281.667958301023;648.1703419622891;666.7732970789931;683.8939964305516;805.4804651000102;824.085265355458;808.8261023879865;813.8413009463158;823.1768231768232;823.9588020598976;826.1738261738262;878.5343767803042;881.5063564403922;882.74400423877;884.1512148337604;884.4251236696134;845.480029781686;880.7930136417937;881.8721285413579;881.9426737262057;882.4220135811054;653.1443808610735;657.8454063295122;659.8152517295165;661.9238787539447;662.6355504472521;13683.31583420835;13936.442542298297;14070.15579065249;14101.012929094968;14310.21293828839;11015.779684406347;11975.161738678296;12984.28586427746;13565;13704.177749334973;11388.544458221744;11434.142439317002;11466.885331146748;11490.885091149064;11512.539498420054;3630.3894054550574;3678.6873115785193;3684.6499582539614;3684.7604905681046;3836.3286424875814;89714.27142964238;92252.53873730656;92311.0768892316;92390;92994;10547.578096876192;10548.367097974146;10628.681139565826;10748.408837514013;10981.450927453596;20672.276470323468;20802.895985519976;20838.166473340898;20941.371758847257;21031;7461.1419686735635;7505.5871927043445;7512.511686740344;7625.69497220116;7759.689612415553;4060.675145988314;4165.75005499671;4185.22573323936;4222.1766755483;4264.232438161161;3341.7660763746303;3449.3963556377535;3461.5153878457036;3463.3765922134257;3475.304939012208;540.5810496864962;618.0790621973317;621.3351713666334;632.9303776584572;637.5027478566633;621.2513920725515;636.1126228910643;657.4214691071921;658.4501940879419;673.7978606418055;8040.316573091236;8558.545047341966;8610.87083693745;8891.288696904232;9027.548622568976;561.8146011816091;590.0205658606692;600.579594284003;618.783425800975;651.0689713709346;4343.022819865568;4413.492448368414;4468.575485328888;4471;4546.136234115507;72186;109445.45277273563;113290;114204.42897785624;115147;79872.00319992047;86716;95975;98014;98315.50842245886;52323.47676523262;52568.73715631474;52900;53748;72886.27113728794;7749.41879359039;8005.759827205193;8211.95894020526;9205.263578913537;9390.201832844285;43110.56889431066;49632.75183624059;50121;52428;55900.440995589524;78130.6093469529;79405;80569;81231;82625;9661.695671084595;10430;10486.580536778443;10800.041733531301;10815.297005694605;11569.710757231136;11690.240134391237;11701.88298117008;11842.407879606157;11931.46308416106;11314.434278286217;11405.657830265105;11502.367369794742;11556.306621602731;11769.352685602375;11261;11503.884961150281;11524.481398336928;11742.706432338919;11912;10968.945155274172;11154.498047587716;11191.44042797873;11598.884011160118;11719.765604687687;9235.307351948497;9465;9579.521023948635;9592.424454532751;9679.66121185755;9066.65867402005;9284.814303714023;9564.378315409476;9713;9847.655332063343 08:58:24 INFO - PROCESS | 1897 | __end_tp_report 08:58:24 INFO - PROCESS | 1897 | __start_cc_report 08:58:24 INFO - PROCESS | 1897 | _x_x_mozilla_cycle_collect,379 08:58:24 INFO - PROCESS | 1897 | __end_cc_report 08:58:24 INFO - PROCESS | 1897 | __startTimestamp1479229104401__endTimestamp 08:58:24 INFO - PROCESS | 1897 | 08:58:24 INFO - PROCESS | 1897 | ------- Summary: start ------- 08:58:24 INFO - PROCESS | 1897 | Number of tests: 6 08:58:24 INFO - PROCESS | 1897 | 08:58:24 INFO - PROCESS | 1897 | [#0] dojo.html Cycles:165 Average:5603.38 Median:5758.60 stddev:640.63 (11.1%) stddev-sans-first:615.92 08:58:24 INFO - PROCESS | 1897 | Values: 3271.1 3285.0 3357.0 3360.3 3374.8 5499.9 5512.6 5517.3 5544.7 5680.7 5534.8 5542.7 5586.6 5625.1 5659.1 5008.6 5083.4 5093.0 5160.8 5210.6 5115.8 5200.8 5205.1 5260.0 5296.2 5075.8 5092.7 5199.8 5202.9 5262.4 5185.8 5191.3 5205.0 5211.6 5248.9 5126.7 5127.6 5132.6 5154.1 5157.6 5449.3 5510.2 5555.4 5557.0 5630.8 6105.1 6134.5 6153.9 6194.3 6196.4 6194.2 6204.5 6231.1 6240.3 6246.7 5567.6 5592.0 5732.9 5758.6 5819.6 6112.7 6155.7 6161.8 6194.0 6234.8 4701.2 5915.9 6063.0 6076.1 6099.8 4909.4 5026.8 5226.6 5278.4 5753.0 5801.7 5993.1 6026.6 6120.5 6137.3 4828.3 5273.6 6141.3 6148.5 6178.8 4898.2 4900.5 4902.8 4903.5 4963.7 3892.1 4350.6 4357.5 4396.8 4411.6 5638.9 5682.2 5685.7 5728.3 5807.7 6112.3 6112.6 6116.1 6123.7 6130.7 5643.4 5668.1 5693.3 5694.3 5720.7 4474.2 5212.7 5289.1 5324.7 5341.2 6116.7 6141.5 6162.3 6174.1 6176.6 6168.1 6189.7 6192.0 6200.7 6226.5 5291.0 6181.5 6278.8 6280.0 6299.7 5932.4 6001.5 6011.9 6125.1 6134.8 6058.7 6122.0 6137.5 6139.9 6210.7 6001.0 6015.6 6048.2 6079.2 6104.8 5803.7 6074.4 6078.6 6081.0 6086.8 4819.0 5759.8 5824.2 5834.8 5840.1 5918.9 6001.1 6012.9 6056.3 6086.0 5893.6 5916.7 5941.0 5974.1 5980.5 08:58:24 INFO - PROCESS | 1897 | 08:58:24 INFO - PROCESS | 1897 | [#1] ext.html Cycles:180 Average:73779.87 Median:37644.91 stddev:173880.01 (461.9%) stddev-sans-first:174280.60 08:58:24 INFO - PROCESS | 1897 | Values: 437.9 469.5 501.3 518.2 539.7 35543.6 37892.9 37955.9 37981.2 38018.0 38544.4 39145.4 39239.0 39262.0 39355.2 7838.6 9263.4 9398.2 9486.0 9511.6 7305.9 7920.3 9416.6 9473.4 9493.8 8072.4 9429.9 9503.5 9505.8 9512.5 9407.6 9477.1 9486.3 9502.6 9507.0 9306.7 9357.8 9400.3 9402.4 9413.9 33606.0 33838.8 33964.8 33994.7 34006.3 3197.4 3322.3 3437.9 3732.6 3837.4 24079.8 29072.3 29667.7 29901.9 30247.0 3129.2 3244.7 3283.7 3319.1 3329.2 495567.0 496107.0 502212.0 503906.0 505374.5 57083.7 57275.0 57406.3 57451.0 57565.8 51461.9 51466.0 59867.1 60031.4 60162.8 15563.9 15582.9 15806.9 15834.4 15854.4 12126.6 12244.4 12256.3 12259.4 12290.0 14453.0 15611.9 15863.2 15882.6 16040.2 3144.2 3239.7 3274.0 6351.1 6421.6 939.4 957.6 1018.6 1020.2 1041.9 26174.3 27082.6 27632.3 29188.4 29575.0 999.3 1052.0 1080.4 1088.1 1100.8 14207.6 14230.6 15232.7 15806.3 16141.7 700904.0 835646.0 853260.0 854788.0 855240.0 55472.9 56001.4 56079.0 56231.7 56264.7 1197.3 1299.3 1329.7 1329.8 1348.1 25392.1 29287.0 29313.4 29551.7 30356.2 43593.6 48953.8 49383.0 49533.5 49671.0 488128.0 569164.0 592515.0 593324.0 594717.0 25753.7 26197.2 26393.3 26533.0 26588.9 37373.4 41009.0 44253.3 44967.3 45444.3 31893.8 37941.6 38133.4 38208.9 38492.2 33369.7 36823.0 37063.8 37419.8 37870.0 32693.5 36296.8 36324.5 36920.6 37026.8 27804.7 30329.4 32946.0 33515.5 33597.3 24241.9 24344.5 24447.0 24495.0 24769.4 08:58:24 INFO - PROCESS | 1897 | 08:58:24 INFO - PROCESS | 1897 | [#2] jquery.html Cycles:180 Average:35600.67 Median:34964.74 stddev:94863.92 (271.3%) stddev-sans-first:95093.15 08:58:24 INFO - PROCESS | 1897 | Values: 369.2 371.7 378.4 381.2 397.9 19094.0 19894.4 20067.0 20296.7 20413.6 19416.7 19632.7 19772.7 20220.4 20235.5 4760.4 5772.4 5828.5 5921.0 5954.6 5854.4 5985.2 5986.1 5990.3 5990.9 5620.5 5821.0 5873.9 5885.9 5944.5 4841.6 5818.3 5881.8 5895.6 5918.0 4878.6 5076.7 5118.8 5128.4 5129.8 24618.8 24814.0 25045.0 25133.2 25204.9 2203.6 2380.8 2460.3 2492.3 2781.7 15985.4 16662.8 16704.3 16862.7 17307.1 2011.3 2062.5 2086.5 2089.3 2093.2 114030.0 129816.0 137419.0 142251.0 143826.8 17457.8 18980.3 20065.0 20951.6 21301.0 34733.8 34824.8 34947.5 34982.0 34985.5 8870.8 9316.8 9598.8 10370.5 10537.6 7098.0 7198.5 8661.5 8951.9 8953.9 7831.9 7959.6 9415.1 9816.0 9825.8 3770.4 3856.2 3859.7 3862.7 3872.6 740.0 812.4 943.9 979.8 990.2 16576.5 17531.3 19716.2 19942.5 20739.4 862.7 887.9 918.1 946.4 1009.7 10040.6 10780.9 10790.6 10853.3 10856.9 499987.0 514895.0 582439.0 604081.0 604441.0 130281.0 136698.8 138040.3 140524.0 141373.3 850.6 973.4 985.8 1024.5 1024.9 13807.5 16197.1 18330.8 18642.3 19020.8 49458.8 50955.5 52531.5 53081.0 53093.7 73604.3 78484.2 78966.2 79277.0 80921.6 7846.8 7886.3 7912.6 7943.6 8104.7 11737.6 12238.8 12352.6 12579.6 12676.6 10610.5 10929.4 11078.0 11100.3 11196.5 9221.2 10884.6 10901.9 11067.8 11074.3 10236.0 11148.6 11299.9 11352.3 11371.9 5713.3 6233.6 6523.7 6580.9 7502.4 8233.7 11056.8 11171.6 11187.7 11233.8 08:58:24 INFO - PROCESS | 1897 | 08:58:24 INFO - PROCESS | 1897 | [#3] mootools.html Cycles:180 Average:5296.98 Median:4809.36 stddev:2962.18 (61.6%) stddev-sans-first:2956.34 08:58:24 INFO - PROCESS | 1897 | Values: 1444.1 1476.0 1649.6 1705.8 1802.4 7539.9 7804.8 8075.5 8499.2 8540.4 7974.6 8266.3 8287.5 8353.3 8368.2 2245.6 2279.8 2288.6 2298.1 2301.7 2296.6 2335.9 2345.5 2356.4 2356.6 2241.6 2243.0 2244.6 2247.8 2275.6 2250.6 2256.7 2283.5 2283.6 2285.7 1938.7 2299.9 2327.0 2334.0 2335.9 8294.9 8404.3 8440.4 8453.5 8462.0 4846.2 4988.5 4994.1 5008.6 5011.3 3181.5 3279.4 3529.9 3530.4 3558.4 1027.1 1047.2 1070.2 1077.4 1086.1 9504.5 10093.4 10737.4 10805.5 10888.2 4399.8 4419.3 4639.0 4639.7 4642.1 7878.5 9281.0 9363.1 9377.9 9380.5 5130.9 5136.3 5159.3 5178.4 5178.7 3674.8 3853.5 3921.8 4336.9 4675.6 3078.4 3152.2 3672.7 3814.6 3825.4 1236.6 1248.5 1251.6 1262.3 1264.2 793.5 828.8 864.4 873.5 890.6 4539.9 4561.3 5127.6 5573.2 5631.0 424.5 425.8 426.3 426.5 435.3 2889.6 3683.0 3774.9 3790.4 3826.0 9826.9 10069.6 10189.3 10199.2 10199.7 10140.6 10173.8 10177.9 10180.2 10198.5 7378.0 7637.8 7787.0 7970.4 7987.6 4762.7 5523.6 5668.0 5707.2 5806.4 9100.1 9186.2 9268.0 9357.9 9361.6 6792.8 9023.3 9329.8 9366.9 9542.6 5434.7 6558.9 6768.4 6905.9 7234.6 7768.7 7941.4 7966.0 7973.8 7984.7 6500.3 6590.9 6682.5 6686.6 7062.1 5589.9 6927.8 6959.9 7072.2 7092.1 5760.0 6455.9 6543.9 6647.8 6681.5 4710.6 4794.4 4800.4 4818.3 4915.1 4399.7 4758.2 4843.5 4891.2 4899.5 08:58:24 INFO - PROCESS | 1897 | 08:58:24 INFO - PROCESS | 1897 | [#4] prototype.html Cycles:180 Average:4507.19 Median:5035.76 stddev:1423.21 (28.3%) stddev-sans-first:1390.92 08:58:24 INFO - PROCESS | 1897 | Values: 252.6 293.4 302.5 306.1 307.7 4547.5 4942.3 5148.9 5353.6 5526.7 5186.4 5357.4 5378.3 5382.8 5410.9 3194.4 4057.8 4075.8 4140.3 4150.8 3146.0 4068.0 4131.9 4166.7 4176.1 3933.1 3997.2 4063.8 4086.7 4087.7 3472.9 4060.5 4085.8 4087.5 4093.2 2964.8 3050.0 3319.3 3526.8 3536.2 4741.8 4870.7 5090.3 5296.7 5369.2 4632.5 4640.4 4659.6 4662.8 4665.2 4445.5 5688.2 5746.5 5755.9 5759.8 3698.0 3814.2 3846.7 3864.3 3888.4 4621.5 4859.6 5030.1 5710.8 5948.3 3769.3 3857.6 3877.4 3885.7 3891.8 3052.4 3147.6 3660.9 3935.0 3952.1 4084.8 4550.0 4611.4 4617.4 4657.0 4599.6 4978.3 5067.5 5336.5 5367.9 1006.9 1108.4 1238.6 1242.1 1266.5 731.4 778.0 868.6 882.2 887.5 4528.2 4677.9 4714.9 5059.5 5154.4 4821.6 5195.8 5475.8 5567.9 5600.1 4705.1 5041.4 5082.9 5104.7 5136.9 2431.5 2514.4 2841.1 2853.9 2911.3 5204.9 5332.5 5339.2 5343.4 5786.7 4642.4 4946.1 5360.1 5662.5 5693.8 4565.5 5675.8 6084.3 6164.0 6249.5 4736.7 5411.9 5838.3 5885.0 6025.8 5384.5 5756.1 5901.1 5919.3 5958.2 5157.4 6020.2 6265.5 6290.2 6296.2 3883.7 4035.6 4046.1 4059.9 4067.5 5676.2 5881.9 5925.7 5936.7 5949.0 4926.1 5077.6 5577.5 5613.2 5711.4 5007.5 5181.4 5255.8 5745.8 5827.8 5412.2 5638.1 5638.6 5643.3 5656.9 5238.9 5348.3 5763.9 5765.9 5777.6 5559.3 5586.4 5609.9 5651.9 5711.8 08:58:24 INFO - PROCESS | 1897 | 08:58:24 INFO - PROCESS | 1897 | [#5] yui.html Cycles:180 Average:19318.80 Median:27144.29 stddev:28739.23 (105.9%) stddev-sans-first:28785.81 08:58:24 INFO - PROCESS | 1897 | Values: 690.2 691.4 714.4 723.8 759.4 13721.1 14540.6 14571.1 14589.9 14608.8 10777.8 13095.7 13105.1 13117.6 13281.7 648.2 666.8 683.9 805.5 824.1 808.8 813.8 823.2 824.0 826.2 878.5 881.5 882.7 884.2 884.4 845.5 880.8 881.9 881.9 882.4 653.1 657.8 659.8 661.9 662.6 13683.3 13936.4 14070.2 14101.0 14310.2 11015.8 11975.2 12984.3 13565.0 13704.2 11388.5 11434.1 11466.9 11490.9 11512.5 3630.4 3678.7 3684.6 3684.8 3836.3 89714.3 92252.5 92311.1 92390.0 92994.0 10547.6 10548.4 10628.7 10748.4 10981.5 20672.3 20802.9 20838.2 20941.4 21031.0 7461.1 7505.6 7512.5 7625.7 7759.7 4060.7 4165.8 4185.2 4222.2 4264.2 3341.8 3449.4 3461.5 3463.4 3475.3 540.6 618.1 621.3 632.9 637.5 621.3 636.1 657.4 658.5 673.8 8040.3 8558.5 8610.9 8891.3 9027.5 561.8 590.0 600.6 618.8 651.1 4343.0 4413.5 4468.6 4471.0 4546.1 72186.0 109445.5 113290.0 114204.4 115147.0 79872.0 86716.0 95975.0 98014.0 98315.5 52323.5 52568.7 52900.0 53748.0 72886.3 7749.4 8005.8 8212.0 9205.3 9390.2 43110.6 49632.8 50121.0 52428.0 55900.4 78130.6 79405.0 80569.0 81231.0 82625.0 9661.7 10430.0 10486.6 10800.0 10815.3 11569.7 11690.2 11701.9 11842.4 11931.5 11314.4 11405.7 11502.4 11556.3 11769.4 11261.0 11503.9 11524.5 11742.7 11912.0 10968.9 11154.5 11191.4 11598.9 11719.8 9235.3 9465.0 9579.5 9592.4 9679.7 9066.7 9284.8 9564.4 9713.0 9847.7 08:58:24 INFO - PROCESS | 1897 | -------- Summary: end -------- 08:58:24 INFO - PROCESS | 1897 | 08:58:24 INFO - TEST-INFO | 1897: exit 0 08:58:25 INFO - TEST-OK | dromaeo_css | took 1116161ms 08:58:25 INFO - TEST-START | kraken 08:58:25 INFO - operating with platform_type : mac_ 08:58:25 INFO - Initialising browser for kraken test... 08:58:25 INFO - TEST-INFO | started process 1902 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpT40HTv/profile http://localhost:49370/getInfo.html) 08:58:27 INFO - PROCESS | 1902 | 2016-11-15 08:58:27.700 plugin-container[1903:15560] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 08:58:27 INFO - PROCESS | 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:27 INFO - PROCESS | 1902 | 2016-11-15 08:58:27.703 plugin-container[1903:15560] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470f, name = 'com.apple.CFPasteboardClient' 08:58:27 INFO - PROCESS | 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:27 INFO - PROCESS | 1902 | 2016-11-15 08:58:27.703 plugin-container[1903:15560] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:58:27 INFO - PROCESS | 1902 | __metrics Screen width/height:1600/1200 08:58:27 INFO - PROCESS | 1902 | colorDepth:24 08:58:27 INFO - PROCESS | 1902 | Browser inner width/height: 1024/689 08:58:27 INFO - PROCESS | 1902 | __metrics 08:58:27 ERROR - PROCESS | 1902 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 08:58:28 INFO - TEST-INFO | 1902: exit 0 08:58:28 INFO - Browser initialized. 08:58:28 INFO - Running cycle 1/1 for kraken test... 08:58:28 INFO - TEST-INFO | started process 1904 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpT40HTv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:58:30 INFO - PROCESS | 1904 | 2016-11-15 08:58:30.896 plugin-container[1905:15673] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8747, name = 'com.apple.tsm.portname' 08:58:30 INFO - PROCESS | 1904 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:30 INFO - PROCESS | 1904 | 2016-11-15 08:58:30.896 plugin-container[1905:15673] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4513, name = 'com.apple.CFPasteboardClient' 08:58:30 INFO - PROCESS | 1904 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:30 INFO - PROCESS | 1904 | 2016-11-15 08:58:30.896 plugin-container[1905:15673] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:58:40 INFO - PROCESS | 1904 | RSS: Main: 237899776 08:58:40 INFO - PROCESS | 1904 | 08:58:43 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-beat-detection) 08:58:44 INFO - PROCESS | 1904 | RSS: Main: 250228736 08:58:44 INFO - PROCESS | 1904 | 08:58:46 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-dft) 08:58:47 INFO - PROCESS | 1904 | RSS: Main: 257961984 08:58:47 INFO - PROCESS | 1904 | 08:58:50 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-fft) 08:58:51 INFO - PROCESS | 1904 | RSS: Main: 260468736 08:58:51 INFO - PROCESS | 1904 | 08:58:53 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-oscillator) 08:58:54 INFO - PROCESS | 1904 | RSS: Main: 263000064 08:58:54 INFO - PROCESS | 1904 | 08:58:56 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49370/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:58:56 INFO - PROCESS | 1904 | RSS: Main: 263110656 08:58:56 INFO - PROCESS | 1904 | 08:58:59 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49370/tests/kraken/driver.html?testName=imaging-darkroom) 08:59:00 INFO - PROCESS | 1904 | RSS: Main: 268673024 08:59:00 INFO - PROCESS | 1904 | 08:59:03 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49370/tests/kraken/driver.html?testName=imaging-desaturate) 08:59:04 INFO - PROCESS | 1904 | RSS: Main: 268054528 08:59:04 INFO - PROCESS | 1904 | 08:59:07 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49370/tests/kraken/driver.html?testName=json-parse-financial) 08:59:07 INFO - PROCESS | 1904 | RSS: Main: 268345344 08:59:07 INFO - PROCESS | 1904 | 08:59:09 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49370/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:59:10 INFO - PROCESS | 1904 | RSS: Main: 267325440 08:59:10 INFO - PROCESS | 1904 | 08:59:12 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:59:12 INFO - PROCESS | 1904 | RSS: Main: 267436032 08:59:12 INFO - PROCESS | 1904 | 08:59:15 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:59:15 INFO - PROCESS | 1904 | RSS: Main: 267481088 08:59:15 INFO - PROCESS | 1904 | 08:59:18 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:59:19 INFO - PROCESS | 1904 | RSS: Main: 267739136 08:59:19 INFO - PROCESS | 1904 | 08:59:22 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:59:23 INFO - PROCESS | 1904 | RSS: Main: 267853824 08:59:23 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49370/tests/kraken/driver.html?testName=ai-astar) 08:59:25 INFO - PROCESS | 1904 | RSS: Main: 269680640 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | __start_tp_report 08:59:25 INFO - PROCESS | 1904 | _x_x_mozilla_page_load 08:59:25 INFO - PROCESS | 1904 | _x_x_mozilla_page_load_details 08:59:25 INFO - PROCESS | 1904 | |i|pagename|runs| 08:59:25 INFO - PROCESS | 1904 | |0;ai-astar;117;119;119;118;121;137;154;120;118;119 08:59:25 INFO - PROCESS | 1904 | |1;audio-beat-detection;151;143;115;157;116;122;118;121;148;119 08:59:25 INFO - PROCESS | 1904 | |2;audio-dft;195;197;205;232;221;198;204;199;202;199 08:59:25 INFO - PROCESS | 1904 | |3;audio-fft;82;61;60;82;61;60;50;57;89;82 08:59:25 INFO - PROCESS | 1904 | |4;audio-oscillator;87;73;88;71;70;72;86;68;74;116 08:59:25 INFO - PROCESS | 1904 | |5;imaging-gaussian-blur;89;96;119;86;135;82;110;121;138;134 08:59:25 INFO - PROCESS | 1904 | |6;imaging-darkroom;125;95;97;96;112;97;152;95;98;93 08:59:25 INFO - PROCESS | 1904 | |7;imaging-desaturate;92;95;146;79;82;104;113;150;147;108 08:59:25 INFO - PROCESS | 1904 | |8;json-parse-financial;58;57;58;55;59;59;58;57;59;59 08:59:25 INFO - PROCESS | 1904 | |9;json-stringify-tinderbox;70;77;74;79;75;72;72;72;74;73 08:59:25 INFO - PROCESS | 1904 | |10;stanford-crypto-aes;90;88;106;79;100;113;114;102;87;90 08:59:25 INFO - PROCESS | 1904 | |11;stanford-crypto-ccm;154;153;146;156;169;158;161;167;186;154 08:59:25 INFO - PROCESS | 1904 | |12;stanford-crypto-pbkdf2;182;183;174;175;171;208;219;244;173;246 08:59:25 INFO - PROCESS | 1904 | |13;stanford-crypto-sha256-iterative;70;68;69;70;69;68;71;71;67;70 08:59:25 INFO - PROCESS | 1904 | __end_tp_report 08:59:25 INFO - PROCESS | 1904 | __start_cc_report 08:59:25 INFO - PROCESS | 1904 | _x_x_mozilla_cycle_collect,976 08:59:25 INFO - PROCESS | 1904 | __end_cc_report 08:59:25 INFO - PROCESS | 1904 | __startTimestamp1479229165298__endTimestamp 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | ------- Summary: start ------- 08:59:25 INFO - PROCESS | 1904 | Number of tests: 14 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | [#0] ai-astar Cycles:10 Average:124.20 Median:119.50 stddev:11.97 (10.0%) stddev-sans-first:12.41 08:59:25 INFO - PROCESS | 1904 | Values: 117.0 119.0 119.0 118.0 121.0 137.0 154.0 120.0 118.0 119.0 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | [#1] audio-beat-detection Cycles:10 Average:131.00 Median:132.50 stddev:16.61 (12.5%) stddev-sans-first:15.97 08:59:25 INFO - PROCESS | 1904 | Values: 151.0 143.0 115.0 157.0 116.0 122.0 118.0 121.0 148.0 119.0 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | [#2] audio-dft Cycles:10 Average:205.20 Median:203.00 stddev:11.92 (5.9%) stddev-sans-first:12.06 08:59:25 INFO - PROCESS | 1904 | Values: 195.0 197.0 205.0 232.0 221.0 198.0 204.0 199.0 202.0 199.0 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | [#3] audio-fft Cycles:10 Average:68.40 Median:71.50 stddev:13.74 (19.2%) stddev-sans-first:13.66 08:59:25 INFO - PROCESS | 1904 | Values: 82.0 61.0 60.0 82.0 61.0 60.0 50.0 57.0 89.0 82.0 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:73.50 stddev:14.59 (19.9%) stddev-sans-first:15.29 08:59:25 INFO - PROCESS | 1904 | Values: 87.0 73.0 88.0 71.0 70.0 72.0 86.0 68.0 74.0 116.0 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | [#5] imaging-gaussian-blur Cycles:10 Average:111.00 Median:110.00 stddev:21.54 (19.6%) stddev-sans-first:21.32 08:59:25 INFO - PROCESS | 1904 | Values: 89.0 96.0 119.0 86.0 135.0 82.0 110.0 121.0 138.0 134.0 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | [#6] imaging-darkroom Cycles:10 Average:106.00 Median:95.50 stddev:19.00 (19.9%) stddev-sans-first:18.87 08:59:25 INFO - PROCESS | 1904 | Values: 125.0 95.0 97.0 96.0 112.0 97.0 152.0 95.0 98.0 93.0 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | [#7] imaging-desaturate Cycles:10 Average:111.60 Median:114.50 stddev:27.04 (23.6%) stddev-sans-first:27.74 08:59:25 INFO - PROCESS | 1904 | Values: 92.0 95.0 146.0 79.0 82.0 104.0 113.0 150.0 147.0 108.0 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:58.50 stddev:1.29 (2.2%) stddev-sans-first:1.36 08:59:25 INFO - PROCESS | 1904 | Values: 58.0 57.0 58.0 55.0 59.0 59.0 58.0 57.0 59.0 59.0 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | [#9] json-stringify-tinderbox Cycles:10 Average:73.80 Median:74.00 stddev:2.66 (3.6%) stddev-sans-first:2.44 08:59:25 INFO - PROCESS | 1904 | Values: 70.0 77.0 74.0 79.0 75.0 72.0 72.0 72.0 74.0 73.0 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | [#10] stanford-crypto-aes Cycles:10 Average:96.90 Median:83.00 stddev:11.85 (14.3%) stddev-sans-first:12.30 08:59:25 INFO - PROCESS | 1904 | Values: 90.0 88.0 106.0 79.0 100.0 113.0 114.0 102.0 87.0 90.0 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | [#11] stanford-crypto-ccm Cycles:10 Average:160.40 Median:159.50 stddev:11.27 (7.1%) stddev-sans-first:11.71 08:59:25 INFO - PROCESS | 1904 | Values: 154.0 153.0 146.0 156.0 169.0 158.0 161.0 167.0 186.0 154.0 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.50 Median:195.50 stddev:29.62 (15.2%) stddev-sans-first:30.89 08:59:25 INFO - PROCESS | 1904 | Values: 182.0 183.0 174.0 175.0 171.0 208.0 219.0 244.0 173.0 246.0 08:59:25 INFO - PROCESS | 1904 | 08:59:25 INFO - PROCESS | 1904 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:70.00 stddev:1.34 (1.9%) stddev-sans-first:1.39 08:59:25 INFO - PROCESS | 1904 | Values: 70.0 68.0 69.0 70.0 69.0 68.0 71.0 71.0 67.0 70.0 08:59:25 INFO - PROCESS | 1904 | -------- Summary: end -------- 08:59:25 INFO - PROCESS | 1904 | 08:59:26 INFO - TEST-INFO | 1904: exit 0 08:59:26 INFO - TEST-OK | kraken | took 61181ms 08:59:26 INFO - SUITE-END | took 1177s 08:59:26 INFO - Completed test suite (00:19:37) 08:59:26 INFO - javascript benchmark 08:59:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7110.913435306828, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3271.116798464416, 3284.9507257391138, 3357.0, 3360.2775403288288, 3374.8481318340673, 5499.890002199953, 5512.586556008296, 5517.2551705519745, 5544.667319960805, 5680.744366503507, 5534.806281780138, 5542.667439953606, 5586.581006424514, 5625.071863142587, 5659.122835960433, 5008.574271186953, 5083.364579427572, 5093.00156442006, 5160.819371322004, 5210.609204309673, 5115.846524604268, 5200.843974680765, 5205.063088644042, 5259.973700131494, 5296.2055691646365, 5075.847724568269, 5092.694438333712, 5199.818006369796, 5202.869928251786, 5262.368515778112, 5185.818496352647, 5191.325127733408, 5205.0, 5211.557017653504, 5248.947510524884, 5126.71803050832, 5127.564157046656, 5132.640715149941, 5154.149565321717, 5157.613179011589, 5449.264349312872, 5510.173473978895, 5555.416681248451, 5556.99974004679, 5630.831075067755, 6105.084237364386, 6134.478569321613, 6153.938460615382, 6194.318624951252, 6196.411340922605, 6194.194754681863, 6204.503639708812, 6231.096491008823, 6240.31356550779, 6246.6876656167005, 5567.638103523258, 5592.0, 5732.942670573282, 5758.596898217084, 5819.621724587888, 6112.685862051835, 6155.72299246535, 6161.815145545641, 6193.969030154821, 6234.750609975641, 4701.200795864711, 5915.881682366328, 6062.969685151546, 6076.058210977306, 6099.756009759649, 4909.361782968192, 5026.768441731799, 5226.5818734501145, 5278.392984806719, 5753.0, 5801.723620803416, 5993.130996005555, 6026.638401695912, 6120.510359171256, 6137.263528376623, 4828.324034635154, 5273.578113750891, 6141.324454310023, 6148.477379422709, 6178.845528861814, 4898.24077268024, 4900.5099490051425, 4902.803887844518, 4903.53416425439, 4963.652544321935, 3892.1048158923604, 4350.608445239944, 4357.455318085239, 4396.802143903532, 4411.625011874026, 5638.887222255532, 5682.176084467729, 5685.744141513642, 5728.312602487645, 5807.709614519342, 6112.2665280165775, 6112.572119951561, 6116.052011938156, 6123.663198524035, 6130.724117414727, 5643.351014633287, 5668.121441176624, 5693.345265294544, 5694.288213973254, 5720.68536230503, 4474.194644963938, 5212.661177023481, 5289.074411977888, 5324.6805191687845, 5341.172118321666, 6116.724747386467, 6141.455323060992, 6162.291336496181, 6174.073888916609, 6176.629402235881, 6168.1056246844855, 6189.659568723763, 6192.040233763774, 6200.720967556381, 6226.501879849692, 5291.0, 6181.474574661204, 6278.811635650938, 6279.96860015697, 6299.6535190564955, 5932.436418540232, 6001.489873360813, 6011.947909115975, 6125.111858780363, 6134.846628834315, 6058.697065146813, 6121.969390153021, 6137.5302083125, 6139.938600613937, 6210.720517576629, 6001.0, 6015.608985416109, 6048.153258543895, 6079.179310792986, 6104.755809767737, 5803.738831752666, 6074.42292982166, 6078.57449978515, 6081.027035674271, 6086.817395478142, 4818.95181048185, 5759.827205183851, 5824.242848429677, 5834.795782147605, 5840.065589505659, 5918.940810591839, 6001.129836173817, 6012.8797424052145, 6056.273247210187, 6085.969570152298, 5893.5874488785375, 5916.67458289781, 5941.0197317442135, 5974.133750606225, 5980.461758441761], "value": 5564.270486981519, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [437.9321205213191, 469.5046725704382, 501.2781594503913, 518.2381898609043, 539.7328322480373, 35543.64456355436, 37892.863214103585, 37955.912424816655, 37981.15141364402, 38018.04954876129, 38544.42183367252, 39145.43418263273, 39239.0, 39262.0, 39355.212895742065, 7838.647260873246, 9263.351565390409, 9398.201152902011, 9486.003969583198, 9511.571979260916, 7305.853882922338, 7920.287174154325, 9416.62333506659, 9473.384230025027, 9493.81012379752, 8072.394570407243, 9429.858552121723, 9503.477308748015, 9505.762355941088, 9512.47681377524, 9407.576659050324, 9477.052294770538, 9486.288528360332, 9502.572384242725, 9507.049295070437, 9306.720798375993, 9357.766055848659, 9400.341976061678, 9402.38884472507, 9413.905860941371, 33606.0, 33838.83080584606, 33964.83017584896, 33994.6600533994, 34006.31987360239, 3197.3605278944306, 3322.2691007978206, 3437.914052148691, 3732.6080761520193, 3837.3668344723087, 24079.759202407928, 29072.273193170127, 29667.703322966925, 29901.850490747405, 30247.0, 3129.2020534763487, 3244.659310772382, 3283.7044665980175, 3319.1038419626566, 3329.234276116507, 495567.0, 496107.0, 502212.0, 503906.0, 505374.47312763566, 57083.71458142766, 57275.0, 57406.277811665714, 57451.0, 57565.84868302694, 51461.912285263, 51466.0, 59867.10199347013, 60031.39968600345, 60162.79674406575, 15563.922180389025, 15582.9220853895, 15806.9209653951, 15834.366625334858, 15854.44509442187, 12126.636200913987, 12244.387780610934, 12256.264624122581, 12259.448324825404, 12290.0, 14452.988290819541, 15611.921940390226, 15863.20683965797, 15882.60293492672, 16040.197990100449, 3144.198229451475, 3239.7084262416497, 3274.0341599228286, 6351.110844481684, 6421.646809425433, 939.4316438554713, 957.6353695983336, 1018.5620183321244, 1020.2042406922612, 1041.8749750030045, 26174.34564135912, 27082.593761093598, 27632.309192270353, 29188.416231675248, 29575.0, 999.2505620784411, 1052.0058544675207, 1080.3679847289266, 1088.1403691083956, 1100.8403781451646, 14207.573772786833, 14230.573082807532, 15232.695346093238, 15806.288717007763, 16141.67716645684, 700904.0, 835646.0, 853260.0, 854788.0, 855240.0, 55472.89054218893, 56001.43998559962, 56079.0, 56231.71884140635, 56264.718676407174, 1197.2636828350676, 1299.27240745183, 1329.692982675058, 1329.8005299205197, 1348.1237195822794, 25392.111276105246, 29287.0, 29313.413731725672, 29551.704482954894, 30356.241093971945, 43593.56406436022, 48953.755231223615, 49383.0, 49533.50466495289, 49671.0, 488128.0, 569164.0, 592515.0, 593324.0, 594717.0, 25753.742462575883, 26197.214083577524, 26393.34016649522, 26533.0, 26588.878723797843, 37373.4393984085, 41009.0, 44253.336199957674, 44967.32549011702, 45444.318335224336, 31893.8405307972, 37941.62058379381, 38133.427998579486, 38208.853734388016, 38492.230155397294, 33369.66630333666, 36823.0, 37063.81468092642, 37419.81290093532, 37870.0, 32693.509597356533, 36296.81851590725, 36324.455133172494, 36920.63079369172, 37026.814865925495, 27804.72195278021, 30329.393412132074, 32946.0, 33515.4972675415, 33597.32805343928, 24241.87879060664, 24344.51310973806, 24446.958333125633, 24495.0, 24769.389989650615], "value": 18961.284534277227, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.17305236270744, 371.6580745713943, 378.3945686900958, 381.19567712127423, 397.93036218661746, 19094.0, 19894.403167904988, 20066.996650167468, 20296.69554956673, 20413.591728165447, 19416.708749368736, 19632.70550941737, 19772.70340944887, 20220.39338819834, 20235.49411264719, 4760.3573517575205, 5772.393898640635, 5828.548570425033, 5921.0, 5954.583179177438, 5854.356020837705, 5985.191999080112, 5986.10208468731, 5990.281166260055, 5990.9101363479585, 5620.522255608258, 5821.0, 5873.941260587403, 5885.852853678671, 5944.464998150187, 4841.612670986313, 5818.301803783552, 5881.794137205177, 5895.646261224319, 5917.970410147922, 4878.585320247783, 5076.670016448956, 5118.820841270538, 5128.384593848725, 5129.794808207667, 24618.753812462004, 24814.00743970239, 25045.0, 25133.24600261995, 25204.873975630006, 2203.5703037907615, 2380.785729284373, 2460.274219105368, 2492.3395300245447, 2781.680106787705, 15985.440509582226, 16662.83337166625, 16704.331826726917, 16862.723532323223, 17307.134643267786, 2011.336259034515, 2062.463759422546, 2086.467950672584, 2089.3209706845337, 2093.1731965873546, 114029.99999999917, 129816.0, 137419.0, 142251.0, 143826.84259736113, 17457.82542174562, 18980.305908227478, 20065.0, 20951.638143520624, 21301.0, 34733.82633086869, 34824.82587587046, 34947.4757878632, 34982.0, 34985.47521787175, 8870.822583548423, 9316.767080823034, 9598.800149981253, 10370.481475926174, 10537.63118290856, 7098.0, 7198.532095413886, 8661.52361620117, 8951.896304368469, 8953.910460895439, 7831.921680783119, 7959.602019899097, 9415.105564971316, 9815.950920245496, 9825.803483930282, 3770.3967365221433, 3856.151646637735, 3859.749116307431, 3862.6823905656383, 3872.5546562145714, 740.0379506641344, 812.3907069697726, 943.9386439881386, 979.8236317462782, 990.1583653894277, 16576.502704918872, 17531.298748049936, 19716.21135154578, 19942.50143746418, 20739.377818665464, 862.7109918177481, 887.9156480134507, 918.1415376622879, 946.3580687507169, 1009.6920439265947, 10040.598376064876, 10780.94609526963, 10790.622328218475, 10853.294535855144, 10856.891431085745, 499987.0, 514895.0, 582439.0, 604081.0, 604441.0, 130281.0, 136698.76354633097, 138040.30979845035, 140524.0, 141373.29313353368, 850.5922697934927, 973.352720440913, 985.8422652375585, 1024.4877561219391, 1024.90263424976, 13807.516736914562, 16197.109158995898, 18330.81669183291, 18642.347517837352, 19020.809791901906, 49458.752706236235, 50955.49044509508, 52531.47468525266, 53081.0, 53093.73453132709, 73604.26395735974, 78484.21515784998, 78966.21033789818, 79277.0, 80921.59539202266, 7846.843063138819, 7886.250806173404, 7912.5648089353335, 7943.563104029334, 8104.675812967415, 11737.55521365811, 12238.816417753918, 12352.62942111738, 12579.559715409916, 12676.556320528738, 10610.469476526298, 10929.398883061507, 11078.0, 11100.278481898651, 11196.496157673088, 9221.170094691242, 10884.564617415532, 10901.890981090088, 11067.833982490109, 11074.280171788809, 10236.0, 11148.609798657008, 11299.887001129884, 11352.262102963281, 11371.943140284246, 5713.314402271754, 6233.625982441068, 6523.706433210612, 6580.934190658032, 7502.3622992046285, 8233.711820086268, 11056.778864422828, 11171.608993685182, 11187.67896138679, 11233.831492527783], "value": 10351.738928040488, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1444.083007290371, 1475.9557213283601, 1649.645326254855, 1705.7782488276523, 1802.3511535847092, 7539.9246007539905, 7804.843903121934, 8075.51546907186, 8499.235068843804, 8540.444871083382, 7974.641141148648, 8266.29736472399, 8287.461315014534, 8353.331733461324, 8368.246857782799, 2245.5845668551287, 2279.794818466338, 2288.61093614086, 2298.080767692928, 2301.7122859642545, 2296.5521723263887, 2335.8832058397097, 2345.4605440748637, 2356.3991182248587, 2356.5640356534095, 2241.551689662066, 2242.9775702242932, 2244.551089782042, 2247.797698207161, 2275.601769690306, 2250.628646273363, 2256.7404748453973, 2283.54329134173, 2283.6117859963765, 2285.691431656738, 1938.6607343714934, 2299.850509716863, 2326.9883650581637, 2333.988330058356, 2335.859848409101, 8294.875576866352, 8404.327653787683, 8440.409171358007, 8453.492790432594, 8461.957690211571, 4846.224604063333, 4988.501149885019, 4994.076095922266, 5008.599312055063, 5011.298418221452, 3181.538676891861, 3279.442494775894, 3529.9294014119573, 3530.3998320285614, 3558.3594952908466, 1027.0756319312604, 1047.240750455921, 1070.1545778834711, 1077.4020418667637, 1086.1148164246115, 9504.524773761284, 10093.39439633617, 10737.355758654505, 10805.459727013618, 10888.183386246063, 4399.802008909607, 4419.292913133883, 4639.0, 4639.721616703008, 4642.094791515689, 7878.487898286593, 9281.025492323344, 9363.063693630582, 9377.859332110023, 9380.48407337603, 5130.9230361544605, 5136.2552429898205, 5159.25190847325, 5178.404483484373, 5178.663386879841, 3674.805688151308, 3853.460515527828, 3921.843056298399, 4336.891577710582, 4675.649326300541, 3078.415101130778, 3152.2277042124824, 3672.7245456590863, 3814.599467055975, 3825.445310429972, 1236.6088150830317, 1248.4756402311052, 1251.6495381293253, 1262.2805001149256, 1264.2288204195434, 793.5318162284165, 828.8300898315831, 864.386285737121, 873.4715497124148, 890.5725251879135, 4539.910421498883, 4561.338605902126, 5127.589792816566, 5573.247611572467, 5631.0, 424.5181718749242, 425.8105143211241, 426.31789137379945, 426.5350767663262, 435.27743945051066, 2889.5954566360724, 3683.042408973686, 3774.8867533974026, 3790.3556395412847, 3825.9808700956874, 9826.901730982741, 10069.59721611142, 10189.337693049929, 10199.235057370726, 10199.694009179737, 10140.594376225015, 10173.796524069625, 10177.949110254402, 10180.236482263712, 10198.490075496196, 7378.0, 7637.847243055107, 7786.961065194638, 7970.362371010306, 7987.56068416231, 4762.690425122356, 5523.6409633373705, 5668.0, 5707.229524014371, 5806.4193580641595, 9100.135487128713, 9186.173244408035, 9267.953660231655, 9357.906420935977, 9361.578728957076, 6792.76225332111, 9023.323250756352, 9329.813403732023, 9366.859497107413, 9542.570584323583, 5434.728263586884, 6558.901616475852, 6768.357006084414, 6905.930940690529, 7234.6020968847215, 7768.689252429839, 7941.364690824605, 7966.0440747110715, 7973.800654983672, 7984.720534781487, 6500.252470965899, 6590.934090659224, 6682.46540276767, 6686.629241005679, 7062.117235345582, 5589.860253493533, 6927.787637163577, 6959.89560156588, 7072.186698529735, 7092.078029856086, 5760.049591817303, 6455.935440645721, 6543.934560654523, 6647.767328143492, 6681.46548276127, 4710.576048155683, 4794.376731024944, 4800.3759511263315, 4818.349522814516, 4915.090708218991, 4399.714018588781, 4758.16732071893, 4843.515648435128, 4891.192953162688, 4899.485554016777], "value": 4221.143471733203, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [252.57693363615945, 293.4146377975937, 302.4963435879261, 306.0954878334521, 307.6523528412893, 4547.47704014038, 4942.258661200822, 5148.948510514893, 5353.625246232766, 5526.723663816808, 5186.392218412295, 5357.4374690657505, 5378.273933019051, 5382.757775900084, 5410.864728381793, 3194.393065317582, 4057.8173982170874, 4075.7976396962968, 4140.337545992642, 4150.783143064119, 3145.9685403145904, 4068.023674318157, 4131.876043718678, 4166.708330416872, 4176.060386413048, 3933.0953880607476, 3997.240524300388, 4063.817128229237, 4086.6730661547153, 4087.6729861611147, 3472.878449254289, 4060.47213862199, 4085.754854708697, 4087.4890638670167, 4093.22228776533, 2964.762818974477, 3049.9695003049906, 3319.3029463812627, 3526.7531272810656, 3536.169000284931, 4741.838249628863, 4870.732109733964, 5090.261912022736, 5296.735163241822, 5369.194620806871, 4632.513586073447, 4640.350350950869, 4659.603933665642, 4662.8134874604975, 4665.206914824488, 4445.533219012022, 5688.203651488795, 5746.454086861741, 5755.870327978633, 5759.827205183851, 3697.96027566252, 3814.1799513104816, 3846.74996125251, 3864.3237433449035, 3888.3778595424596, 4621.491635920046, 4859.611231101539, 5030.094582975063, 5710.828675139753, 5948.300842334594, 3769.3403654360927, 3857.589006870706, 3877.3796192609047, 3885.678869184491, 3891.8248678809523, 3052.435299469561, 3147.6065491813524, 3660.908477288089, 3934.960650393459, 3952.063488890423, 4084.8366065357645, 4550.044490656999, 4611.377464042312, 4617.39842922521, 4657.04404150251, 4599.563041511051, 4978.327925730053, 5067.46791586878, 5336.466353364633, 5367.892642147135, 1006.8691070160833, 1108.4346983038708, 1238.6098379010582, 1242.1243023668474, 1266.5060626355728, 731.4331393170229, 777.9766607001686, 868.5613765048736, 882.179572997106, 887.5207388010402, 4528.207563676344, 4677.906441871211, 4714.939138693767, 5059.468755780664, 5154.433012368637, 4821.63837712173, 5195.817756542261, 5475.835724928259, 5567.944320556823, 5600.123958473957, 4705.106029854317, 5041.42023667283, 5082.898342033139, 5104.7283958638045, 5136.948630513722, 2431.538007778516, 2514.3588384961718, 2841.0766500887294, 2853.9429211415654, 2911.2721819545113, 5204.9219261711105, 5332.467078137931, 5339.172428273624, 5343.381742946006, 5786.697992951552, 4642.4040200202235, 4946.109700253989, 5360.088784906575, 5662.462066103713, 5693.772249110072, 4565.474970378449, 5675.829725108253, 6084.269887613515, 6164.0, 6249.500039996789, 4736.715797052188, 5411.891762164813, 5838.32859221195, 5884.9705751470965, 6025.849353766191, 5384.542313903362, 5756.071715975195, 5901.114832775118, 5919.289685237627, 5958.165856779968, 5157.432682404858, 6020.19394181735, 6265.467435267871, 6290.21372328459, 6296.244450665949, 3883.7475564088245, 4035.6189847989826, 4046.1300820323772, 4059.878203653895, 4067.491563554556, 5676.205331253545, 5881.852953676193, 5925.703714814328, 5936.703164841654, 5949.0, 4926.064047830901, 5077.623380451958, 5577.52591029767, 5613.158026296088, 5711.400302968129, 5007.499250074963, 5181.404138523967, 5255.84371438283, 5745.827625171251, 5827.776167004977, 5412.161115027177, 5638.053362646246, 5638.605297629127, 5643.28785464142, 5656.85857853557, 5238.947610523846, 5348.304720386325, 5763.896177894954, 5765.884682306247, 5777.595568310178, 5559.332880054258, 5586.413426590149, 5609.9158512621525, 5651.886962260814, 5711.771529138788], "value": 4040.76680855515, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.19592175116, 691.357037954702, 714.4320265389015, 723.7937187901449, 759.4342215049788, 13721.108127971687, 14540.636484087903, 14571.12573245606, 14589.854101458996, 14608.78086828697, 10777.78444431111, 13095.672608184801, 13105.148165369244, 13117.606471805837, 13281.667958301023, 648.1703419622891, 666.7732970789931, 683.8939964305516, 805.4804651000102, 824.085265355458, 808.8261023879865, 813.8413009463158, 823.1768231768232, 823.9588020598976, 826.1738261738262, 878.5343767803042, 881.5063564403922, 882.74400423877, 884.1512148337604, 884.4251236696134, 845.480029781686, 880.7930136417937, 881.8721285413579, 881.9426737262057, 882.4220135811054, 653.1443808610735, 657.8454063295122, 659.8152517295165, 661.9238787539447, 662.6355504472521, 13683.31583420835, 13936.442542298297, 14070.15579065249, 14101.012929094968, 14310.21293828839, 11015.779684406347, 11975.161738678296, 12984.28586427746, 13565.0, 13704.177749334973, 11388.544458221744, 11434.142439317002, 11466.885331146748, 11490.885091149064, 11512.539498420054, 3630.3894054550574, 3678.6873115785193, 3684.6499582539614, 3684.7604905681046, 3836.3286424875814, 89714.27142964238, 92252.53873730656, 92311.0768892316, 92390.0, 92994.0, 10547.578096876192, 10548.367097974146, 10628.681139565826, 10748.408837514013, 10981.450927453596, 20672.276470323468, 20802.895985519976, 20838.166473340898, 20941.371758847257, 21031.0, 7461.1419686735635, 7505.5871927043445, 7512.511686740344, 7625.69497220116, 7759.689612415553, 4060.675145988314, 4165.75005499671, 4185.22573323936, 4222.1766755483, 4264.232438161161, 3341.7660763746303, 3449.3963556377535, 3461.5153878457036, 3463.3765922134257, 3475.304939012208, 540.5810496864962, 618.0790621973317, 621.3351713666334, 632.9303776584572, 637.5027478566633, 621.2513920725515, 636.1126228910643, 657.4214691071921, 658.4501940879419, 673.7978606418055, 8040.316573091236, 8558.545047341966, 8610.87083693745, 8891.288696904232, 9027.548622568976, 561.8146011816091, 590.0205658606692, 600.579594284003, 618.783425800975, 651.0689713709346, 4343.022819865568, 4413.492448368414, 4468.575485328888, 4471.0, 4546.136234115507, 72186.0, 109445.45277273563, 113290.0, 114204.42897785624, 115147.0, 79872.00319992047, 86716.0, 95975.0, 98014.0, 98315.50842245886, 52323.47676523262, 52568.73715631474, 52900.0, 53748.0, 72886.27113728794, 7749.41879359039, 8005.759827205193, 8211.95894020526, 9205.263578913537, 9390.201832844285, 43110.56889431066, 49632.75183624059, 50121.0, 52428.0, 55900.440995589524, 78130.6093469529, 79405.0, 80569.0, 81231.0, 82625.0, 9661.695671084595, 10430.0, 10486.580536778443, 10800.041733531301, 10815.297005694605, 11569.710757231136, 11690.240134391237, 11701.88298117008, 11842.407879606157, 11931.46308416106, 11314.434278286217, 11405.657830265105, 11502.367369794742, 11556.306621602731, 11769.352685602375, 11261.0, 11503.884961150281, 11524.481398336928, 11742.706432338919, 11912.0, 10968.945155274172, 11154.498047587716, 11191.44042797873, 11598.884011160118, 11719.765604687687, 9235.307351948497, 9465.0, 9579.521023948635, 9592.424454532751, 9679.66121185755, 9066.65867402005, 9284.814303714023, 9564.378315409476, 9713.0, 9847.655332063343], "value": 6939.365349117843, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1593.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 119.0, 118.0, 121.0, 137.0, 154.0, 120.0, 118.0, 119.0], "value": 124.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 143.0, 115.0, 157.0, 116.0, 122.0, 118.0, 121.0, 148.0, 119.0], "value": 131.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 197.0, 205.0, 232.0, 221.0, 198.0, 204.0, 199.0, 202.0, 199.0], "value": 205.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 60.0, 82.0, 61.0, 60.0, 50.0, 57.0, 89.0, 82.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 73.0, 88.0, 71.0, 70.0, 72.0, 86.0, 68.0, 74.0, 116.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 96.0, 119.0, 86.0, 135.0, 82.0, 110.0, 121.0, 138.0, 134.0], "value": 111.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 95.0, 97.0, 96.0, 112.0, 97.0, 152.0, 95.0, 98.0, 93.0], "value": 106.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 146.0, 79.0, 82.0, 104.0, 113.0, 150.0, 147.0, 108.0], "value": 111.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 55.0, 59.0, 59.0, 58.0, 57.0, 59.0, 59.0], "value": 57.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 74.0, 79.0, 75.0, 72.0, 72.0, 72.0, 74.0, 73.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 106.0, 79.0, 100.0, 113.0, 114.0, 102.0, 87.0, 90.0], "value": 96.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 153.0, 146.0, 156.0, 169.0, 158.0, 161.0, 167.0, 186.0, 154.0], "value": 160.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 174.0, 175.0, 171.0, 208.0, 219.0, 244.0, 173.0, 246.0], "value": 197.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 69.0, 70.0, 69.0, 68.0, 71.0, 71.0, 67.0, 70.0], "value": 69.3, "unit": "score"}]}]} 08:59:26 INFO - Return code: 0 08:59:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:59:26 ERROR - # TBPL SUCCESS # 08:59:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:59:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:59:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:59:26 INFO - 2016-11-15 08:59:26.637 screenresolution[1909:16040] starting screenresolution argv=screenresolution get 08:59:26 INFO - 2016-11-15 08:59:26.640 screenresolution[1909:16040] Display 0: 1600x1200x32@60 08:59:26 INFO - 2016-11-15 08:59:26.646 screenresolution[1910:16043] starting screenresolution argv=screenresolution list 08:59:26 INFO - Available Modes on Display 0 08:59:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:59:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:59:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:59:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:59:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:59:26 INFO - Intel Iris: 08:59:26 INFO - Chipset Model: Intel Iris 08:59:26 INFO - Type: GPU 08:59:26 INFO - Bus: Built-In 08:59:26 INFO - VRAM (Dynamic, Max): 1536 MB 08:59:26 INFO - Vendor: Intel (0x8086) 08:59:26 INFO - Device ID: 0x0a2e 08:59:26 INFO - Revision ID: 0x0009 08:59:26 INFO - Displays: 08:59:26 INFO - Display: 08:59:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:59:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:59:26 INFO - Display Serial Number: 1600x1200 60 08:59:26 INFO - Main Display: Yes 08:59:26 INFO - Mirror: Off 08:59:26 INFO - Online: Yes 08:59:26 INFO - Rotation: Supported 08:59:26 INFO - Return code: 0 08:59:26 INFO - Running post-action listener: _resource_record_post_action 08:59:26 INFO - [mozharness: 2016-11-15 16:59:26.889493Z] Finished run-tests step (success) 08:59:26 INFO - Running post-run listener: _resource_record_post_run 08:59:26 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 2000384; Write bytes: 700131328; Read time: 23; Write time: 1873 08:59:26 INFO - TinderboxPrint: CPU usage
54.4% 08:59:26 INFO - TinderboxPrint: I/O read bytes / time
2,000,384 / 23 08:59:26 INFO - TinderboxPrint: I/O write bytes / time
700,131,328 / 1,873 08:59:26 INFO - TinderboxPrint: CPU idle
2,209.6 (45.6%) 08:59:26 INFO - TinderboxPrint: CPU system
983.8 (20.3%) 08:59:26 INFO - TinderboxPrint: CPU user
1,656.4 (34.2%) 08:59:26 INFO - TinderboxPrint: Swap in / out
401,592,320 / 0 08:59:26 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397978112; Write bytes: 392549888; Read time: 29409; Write time: 701 08:59:26 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1992192; Write bytes: 297382400; Read time: 22; Write time: 1140 08:59:27 INFO - Running post-run listener: _upload_blobber_files 08:59:27 INFO - Blob upload gear active. 08:59:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:59:27 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 08:59:27 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:59:27 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:59:27 INFO - (blobuploader) - INFO - Open directory for files ... 08:59:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:59:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:59:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:59:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:59:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:59:28 INFO - (blobuploader) - INFO - Done attempting. 08:59:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:59:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:59:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:59:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:59:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:59:29 INFO - (blobuploader) - INFO - Done attempting. 08:59:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:59:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:59:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:59:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:59:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:59:31 INFO - (blobuploader) - INFO - Done attempting. 08:59:31 INFO - (blobuploader) - INFO - Iteration through files over. 08:59:31 INFO - Return code: 0 08:59:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:59:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:59:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1d2f7ae71a6225f9592d0e0c54c9eb264c206e7d7ee8d7bd85a31e8ade1ca21f75af7dd1c8cab4b7f450229d11800bc57d4ce0c73604e9fd609f6b759c17491", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7322d64d251688df02630a3e9e15eb346384d7090d7e06cea831471d6a51d18db44bbaed69bbc3ed48b76a8e05587a3c70be82289b471f75c4bc8f041caa8720", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78b45d463d67375eba7b8a50a4830dc9d94a7e66c7bbbef2ea9e672875af8d5db8a63ce82645a67cdad5e524f3e029f8eb2d7cf071409a43b8884c42c2341efb"} 08:59:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:59:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:59:31 INFO - Contents: 08:59:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1d2f7ae71a6225f9592d0e0c54c9eb264c206e7d7ee8d7bd85a31e8ade1ca21f75af7dd1c8cab4b7f450229d11800bc57d4ce0c73604e9fd609f6b759c17491", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7322d64d251688df02630a3e9e15eb346384d7090d7e06cea831471d6a51d18db44bbaed69bbc3ed48b76a8e05587a3c70be82289b471f75c4bc8f041caa8720", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78b45d463d67375eba7b8a50a4830dc9d94a7e66c7bbbef2ea9e672875af8d5db8a63ce82645a67cdad5e524f3e029f8eb2d7cf071409a43b8884c42c2341efb"} 08:59:31 INFO - Running post-run listener: copy_logs_to_upload_dir 08:59:31 INFO - Copying logs to upload dir... 08:59:31 INFO - mkdir: /builds/slave/test/build/upload/logs 08:59:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.148403 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-11-15 08:59:31.824011) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 08:59:31.827325) ========= 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.B1b4S84xHu/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.BvNxSFSmHE/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1d2f7ae71a6225f9592d0e0c54c9eb264c206e7d7ee8d7bd85a31e8ade1ca21f75af7dd1c8cab4b7f450229d11800bc57d4ce0c73604e9fd609f6b759c17491", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7322d64d251688df02630a3e9e15eb346384d7090d7e06cea831471d6a51d18db44bbaed69bbc3ed48b76a8e05587a3c70be82289b471f75c4bc8f041caa8720", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78b45d463d67375eba7b8a50a4830dc9d94a7e66c7bbbef2ea9e672875af8d5db8a63ce82645a67cdad5e524f3e029f8eb2d7cf071409a43b8884c42c2341efb"} build_url:https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010130 build_url: 'https://queue.taskcluster.net/v1/task/AildDL21THqyIDqlcGSjJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1d2f7ae71a6225f9592d0e0c54c9eb264c206e7d7ee8d7bd85a31e8ade1ca21f75af7dd1c8cab4b7f450229d11800bc57d4ce0c73604e9fd609f6b759c17491", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7322d64d251688df02630a3e9e15eb346384d7090d7e06cea831471d6a51d18db44bbaed69bbc3ed48b76a8e05587a3c70be82289b471f75c4bc8f041caa8720", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78b45d463d67375eba7b8a50a4830dc9d94a7e66c7bbbef2ea9e672875af8d5db8a63ce82645a67cdad5e524f3e029f8eb2d7cf071409a43b8884c42c2341efb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 08:59:31.859283) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 08:59:31.859601) ========= 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.B1b4S84xHu/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.BvNxSFSmHE/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.005147 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 08:59:31.908234) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 08:59:31.908615) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 08:59:31.908988) ========= ========= Total master_lag: 0.18 =========