builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0071 starttime: 1493424222.85 results: success (0) buildid: 20170428170219 builduid: 20841885e905473d96863152919a3e23 revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:03:42.847949) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:03:42.848438) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:03:42.848729) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:03:42.876371) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:03:42.877023) ========= 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.01Z8LG1B9C/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.J79CwwOcMK/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-28 17:03:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.4M=0.001s 2017-04-28 17:03:43 (13.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.274078 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:03:43.177900) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:03:43.178227) ========= 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.01Z8LG1B9C/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.J79CwwOcMK/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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.028395 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:03:43.228211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 17:03:43.228564) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b3b32894d9326d147238d9f071e1814ab3fdc850 --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 b3b32894d9326d147238d9f071e1814ab3fdc850 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.01Z8LG1B9C/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.J79CwwOcMK/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-28 17:03:43,331 truncating revision to first 12 chars 2017-04-28 17:03:43,332 Setting DEBUG logging. 2017-04-28 17:03:43,332 attempt 1/10 2017-04-28 17:03:43,332 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b3b32894d932?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-28 17:03:44,732 unpacking tar archive at: mozilla-central-b3b32894d932/testing/mozharness/ program finished with exit code 0 elapsedTime=1.672920 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 17:03:44.926251) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:03:44.926587) ========= script_repo_revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:03:44.927017) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:03:44.927429) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:03:44.948002) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-04-28 17:03:44.948409) ========= /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.01Z8LG1B9C/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.J79CwwOcMK/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 17:03:45 INFO - MultiFileLogger online at 20170428 17:03:45 in /builds/slave/test 17:03:45 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 17:03:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:03:45 INFO - {'append_to_log': False, 17:03:45 INFO - 'base_work_dir': '/builds/slave/test', 17:03:45 INFO - 'blob_upload_branch': 'Firefox', 17:03:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:03:45 INFO - 'branch': 'Firefox', 17:03:45 INFO - 'buildbot_json_path': 'buildprops.json', 17:03:45 INFO - 'config_files': ('talos/mac_config.py',), 17:03:45 INFO - 'default_actions': ('clobber', 17:03:45 INFO - 'read-buildbot-config', 17:03:45 INFO - 'download-and-extract', 17:03:45 INFO - 'populate-webroot', 17:03:45 INFO - 'create-virtualenv', 17:03:45 INFO - 'install', 17:03:45 INFO - 'run-tests'), 17:03:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:03:45 INFO - 'download_minidump_stackwalk': True, 17:03:45 INFO - 'download_symbols': 'ondemand', 17:03:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:03:45 INFO - 'tooltool.py': '/tools/tooltool.py', 17:03:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:03:45 INFO - '/tools/misc-python/virtualenv.py')}, 17:03:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:03:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:03:45 INFO - 'gecko_profile': False, 17:03:45 INFO - 'gecko_profile_interval': 0, 17:03:45 INFO - 'installer_path': 'installer.exe', 17:03:45 INFO - 'log_level': 'info', 17:03:45 INFO - 'log_name': 'talos', 17:03:45 INFO - 'log_to_console': True, 17:03:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:03:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:03:45 INFO - 'opt_config_files': (), 17:03:45 INFO - 'pip_index': False, 17:03:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:03:45 INFO - 'cmd': ('bash', 17:03:45 INFO - '-c', 17:03:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:03:45 INFO - 'enabled': True, 17:03:45 INFO - 'halt_on_failure': False, 17:03:45 INFO - 'name': 'check_screen_resolution'},), 17:03:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:03:45 INFO - 'cmd': ('bash', 17:03:45 INFO - '-c', 17:03:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:03:45 INFO - 'enabled': True, 17:03:45 INFO - 'halt_on_failure': False, 17:03:45 INFO - 'name': 'check_screen_resolution'},), 17:03:45 INFO - 'run_cmd_checks_enabled': True, 17:03:45 INFO - 'suite': 'dromaeojs-e10s', 17:03:45 INFO - 'system_bits': '32', 17:03:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:03:45 INFO - 'title': 't-yosemite-r7-0071', 17:03:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:03:45 INFO - 'use_talos_json': True, 17:03:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:03:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:03:45 INFO - 'work_dir': 'build'} 17:03:45 INFO - [mozharness: 2017-04-29 00:03:45.169316Z] Running clobber step. 17:03:45 INFO - Running pre-action listener: _resource_record_pre_action 17:03:45 INFO - Running main action method: clobber 17:03:45 INFO - rmtree: /builds/slave/test/build 17:03:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:03:47 INFO - Running post-action listener: _resource_record_post_action 17:03:47 INFO - [mozharness: 2017-04-29 00:03:47.750164Z] Finished clobber step (success) 17:03:47 INFO - [mozharness: 2017-04-29 00:03:47.750351Z] Running read-buildbot-config step. 17:03:47 INFO - Running pre-action listener: _resource_record_pre_action 17:03:47 INFO - Running main action method: read_buildbot_config 17:03:47 INFO - Using buildbot properties: 17:03:47 INFO - { 17:03:47 INFO - "project": "", 17:03:47 INFO - "product": "firefox", 17:03:47 INFO - "script_repo_revision": "production", 17:03:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:03:47 INFO - "repository": "", 17:03:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:03:47 INFO - "buildid": "20170428170219", 17:03:47 INFO - "stage_platform": "macosx64", 17:03:47 INFO - "basedir": "/builds/slave/test", 17:03:47 INFO - "buildnumber": 205, 17:03:47 INFO - "platform": "yosemite_r7", 17:03:47 INFO - "mozci_request": { 17:03:47 INFO - "priority": 0, 17:03:47 INFO - "type": "trigger_all_talos_jobs", 17:03:47 INFO - "times": 6 17:03:47 INFO - }, 17:03:47 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 17:03:47 INFO - "slavebuilddir": "test", 17:03:47 INFO - "branch": "mozilla-central", 17:03:47 INFO - "revision": "b3b32894d9326d147238d9f071e1814ab3fdc850", 17:03:47 INFO - "slavename": "t-yosemite-r7-0071", 17:03:47 INFO - "builduid": "20841885e905473d96863152919a3e23", 17:03:47 INFO - "repo_path": "mozilla-central" 17:03:47 INFO - } 17:03:47 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg. 17:03:47 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json. 17:03:47 INFO - Running post-action listener: _resource_record_post_action 17:03:47 INFO - [mozharness: 2017-04-29 00:03:47.754206Z] Finished read-buildbot-config step (success) 17:03:47 INFO - [mozharness: 2017-04-29 00:03:47.754308Z] Running download-and-extract step. 17:03:47 INFO - Running pre-action listener: _resource_record_pre_action 17:03:47 INFO - Running main action method: download_and_extract 17:03:47 INFO - mkdir: /builds/slave/test/build/tests 17:03: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')]} 17:03:47 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json 17:03:47 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:03:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 17:03:48 INFO - Downloaded 1325 bytes. 17:03:48 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:03:48 INFO - Using the following test package requirements: 17:03:48 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:03:48 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:03:48 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:03:48 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:03:48 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:03:48 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:03:48 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:03:48 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:03:48 INFO - u'jsshell-mac.zip'], 17:03:48 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:03:48 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:03:48 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:03:48 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:03:48 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:03:48 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:03:48 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:03:48 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:03:48 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:03:48 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:03:48 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:03:48 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:03:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip 17:03:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:03:48 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:03:49 INFO - Content-Length response header: 52698163 17:03:49 INFO - Bytes received: 52698163 17:04:05 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:04:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip 17:04:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:04:05 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:04:06 INFO - Content-Length response header: 52698163 17:04:06 INFO - Bytes received: 52698163 17:04:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.talos.tests.zip 17:04:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:04:22 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:04:22 INFO - Content-Length response header: 14408809 17:04:22 INFO - Bytes received: 14408809 17:04:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:22 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:04:22 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:04:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 17:04:24 INFO - Downloaded 71219323 bytes. 17:04:24 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:04:24 INFO - mkdir: /builds/slave/test/properties 17:04:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:04:24 INFO - Writing to file /builds/slave/test/properties/build_url 17:04:24 INFO - Contents: 17:04:24 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:04:24 INFO - Running post-action listener: _resource_record_post_action 17:04:24 INFO - Running post-action listener: set_extra_try_arguments 17:04:24 INFO - [mozharness: 2017-04-29 00:04:24.583070Z] Finished download-and-extract step (success) 17:04:24 INFO - [mozharness: 2017-04-29 00:04:24.583257Z] Running populate-webroot step. 17:04:24 INFO - Running pre-action listener: _resource_record_pre_action 17:04:24 INFO - Running main action method: populate_webroot 17:04:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:04:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:04:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:04:24 INFO - '--tppagecycles', 17:04:24 INFO - '7'], 17:04:24 INFO - 'tests': ['tsvgm']}}, 17:04:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:04:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:04:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:04:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:04:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:04:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:04:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:04:24 INFO - 'talos_options': ['--disable-e10s'], 17:04:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:04:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:04:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:04:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:04:24 INFO - 'talos_options': ['--disable-e10s'], 17:04:24 INFO - 'tests': ['damp', 'tps']}, 17:04:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:04:24 INFO - 'tests': ['damp', 'tps']}, 17:04:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:04:24 INFO - 'tests': ['dromaeo_dom']}, 17:04:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:04:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:04:24 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:04:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:04:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:04:24 INFO - 'tests': ['a11yr', 17:04:24 INFO - 'ts_paint', 17:04:24 INFO - 'tpaint', 17:04:24 INFO - 'sessionrestore', 17:04:24 INFO - 'sessionrestore_no_auto_restore', 17:04:24 INFO - 'tabpaint']}, 17:04:24 INFO - 'other-e10s': {'tests': ['a11yr', 17:04:24 INFO - 'ts_paint', 17:04:24 INFO - 'tpaint', 17:04:24 INFO - 'sessionrestore', 17:04:24 INFO - 'sessionrestore_no_auto_restore', 17:04:24 INFO - 'tabpaint']}, 17:04:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:04:24 INFO - 'ts_paint', 17:04:24 INFO - 'tpaint', 17:04:24 INFO - 'sessionrestore', 17:04:24 INFO - 'sessionrestore_no_auto_restore', 17:04:24 INFO - 'tabpaint']}, 17:04:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:04:24 INFO - 'ts_paint', 17:04:24 INFO - 'tpaint', 17:04:24 INFO - 'sessionrestore', 17:04:24 INFO - 'sessionrestore_no_auto_restore', 17:04:24 INFO - 'tabpaint']}, 17:04:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:04:24 INFO - 'tests': ['a11yr', 17:04:24 INFO - 'ts_paint', 17:04:24 INFO - 'tpaint', 17:04:24 INFO - 'sessionrestore', 17:04:24 INFO - 'sessionrestore_no_auto_restore', 17:04:24 INFO - 'tabpaint']}, 17:04:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:04:24 INFO - 'tests': ['a11yr', 17:04:24 INFO - 'ts_paint', 17:04:24 INFO - 'tpaint', 17:04:24 INFO - 'sessionrestore', 17:04:24 INFO - 'sessionrestore_no_auto_restore', 17:04:24 INFO - 'tabpaint']}, 17:04:24 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:04:24 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:04:24 INFO - 'bloom_basic_ref']}, 17:04:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:04:24 INFO - 'tests': ['tsvgx', 17:04:24 INFO - 'tsvgr_opacity', 17:04:24 INFO - 'tart', 17:04:24 INFO - 'tscrollx', 17:04:24 INFO - 'cart', 17:04:24 INFO - 'tsvg_static']}, 17:04:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:04:24 INFO - 'tsvgr_opacity', 17:04:24 INFO - 'tart', 17:04:24 INFO - 'tscrollx', 17:04:24 INFO - 'cart', 17:04:24 INFO - 'tsvg_static']}, 17:04:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:04:24 INFO - 'talos_options': ['--disable-e10s'], 17:04:24 INFO - 'tests': ['tp5o']}, 17:04:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:04:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:04:24 INFO - 'talos_options': ['--disable-e10s', 17:04:24 INFO - '--xperf_path', 17:04:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:04:24 INFO - 'tests': ['tp5n']}, 17:04:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:04:24 INFO - 'talos_options': ['--xperf_path', 17:04:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:04:24 INFO - 'tests': ['tp5n']}}, 17:04:24 INFO - 'talos.zip': {'path': '', 17:04:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:04:24 INFO - Running post-action listener: _resource_record_post_action 17:04:24 INFO - [mozharness: 2017-04-29 00:04:24.602690Z] Finished populate-webroot step (success) 17:04:24 INFO - [mozharness: 2017-04-29 00:04:24.602830Z] Running create-virtualenv step. 17:04:24 INFO - Running pre-action listener: _resource_record_pre_action 17:04:24 INFO - Running main action method: create_virtualenv 17:04:24 INFO - Creating virtualenv /builds/slave/test/build/venv 17:04:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:04:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:04:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:04:24 INFO - Using real prefix '/tools/python27' 17:04:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:04:25 INFO - Installing distribute.............................................................................................................................................................................................done. 17:04:28 INFO - Installing pip.................done. 17:04:28 INFO - Return code: 0 17:04:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:04:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:04:28 INFO - Reading from file tmpfile_stdout 17:04:28 INFO - Output received: 17:04:28 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:04:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:04:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:28 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 0x100806b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100809768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8521d7b430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005e4d30>, '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 0x10050adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d9be8>, '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.01Z8LG1B9C/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.J79CwwOcMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:04:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:04:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:04:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01Z8LG1B9C/Render', 17:04:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:28 INFO - 'HOME': '/Users/cltbld', 17:04:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:28 INFO - 'LOGNAME': 'cltbld', 17:04:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:28 INFO - 'MOZ_NO_REMOTE': '1', 17:04:28 INFO - 'NO_EM_RESTART': '1', 17:04:28 INFO - 'PAGER': '/bin/cat', 17:04:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:28 INFO - 'PWD': '/builds/slave/test', 17:04:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:28 INFO - 'SHELL': '/bin/bash', 17:04:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J79CwwOcMK/Listeners', 17:04:28 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 17:04:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:28 INFO - 'USER': 'cltbld', 17:04:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:28 INFO - 'XPC_FLAGS': '0x0', 17:04:28 INFO - 'XPC_SERVICE_NAME': '0', 17:04:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:04:29 INFO - Cleaning up... 17:04:29 INFO - Return code: 0 17:04:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100806b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100809768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8521d7b430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005e4d30>, '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 0x10050adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d9be8>, '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.01Z8LG1B9C/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.J79CwwOcMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:04:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:04:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01Z8LG1B9C/Render', 17:04:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:29 INFO - 'HOME': '/Users/cltbld', 17:04:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:29 INFO - 'LOGNAME': 'cltbld', 17:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:29 INFO - 'MOZ_NO_REMOTE': '1', 17:04:29 INFO - 'NO_EM_RESTART': '1', 17:04:29 INFO - 'PAGER': '/bin/cat', 17:04:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:29 INFO - 'PWD': '/builds/slave/test', 17:04:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:29 INFO - 'SHELL': '/bin/bash', 17:04:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J79CwwOcMK/Listeners', 17:04:29 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 17:04:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:29 INFO - 'USER': 'cltbld', 17:04:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:29 INFO - 'XPC_FLAGS': '0x0', 17:04:29 INFO - 'XPC_SERVICE_NAME': '0', 17:04:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:29 INFO - Downloading/unpacking psutil>=3.1.1 17:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:04:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:04:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:04:32 INFO - Installing collected packages: psutil 17:04:32 INFO - Running setup.py install for psutil 17:04:32 INFO - building 'psutil._psutil_osx' extension 17:04:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:04:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:04:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:04:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:04:33 INFO - building 'psutil._psutil_posix' extension 17:04:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:04:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:04:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:04:33 INFO - ^ 17:04:33 INFO - 1 warning generated. 17:04:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:04:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:04:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:04:34 INFO - Successfully installed psutil 17:04:34 INFO - Cleaning up... 17:04:34 INFO - Return code: 0 17:04:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:34 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 0x100806b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100809768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8521d7b430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005e4d30>, '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 0x10050adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d9be8>, '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.01Z8LG1B9C/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.J79CwwOcMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:04:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:04:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:04:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01Z8LG1B9C/Render', 17:04:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:34 INFO - 'HOME': '/Users/cltbld', 17:04:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:34 INFO - 'LOGNAME': 'cltbld', 17:04:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:34 INFO - 'MOZ_NO_REMOTE': '1', 17:04:34 INFO - 'NO_EM_RESTART': '1', 17:04:34 INFO - 'PAGER': '/bin/cat', 17:04:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:34 INFO - 'PWD': '/builds/slave/test', 17:04:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:34 INFO - 'SHELL': '/bin/bash', 17:04:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J79CwwOcMK/Listeners', 17:04:34 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 17:04:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:34 INFO - 'USER': 'cltbld', 17:04:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:34 INFO - 'XPC_FLAGS': '0x0', 17:04:34 INFO - 'XPC_SERVICE_NAME': '0', 17:04:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:04:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:04:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:04:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:04:37 INFO - Installing collected packages: mozsystemmonitor 17:04:37 INFO - Running setup.py install for mozsystemmonitor 17:04:37 INFO - Successfully installed mozsystemmonitor 17:04:37 INFO - Cleaning up... 17:04:37 INFO - Return code: 0 17:04:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:04:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100806b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100809768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8521d7b430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005e4d30>, '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 0x10050adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d9be8>, '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.01Z8LG1B9C/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.J79CwwOcMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:04:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:04:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:04:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01Z8LG1B9C/Render', 17:04:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:37 INFO - 'HOME': '/Users/cltbld', 17:04:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:37 INFO - 'LOGNAME': 'cltbld', 17:04:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:37 INFO - 'MOZ_NO_REMOTE': '1', 17:04:37 INFO - 'NO_EM_RESTART': '1', 17:04:37 INFO - 'PAGER': '/bin/cat', 17:04:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:37 INFO - 'PWD': '/builds/slave/test', 17:04:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:37 INFO - 'SHELL': '/bin/bash', 17:04:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J79CwwOcMK/Listeners', 17:04:37 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 17:04:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:37 INFO - 'USER': 'cltbld', 17:04:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:37 INFO - 'XPC_FLAGS': '0x0', 17:04:37 INFO - 'XPC_SERVICE_NAME': '0', 17:04:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:37 INFO - Downloading/unpacking jsonschema==2.5.1 17:04:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:04:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:40 INFO - Downloading functools32-3.2.3-2.tar.gz 17:04:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:04:40 INFO - warning: no files found matching '*.txt' 17:04:40 INFO - no previously-included directories found matching 'build' 17:04:40 INFO - no previously-included directories found matching 'dist' 17:04:40 INFO - no previously-included directories found matching '.git*' 17:04:40 INFO - Installing collected packages: jsonschema, functools32 17:04:40 INFO - Running setup.py install for functools32 17:04:40 INFO - warning: no files found matching '*.txt' 17:04:40 INFO - no previously-included directories found matching 'build' 17:04:40 INFO - no previously-included directories found matching 'dist' 17:04:40 INFO - no previously-included directories found matching '.git*' 17:04:40 INFO - Successfully installed jsonschema functools32 17:04:40 INFO - Cleaning up... 17:04:40 INFO - Return code: 0 17:04:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:04:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:41 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 0x100806b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100809768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8521d7b430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005e4d30>, '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 0x10050adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d9be8>, '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.01Z8LG1B9C/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.J79CwwOcMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:04:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:04:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:04:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01Z8LG1B9C/Render', 17:04:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:41 INFO - 'HOME': '/Users/cltbld', 17:04:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:41 INFO - 'LOGNAME': 'cltbld', 17:04:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:41 INFO - 'MOZ_NO_REMOTE': '1', 17:04:41 INFO - 'NO_EM_RESTART': '1', 17:04:41 INFO - 'PAGER': '/bin/cat', 17:04:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:41 INFO - 'PWD': '/builds/slave/test', 17:04:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:41 INFO - 'SHELL': '/bin/bash', 17:04:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J79CwwOcMK/Listeners', 17:04:41 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 17:04:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:41 INFO - 'USER': 'cltbld', 17:04:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:41 INFO - 'XPC_FLAGS': '0x0', 17:04:41 INFO - 'XPC_SERVICE_NAME': '0', 17:04:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:04:41 INFO - Cleaning up... 17:04:41 INFO - Return code: 0 17:04:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100806b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100809768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8521d7b430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005e4d30>, '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 0x10050adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d9be8>, '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.01Z8LG1B9C/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.J79CwwOcMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:04:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:04:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:04:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01Z8LG1B9C/Render', 17:04:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:42 INFO - 'HOME': '/Users/cltbld', 17:04:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:42 INFO - 'LOGNAME': 'cltbld', 17:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:42 INFO - 'MOZ_NO_REMOTE': '1', 17:04:42 INFO - 'NO_EM_RESTART': '1', 17:04:42 INFO - 'PAGER': '/bin/cat', 17:04:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:42 INFO - 'PWD': '/builds/slave/test', 17:04:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:42 INFO - 'SHELL': '/bin/bash', 17:04:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J79CwwOcMK/Listeners', 17:04:42 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 17:04:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:42 INFO - 'USER': 'cltbld', 17:04:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:42 INFO - 'XPC_FLAGS': '0x0', 17:04:42 INFO - 'XPC_SERVICE_NAME': '0', 17:04:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:42 INFO - Downloading/unpacking blobuploader==1.2.4 17:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:45 INFO - Downloading blobuploader-1.2.4.tar.gz 17:04:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:04:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:04:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:46 INFO - Downloading docopt-0.6.1.tar.gz 17:04:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:04:46 INFO - Installing collected packages: blobuploader, requests, docopt 17:04:46 INFO - Running setup.py install for blobuploader 17:04:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:04:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:04:47 INFO - Running setup.py install for requests 17:04:47 INFO - Running setup.py install for docopt 17:04:47 INFO - Successfully installed blobuploader requests docopt 17:04:47 INFO - Cleaning up... 17:04:47 INFO - Return code: 0 17:04:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:04: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')]} 17:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04: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')]} 17:04:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:47 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 0x100806b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100809768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8521d7b430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005e4d30>, '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 0x10050adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d9be8>, '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.01Z8LG1B9C/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.J79CwwOcMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:04:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:04:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:04:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01Z8LG1B9C/Render', 17:04:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:47 INFO - 'HOME': '/Users/cltbld', 17:04:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:47 INFO - 'LOGNAME': 'cltbld', 17:04:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:47 INFO - 'MOZ_NO_REMOTE': '1', 17:04:47 INFO - 'NO_EM_RESTART': '1', 17:04:47 INFO - 'PAGER': '/bin/cat', 17:04:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:47 INFO - 'PWD': '/builds/slave/test', 17:04:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:47 INFO - 'SHELL': '/bin/bash', 17:04:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J79CwwOcMK/Listeners', 17:04:47 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 17:04:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:47 INFO - 'USER': 'cltbld', 17:04:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:47 INFO - 'XPC_FLAGS': '0x0', 17:04:47 INFO - 'XPC_SERVICE_NAME': '0', 17:04:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:04:48 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-E6cSE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:04:48 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-yt9GWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:04:48 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-D7CkvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:04:48 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-u3fNQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:04:48 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-69GGqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:04:48 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-HbxTV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:04:48 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-u90vSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:04:48 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-vAWZGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:04:48 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-yfw2iG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:04:48 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Rf8fPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:04:49 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-xSgBZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:04:49 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-ZEgJ9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:04:49 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-m_uQC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:04:49 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-LIby2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:04:49 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-O4D7l3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:04:49 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-ErXIPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:04:49 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-lTbXSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:04:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:04:49 INFO - Running setup.py install for manifestparser 17:04:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:04:49 INFO - Running setup.py install for mozcrash 17:04:50 INFO - Running setup.py install for mozdebug 17:04:50 INFO - Running setup.py install for mozdevice 17:04:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:04:50 INFO - Running setup.py install for mozfile 17:04:50 INFO - Running setup.py install for mozhttpd 17:04:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:04:50 INFO - Running setup.py install for mozinfo 17:04:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:04:50 INFO - Running setup.py install for mozInstall 17:04:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:04:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:04:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:04:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:04:50 INFO - Running setup.py install for mozleak 17:04:51 INFO - Running setup.py install for mozlog 17:04:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:04:51 INFO - Running setup.py install for moznetwork 17:04:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:04:51 INFO - Running setup.py install for mozprocess 17:04:51 INFO - Running setup.py install for mozprofile 17:04:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:04:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:04:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:04:51 INFO - Running setup.py install for mozrunner 17:04:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:04:51 INFO - Running setup.py install for mozscreenshot 17:04:52 INFO - Running setup.py install for moztest 17:04:52 INFO - Running setup.py install for mozversion 17:04:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:04:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:04:52 INFO - Cleaning up... 17:04:52 INFO - Return code: 0 17:04:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:52 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 0x100806b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100809768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8521d7b430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005e4d30>, '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 0x10050adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d9be8>, '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.01Z8LG1B9C/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.J79CwwOcMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:04:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:04:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:04:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01Z8LG1B9C/Render', 17:04:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:52 INFO - 'HOME': '/Users/cltbld', 17:04:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:52 INFO - 'LOGNAME': 'cltbld', 17:04:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:52 INFO - 'MOZ_NO_REMOTE': '1', 17:04:52 INFO - 'NO_EM_RESTART': '1', 17:04:52 INFO - 'PAGER': '/bin/cat', 17:04:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:52 INFO - 'PWD': '/builds/slave/test', 17:04:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:52 INFO - 'SHELL': '/bin/bash', 17:04:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J79CwwOcMK/Listeners', 17:04:52 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 17:04:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:52 INFO - 'USER': 'cltbld', 17:04:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:52 INFO - 'XPC_FLAGS': '0x0', 17:04:52 INFO - 'XPC_SERVICE_NAME': '0', 17:04:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:04:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Lv6tsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:04:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Ufi6HS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:04:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-PkT9nb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:04:52 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-CVCzl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:04:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-F4zs6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:04:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-b6BcdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:04:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-erzKq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:04:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-nZxWyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:04:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-sb0TE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:04:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-dUVmaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:04:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-jZBHHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:04:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-QbYkcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:04:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-uKqm77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:04:53 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-L7AQFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:04:54 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Uhh1nM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:04:54 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-KszMrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:04:54 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-PelLlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:04:54 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:04:54 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:04:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:57 INFO - Downloading blessings-1.6.tar.gz 17:04:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:04:57 INFO - Installing collected packages: blessings 17:04:57 INFO - Running setup.py install for blessings 17:04:57 INFO - Successfully installed blessings 17:04:57 INFO - Cleaning up... 17:04:57 INFO - Return code: 0 17:04:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:04:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:04:57 INFO - Reading from file tmpfile_stdout 17:04:57 INFO - Current package versions: 17:04:57 INFO - blessings == 1.6 17:04:57 INFO - blobuploader == 1.2.4 17:04:57 INFO - docopt == 0.6.1 17:04:57 INFO - functools32 == 3.2.3-2 17:04:57 INFO - jsonschema == 2.5.1 17:04:57 INFO - manifestparser == 1.1 17:04:57 INFO - mozInstall == 1.12 17:04:57 INFO - mozcrash == 1.0 17:04:57 INFO - mozdebug == 0.1 17:04:57 INFO - mozdevice == 0.50 17:04:57 INFO - mozfile == 1.2 17:04:57 INFO - mozhttpd == 0.7 17:04:57 INFO - mozinfo == 0.9 17:04:57 INFO - mozleak == 0.1 17:04:57 INFO - mozlog == 3.4 17:04:57 INFO - moznetwork == 0.27 17:04:57 INFO - mozprocess == 0.25 17:04:57 INFO - mozprofile == 0.28 17:04:57 INFO - mozrunner == 6.13 17:04:57 INFO - mozscreenshot == 0.1 17:04:57 INFO - mozsystemmonitor == 0.3 17:04:57 INFO - moztest == 0.8 17:04:57 INFO - mozversion == 1.4 17:04:57 INFO - psutil == 3.1.1 17:04:57 INFO - requests == 1.2.3 17:04:57 INFO - wsgiref == 0.1.2 17:04:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:57 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 0x100806b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100809768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8521d7b430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005e4d30>, '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 0x10050adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d9be8>, '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.01Z8LG1B9C/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.J79CwwOcMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:04:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:04:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01Z8LG1B9C/Render', 17:04:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:57 INFO - 'HOME': '/Users/cltbld', 17:04:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:57 INFO - 'LOGNAME': 'cltbld', 17:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:57 INFO - 'MOZ_NO_REMOTE': '1', 17:04:57 INFO - 'NO_EM_RESTART': '1', 17:04:57 INFO - 'PAGER': '/bin/cat', 17:04:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:57 INFO - 'PWD': '/builds/slave/test', 17:04:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:57 INFO - 'SHELL': '/bin/bash', 17:04:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J79CwwOcMK/Listeners', 17:04:57 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 17:04:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:57 INFO - 'USER': 'cltbld', 17:04:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:57 INFO - 'XPC_FLAGS': '0x0', 17:04:57 INFO - 'XPC_SERVICE_NAME': '0', 17:04:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:04:58 INFO - Cleaning up... 17:04:58 INFO - Return code: 0 17:04:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:04: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100806b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100809768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8521d7b430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1005e4d30>, '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 0x10050adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d9be8>, '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.01Z8LG1B9C/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.J79CwwOcMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:04: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', 'jsonschema'] in /builds/slave/test/build 17:04: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 jsonschema 17:04:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01Z8LG1B9C/Render', 17:04:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:04:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:04:58 INFO - 'HOME': '/Users/cltbld', 17:04:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:04:58 INFO - 'LOGNAME': 'cltbld', 17:04:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:58 INFO - 'MOZ_NO_REMOTE': '1', 17:04:58 INFO - 'NO_EM_RESTART': '1', 17:04:58 INFO - 'PAGER': '/bin/cat', 17:04:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:04:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:58 INFO - 'PWD': '/builds/slave/test', 17:04:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:04:58 INFO - 'SHELL': '/bin/bash', 17:04:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J79CwwOcMK/Listeners', 17:04:58 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 17:04:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:04:58 INFO - 'USER': 'cltbld', 17:04:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:04:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:04:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:58 INFO - 'XPC_FLAGS': '0x0', 17:04:58 INFO - 'XPC_SERVICE_NAME': '0', 17:04:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:04:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:04:58 INFO - Cleaning up... 17:04:58 INFO - Return code: 0 17:04:58 INFO - Running post-action listener: _resource_record_post_action 17:04:58 INFO - Running post-action listener: _start_resource_monitoring 17:04:58 INFO - Starting resource monitoring. 17:04:58 INFO - [mozharness: 2017-04-29 00:04:58.341564Z] Finished create-virtualenv step (success) 17:04:58 INFO - [mozharness: 2017-04-29 00:04:58.342510Z] Running install step. 17:04:58 INFO - Running pre-action listener: _resource_record_pre_action 17:04:58 INFO - Running main action method: install 17:04:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:04:58 INFO - Reading from file tmpfile_stdout 17:04:58 INFO - Detecting whether we're running mozinstall >=1.0... 17:04:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:04:58 INFO - Reading from file tmpfile_stdout 17:04:58 INFO - Output received: 17:04:58 INFO - Usage: mozinstall [options] installer 17:04:58 INFO - Options: 17:04:58 INFO - -h, --help show this help message and exit 17:04:58 INFO - -d DEST, --destination=DEST 17:04:58 INFO - Directory to install application into. [default: 17:04:58 INFO - "/builds/slave/test"] 17:04:58 INFO - --app=APP Application being installed. [default: firefox] 17:04:58 INFO - mkdir: /builds/slave/test/build/application 17:04:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:05:18 INFO - Reading from file tmpfile_stdout 17:05:18 INFO - Output received: 17:05:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:05:18 INFO - Running post-action listener: _resource_record_post_action 17:05:18 INFO - [mozharness: 2017-04-29 00:05:18.540881Z] Finished install step (success) 17:05:18 INFO - [mozharness: 2017-04-29 00:05:18.541044Z] Running run-tests step. 17:05:18 INFO - Running pre-action listener: _resource_record_pre_action 17:05:18 INFO - Running main action method: run_tests 17:05:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:05:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:05:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:05:18 INFO - 2017-04-28 17:05:18.555 screenresolution[1625:7705] starting screenresolution argv=screenresolution get 17:05:18 INFO - 2017-04-28 17:05:18.558 screenresolution[1625:7705] Display 0: 1600x1200x32@60 17:05:18 INFO - 2017-04-28 17:05:18.564 screenresolution[1626:7708] starting screenresolution argv=screenresolution list 17:05:18 INFO - Available Modes on Display 0 17:05:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:05:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:05:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:05:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:05:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:05:18 INFO - Intel Iris: 17:05:18 INFO - Chipset Model: Intel Iris 17:05:18 INFO - Type: GPU 17:05:18 INFO - Bus: Built-In 17:05:18 INFO - VRAM (Dynamic, Max): 1536 MB 17:05:18 INFO - Vendor: Intel (0x8086) 17:05:18 INFO - Device ID: 0x0a2e 17:05:18 INFO - Revision ID: 0x0009 17:05:18 INFO - Displays: 17:05:18 INFO - Display: 17:05:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:05:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:05:18 INFO - Display Serial Number: 1600x1200 60 17:05:18 INFO - Main Display: Yes 17:05:18 INFO - Mirror: Off 17:05:18 INFO - Online: Yes 17:05:18 INFO - Rotation: Supported 17:05:18 INFO - Return code: 0 17:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:05:18 INFO - Python 2.7.3 17:05:18 INFO - Return code: 0 17:05:18 INFO - grabbing minidump binary from tooltool 17:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:18 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 0x1005e4d30>, '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 0x10050adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1005d9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:05:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:05:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:05:18 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:05:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:05:18 INFO - Return code: 0 17:05:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:05:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:05:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:05:18 INFO - ENV: RUST_BACKTRACE is now 1 17:05:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:05:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:05:18 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://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0071', '--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 17:05:18 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://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0071 --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 17:05:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01Z8LG1B9C/Render', 17:05:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:18 INFO - 'HOME': '/Users/cltbld', 17:05:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:18 INFO - 'LOGNAME': 'cltbld', 17:05:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:05:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:18 INFO - 'MOZ_NO_REMOTE': '1', 17:05:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:05:18 INFO - 'NO_EM_RESTART': '1', 17:05:18 INFO - 'PAGER': '/bin/cat', 17:05:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:18 INFO - 'PWD': '/builds/slave/test', 17:05:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:05:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:18 INFO - 'RUST_BACKTRACE': '1', 17:05:18 INFO - 'SHELL': '/bin/bash', 17:05:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J79CwwOcMK/Listeners', 17:05:18 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 17:05:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:18 INFO - 'USER': 'cltbld', 17:05:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:18 INFO - 'XPC_FLAGS': '0x0', 17:05:18 INFO - 'XPC_SERVICE_NAME': '0', 17:05:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:18 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://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0071', '--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 17:05:19 INFO - mozversion application_buildid: 20170428075320 17:05:19 INFO - mozversion application_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:05:19 INFO - mozversion application_display_name: Nightly 17:05:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:05:19 INFO - mozversion application_name: Firefox 17:05:19 INFO - mozversion application_remotingname: firefox 17:05:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:05:19 INFO - mozversion application_vendor: Mozilla 17:05:19 INFO - mozversion application_version: 55.0a1 17:05:19 INFO - mozversion platform_buildid: 20170428075320 17:05:19 INFO - mozversion platform_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:05:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:05:19 INFO - mozversion platform_version: 55.0a1 17:05:19 INFO - using testdate: 1493424319 17:05:19 INFO - actual date: 1493424319 17:05:19 INFO - starting webserver on 'localhost:49229' 17:05:19 INFO - SUITE-START | Running 2 tests 17:05:19 INFO - TEST-START | dromaeo_css 17:05:19 INFO - operating with platform_type : mac_ 17:05:19 INFO - Initialising browser for dromaeo_css test... 17:05:19 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpni3Q3o/profile http://localhost:49229/getInfo.html) 17:05:21 INFO - PID 1636 | 2017-04-28 17:05:21.559 plugin-container[1638:7898] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993b, name = 'com.apple.tsm.portname' 17:05:21 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:05:21 INFO - PID 1636 | 2017-04-28 17:05:21.562 plugin-container[1638:7898] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0f, name = 'com.apple.CFPasteboardClient' 17:05:21 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:05:21 INFO - PID 1636 | 2017-04-28 17:05:21.562 plugin-container[1638:7898] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:05:21 INFO - PID 1636 | __metrics Screen width/height:1600/1200 17:05:21 INFO - PID 1636 | colorDepth:24 17:05:21 INFO - PID 1636 | Browser inner width/height: 1024/689 17:05:21 INFO - PID 1636 | __metrics 17:05:21 ERROR - PID 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:05:22 INFO - TEST-INFO | 1636: exit 0 17:05:22 INFO - Browser initialized. 17:05:22 INFO - Running cycle 1/1 for dromaeo_css test... 17:05:22 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpni3Q3o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:05:24 INFO - PID 1639 | 2017-04-28 17:05:24.916 plugin-container[1640:8029] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 17:05:24 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:05:24 INFO - PID 1639 | 2017-04-28 17:05:24.918 plugin-container[1640:8029] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 17:05:24 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:05:24 INFO - PID 1639 | 2017-04-28 17:05:24.918 plugin-container[1640:8029] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:05:35 INFO - PID 1639 | RSS: Main: 251387904 17:05:35 INFO - PID 1639 | 17:08:39 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 17:08:40 INFO - PID 1639 | RSS: Main: 257781760 17:08:40 INFO - PID 1639 | 17:10:23 INFO - PID 1639 | ************************* 17:10:23 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 17:10:23 INFO - PID 1639 | 17:10:23 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:10:23 INFO - PID 1639 | Full stack: 17:10:23 INFO - PID 1639 | ************************* 17:10:23 INFO - PID 1639 | ************************* 17:10:23 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 17:10:23 INFO - PID 1639 | 17:10:23 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:10:23 INFO - PID 1639 | Full stack: 17:10:23 INFO - PID 1639 | ************************* 17:10:23 INFO - PID 1639 | ************************* 17:10:23 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 17:10:23 INFO - PID 1639 | 17:10:23 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:10:23 INFO - PID 1639 | Full stack: 17:10:23 INFO - PID 1639 | ************************* 17:10:23 INFO - PID 1639 | ************************* 17:10:23 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 17:10:23 INFO - PID 1639 | 17:10:23 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:10:23 INFO - PID 1639 | Full stack: 17:10:23 INFO - PID 1639 | ************************* 17:11:42 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 17:11:43 INFO - PID 1639 | RSS: Main: 259104768 17:11:43 INFO - PID 1639 | 17:14:45 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 17:14:45 INFO - PID 1639 | RSS: Main: 270381056 17:14:45 INFO - PID 1639 | 17:17:47 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 17:17:48 INFO - PID 1639 | RSS: Main: 262967296 17:17:48 INFO - PID 1639 | 17:20:51 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 17:20:51 INFO - PID 1639 | RSS: Main: 272113664 17:20:51 INFO - PID 1639 | 17:23:53 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 17:23:53 INFO - PID 1639 | RSS: Main: 266727424 17:23:53 INFO - PID 1639 | 17:23:53 INFO - PID 1639 | __start_tp_report 17:23:53 INFO - PID 1639 | _x_x_mozilla_page_load 17:23:53 INFO - PID 1639 | _x_x_mozilla_page_load_details 17:23:53 INFO - PID 1639 | |i|pagename|runs| 17:23:53 INFO - PID 1639 | |0;dojo.html;2886.6968968258334;3156.968430315697;3628.8367023483956;3639.10841843748;3656.981715091424;4997.4003119625695;5680.431956804312;6203.2245969253845;6292.46514046306;6349.873002539946;6206.348333424993;6211.689415529217;6219.40915613016;6258.436740693337;6321.43107120359;5390.460953904618;5458.536024437927;5481.917771233435;5487.725613719308;5549.278593782803;5019.598432125421;5595.54664530859;5612.66324020558;5628.9305032043985;5640.351359593637;5005.199168133081;5446.618736688394;5449.918251226235;5513.5037846594;5591.580631452637;5481.205225242357;5491.862703432406;5513.09034009388;5545;5553.278073850414;4364.825406983717;5296.602754793406;5432.674039557639;5443.319585051869;5488.588355873286;6074.149619053336;6120.233815636177;6260.342664020258;6265.624062556261;6274.529410294246;6495.155629768147;6628.53600247978;6695.029220762962;6696.46428285736;6721.529492935497;5676.971615141939;6765.357291057391;6776.966115169393;6778.118844550227;6784.1858976922595;6243.157173781572;6309.716062777186;6324.430801227865;6331.398517140864;6344.143540622049;6218.689065546704;6635.86728265432;6705.765298214539;6919.965400172967;6948;5657.264555607745;6150.769846030812;6450.41946224842;6513.1532900722605;6582.275949645631;6716.496262780311;6808.761693340709;6816.216135144423;6819.318068193141;6862.9313706863295;6726.091977583062;6730.629815360105;6739.663016849138;6744.966275168593;6755.22314933779;6460.224773027173;6557.508186885907;6610.338966103352;6648.634325112165;6710.966445167841;5142.6400151989;5379.327584051994;5379.596530260246;5388.649737767033;5411.83764487066;4738.73936933465;4751.382320298339;4752.429708434941;4777.020710754287;4801.663883528189;6133.969330153321;6311.274203466568;6316.652584107826;6397.296297407282;6407.583507072119;6791.4906382021545;6791.93208067913;6840.9657951710915;6846.110005699227;6846.828829279209;5325.733713314319;6334.1765570475545;6363.90454143179;6373.936260637427;6395.584287021329;5791.768329266866;5823.854403639943;5824.155497452845;5824.213731146326;5909.231799866076;6826.488013398915;6932.410745086522;6997.370236678723;7007.264237255014;7046.859062818612;6845.14435695538;6853.246142924175;6865.485088618274;6884.4148247399535;6887.7244910203035;5971.7611295547695;6507.511936604679;6907.974910839994;6984;7017.543859649107;6472.35276472349;6527.281998980204;6611.438027767694;6677;6691.5315927884585;6787.423069039187;6818.727250909908;6824.18109826824;6825.010373495915;6829.965850170916;6378.489720822416;6448.645324507197;6514.446272066927;6544.247411547733;6573.5398522103;5933.821985340447;6421.036844473367;6601.603903765789;6606.933930660632;6614.933850661624;6252.468540174136;6331.145295385064;6332.366763323815;6340.1440805490665;6343.3022367538615;5379.54273886724;6552.868942621216;6589.604623722592;6646.634435105922;6650.501212408992;6437.613743175424;6438.90341644885;6445.291017988111;6453.515986300952;6457.418832305115 17:23:53 INFO - PID 1639 | |1;ext.html;699.4055053204777;737.6311844077961;1091.3451928842696;1127.830825376194;1129.2490493821608;36259.81870090649;37313.25373492528;37328.626713732854;37344.62655373442;37350.252994940085;37184.25631487369;37519.06202344943;37523.49906003757;37525;37549.43675844857;7806.258405451487;7876.803079923019;8274.1312162223;8512.063672995997;8523.616437260307;8505.74482765515;8622.913770862273;8626;8648;8659.307255419582;8585.613647385851;8621.65513379464;8638.265747411477;8654.48073115615;8661.783455413602;8524.531150786705;8565.957170214171;8612.13878612137;8632.697855575088;8651.437656552303;8308.833823323499;8330.166983301682;8395.70615028471;8418.074011858691;8423.747287581382;37280.813595931846;37282.627173728186;37423.62576374229;37470.43794343087;37482;3566.946495802565;3570.80360580168;3600.1899572596217;3603.3333833240936;3607.3326434609417;30702.692973070207;30803.383932321227;30817;30830.383392332253;31020;3135.106494649014;3142.8271445070513;3155.4004739099496;3170.5719727836677;3171.9365612687848;295036;295048.5742713839;295791.99999999785;296173;296320;35826;37857.81071094627;39011.609883901365;47827.76086119547;48826;42906;46547;46649.7667511667;50808.72978175575;50875.74562127166;12813.615591532083;12883.48466061347;14221.928890355688;14323.713525729427;14480.85519144795;10394.22043346737;11062.668119956414;11520.135989800798;11669.533218671328;11822.645320640395;12311.322877241837;13018.958483321501;13430.664233394244;13438.731225375634;13595.796063059062;4544.113897789899;5174.6119041071315;5258.843054527998;5310.123829568078;5318.25544423781;980.3480685344166;1016.5730393234711;1028.228828378716;1032.7779527401644;1062.7236918401272;19354.903225483784;22266.88866555657;24064.639030414557;24077.759222407552;24156.7584324158;1127.9154063445274;1130.2032067392468;1163.1276542593055;1164.4759858063906;1168.0772189969828;9706.514674266258;12257.816132758016;12274.509019639294;12310.569130080405;12357;322180;323820.380898094;325673;325976;326583.36708316783;53084.20373694398;53560;53742.46257537375;53809.730951344995;54944;1474.5871156076316;1506.9623259418602;1540.4377402248099;1542.4755583101798;1544.7451170556733;19458.8054119457;21948.34154975353;23948;23981.520369592858;24005.51988960246;38020.239595207386;38074.42888356732;38082.2383552333;38096;38232.426513601764;287801;292127;293432;293890;299091;29016.854915725286;29403.852980735814;33492.832535837166;33726.66273337235;34411.311773764886;41555;41988.790056049525;43308.78345608378;43439.78280108706;43603.127937441706;30053.54919676163;30349.089527314216;30499;30679;30785.538216927212;28754.137375878756;29936.85031574828;30343.763224055605;30679.846600766854;30895.69104309018;36059.81970090133;36278.81860590786;36448.81775591105;36541.26917461582;36565.45151822671;29140.417191656474;31101.53347699741;31581.368372632878;31761.841190793897;31821.045368638977;24362.634560481252;24432.633510497002;24456.26631201067;24813.131540396;24893.502129957662 17:23:53 INFO - PID 1639 | |2;jquery.html;395.2450818935833;421.8101854165626;430.9504406993193;440.3065172353543;450.76560188701876;19912.50218744532;21132.260370887;21745.782542174573;22003.33989980303;22031.228906988257;21424.678629820566;21864.720291956215;21866.562668746614;21990.230341938037;22047.22834700786;4941.357623508939;6125.938740612582;6135.601185922923;6138.447539721425;6155.507559395238;4783.330333753277;5871.442212989759;5879.0593505039205;5942.138389933457;6017.458428741413;5019.895622962942;5823.9708801456145;5882.176495290662;5900.734466948976;5904;5665.2351932489;5883.970580147115;5931.91102133464;5943.138244954501;6043.425874541934;5103.719295438749;5265.262863199156;5271.973640131775;5279.86800329991;5284.365876094893;24893.004279828787;25495.745042549523;25612.84742186606;25980.610290845652;26154.215373538824;1998.5610360540388;2074.118499637648;2196.8132708719777;2232.5423288225875;2234.3520379090046;17586.944783313043;17705.29178832845;17893.463196104138;18056.368027118988;18089.819101809077;2091.8117369436823;2153.7846215378336;2177.205320058183;2179.0412218623756;2179.063002908749;149197;150064;150959;152289;154354.96692714936;9703.708888733348;12228.571999979958;12332.50669973209;12342.074344424203;12365.75268494625;15816.841831581767;15869.12719800422;15876.60308492297;15882;15914.0451572906;8705.956470217609;8878.689245876363;8951.507667078244;9007.23438507721;9082.772930676785;7296.7081316746735;8381.119982401882;8510.78723031929;8681.088485709037;8706.259967902672;7538.283863033003;7587.203343648837;7669.769906902802;7758.689652413953;7763.4953728007495;2143.7137717369765;2147.162606583434;2198.4613769626544;2207.3377986603955;2212.822974162063;807.6688557691318;969.185883857563;969.888462826784;971.4705485510414;979.1579241852002;15280.38878444872;18580.535486612673;20167.394978150678;20222.29221977253;20731.27440539604;1011.3729487717515;1036.8392899100652;1044.613493007592;1054.7995880782628;1056.6407421476736;8320.084790673021;9501.669766232733;10107.393556386492;10479.790404191874;10555.114552598443;471583;518767;551246;565312;567301;124910.37544812218;131460;133789;139497.9075313871;139888.30055849656;1214.690253985228;1241.900647948162;1263.5577547858177;1264.5700461843016;1271.5676669932081;20360.59278814445;21406.357809265748;21459.46351341229;21536.138554457648;21847.453813654785;46448.30327545157;58609.70695146497;59183;59486.10770838527;62239.06641400292;65316.67341663261;83822.58088709517;84683.5765821167;84819.5759021201;85505.57247213724;7530.43521735861;8769.693060742844;8824.602892869709;9001.459912405277;9171.633134674805;13528.323583820573;14089.365978530786;14148.929255353658;14184.148951062969;14426.711465770415;11890.28658280506;11987.880121198676;12042.63872083839;12146.817797732865;12167.695807604881;10425;10980.670579882617;11044.950729680668;11225.494852731483;11346.489407976826;11855.822162667395;12009.879901200877;12220;12222.511099555919;12257.26456412618;7572.81067973283;8646.740597782076;8836.248918841971;8933.46399216049;8954.552272386485;11766.411679416165;12004.279743215435;12113.394330283627;12153.270803751802;12268.693282668004 17:23:53 INFO - PID 1639 | |3;mootools.html;1420.1692010174047;1470.176701047413;1471.6835880285737;1491.164947629327;1492.9104253744772;11390.08879289657;11836.230645008069;11928.165028448024;11939.164258501909;12226.877731222685;11647.708807279821;11810.881891181085;11838.585649502269;11872.465739041741;11885.76228475432;2479.47930934504;2529.620556916459;2530.671012768347;2533.8226324157317;2537.961930571043;2456.828022038458;2489.8381605195696;2495.7629025242572;2503.4492411669494;2508.3729067733066;2472.4807790363957;2480.1567467061195;2517.4335774450783;2522.7225005249406;2523.4826860493554;2473.1591258971944;2473.282748003077;2478.21936090131;2484.689413823272;2492.8753562321813;2466.987665061681;2475.6781618389496;2483.192962287246;2489.253224032801;2491.7134529529153;11170.218084734071;11269.549218031269;11288.983991440728;11302.208845380826;11329.546818127264;6480.935190648128;6674.699638516278;6733.158355205599;6741.528093033489;6743.763968261087;3923.9607603923882;4235.237657221699;4330.696851220416;4386.078923426085;4388.692791504596;1151.8272259161108;1269.9809502857465;1315.7039666075152;1316.605018494448;1318.9274589897552;16317.59206019847;16371.672566548603;16524.339026438927;16715.66568668632;16969.236384362855;5450.5367043800925;5568.610197286151;5728.255326807572;5754.136879468029;5771.769129234784;11952.940235298942;11990;11992.820107698391;12005.759884802255;12034;5446.400895901447;6623.86752264952;6624.138861948012;6626.966865165643;6631.436327912085;5935.584509084322;6214.937850621526;6235.314115447297;6272.780452684134;6310.368963103653;4250.766207858535;4366.497852746911;4387.956120438818;4411.382406463098;4426.955730442719;1095.6055819904827;1257.4781465691635;1271.3706715175863;1291.7416516696699;1295.112847699329;620.7516993202755;691.239636399956;699.8075529229502;759.6695437484601;803.6383627367708;5035.899282014412;6113.724882380304;6135.601185922901;6311.810645680637;6334.3665633436285;590.0853676800941;592.1502643706295;601.1313651773146;606.6754286456725;620.1999420747276;3571.8571257149424;4358.978205108954;4383;4433.334999750064;4480.887977800581;13986.930065349812;14180.85819141816;14608.780868286984;15129.167895765619;15368.231588420535;14586.708265834624;15132.243387830784;15137.394504219928;15145.242737863064;15250.313735881911;7535.208803075708;8487.533185674847;8636.0500344962;8732.912670873337;8742;5803.970980145073;6192.566520343533;6734.494912881455;6874.9656251718425;6978.755743548952;9756.707298781048;12673.873261267268;12740.554080607135;12984;13190;12958.416871240628;13029.413676384775;13243.668908276984;13296.473670002668;13483.797743034058;9041.81916361656;9296.535173241176;9549.188318992685;9597.9040209597;9601.855972160563;10431.843522347323;10639.8936010642;10827.621033263798;10929.836052459379;10948.124150067817;9142.2116254543;9270.814583708423;9288.674896378594;9379.296552758708;9455.243580513405;8964.177171042642;9102.726918192464;9129.178373946377;9144.679936202201;9163.404378715362;8287.212714792086;8383.622736976973;8404.747857564284;8412.242898139197;8437.451565648213;5362.973185134049;5434.456554344534;5724.3703192647945;6217.595856269509;6231.470325022424;5593.552515798645;5727.570432217517;5742.827715168552;6303.716332765129;6451.483881289391 17:23:53 INFO - PID 1639 | |4;prototype.html;329.89113592514474;368.6074330837658;398.479983621374;412.0420023445488;412.1283485428318;6052.757889684407;6260.718267677954;6274.341194174609;6328.841778955522;6333.36666333367;4836.685615434994;6097.41467218523;6172.259328880529;6173.814785556429;6186.7525298987985;3887.961120388788;4727.763611819395;4733.881652958669;4748.6913350632185;4770.236762118062;4317.287647538153;4649.58153766161;4679.953200468003;4700.965787526756;4758.857234282977;4666.556677115668;4694.225452800284;4713.6936099153445;4717.811287548494;4720.371471842209;4048.9797551012352;4371.519132895411;4442.667199840035;4485.999999999984;4707.835225767081;4110.362893751473;4122.319817230154;4157.459530261077;4181.163767246532;4221.24017676818;5883.440703294081;5948.613340132878;6028.156058151819;6030.849228769272;6038.547108966801;5545.805896793633;5626.606137570372;5642.661440313554;5665.490105890449;5673.8297851064535;5091.9236211456855;6602.801915942529;6648.9667551662415;6671.466282697373;6671.833204169886;4200.85297014603;4383.49589797171;4796.184648609745;4799.880002999918;4841.636877234221;6784.7964561063245;7027.262137475543;7042.859142817115;7052.153741550996;7053.6722881448995;4915.262710593368;4923.212286034289;4924.8030078797165;4928.507149285043;4967.453580106186;4180.352045432963;4926.950730492721;5010.974945125251;5031;5057.841002177832;5542.806001789918;5607.43925607436;5621.297337832771;5642;5655.462731040545;5147.639665223398;5672.574556908248;5986.2217911671205;6275.184226050585;6597.538172327987;1481.1483397046525;1674.6566953774447;1696.423216106505;1697.8811483196305;1699.6940550700992;1055.9366438013744;1150.1201580790535;1184.141497414385;1189.9643010709692;1191.7594921547436;6381.4575761059905;6383.680815959184;6385.2656944451055;6391.744330226739;6396.456301214356;5793.681347525842;6451.774187903401;6504.552587128829;6774.45804335652;6885.794985877452;6239.532035097386;6341.4292713656;6353.841153971188;6379.585326953733;6406;3373.7807042542645;3557.5553055868018;3606;3614.7288953328607;3653.6529029742132;6399.8080057598345;7036.788896333118;7072.504924655224;7103.147622285255;7104.21853596104;5551.167324901297;6182.536309776695;6228.937710622927;6616.933830661632;7080.964595176991;5663.32040155184;6775.62734049632;6975.476839237078;7017.8771396575285;7125;5799.014167591604;6844.828879278058;6931.896021559782;6945.409640180641;6981.685824138028;6118.663473509;6673.965535342029;6812.206995131972;6827.317268273133;6839.774537066918;6907.6891539879825;7048.753293634698;7154.177269613853;7170.924361345631;7176.282371762782;4258.6593072553505;4593.530070377447;4868.41579010521;4881.8535443936735;4918.458969513422;6052.546059045501;6409.397650587353;6741.22475915276;6830.487713421414;6962.930370696228;5834.533237340917;6391.242408337745;6579.374959378852;6596.921969579567;6634.767783127568;5991;6195.663035874995;6676.9666151668935;6686.866262674816;6729.629870357177;6208.782692605737;6471.676416179078;6506.4469520091325;6524.510661700297;6537.673116344259;6147.3280306966135;6668.3331666834865;6705.362990515893;6710.63091529951;6714;6322.873542529215;6426.935730642634;6448.419642232222;6516.478681705547;6527.706253218712 17:23:53 INFO - PID 1639 | |5;yui.html;823.7075838077486;835.3734698975765;846.4709556527171;856.9502359609479;864.844328020956;15382.846171538282;16425.260863261152;17114.400995965127;17171.227294771736;18566.72149917752;16239.025658460501;16287.83712162878;16308.510744677678;17133.22900469479;18487.815121848813;774.6436639146;982.9286078174788;996;1018.5060245780791;1022.1260821997197;849.392684230778;996.6262651505685;998.6767533018743;1010.7725761703595;1011.8381059030556;869.9347548933855;907.5280853955938;1071.201954543866;1071.8874518175596;1072.6460268111505;1055.7888422315568;1072.2654981337769;1073.6564299424188;1077.3589714120087;1081.7511972246427;789.7551758954743;793.2186796005988;793.9920600794034;795.6300320351064;797.9122296547375;13339.39972701221;17048.744268835977;17588.736168957475;17727.822721772747;17794.46616601504;16931.83068169327;17128.657426851394;17455.65088698219;17564.38524651631;17651.558711032198;13627;13934.930325348309;13950.162990220619;13953.790693139612;14018.509352172625;3531.346700860349;3591.515145455356;3605.2248766515327;3634.1641422472717;3635.163912300186;121683;124458;124907;126251.36874315569;127154;13387.598372048855;13517.256550889599;13744.65638359029;13771.173729576058;13791.51729689476;19341.80658193428;25182.370440738763;26300;26418.47163056728;26419.471610568067;8004.159563245892;8012.238837310446;8013.7996550085545;8051.879221811678;8068.0721717002125;3835.424686297022;3845.7115716321387;3900.570937196906;3921.3333733265413;3969.384745364473;4232.873013809591;4325.2214601371425;4329;4333.306670932699;4340.00179958605;882.2368651116738;955.1499165742356;955.5461155950868;963.6290028339;975.2636759246865;636.9522285828581;651.8044586623994;654.2443477783168;670.356457800517;678.934073504593;11395.829062564067;11910.166288359906;12097.80952618824;12112.21270617407;12136.514539418324;646.1374065622352;664.9401553860176;665.9833504162435;686.0326939015963;687.5805758487319;5374.731263436813;5548.55611551075;5669.886602267931;5802.303723553201;5991.910121348184;103152.41501968465;106725;107859.46070269756;109269.90730092756;112735;87021.56489217513;87027;89024;89124.10875891158;94456;70344;82679;83425.74861377206;84049;84938.57530712307;10381.221408394249;12884.935575322439;13036.087473877116;13063.346832658139;13109.60671179866;28966.855165724035;37302.8134859324;37888.40060194277;39897.80051099842;40572.59427405688;47473.52526474691;49371.531423429406;53133.734331328094;53839.923201536534;54836.725816370665;15857.762133567774;16076.598085047968;16274.755878661592;16286.51140465788;16287;15455.84544154544;17374.39189628357;17516.562085947444;17863.642727145645;18364.17361218775;13705.83500402477;15952.441664541686;16326.918365408097;16623.25195366187;16821.663566728843;13536.661583460493;16246.512604621881;16695.415660451825;17266.22301996388;17562.82437175612;14026.29868506591;15813.762793557875;16980.896241744245;17038.659226815642;17090.145492725067;11772.411379430823;11826;12038.518459261531;12154.331511766937;12212.720337081382;13006.674833128869;13368.398422070835;13421.32893355348;13448.529301474402;13452.596422107352 17:23:53 INFO - PID 1639 | __end_tp_report 17:23:53 INFO - PID 1639 | __start_cc_report 17:23:53 INFO - PID 1639 | _x_x_mozilla_cycle_collect,419 17:23:53 INFO - PID 1639 | __end_cc_report 17:23:53 INFO - PID 1639 | __startTimestamp1493425433861__endTimestamp 17:23:53 INFO - PID 1639 | 17:23:53 INFO - PID 1639 | ------- Summary: start ------- 17:23:53 INFO - PID 1639 | Number of tests: 6 17:23:53 INFO - PID 1639 | 17:23:53 INFO - PID 1639 | [#0] dojo.html Cycles:165 Average:6141.28 Median:6363.90 stddev:770.36 (12.1%) stddev-sans-first:729.19 17:23:53 INFO - PID 1639 | Values: 2886.7 3157.0 3628.8 3639.1 3657.0 4997.4 5680.4 6203.2 6292.5 6349.9 6206.3 6211.7 6219.4 6258.4 6321.4 5390.5 5458.5 5481.9 5487.7 5549.3 5019.6 5595.5 5612.7 5628.9 5640.4 5005.2 5446.6 5449.9 5513.5 5591.6 5481.2 5491.9 5513.1 5545.0 5553.3 4364.8 5296.6 5432.7 5443.3 5488.6 6074.1 6120.2 6260.3 6265.6 6274.5 6495.2 6628.5 6695.0 6696.5 6721.5 5677.0 6765.4 6777.0 6778.1 6784.2 6243.2 6309.7 6324.4 6331.4 6344.1 6218.7 6635.9 6705.8 6920.0 6948.0 5657.3 6150.8 6450.4 6513.2 6582.3 6716.5 6808.8 6816.2 6819.3 6862.9 6726.1 6730.6 6739.7 6745.0 6755.2 6460.2 6557.5 6610.3 6648.6 6711.0 5142.6 5379.3 5379.6 5388.6 5411.8 4738.7 4751.4 4752.4 4777.0 4801.7 6134.0 6311.3 6316.7 6397.3 6407.6 6791.5 6791.9 6841.0 6846.1 6846.8 5325.7 6334.2 6363.9 6373.9 6395.6 5791.8 5823.9 5824.2 5824.2 5909.2 6826.5 6932.4 6997.4 7007.3 7046.9 6845.1 6853.2 6865.5 6884.4 6887.7 5971.8 6507.5 6908.0 6984.0 7017.5 6472.4 6527.3 6611.4 6677.0 6691.5 6787.4 6818.7 6824.2 6825.0 6830.0 6378.5 6448.6 6514.4 6544.2 6573.5 5933.8 6421.0 6601.6 6606.9 6614.9 6252.5 6331.1 6332.4 6340.1 6343.3 5379.5 6552.9 6589.6 6646.6 6650.5 6437.6 6438.9 6445.3 6453.5 6457.4 17:23:53 INFO - PID 1639 | 17:23:53 INFO - PID 1639 | [#1] ext.html Cycles:180 Average:44993.09 Median:325824.50 stddev:80031.93 (24.6%) stddev-sans-first:80187.34 17:23:53 INFO - PID 1639 | Values: 699.4 737.6 1091.3 1127.8 1129.2 36259.8 37313.3 37328.6 37344.6 37350.3 37184.3 37519.1 37523.5 37525.0 37549.4 7806.3 7876.8 8274.1 8512.1 8523.6 8505.7 8622.9 8626.0 8648.0 8659.3 8585.6 8621.7 8638.3 8654.5 8661.8 8524.5 8566.0 8612.1 8632.7 8651.4 8308.8 8330.2 8395.7 8418.1 8423.7 37280.8 37282.6 37423.6 37470.4 37482.0 3566.9 3570.8 3600.2 3603.3 3607.3 30702.7 30803.4 30817.0 30830.4 31020.0 3135.1 3142.8 3155.4 3170.6 3171.9 295036.0 295048.6 295792.0 296173.0 296320.0 35826.0 37857.8 39011.6 47827.8 48826.0 42906.0 46547.0 46649.8 50808.7 50875.7 12813.6 12883.5 14221.9 14323.7 14480.9 10394.2 11062.7 11520.1 11669.5 11822.6 12311.3 13019.0 13430.7 13438.7 13595.8 4544.1 5174.6 5258.8 5310.1 5318.3 980.3 1016.6 1028.2 1032.8 1062.7 19354.9 22266.9 24064.6 24077.8 24156.8 1127.9 1130.2 1163.1 1164.5 1168.1 9706.5 12257.8 12274.5 12310.6 12357.0 322180.0 323820.4 325673.0 325976.0 326583.4 53084.2 53560.0 53742.5 53809.7 54944.0 1474.6 1507.0 1540.4 1542.5 1544.7 19458.8 21948.3 23948.0 23981.5 24005.5 38020.2 38074.4 38082.2 38096.0 38232.4 287801.0 292127.0 293432.0 293890.0 299091.0 29016.9 29403.9 33492.8 33726.7 34411.3 41555.0 41988.8 43308.8 43439.8 43603.1 30053.5 30349.1 30499.0 30679.0 30785.5 28754.1 29936.9 30343.8 30679.8 30895.7 36059.8 36278.8 36448.8 36541.3 36565.5 29140.4 31101.5 31581.4 31761.8 31821.0 24362.6 24432.6 24456.3 24813.1 24893.5 17:23:53 INFO - PID 1639 | 17:23:53 INFO - PID 1639 | [#2] jquery.html Cycles:180 Average:35129.40 Median:22017.28 stddev:91269.62 (414.5%) stddev-sans-first:91488.40 17:23:53 INFO - PID 1639 | Values: 395.2 421.8 431.0 440.3 450.8 19912.5 21132.3 21745.8 22003.3 22031.2 21424.7 21864.7 21866.6 21990.2 22047.2 4941.4 6125.9 6135.6 6138.4 6155.5 4783.3 5871.4 5879.1 5942.1 6017.5 5019.9 5824.0 5882.2 5900.7 5904.0 5665.2 5884.0 5931.9 5943.1 6043.4 5103.7 5265.3 5272.0 5279.9 5284.4 24893.0 25495.7 25612.8 25980.6 26154.2 1998.6 2074.1 2196.8 2232.5 2234.4 17586.9 17705.3 17893.5 18056.4 18089.8 2091.8 2153.8 2177.2 2179.0 2179.1 149197.0 150064.0 150959.0 152289.0 154355.0 9703.7 12228.6 12332.5 12342.1 12365.8 15816.8 15869.1 15876.6 15882.0 15914.0 8706.0 8878.7 8951.5 9007.2 9082.8 7296.7 8381.1 8510.8 8681.1 8706.3 7538.3 7587.2 7669.8 7758.7 7763.5 2143.7 2147.2 2198.5 2207.3 2212.8 807.7 969.2 969.9 971.5 979.2 15280.4 18580.5 20167.4 20222.3 20731.3 1011.4 1036.8 1044.6 1054.8 1056.6 8320.1 9501.7 10107.4 10479.8 10555.1 471583.0 518767.0 551246.0 565312.0 567301.0 124910.4 131460.0 133789.0 139497.9 139888.3 1214.7 1241.9 1263.6 1264.6 1271.6 20360.6 21406.4 21459.5 21536.1 21847.5 46448.3 58609.7 59183.0 59486.1 62239.1 65316.7 83822.6 84683.6 84819.6 85505.6 7530.4 8769.7 8824.6 9001.5 9171.6 13528.3 14089.4 14148.9 14184.1 14426.7 11890.3 11987.9 12042.6 12146.8 12167.7 10425.0 10980.7 11045.0 11225.5 11346.5 11855.8 12009.9 12220.0 12222.5 12257.3 7572.8 8646.7 8836.2 8933.5 8954.6 11766.4 12004.3 12113.4 12153.3 12268.7 17:23:53 INFO - PID 1639 | 17:23:53 INFO - PID 1639 | [#3] mootools.html Cycles:180 Average:7053.20 Median:4079.60 stddev:4471.19 (109.6%) stddev-sans-first:4463.70 17:23:53 INFO - PID 1639 | Values: 1420.2 1470.2 1471.7 1491.2 1492.9 11390.1 11836.2 11928.2 11939.2 12226.9 11647.7 11810.9 11838.6 11872.5 11885.8 2479.5 2529.6 2530.7 2533.8 2538.0 2456.8 2489.8 2495.8 2503.4 2508.4 2472.5 2480.2 2517.4 2522.7 2523.5 2473.2 2473.3 2478.2 2484.7 2492.9 2467.0 2475.7 2483.2 2489.3 2491.7 11170.2 11269.5 11289.0 11302.2 11329.5 6480.9 6674.7 6733.2 6741.5 6743.8 3924.0 4235.2 4330.7 4386.1 4388.7 1151.8 1270.0 1315.7 1316.6 1318.9 16317.6 16371.7 16524.3 16715.7 16969.2 5450.5 5568.6 5728.3 5754.1 5771.8 11952.9 11990.0 11992.8 12005.8 12034.0 5446.4 6623.9 6624.1 6627.0 6631.4 5935.6 6214.9 6235.3 6272.8 6310.4 4250.8 4366.5 4388.0 4411.4 4427.0 1095.6 1257.5 1271.4 1291.7 1295.1 620.8 691.2 699.8 759.7 803.6 5035.9 6113.7 6135.6 6311.8 6334.4 590.1 592.2 601.1 606.7 620.2 3571.9 4359.0 4383.0 4433.3 4480.9 13986.9 14180.9 14608.8 15129.2 15368.2 14586.7 15132.2 15137.4 15145.2 15250.3 7535.2 8487.5 8636.1 8732.9 8742.0 5804.0 6192.6 6734.5 6875.0 6978.8 9756.7 12673.9 12740.6 12984.0 13190.0 12958.4 13029.4 13243.7 13296.5 13483.8 9041.8 9296.5 9549.2 9597.9 9601.9 10431.8 10639.9 10827.6 10929.8 10948.1 9142.2 9270.8 9288.7 9379.3 9455.2 8964.2 9102.7 9129.2 9144.7 9163.4 8287.2 8383.6 8404.7 8412.2 8437.5 5363.0 5434.5 5724.4 6217.6 6231.5 5593.6 5727.6 5742.8 6303.7 6451.5 17:23:53 INFO - PID 1639 | 17:23:53 INFO - PID 1639 | [#4] prototype.html Cycles:180 Average:5431.55 Median:6045.55 stddev:1635.66 (27.1%) stddev-sans-first:1594.79 17:23:53 INFO - PID 1639 | Values: 329.9 368.6 398.5 412.0 412.1 6052.8 6260.7 6274.3 6328.8 6333.4 4836.7 6097.4 6172.3 6173.8 6186.8 3888.0 4727.8 4733.9 4748.7 4770.2 4317.3 4649.6 4680.0 4701.0 4758.9 4666.6 4694.2 4713.7 4717.8 4720.4 4049.0 4371.5 4442.7 4486.0 4707.8 4110.4 4122.3 4157.5 4181.2 4221.2 5883.4 5948.6 6028.2 6030.8 6038.5 5545.8 5626.6 5642.7 5665.5 5673.8 5091.9 6602.8 6649.0 6671.5 6671.8 4200.9 4383.5 4796.2 4799.9 4841.6 6784.8 7027.3 7042.9 7052.2 7053.7 4915.3 4923.2 4924.8 4928.5 4967.5 4180.4 4927.0 5011.0 5031.0 5057.8 5542.8 5607.4 5621.3 5642.0 5655.5 5147.6 5672.6 5986.2 6275.2 6597.5 1481.1 1674.7 1696.4 1697.9 1699.7 1055.9 1150.1 1184.1 1190.0 1191.8 6381.5 6383.7 6385.3 6391.7 6396.5 5793.7 6451.8 6504.6 6774.5 6885.8 6239.5 6341.4 6353.8 6379.6 6406.0 3373.8 3557.6 3606.0 3614.7 3653.7 6399.8 7036.8 7072.5 7103.1 7104.2 5551.2 6182.5 6228.9 6616.9 7081.0 5663.3 6775.6 6975.5 7017.9 7125.0 5799.0 6844.8 6931.9 6945.4 6981.7 6118.7 6674.0 6812.2 6827.3 6839.8 6907.7 7048.8 7154.2 7170.9 7176.3 4258.7 4593.5 4868.4 4881.9 4918.5 6052.5 6409.4 6741.2 6830.5 6962.9 5834.5 6391.2 6579.4 6596.9 6634.8 5991.0 6195.7 6677.0 6686.9 6729.6 6208.8 6471.7 6506.4 6524.5 6537.7 6147.3 6668.3 6705.4 6710.6 6714.0 6322.9 6426.9 6448.4 6516.5 6527.7 17:23:53 INFO - PID 1639 | 17:23:53 INFO - PID 1639 | [#5] yui.html Cycles:180 Average:21585.03 Median:17689.69 stddev:30553.84 (172.7%) stddev-sans-first:30599.78 17:23:53 INFO - PID 1639 | Values: 823.7 835.4 846.5 857.0 864.8 15382.8 16425.3 17114.4 17171.2 18566.7 16239.0 16287.8 16308.5 17133.2 18487.8 774.6 982.9 996.0 1018.5 1022.1 849.4 996.6 998.7 1010.8 1011.8 869.9 907.5 1071.2 1071.9 1072.6 1055.8 1072.3 1073.7 1077.4 1081.8 789.8 793.2 794.0 795.6 797.9 13339.4 17048.7 17588.7 17727.8 17794.5 16931.8 17128.7 17455.7 17564.4 17651.6 13627.0 13934.9 13950.2 13953.8 14018.5 3531.3 3591.5 3605.2 3634.2 3635.2 121683.0 124458.0 124907.0 126251.4 127154.0 13387.6 13517.3 13744.7 13771.2 13791.5 19341.8 25182.4 26300.0 26418.5 26419.5 8004.2 8012.2 8013.8 8051.9 8068.1 3835.4 3845.7 3900.6 3921.3 3969.4 4232.9 4325.2 4329.0 4333.3 4340.0 882.2 955.1 955.5 963.6 975.3 637.0 651.8 654.2 670.4 678.9 11395.8 11910.2 12097.8 12112.2 12136.5 646.1 664.9 666.0 686.0 687.6 5374.7 5548.6 5669.9 5802.3 5991.9 103152.4 106725.0 107859.5 109269.9 112735.0 87021.6 87027.0 89024.0 89124.1 94456.0 70344.0 82679.0 83425.7 84049.0 84938.6 10381.2 12884.9 13036.1 13063.3 13109.6 28966.9 37302.8 37888.4 39897.8 40572.6 47473.5 49371.5 53133.7 53839.9 54836.7 15857.8 16076.6 16274.8 16286.5 16287.0 15455.8 17374.4 17516.6 17863.6 18364.2 13705.8 15952.4 16326.9 16623.3 16821.7 13536.7 16246.5 16695.4 17266.2 17562.8 14026.3 15813.8 16980.9 17038.7 17090.1 11772.4 11826.0 12038.5 12154.3 12212.7 13006.7 13368.4 13421.3 13448.5 13452.6 17:23:53 INFO - PID 1639 | -------- Summary: end -------- 17:23:53 INFO - PID 1639 | 17:23:54 INFO - TEST-INFO | 1639: exit 0 17:23:54 INFO - TEST-OK | dromaeo_css | took 1115340ms 17:23:54 INFO - TEST-START | kraken 17:23:54 INFO - operating with platform_type : mac_ 17:23:54 INFO - Initialising browser for kraken test... 17:23:54 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpc3bjo_/profile http://localhost:49229/getInfo.html) 17:23:56 INFO - PID 1651 | 2017-04-28 17:23:56.859 plugin-container[1652:10573] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 17:23:56 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:23:56 INFO - PID 1651 | 2017-04-28 17:23:56.862 plugin-container[1652:10573] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 17:23:56 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:23:56 INFO - PID 1651 | 2017-04-28 17:23:56.862 plugin-container[1652:10573] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:23:57 INFO - PID 1651 | __metrics Screen width/height:1600/1200 17:23:57 INFO - PID 1651 | colorDepth:24 17:23:57 INFO - PID 1651 | Browser inner width/height: 1024/689 17:23:57 INFO - PID 1651 | __metrics 17:23:57 ERROR - PID 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:23:58 INFO - TEST-INFO | 1651: exit 0 17:23:58 INFO - Browser initialized. 17:23:58 INFO - Running cycle 1/1 for kraken test... 17:23:58 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpc3bjo_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:24:00 INFO - PID 1653 | 2017-04-28 17:24:00.520 plugin-container[1654:10697] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 17:24:00 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:24:00 INFO - PID 1653 | 2017-04-28 17:24:00.521 plugin-container[1654:10697] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980f, name = 'com.apple.CFPasteboardClient' 17:24:00 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:24:00 INFO - PID 1653 | 2017-04-28 17:24:00.521 plugin-container[1654:10697] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:24:10 INFO - PID 1653 | RSS: Main: 251629568 17:24:10 INFO - PID 1653 | 17:24:13 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 17:24:14 INFO - PID 1653 | RSS: Main: 259563520 17:24:14 INFO - PID 1653 | 17:24:17 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 17:24:17 INFO - PID 1653 | RSS: Main: 266305536 17:24:17 INFO - PID 1653 | 17:24:21 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 17:24:21 INFO - PID 1653 | RSS: Main: 262688768 17:24:21 INFO - PID 1653 | 17:24:23 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 17:24:24 INFO - PID 1653 | RSS: Main: 264663040 17:24:24 INFO - PID 1653 | 17:24:26 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:24:26 INFO - PID 1653 | RSS: Main: 265433088 17:24:26 INFO - PID 1653 | 17:24:29 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 17:24:30 INFO - PID 1653 | RSS: Main: 270692352 17:24:30 INFO - PID 1653 | 17:24:33 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 17:24:34 INFO - PID 1653 | RSS: Main: 271818752 17:24:34 INFO - PID 1653 | 17:24:37 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 17:24:37 INFO - PID 1653 | RSS: Main: 271618048 17:24:37 INFO - PID 1653 | 17:24:39 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:24:39 INFO - PID 1653 | RSS: Main: 270942208 17:24:39 INFO - PID 1653 | 17:24:41 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:24:42 INFO - PID 1653 | RSS: Main: 271044608 17:24:42 INFO - PID 1653 | 17:24:44 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:24:44 INFO - PID 1653 | RSS: Main: 270544896 17:24:44 INFO - PID 1653 | 17:24:47 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:24:48 INFO - PID 1653 | RSS: Main: 270893056 17:24:48 INFO - PID 1653 | 17:24:51 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:24:52 INFO - PID 1653 | RSS: Main: 270970880 17:24:52 INFO - PID 1653 | 17:24:53 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 17:24:54 INFO - PID 1653 | RSS: Main: 272384000 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | __start_tp_report 17:24:54 INFO - PID 1653 | _x_x_mozilla_page_load 17:24:54 INFO - PID 1653 | _x_x_mozilla_page_load_details 17:24:54 INFO - PID 1653 | |i|pagename|runs| 17:24:54 INFO - PID 1653 | |0;ai-astar;101;117;116;116;121;144;115;115;114;115 17:24:54 INFO - PID 1653 | |1;audio-beat-detection;160;141;148;136;143;147;147;136;138;110 17:24:54 INFO - PID 1653 | |2;audio-dft;196;226;274;217;211;197;200;201;202;194 17:24:54 INFO - PID 1653 | |3;audio-fft;80;59;60;80;57;61;72;82;90;83 17:24:54 INFO - PID 1653 | |4;audio-oscillator;88;69;83;72;68;67;85;70;71;104 17:24:54 INFO - PID 1653 | |5;imaging-gaussian-blur;91;91;90;110;91;90;91;112;90;133 17:24:54 INFO - PID 1653 | |6;imaging-darkroom;97;98;96;97;105;97;97;97;96;107 17:24:54 INFO - PID 1653 | |7;imaging-desaturate;90;92;111;149;89;94;92;90;92;91 17:24:54 INFO - PID 1653 | |8;json-parse-financial;50;51;49;51;51;51;50;52;52;49 17:24:54 INFO - PID 1653 | |9;json-stringify-tinderbox;57;59;62;61;64;57;56;59;57;61 17:24:54 INFO - PID 1653 | |10;stanford-crypto-aes;81;81;84;84;83;82;84;82;85;86 17:24:54 INFO - PID 1653 | |11;stanford-crypto-ccm;133;151;162;155;130;135;136;134;185;143 17:24:54 INFO - PID 1653 | |12;stanford-crypto-pbkdf2;184;176;185;184;184;180;182;183;193;187 17:24:54 INFO - PID 1653 | |13;stanford-crypto-sha256-iterative;66;72;65;69;70;68;69;71;75;93 17:24:54 INFO - PID 1653 | __end_tp_report 17:24:54 INFO - PID 1653 | __start_cc_report 17:24:54 INFO - PID 1653 | _x_x_mozilla_cycle_collect,1007 17:24:54 INFO - PID 1653 | __end_cc_report 17:24:54 INFO - PID 1653 | __startTimestamp1493425494184__endTimestamp 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | ------- Summary: start ------- 17:24:54 INFO - PID 1653 | Number of tests: 14 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | [#0] ai-astar Cycles:10 Average:117.40 Median:116.00 stddev:10.66 (9.2%) stddev-sans-first:9.51 17:24:54 INFO - PID 1653 | Values: 101.0 117.0 116.0 116.0 121.0 144.0 115.0 115.0 114.0 115.0 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:145.00 stddev:12.93 (8.9%) stddev-sans-first:11.65 17:24:54 INFO - PID 1653 | Values: 160.0 141.0 148.0 136.0 143.0 147.0 147.0 136.0 138.0 110.0 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | [#2] audio-dft Cycles:10 Average:211.80 Median:206.50 stddev:24.12 (11.7%) stddev-sans-first:24.90 17:24:54 INFO - PID 1653 | Values: 196.0 226.0 274.0 217.0 211.0 197.0 200.0 201.0 202.0 194.0 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | [#3] audio-fft Cycles:10 Average:72.40 Median:80.00 stddev:12.16 (15.2%) stddev-sans-first:12.58 17:24:54 INFO - PID 1653 | Values: 80.0 59.0 60.0 80.0 57.0 61.0 72.0 82.0 90.0 83.0 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:71.50 stddev:12.02 (16.8%) stddev-sans-first:12.16 17:24:54 INFO - PID 1653 | Values: 88.0 69.0 83.0 72.0 68.0 67.0 85.0 70.0 71.0 104.0 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:98.90 Median:90.50 stddev:14.70 (16.2%) stddev-sans-first:15.31 17:24:54 INFO - PID 1653 | Values: 91.0 91.0 90.0 110.0 91.0 90.0 91.0 112.0 90.0 133.0 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | [#6] imaging-darkroom Cycles:10 Average:98.70 Median:97.00 stddev:3.92 (4.0%) stddev-sans-first:4.11 17:24:54 INFO - PID 1653 | Values: 97.0 98.0 96.0 97.0 105.0 97.0 97.0 97.0 96.0 107.0 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | [#7] imaging-desaturate Cycles:10 Average:99.00 Median:91.50 stddev:18.68 (20.4%) stddev-sans-first:19.53 17:24:54 INFO - PID 1653 | Values: 90.0 92.0 111.0 149.0 89.0 94.0 92.0 90.0 92.0 91.0 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:1.07 (2.1%) stddev-sans-first:1.12 17:24:54 INFO - PID 1653 | Values: 50.0 51.0 49.0 51.0 51.0 51.0 50.0 52.0 52.0 49.0 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:60.00 stddev:2.63 (4.4%) stddev-sans-first:2.65 17:24:54 INFO - PID 1653 | Values: 57.0 59.0 62.0 61.0 64.0 57.0 56.0 59.0 57.0 61.0 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:84.00 stddev:1.69 (2.0%) stddev-sans-first:1.59 17:24:54 INFO - PID 1653 | Values: 81.0 81.0 84.0 84.0 83.0 82.0 84.0 82.0 85.0 86.0 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:147.00 stddev:17.26 (11.7%) stddev-sans-first:17.61 17:24:54 INFO - PID 1653 | Values: 133.0 151.0 162.0 155.0 130.0 135.0 136.0 134.0 185.0 143.0 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.80 Median:184.00 stddev:4.42 (2.4%) stddev-sans-first:4.68 17:24:54 INFO - PID 1653 | Values: 184.0 176.0 185.0 184.0 184.0 180.0 182.0 183.0 193.0 187.0 17:24:54 INFO - PID 1653 | 17:24:54 INFO - PID 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:70.50 stddev:7.98 (11.3%) stddev-sans-first:8.19 17:24:54 INFO - PID 1653 | Values: 66.0 72.0 65.0 69.0 70.0 68.0 69.0 71.0 75.0 93.0 17:24:54 INFO - PID 1653 | -------- Summary: end -------- 17:24:54 INFO - PID 1653 | 17:24:55 INFO - TEST-INFO | 1653: exit 0 17:24:55 INFO - TEST-OK | kraken | took 60837ms 17:24:55 INFO - SUITE-END | took 1176s 17:24:55 INFO - Completed test suite (00:19:36) 17:24:55 INFO - javascript benchmark 17:24:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7857.979859100249, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2886.6968968258334, 3156.968430315697, 3628.8367023483956, 3639.10841843748, 3656.981715091424, 4997.4003119625695, 5680.431956804312, 6203.2245969253845, 6292.46514046306, 6349.873002539946, 6206.348333424993, 6211.689415529217, 6219.40915613016, 6258.436740693337, 6321.43107120359, 5390.460953904618, 5458.536024437927, 5481.917771233435, 5487.725613719308, 5549.278593782803, 5019.598432125421, 5595.54664530859, 5612.66324020558, 5628.9305032043985, 5640.351359593637, 5005.199168133081, 5446.618736688394, 5449.918251226235, 5513.5037846594, 5591.580631452637, 5481.205225242357, 5491.862703432406, 5513.09034009388, 5545.0, 5553.278073850414, 4364.825406983717, 5296.602754793406, 5432.674039557639, 5443.319585051869, 5488.588355873286, 6074.149619053336, 6120.233815636177, 6260.342664020258, 6265.624062556261, 6274.529410294246, 6495.155629768147, 6628.53600247978, 6695.029220762962, 6696.46428285736, 6721.529492935497, 5676.971615141939, 6765.357291057391, 6776.966115169393, 6778.118844550227, 6784.1858976922595, 6243.157173781572, 6309.716062777186, 6324.430801227865, 6331.398517140864, 6344.143540622049, 6218.689065546704, 6635.86728265432, 6705.765298214539, 6919.965400172967, 6948.0, 5657.264555607745, 6150.769846030812, 6450.41946224842, 6513.1532900722605, 6582.275949645631, 6716.496262780311, 6808.761693340709, 6816.216135144423, 6819.318068193141, 6862.9313706863295, 6726.091977583062, 6730.629815360105, 6739.663016849138, 6744.966275168593, 6755.22314933779, 6460.224773027173, 6557.508186885907, 6610.338966103352, 6648.634325112165, 6710.966445167841, 5142.6400151989, 5379.327584051994, 5379.596530260246, 5388.649737767033, 5411.83764487066, 4738.73936933465, 4751.382320298339, 4752.429708434941, 4777.020710754287, 4801.663883528189, 6133.969330153321, 6311.274203466568, 6316.652584107826, 6397.296297407282, 6407.583507072119, 6791.4906382021545, 6791.93208067913, 6840.9657951710915, 6846.110005699227, 6846.828829279209, 5325.733713314319, 6334.1765570475545, 6363.90454143179, 6373.936260637427, 6395.584287021329, 5791.768329266866, 5823.854403639943, 5824.155497452845, 5824.213731146326, 5909.231799866076, 6826.488013398915, 6932.410745086522, 6997.370236678723, 7007.264237255014, 7046.859062818612, 6845.14435695538, 6853.246142924175, 6865.485088618274, 6884.4148247399535, 6887.7244910203035, 5971.7611295547695, 6507.511936604679, 6907.974910839994, 6984.0, 7017.543859649107, 6472.35276472349, 6527.281998980204, 6611.438027767694, 6677.0, 6691.5315927884585, 6787.423069039187, 6818.727250909908, 6824.18109826824, 6825.010373495915, 6829.965850170916, 6378.489720822416, 6448.645324507197, 6514.446272066927, 6544.247411547733, 6573.5398522103, 5933.821985340447, 6421.036844473367, 6601.603903765789, 6606.933930660632, 6614.933850661624, 6252.468540174136, 6331.145295385064, 6332.366763323815, 6340.1440805490665, 6343.3022367538615, 5379.54273886724, 6552.868942621216, 6589.604623722592, 6646.634435105922, 6650.501212408992, 6437.613743175424, 6438.90341644885, 6445.291017988111, 6453.515986300952, 6457.418832305115], "value": 6088.395525490405, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.4055053204777, 737.6311844077961, 1091.3451928842696, 1127.830825376194, 1129.2490493821608, 36259.81870090649, 37313.25373492528, 37328.626713732854, 37344.62655373442, 37350.252994940085, 37184.25631487369, 37519.06202344943, 37523.49906003757, 37525.0, 37549.43675844857, 7806.258405451487, 7876.803079923019, 8274.1312162223, 8512.063672995997, 8523.616437260307, 8505.74482765515, 8622.913770862273, 8626.0, 8648.0, 8659.307255419582, 8585.613647385851, 8621.65513379464, 8638.265747411477, 8654.48073115615, 8661.783455413602, 8524.531150786705, 8565.957170214171, 8612.13878612137, 8632.697855575088, 8651.437656552303, 8308.833823323499, 8330.166983301682, 8395.70615028471, 8418.074011858691, 8423.747287581382, 37280.813595931846, 37282.627173728186, 37423.62576374229, 37470.43794343087, 37482.0, 3566.946495802565, 3570.80360580168, 3600.1899572596217, 3603.3333833240936, 3607.3326434609417, 30702.692973070207, 30803.383932321227, 30817.0, 30830.383392332253, 31020.0, 3135.106494649014, 3142.8271445070513, 3155.4004739099496, 3170.5719727836677, 3171.9365612687848, 295036.0, 295048.5742713839, 295791.99999999785, 296173.0, 296320.0, 35826.0, 37857.81071094627, 39011.609883901365, 47827.76086119547, 48826.0, 42906.0, 46547.0, 46649.7667511667, 50808.72978175575, 50875.74562127166, 12813.615591532083, 12883.48466061347, 14221.928890355688, 14323.713525729427, 14480.85519144795, 10394.22043346737, 11062.668119956414, 11520.135989800798, 11669.533218671328, 11822.645320640395, 12311.322877241837, 13018.958483321501, 13430.664233394244, 13438.731225375634, 13595.796063059062, 4544.113897789899, 5174.6119041071315, 5258.843054527998, 5310.123829568078, 5318.25544423781, 980.3480685344166, 1016.5730393234711, 1028.228828378716, 1032.7779527401644, 1062.7236918401272, 19354.903225483784, 22266.88866555657, 24064.639030414557, 24077.759222407552, 24156.7584324158, 1127.9154063445274, 1130.2032067392468, 1163.1276542593055, 1164.4759858063906, 1168.0772189969828, 9706.514674266258, 12257.816132758016, 12274.509019639294, 12310.569130080405, 12357.0, 322180.0, 323820.380898094, 325673.0, 325976.0, 326583.36708316783, 53084.20373694398, 53560.0, 53742.46257537375, 53809.730951344995, 54944.0, 1474.5871156076316, 1506.9623259418602, 1540.4377402248099, 1542.4755583101798, 1544.7451170556733, 19458.8054119457, 21948.34154975353, 23948.0, 23981.520369592858, 24005.51988960246, 38020.239595207386, 38074.42888356732, 38082.2383552333, 38096.0, 38232.426513601764, 287801.0, 292127.0, 293432.0, 293890.0, 299091.0, 29016.854915725286, 29403.852980735814, 33492.832535837166, 33726.66273337235, 34411.311773764886, 41555.0, 41988.790056049525, 43308.78345608378, 43439.78280108706, 43603.127937441706, 30053.54919676163, 30349.089527314216, 30499.0, 30679.0, 30785.538216927212, 28754.137375878756, 29936.85031574828, 30343.763224055605, 30679.846600766854, 30895.69104309018, 36059.81970090133, 36278.81860590786, 36448.81775591105, 36541.26917461582, 36565.45151822671, 29140.417191656474, 31101.53347699741, 31581.368372632878, 31761.841190793897, 31821.045368638977, 24362.634560481252, 24432.633510497002, 24456.26631201067, 24813.131540396, 24893.502129957662], "value": 17273.562416692563, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.2450818935833, 421.8101854165626, 430.9504406993193, 440.3065172353543, 450.76560188701876, 19912.50218744532, 21132.260370887, 21745.782542174573, 22003.33989980303, 22031.228906988257, 21424.678629820566, 21864.720291956215, 21866.562668746614, 21990.230341938037, 22047.22834700786, 4941.357623508939, 6125.938740612582, 6135.601185922923, 6138.447539721425, 6155.507559395238, 4783.330333753277, 5871.442212989759, 5879.0593505039205, 5942.138389933457, 6017.458428741413, 5019.895622962942, 5823.9708801456145, 5882.176495290662, 5900.734466948976, 5904.0, 5665.2351932489, 5883.970580147115, 5931.91102133464, 5943.138244954501, 6043.425874541934, 5103.719295438749, 5265.262863199156, 5271.973640131775, 5279.86800329991, 5284.365876094893, 24893.004279828787, 25495.745042549523, 25612.84742186606, 25980.610290845652, 26154.215373538824, 1998.5610360540388, 2074.118499637648, 2196.8132708719777, 2232.5423288225875, 2234.3520379090046, 17586.944783313043, 17705.29178832845, 17893.463196104138, 18056.368027118988, 18089.819101809077, 2091.8117369436823, 2153.7846215378336, 2177.205320058183, 2179.0412218623756, 2179.063002908749, 149197.0, 150064.0, 150959.0, 152289.0, 154354.96692714936, 9703.708888733348, 12228.571999979958, 12332.50669973209, 12342.074344424203, 12365.75268494625, 15816.841831581767, 15869.12719800422, 15876.60308492297, 15882.0, 15914.0451572906, 8705.956470217609, 8878.689245876363, 8951.507667078244, 9007.23438507721, 9082.772930676785, 7296.7081316746735, 8381.119982401882, 8510.78723031929, 8681.088485709037, 8706.259967902672, 7538.283863033003, 7587.203343648837, 7669.769906902802, 7758.689652413953, 7763.4953728007495, 2143.7137717369765, 2147.162606583434, 2198.4613769626544, 2207.3377986603955, 2212.822974162063, 807.6688557691318, 969.185883857563, 969.888462826784, 971.4705485510414, 979.1579241852002, 15280.38878444872, 18580.535486612673, 20167.394978150678, 20222.29221977253, 20731.27440539604, 1011.3729487717515, 1036.8392899100652, 1044.613493007592, 1054.7995880782628, 1056.6407421476736, 8320.084790673021, 9501.669766232733, 10107.393556386492, 10479.790404191874, 10555.114552598443, 471583.0, 518767.0, 551246.0, 565312.0, 567301.0, 124910.37544812218, 131460.0, 133789.0, 139497.9075313871, 139888.30055849656, 1214.690253985228, 1241.900647948162, 1263.5577547858177, 1264.5700461843016, 1271.5676669932081, 20360.59278814445, 21406.357809265748, 21459.46351341229, 21536.138554457648, 21847.453813654785, 46448.30327545157, 58609.70695146497, 59183.0, 59486.10770838527, 62239.06641400292, 65316.67341663261, 83822.58088709517, 84683.5765821167, 84819.5759021201, 85505.57247213724, 7530.43521735861, 8769.693060742844, 8824.602892869709, 9001.459912405277, 9171.633134674805, 13528.323583820573, 14089.365978530786, 14148.929255353658, 14184.148951062969, 14426.711465770415, 11890.28658280506, 11987.880121198676, 12042.63872083839, 12146.817797732865, 12167.695807604881, 10425.0, 10980.670579882617, 11044.950729680668, 11225.494852731483, 11346.489407976826, 11855.822162667395, 12009.879901200877, 12220.0, 12222.511099555919, 12257.26456412618, 7572.81067973283, 8646.740597782076, 8836.248918841971, 8933.46399216049, 8954.552272386485, 11766.411679416165, 12004.279743215435, 12113.394330283627, 12153.270803751802, 12268.693282668004], "value": 10299.041231572637, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.1692010174047, 1470.176701047413, 1471.6835880285737, 1491.164947629327, 1492.9104253744772, 11390.08879289657, 11836.230645008069, 11928.165028448024, 11939.164258501909, 12226.877731222685, 11647.708807279821, 11810.881891181085, 11838.585649502269, 11872.465739041741, 11885.76228475432, 2479.47930934504, 2529.620556916459, 2530.671012768347, 2533.8226324157317, 2537.961930571043, 2456.828022038458, 2489.8381605195696, 2495.7629025242572, 2503.4492411669494, 2508.3729067733066, 2472.4807790363957, 2480.1567467061195, 2517.4335774450783, 2522.7225005249406, 2523.4826860493554, 2473.1591258971944, 2473.282748003077, 2478.21936090131, 2484.689413823272, 2492.8753562321813, 2466.987665061681, 2475.6781618389496, 2483.192962287246, 2489.253224032801, 2491.7134529529153, 11170.218084734071, 11269.549218031269, 11288.983991440728, 11302.208845380826, 11329.546818127264, 6480.935190648128, 6674.699638516278, 6733.158355205599, 6741.528093033489, 6743.763968261087, 3923.9607603923882, 4235.237657221699, 4330.696851220416, 4386.078923426085, 4388.692791504596, 1151.8272259161108, 1269.9809502857465, 1315.7039666075152, 1316.605018494448, 1318.9274589897552, 16317.59206019847, 16371.672566548603, 16524.339026438927, 16715.66568668632, 16969.236384362855, 5450.5367043800925, 5568.610197286151, 5728.255326807572, 5754.136879468029, 5771.769129234784, 11952.940235298942, 11990.0, 11992.820107698391, 12005.759884802255, 12034.0, 5446.400895901447, 6623.86752264952, 6624.138861948012, 6626.966865165643, 6631.436327912085, 5935.584509084322, 6214.937850621526, 6235.314115447297, 6272.780452684134, 6310.368963103653, 4250.766207858535, 4366.497852746911, 4387.956120438818, 4411.382406463098, 4426.955730442719, 1095.6055819904827, 1257.4781465691635, 1271.3706715175863, 1291.7416516696699, 1295.112847699329, 620.7516993202755, 691.239636399956, 699.8075529229502, 759.6695437484601, 803.6383627367708, 5035.899282014412, 6113.724882380304, 6135.601185922901, 6311.810645680637, 6334.3665633436285, 590.0853676800941, 592.1502643706295, 601.1313651773146, 606.6754286456725, 620.1999420747276, 3571.8571257149424, 4358.978205108954, 4383.0, 4433.334999750064, 4480.887977800581, 13986.930065349812, 14180.85819141816, 14608.780868286984, 15129.167895765619, 15368.231588420535, 14586.708265834624, 15132.243387830784, 15137.394504219928, 15145.242737863064, 15250.313735881911, 7535.208803075708, 8487.533185674847, 8636.0500344962, 8732.912670873337, 8742.0, 5803.970980145073, 6192.566520343533, 6734.494912881455, 6874.9656251718425, 6978.755743548952, 9756.707298781048, 12673.873261267268, 12740.554080607135, 12984.0, 13190.0, 12958.416871240628, 13029.413676384775, 13243.668908276984, 13296.473670002668, 13483.797743034058, 9041.81916361656, 9296.535173241176, 9549.188318992685, 9597.9040209597, 9601.855972160563, 10431.843522347323, 10639.8936010642, 10827.621033263798, 10929.836052459379, 10948.124150067817, 9142.2116254543, 9270.814583708423, 9288.674896378594, 9379.296552758708, 9455.243580513405, 8964.177171042642, 9102.726918192464, 9129.178373946377, 9144.679936202201, 9163.404378715362, 8287.212714792086, 8383.622736976973, 8404.747857564284, 8412.242898139197, 8437.451565648213, 5362.973185134049, 5434.456554344534, 5724.3703192647945, 6217.595856269509, 6231.470325022424, 5593.552515798645, 5727.570432217517, 5742.827715168552, 6303.716332765129, 6451.483881289391], "value": 5282.408535257373, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.89113592514474, 368.6074330837658, 398.479983621374, 412.0420023445488, 412.1283485428318, 6052.757889684407, 6260.718267677954, 6274.341194174609, 6328.841778955522, 6333.36666333367, 4836.685615434994, 6097.41467218523, 6172.259328880529, 6173.814785556429, 6186.7525298987985, 3887.961120388788, 4727.763611819395, 4733.881652958669, 4748.6913350632185, 4770.236762118062, 4317.287647538153, 4649.58153766161, 4679.953200468003, 4700.965787526756, 4758.857234282977, 4666.556677115668, 4694.225452800284, 4713.6936099153445, 4717.811287548494, 4720.371471842209, 4048.9797551012352, 4371.519132895411, 4442.667199840035, 4485.999999999984, 4707.835225767081, 4110.362893751473, 4122.319817230154, 4157.459530261077, 4181.163767246532, 4221.24017676818, 5883.440703294081, 5948.613340132878, 6028.156058151819, 6030.849228769272, 6038.547108966801, 5545.805896793633, 5626.606137570372, 5642.661440313554, 5665.490105890449, 5673.8297851064535, 5091.9236211456855, 6602.801915942529, 6648.9667551662415, 6671.466282697373, 6671.833204169886, 4200.85297014603, 4383.49589797171, 4796.184648609745, 4799.880002999918, 4841.636877234221, 6784.7964561063245, 7027.262137475543, 7042.859142817115, 7052.153741550996, 7053.6722881448995, 4915.262710593368, 4923.212286034289, 4924.8030078797165, 4928.507149285043, 4967.453580106186, 4180.352045432963, 4926.950730492721, 5010.974945125251, 5031.0, 5057.841002177832, 5542.806001789918, 5607.43925607436, 5621.297337832771, 5642.0, 5655.462731040545, 5147.639665223398, 5672.574556908248, 5986.2217911671205, 6275.184226050585, 6597.538172327987, 1481.1483397046525, 1674.6566953774447, 1696.423216106505, 1697.8811483196305, 1699.6940550700992, 1055.9366438013744, 1150.1201580790535, 1184.141497414385, 1189.9643010709692, 1191.7594921547436, 6381.4575761059905, 6383.680815959184, 6385.2656944451055, 6391.744330226739, 6396.456301214356, 5793.681347525842, 6451.774187903401, 6504.552587128829, 6774.45804335652, 6885.794985877452, 6239.532035097386, 6341.4292713656, 6353.841153971188, 6379.585326953733, 6406.0, 3373.7807042542645, 3557.5553055868018, 3606.0, 3614.7288953328607, 3653.6529029742132, 6399.8080057598345, 7036.788896333118, 7072.504924655224, 7103.147622285255, 7104.21853596104, 5551.167324901297, 6182.536309776695, 6228.937710622927, 6616.933830661632, 7080.964595176991, 5663.32040155184, 6775.62734049632, 6975.476839237078, 7017.8771396575285, 7125.0, 5799.014167591604, 6844.828879278058, 6931.896021559782, 6945.409640180641, 6981.685824138028, 6118.663473509, 6673.965535342029, 6812.206995131972, 6827.317268273133, 6839.774537066918, 6907.6891539879825, 7048.753293634698, 7154.177269613853, 7170.924361345631, 7176.282371762782, 4258.6593072553505, 4593.530070377447, 4868.41579010521, 4881.8535443936735, 4918.458969513422, 6052.546059045501, 6409.397650587353, 6741.22475915276, 6830.487713421414, 6962.930370696228, 5834.533237340917, 6391.242408337745, 6579.374959378852, 6596.921969579567, 6634.767783127568, 5991.0, 6195.663035874995, 6676.9666151668935, 6686.866262674816, 6729.629870357177, 6208.782692605737, 6471.676416179078, 6506.4469520091325, 6524.510661700297, 6537.673116344259, 6147.3280306966135, 6668.3331666834865, 6705.362990515893, 6710.63091529951, 6714.0, 6322.873542529215, 6426.935730642634, 6448.419642232222, 6516.478681705547, 6527.706253218712], "value": 4922.335701147022, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [823.7075838077486, 835.3734698975765, 846.4709556527171, 856.9502359609479, 864.844328020956, 15382.846171538282, 16425.260863261152, 17114.400995965127, 17171.227294771736, 18566.72149917752, 16239.025658460501, 16287.83712162878, 16308.510744677678, 17133.22900469479, 18487.815121848813, 774.6436639146, 982.9286078174788, 996.0, 1018.5060245780791, 1022.1260821997197, 849.392684230778, 996.6262651505685, 998.6767533018743, 1010.7725761703595, 1011.8381059030556, 869.9347548933855, 907.5280853955938, 1071.201954543866, 1071.8874518175596, 1072.6460268111505, 1055.7888422315568, 1072.2654981337769, 1073.6564299424188, 1077.3589714120087, 1081.7511972246427, 789.7551758954743, 793.2186796005988, 793.9920600794034, 795.6300320351064, 797.9122296547375, 13339.39972701221, 17048.744268835977, 17588.736168957475, 17727.822721772747, 17794.46616601504, 16931.83068169327, 17128.657426851394, 17455.65088698219, 17564.38524651631, 17651.558711032198, 13627.0, 13934.930325348309, 13950.162990220619, 13953.790693139612, 14018.509352172625, 3531.346700860349, 3591.515145455356, 3605.2248766515327, 3634.1641422472717, 3635.163912300186, 121683.0, 124458.0, 124907.0, 126251.36874315569, 127154.0, 13387.598372048855, 13517.256550889599, 13744.65638359029, 13771.173729576058, 13791.51729689476, 19341.80658193428, 25182.370440738763, 26300.0, 26418.47163056728, 26419.471610568067, 8004.159563245892, 8012.238837310446, 8013.7996550085545, 8051.879221811678, 8068.0721717002125, 3835.424686297022, 3845.7115716321387, 3900.570937196906, 3921.3333733265413, 3969.384745364473, 4232.873013809591, 4325.2214601371425, 4329.0, 4333.306670932699, 4340.00179958605, 882.2368651116738, 955.1499165742356, 955.5461155950868, 963.6290028339, 975.2636759246865, 636.9522285828581, 651.8044586623994, 654.2443477783168, 670.356457800517, 678.934073504593, 11395.829062564067, 11910.166288359906, 12097.80952618824, 12112.21270617407, 12136.514539418324, 646.1374065622352, 664.9401553860176, 665.9833504162435, 686.0326939015963, 687.5805758487319, 5374.731263436813, 5548.55611551075, 5669.886602267931, 5802.303723553201, 5991.910121348184, 103152.41501968465, 106725.0, 107859.46070269756, 109269.90730092756, 112735.0, 87021.56489217513, 87027.0, 89024.0, 89124.10875891158, 94456.0, 70344.0, 82679.0, 83425.74861377206, 84049.0, 84938.57530712307, 10381.221408394249, 12884.935575322439, 13036.087473877116, 13063.346832658139, 13109.60671179866, 28966.855165724035, 37302.8134859324, 37888.40060194277, 39897.80051099842, 40572.59427405688, 47473.52526474691, 49371.531423429406, 53133.734331328094, 53839.923201536534, 54836.725816370665, 15857.762133567774, 16076.598085047968, 16274.755878661592, 16286.51140465788, 16287.0, 15455.84544154544, 17374.39189628357, 17516.562085947444, 17863.642727145645, 18364.17361218775, 13705.83500402477, 15952.441664541686, 16326.918365408097, 16623.25195366187, 16821.663566728843, 13536.661583460493, 16246.512604621881, 16695.415660451825, 17266.22301996388, 17562.82437175612, 14026.29868506591, 15813.762793557875, 16980.896241744245, 17038.659226815642, 17090.145492725067, 11772.411379430823, 11826.0, 12038.518459261531, 12154.331511766937, 12212.720337081382, 13006.674833128869, 13368.398422070835, 13421.32893355348, 13448.529301474402, 13452.596422107352], "value": 8358.940947213025, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1511.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 117.0, 116.0, 116.0, 121.0, 144.0, 115.0, 115.0, 114.0, 115.0], "value": 117.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 141.0, 148.0, 136.0, 143.0, 147.0, 147.0, 136.0, 138.0, 110.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 226.0, 274.0, 217.0, 211.0, 197.0, 200.0, 201.0, 202.0, 194.0], "value": 211.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 60.0, 80.0, 57.0, 61.0, 72.0, 82.0, 90.0, 83.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 83.0, 72.0, 68.0, 67.0, 85.0, 70.0, 71.0, 104.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 90.0, 110.0, 91.0, 90.0, 91.0, 112.0, 90.0, 133.0], "value": 98.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 96.0, 97.0, 105.0, 97.0, 97.0, 97.0, 96.0, 107.0], "value": 98.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 111.0, 149.0, 89.0, 94.0, 92.0, 90.0, 92.0, 91.0], "value": 99.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 49.0, 51.0, 51.0, 51.0, 50.0, 52.0, 52.0, 49.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 62.0, 61.0, 64.0, 57.0, 56.0, 59.0, 57.0, 61.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 84.0, 84.0, 83.0, 82.0, 84.0, 82.0, 85.0, 86.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 151.0, 162.0, 155.0, 130.0, 135.0, 136.0, 134.0, 185.0, 143.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 176.0, 185.0, 184.0, 184.0, 180.0, 182.0, 183.0, 193.0, 187.0], "value": 183.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 65.0, 69.0, 70.0, 68.0, 69.0, 71.0, 75.0, 93.0], "value": 71.8, "unit": "score"}]}]} 17:24:55 INFO - Return code: 0 17:24:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:24:55 ERROR - # TBPL SUCCESS # 17:24:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:24:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:24:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:24:55 INFO - 2017-04-28 17:24:55.957 screenresolution[1658:11084] starting screenresolution argv=screenresolution get 17:24:55 INFO - 2017-04-28 17:24:55.960 screenresolution[1658:11084] Display 0: 1600x1200x32@60 17:24:55 INFO - 2017-04-28 17:24:55.966 screenresolution[1659:11087] starting screenresolution argv=screenresolution list 17:24:55 INFO - Available Modes on Display 0 17:24:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:24:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:24:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:24:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:24:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:24:56 INFO - Intel Iris: 17:24:56 INFO - Chipset Model: Intel Iris 17:24:56 INFO - Type: GPU 17:24:56 INFO - Bus: Built-In 17:24:56 INFO - VRAM (Dynamic, Max): 1536 MB 17:24:56 INFO - Vendor: Intel (0x8086) 17:24:56 INFO - Device ID: 0x0a2e 17:24:56 INFO - Revision ID: 0x0009 17:24:56 INFO - Displays: 17:24:56 INFO - Display: 17:24:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:24:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:24:56 INFO - Display Serial Number: 1600x1200 60 17:24:56 INFO - Main Display: Yes 17:24:56 INFO - Mirror: Off 17:24:56 INFO - Online: Yes 17:24:56 INFO - Rotation: Supported 17:24:56 INFO - Return code: 0 17:24:56 INFO - Running post-action listener: _resource_record_post_action 17:24:56 INFO - [mozharness: 2017-04-29 00:24:56.206670Z] Finished run-tests step (success) 17:24:56 INFO - Running post-run listener: _resource_record_post_run 17:24:56 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 41617920; Write bytes: 515382272; Read time: 412; Write time: 2129 17:24:56 INFO - TinderboxPrint: CPU usage
54.0% 17:24:56 INFO - TinderboxPrint: I/O read bytes / time
41,617,920 / 412 17:24:56 INFO - TinderboxPrint: I/O write bytes / time
515,382,272 / 2,129 17:24:56 INFO - TinderboxPrint: CPU idle
2,202.9 (46.0%) 17:24:56 INFO - TinderboxPrint: CPU system
961.6 (20.1%) 17:24:56 INFO - TinderboxPrint: CPU user
1,625.2 (33.9%) 17:24:56 INFO - TinderboxPrint: Swap in / out
249,257,984 / 0 17:24:56 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208470528; Write bytes: 205834240; Read time: 16106; Write time: 443 17:24:56 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36563456; Write bytes: 302339072; Read time: 374; Write time: 1657 17:24:56 INFO - Running post-run listener: _upload_blobber_files 17:24:56 INFO - Blob upload gear active. 17:24:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:24:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:24:56 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'] 17:24:56 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 17:24:57 INFO - (blobuploader) - INFO - Open directory for files ... 17:24:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:24:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:24:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:24:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:24:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:24:57 INFO - (blobuploader) - INFO - Done attempting. 17:24:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:24:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:24:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:24:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:24:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:24:58 INFO - (blobuploader) - INFO - Done attempting. 17:24:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:24:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:24:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:24:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:24:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:24:59 INFO - (blobuploader) - INFO - Done attempting. 17:24:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:24:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:24:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:25:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:01 INFO - (blobuploader) - INFO - Done attempting. 17:25:01 INFO - (blobuploader) - INFO - Iteration through files over. 17:25:01 INFO - Return code: 0 17:25:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:25:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:25:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f2bf181aca617c191a97b49ec200bad11534c9b8fd5d1285b95aa4f008e54ad7b36213b39f4a026e8d217509c480f7fb5399faf7e468773d0386d9cfbe5106f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c5d48519b36b02581ab8d76eb18b7fab9194cc7a67c9a1394eaf872a7b66905a14beb40246acd33f5a8e56efa9ab3837d1b5b062693b8fdb3cc6ed7bdbd4132", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8946417a1c29ca71ef7d9824bd916ee6ca3d1de30ef639a5edef404181bed14a005856c15cf880b88eee69bec1dc62a7a84929d37e0ac075ef1c19f9d2905ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ada5ee6bafc84e21aace9cb7a88d9ce15f7fc1385bd84291517729e1dd52001a842828a113ae8672e89a45b633055e3e44e731c94d337bfea8d163fff0309bf"} 17:25:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:25:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:25:01 INFO - Contents: 17:25:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f2bf181aca617c191a97b49ec200bad11534c9b8fd5d1285b95aa4f008e54ad7b36213b39f4a026e8d217509c480f7fb5399faf7e468773d0386d9cfbe5106f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c5d48519b36b02581ab8d76eb18b7fab9194cc7a67c9a1394eaf872a7b66905a14beb40246acd33f5a8e56efa9ab3837d1b5b062693b8fdb3cc6ed7bdbd4132", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8946417a1c29ca71ef7d9824bd916ee6ca3d1de30ef639a5edef404181bed14a005856c15cf880b88eee69bec1dc62a7a84929d37e0ac075ef1c19f9d2905ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ada5ee6bafc84e21aace9cb7a88d9ce15f7fc1385bd84291517729e1dd52001a842828a113ae8672e89a45b633055e3e44e731c94d337bfea8d163fff0309bf"} 17:25:01 INFO - Running post-run listener: copy_logs_to_upload_dir 17:25:01 INFO - Copying logs to upload dir... 17:25:01 INFO - mkdir: /builds/slave/test/build/upload/logs 17:25:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.993149 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-04-28 17:25:01.983840) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 17:25:01.987250) ========= 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.01Z8LG1B9C/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.J79CwwOcMK/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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/1f2bf181aca617c191a97b49ec200bad11534c9b8fd5d1285b95aa4f008e54ad7b36213b39f4a026e8d217509c480f7fb5399faf7e468773d0386d9cfbe5106f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c5d48519b36b02581ab8d76eb18b7fab9194cc7a67c9a1394eaf872a7b66905a14beb40246acd33f5a8e56efa9ab3837d1b5b062693b8fdb3cc6ed7bdbd4132", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8946417a1c29ca71ef7d9824bd916ee6ca3d1de30ef639a5edef404181bed14a005856c15cf880b88eee69bec1dc62a7a84929d37e0ac075ef1c19f9d2905ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ada5ee6bafc84e21aace9cb7a88d9ce15f7fc1385bd84291517729e1dd52001a842828a113ae8672e89a45b633055e3e44e731c94d337bfea8d163fff0309bf"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f2bf181aca617c191a97b49ec200bad11534c9b8fd5d1285b95aa4f008e54ad7b36213b39f4a026e8d217509c480f7fb5399faf7e468773d0386d9cfbe5106f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c5d48519b36b02581ab8d76eb18b7fab9194cc7a67c9a1394eaf872a7b66905a14beb40246acd33f5a8e56efa9ab3837d1b5b062693b8fdb3cc6ed7bdbd4132", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8946417a1c29ca71ef7d9824bd916ee6ca3d1de30ef639a5edef404181bed14a005856c15cf880b88eee69bec1dc62a7a84929d37e0ac075ef1c19f9d2905ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ada5ee6bafc84e21aace9cb7a88d9ce15f7fc1385bd84291517729e1dd52001a842828a113ae8672e89a45b633055e3e44e731c94d337bfea8d163fff0309bf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 17:25:02.023271) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:25:02.023622) ========= 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.01Z8LG1B9C/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.J79CwwOcMK/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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.005397 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:25:02.072850) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 17:25:02.073195) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 17:25:02.073523) ========= ========= Total master_lag: 0.18 =========