builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0286 starttime: 1493424270.32 results: success (0) buildid: 20170428170223 builduid: 74266b055f5a4389972cb644c97bafe3 revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:30.320388) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:30.320863) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:30.321148) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:30.348183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:30.348581) ========= 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.ETNjsGzJRF/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.H993OiK41J/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:04:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 157M=0s 2017-04-28 17:04:30 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093253 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:30.467313) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:30.467638) ========= 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.ETNjsGzJRF/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.H993OiK41J/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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.026353 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:30.516554) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 17:04:30.516937) ========= 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.ETNjsGzJRF/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.H993OiK41J/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:04:30,625 truncating revision to first 12 chars 2017-04-28 17:04:30,625 Setting DEBUG logging. 2017-04-28 17:04:30,625 attempt 1/10 2017-04-28 17:04:30,625 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:04:32,022 unpacking tar archive at: mozilla-central-b3b32894d932/testing/mozharness/ program finished with exit code 0 elapsedTime=1.675897 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 17:04:32.216660) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:32.219277) ========= script_repo_revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:32.219674) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:32.219964) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:32.240399) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-04-28 17:04:32.240778) ========= /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.ETNjsGzJRF/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.H993OiK41J/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:04:32 INFO - MultiFileLogger online at 20170428 17:04:32 in /builds/slave/test 17:04:32 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:04:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:04:32 INFO - {'append_to_log': False, 17:04:32 INFO - 'base_work_dir': '/builds/slave/test', 17:04:32 INFO - 'blob_upload_branch': 'Firefox', 17:04:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:04:32 INFO - 'branch': 'Firefox', 17:04:32 INFO - 'buildbot_json_path': 'buildprops.json', 17:04:32 INFO - 'config_files': ('talos/mac_config.py',), 17:04:32 INFO - 'default_actions': ('clobber', 17:04:32 INFO - 'read-buildbot-config', 17:04:32 INFO - 'download-and-extract', 17:04:32 INFO - 'populate-webroot', 17:04:32 INFO - 'create-virtualenv', 17:04:32 INFO - 'install', 17:04:32 INFO - 'run-tests'), 17:04:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:04:32 INFO - 'download_minidump_stackwalk': True, 17:04:32 INFO - 'download_symbols': 'ondemand', 17:04:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:04:32 INFO - 'tooltool.py': '/tools/tooltool.py', 17:04:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:04:32 INFO - '/tools/misc-python/virtualenv.py')}, 17:04:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:04:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:04:32 INFO - 'gecko_profile': False, 17:04:32 INFO - 'gecko_profile_interval': 0, 17:04:32 INFO - 'installer_path': 'installer.exe', 17:04:32 INFO - 'log_level': 'info', 17:04:32 INFO - 'log_name': 'talos', 17:04:32 INFO - 'log_to_console': True, 17:04:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:04:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:04:32 INFO - 'opt_config_files': (), 17:04:32 INFO - 'pip_index': False, 17:04:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:04:32 INFO - 'cmd': ('bash', 17:04:32 INFO - '-c', 17:04:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:04:32 INFO - 'enabled': True, 17:04:32 INFO - 'halt_on_failure': False, 17:04:32 INFO - 'name': 'check_screen_resolution'},), 17:04:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:04:32 INFO - 'cmd': ('bash', 17:04:32 INFO - '-c', 17:04:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:04:32 INFO - 'enabled': True, 17:04:32 INFO - 'halt_on_failure': False, 17:04:32 INFO - 'name': 'check_screen_resolution'},), 17:04:32 INFO - 'run_cmd_checks_enabled': True, 17:04:32 INFO - 'suite': 'dromaeojs-e10s', 17:04:32 INFO - 'system_bits': '32', 17:04:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:04:32 INFO - 'title': 't-yosemite-r7-0286', 17:04:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:04:32 INFO - 'use_talos_json': True, 17:04:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:04:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:04:32 INFO - 'work_dir': 'build'} 17:04:32 INFO - [mozharness: 2017-04-29 00:04:32.455430Z] Running clobber step. 17:04:32 INFO - Running pre-action listener: _resource_record_pre_action 17:04:32 INFO - Running main action method: clobber 17:04:32 INFO - rmtree: /builds/slave/test/build 17:04:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:04:34 INFO - Running post-action listener: _resource_record_post_action 17:04:34 INFO - [mozharness: 2017-04-29 00:04:34.891866Z] Finished clobber step (success) 17:04:34 INFO - [mozharness: 2017-04-29 00:04:34.892028Z] Running read-buildbot-config step. 17:04:34 INFO - Running pre-action listener: _resource_record_pre_action 17:04:34 INFO - Running main action method: read_buildbot_config 17:04:34 INFO - Using buildbot properties: 17:04:34 INFO - { 17:04:34 INFO - "project": "", 17:04:34 INFO - "product": "firefox", 17:04:34 INFO - "script_repo_revision": "production", 17:04:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:04:34 INFO - "repository": "", 17:04:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:04:34 INFO - "buildid": "20170428170223", 17:04:34 INFO - "stage_platform": "macosx64", 17:04:34 INFO - "basedir": "/builds/slave/test", 17:04:34 INFO - "buildnumber": 49, 17:04:34 INFO - "platform": "yosemite_r7", 17:04:34 INFO - "mozci_request": { 17:04:34 INFO - "priority": 0, 17:04:34 INFO - "type": "trigger_all_talos_jobs", 17:04:34 INFO - "times": 6 17:04:34 INFO - }, 17:04:34 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:04:34 INFO - "slavebuilddir": "test", 17:04:34 INFO - "branch": "mozilla-central", 17:04:34 INFO - "revision": "b3b32894d9326d147238d9f071e1814ab3fdc850", 17:04:34 INFO - "slavename": "t-yosemite-r7-0286", 17:04:34 INFO - "builduid": "74266b055f5a4389972cb644c97bafe3", 17:04:34 INFO - "repo_path": "mozilla-central" 17:04:34 INFO - } 17:04:34 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg. 17:04:34 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:04:34 INFO - Running post-action listener: _resource_record_post_action 17:04:34 INFO - [mozharness: 2017-04-29 00:04:34.895962Z] Finished read-buildbot-config step (success) 17:04:34 INFO - [mozharness: 2017-04-29 00:04:34.896082Z] Running download-and-extract step. 17:04:34 INFO - Running pre-action listener: _resource_record_pre_action 17:04:34 INFO - Running main action method: download_and_extract 17:04:34 INFO - mkdir: /builds/slave/test/build/tests 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 - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json 17:04:34 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:04:34 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:04:35 INFO - Downloaded 1325 bytes. 17:04:35 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:04:35 INFO - Using the following test package requirements: 17:04:35 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:35 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:04:35 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:04:35 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:35 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:04:35 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:35 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:04:35 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:35 INFO - u'jsshell-mac.zip'], 17:04:35 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:35 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:04:35 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:04:35 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:35 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:04:35 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:35 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:04:35 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:35 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:04:35 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:35 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:04:35 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:04:35 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:35 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:35 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:36 INFO - Content-Length response header: 52698163 17:04:36 INFO - Bytes received: 52698163 17:04:52 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:52 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:52 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:52 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:53 INFO - Content-Length response header: 52698163 17:04:53 INFO - Bytes received: 52698163 17:05:09 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:05:09 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:05:09 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:05:09 INFO - Content-Length response header: 14408809 17:05:09 INFO - Bytes received: 14408809 17:05:09 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:09 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:05:09 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:05:09 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:05:11 INFO - Downloaded 71219323 bytes. 17:05:11 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:05:11 INFO - mkdir: /builds/slave/test/properties 17:05:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:05:11 INFO - Writing to file /builds/slave/test/properties/build_url 17:05:11 INFO - Contents: 17:05:11 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:05:11 INFO - Running post-action listener: _resource_record_post_action 17:05:11 INFO - Running post-action listener: set_extra_try_arguments 17:05:11 INFO - [mozharness: 2017-04-29 00:05:11.648163Z] Finished download-and-extract step (success) 17:05:11 INFO - [mozharness: 2017-04-29 00:05:11.648325Z] Running populate-webroot step. 17:05:11 INFO - Running pre-action listener: _resource_record_pre_action 17:05:11 INFO - Running main action method: populate_webroot 17:05:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:05:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:05:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:05:11 INFO - '--tppagecycles', 17:05:11 INFO - '7'], 17:05:11 INFO - 'tests': ['tsvgm']}}, 17:05:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:05:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:05:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:05:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:05:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:05:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:05:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:05:11 INFO - 'talos_options': ['--disable-e10s'], 17:05:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:05:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:05:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:05:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:05:11 INFO - 'talos_options': ['--disable-e10s'], 17:05:11 INFO - 'tests': ['damp', 'tps']}, 17:05:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:05:11 INFO - 'tests': ['damp', 'tps']}, 17:05:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:05:11 INFO - 'tests': ['dromaeo_dom']}, 17:05:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:05:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:05:11 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:05:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:05:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:05:11 INFO - 'tests': ['a11yr', 17:05:11 INFO - 'ts_paint', 17:05:11 INFO - 'tpaint', 17:05:11 INFO - 'sessionrestore', 17:05:11 INFO - 'sessionrestore_no_auto_restore', 17:05:11 INFO - 'tabpaint']}, 17:05:11 INFO - 'other-e10s': {'tests': ['a11yr', 17:05:11 INFO - 'ts_paint', 17:05:11 INFO - 'tpaint', 17:05:11 INFO - 'sessionrestore', 17:05:11 INFO - 'sessionrestore_no_auto_restore', 17:05:11 INFO - 'tabpaint']}, 17:05:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:05:11 INFO - 'ts_paint', 17:05:11 INFO - 'tpaint', 17:05:11 INFO - 'sessionrestore', 17:05:11 INFO - 'sessionrestore_no_auto_restore', 17:05:11 INFO - 'tabpaint']}, 17:05:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:05:11 INFO - 'ts_paint', 17:05:11 INFO - 'tpaint', 17:05:11 INFO - 'sessionrestore', 17:05:11 INFO - 'sessionrestore_no_auto_restore', 17:05:11 INFO - 'tabpaint']}, 17:05:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:05:11 INFO - 'tests': ['a11yr', 17:05:11 INFO - 'ts_paint', 17:05:11 INFO - 'tpaint', 17:05:11 INFO - 'sessionrestore', 17:05:11 INFO - 'sessionrestore_no_auto_restore', 17:05:11 INFO - 'tabpaint']}, 17:05:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:05:11 INFO - 'tests': ['a11yr', 17:05:11 INFO - 'ts_paint', 17:05:11 INFO - 'tpaint', 17:05:11 INFO - 'sessionrestore', 17:05:11 INFO - 'sessionrestore_no_auto_restore', 17:05:11 INFO - 'tabpaint']}, 17:05:11 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:05:11 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:05:11 INFO - 'bloom_basic_ref']}, 17:05:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:05:11 INFO - 'tests': ['tsvgx', 17:05:11 INFO - 'tsvgr_opacity', 17:05:11 INFO - 'tart', 17:05:11 INFO - 'tscrollx', 17:05:11 INFO - 'cart', 17:05:11 INFO - 'tsvg_static']}, 17:05:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:05:11 INFO - 'tsvgr_opacity', 17:05:11 INFO - 'tart', 17:05:11 INFO - 'tscrollx', 17:05:11 INFO - 'cart', 17:05:11 INFO - 'tsvg_static']}, 17:05:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:05:11 INFO - 'talos_options': ['--disable-e10s'], 17:05:11 INFO - 'tests': ['tp5o']}, 17:05:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:05:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:05:11 INFO - 'talos_options': ['--disable-e10s', 17:05:11 INFO - '--xperf_path', 17:05:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:05:11 INFO - 'tests': ['tp5n']}, 17:05:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:05:11 INFO - 'talos_options': ['--xperf_path', 17:05:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:05:11 INFO - 'tests': ['tp5n']}}, 17:05:11 INFO - 'talos.zip': {'path': '', 17:05:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:05:11 INFO - Running post-action listener: _resource_record_post_action 17:05:11 INFO - [mozharness: 2017-04-29 00:05:11.667613Z] Finished populate-webroot step (success) 17:05:11 INFO - [mozharness: 2017-04-29 00:05:11.667749Z] Running create-virtualenv step. 17:05:11 INFO - Running pre-action listener: _resource_record_pre_action 17:05:11 INFO - Running main action method: create_virtualenv 17:05:11 INFO - Creating virtualenv /builds/slave/test/build/venv 17:05:11 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:05:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:05:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:05:11 INFO - Using real prefix '/tools/python27' 17:05:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:05:12 INFO - Installing distribute.............................................................................................................................................................................................done. 17:05:15 INFO - Installing pip.................done. 17:05:15 INFO - Return code: 0 17:05:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:05:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:05:15 INFO - Reading from file tmpfile_stdout 17:05:15 INFO - Output received: 17:05:15 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:05:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:05:15 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:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:15 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:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:15 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 0x102d0bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d0e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bf0c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae9d30>, '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 0x102a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102adebe8>, '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.ETNjsGzJRF/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.H993OiK41J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:05:15 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:05:15 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:05:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ETNjsGzJRF/Render', 17:05:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:15 INFO - 'HOME': '/Users/cltbld', 17:05:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:15 INFO - 'LOGNAME': 'cltbld', 17:05:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:15 INFO - 'MOZ_NO_REMOTE': '1', 17:05:15 INFO - 'NO_EM_RESTART': '1', 17:05:15 INFO - 'PAGER': '/bin/cat', 17:05:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:15 INFO - 'PWD': '/builds/slave/test', 17:05:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:15 INFO - 'SHELL': '/bin/bash', 17:05:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H993OiK41J/Listeners', 17:05:15 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 17:05:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:15 INFO - 'USER': 'cltbld', 17:05:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:15 INFO - 'XPC_FLAGS': '0x0', 17:05:15 INFO - 'XPC_SERVICE_NAME': '0', 17:05:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:16 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:05:16 INFO - Cleaning up... 17:05:16 INFO - Return code: 0 17:05:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:16 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 0x102d0bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d0e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bf0c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae9d30>, '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 0x102a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102adebe8>, '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.ETNjsGzJRF/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.H993OiK41J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:05:16 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:05:16 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:05:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ETNjsGzJRF/Render', 17:05:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:16 INFO - 'HOME': '/Users/cltbld', 17:05:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:16 INFO - 'LOGNAME': 'cltbld', 17:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:16 INFO - 'MOZ_NO_REMOTE': '1', 17:05:16 INFO - 'NO_EM_RESTART': '1', 17:05:16 INFO - 'PAGER': '/bin/cat', 17:05:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:16 INFO - 'PWD': '/builds/slave/test', 17:05:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:16 INFO - 'SHELL': '/bin/bash', 17:05:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H993OiK41J/Listeners', 17:05:16 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 17:05:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:16 INFO - 'USER': 'cltbld', 17:05:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:16 INFO - 'XPC_FLAGS': '0x0', 17:05:16 INFO - 'XPC_SERVICE_NAME': '0', 17:05:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:16 INFO - Downloading/unpacking psutil>=3.1.1 17:05:16 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:05:16 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:05:16 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:05:16 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:05:16 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:05:16 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:05:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:05:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:05:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:05:19 INFO - Installing collected packages: psutil 17:05:19 INFO - Running setup.py install for psutil 17:05:19 INFO - building 'psutil._psutil_osx' extension 17:05:19 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:05:20 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:05:20 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:05:20 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:05:20 INFO - building 'psutil._psutil_posix' extension 17:05:20 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:05:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:05:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:05:20 INFO - ^ 17:05:20 INFO - 1 warning generated. 17:05:20 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:05:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:05:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:05:20 INFO - Successfully installed psutil 17:05:20 INFO - Cleaning up... 17:05:20 INFO - Return code: 0 17:05:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:05:20 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:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:20 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:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:20 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 0x102d0bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d0e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bf0c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae9d30>, '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 0x102a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102adebe8>, '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.ETNjsGzJRF/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.H993OiK41J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:05:20 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:05:20 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:05:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ETNjsGzJRF/Render', 17:05:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:20 INFO - 'HOME': '/Users/cltbld', 17:05:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:20 INFO - 'LOGNAME': 'cltbld', 17:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:20 INFO - 'MOZ_NO_REMOTE': '1', 17:05:20 INFO - 'NO_EM_RESTART': '1', 17:05:20 INFO - 'PAGER': '/bin/cat', 17:05:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:20 INFO - 'PWD': '/builds/slave/test', 17:05:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:20 INFO - 'SHELL': '/bin/bash', 17:05:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H993OiK41J/Listeners', 17:05:20 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 17:05:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:20 INFO - 'USER': 'cltbld', 17:05:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:20 INFO - 'XPC_FLAGS': '0x0', 17:05:20 INFO - 'XPC_SERVICE_NAME': '0', 17:05:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:05:21 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:05:21 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:05:21 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:05:21 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:05:21 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:05:21 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:05:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:05:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:05:23 INFO - Installing collected packages: mozsystemmonitor 17:05:23 INFO - Running setup.py install for mozsystemmonitor 17:05:23 INFO - Successfully installed mozsystemmonitor 17:05:23 INFO - Cleaning up... 17:05:24 INFO - Return code: 0 17:05:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102d0bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d0e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bf0c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae9d30>, '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 0x102a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102adebe8>, '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.ETNjsGzJRF/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.H993OiK41J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:05:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:05:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:05:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ETNjsGzJRF/Render', 17:05:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:24 INFO - 'HOME': '/Users/cltbld', 17:05:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:24 INFO - 'LOGNAME': 'cltbld', 17:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:24 INFO - 'MOZ_NO_REMOTE': '1', 17:05:24 INFO - 'NO_EM_RESTART': '1', 17:05:24 INFO - 'PAGER': '/bin/cat', 17:05:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:24 INFO - 'PWD': '/builds/slave/test', 17:05:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:24 INFO - 'SHELL': '/bin/bash', 17:05:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H993OiK41J/Listeners', 17:05:24 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 17:05:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:24 INFO - 'USER': 'cltbld', 17:05:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:24 INFO - 'XPC_FLAGS': '0x0', 17:05:24 INFO - 'XPC_SERVICE_NAME': '0', 17:05:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:25 INFO - Downloading/unpacking jsonschema==2.5.1 17:05:25 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:05:25 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:05:25 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:05:25 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:05:25 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:05:25 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:05:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:05:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:05:28 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:05:28 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:05:28 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:05:28 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:05:28 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:05:28 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:05:28 INFO - Downloading functools32-3.2.3-2.tar.gz 17:05:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:05:28 INFO - warning: no files found matching '*.txt' 17:05:28 INFO - no previously-included directories found matching 'build' 17:05:28 INFO - no previously-included directories found matching 'dist' 17:05:28 INFO - no previously-included directories found matching '.git*' 17:05:28 INFO - Installing collected packages: jsonschema, functools32 17:05:28 INFO - Running setup.py install for functools32 17:05:28 INFO - warning: no files found matching '*.txt' 17:05:28 INFO - no previously-included directories found matching 'build' 17:05:28 INFO - no previously-included directories found matching 'dist' 17:05:28 INFO - no previously-included directories found matching '.git*' 17:05:28 INFO - Successfully installed jsonschema functools32 17:05:28 INFO - Cleaning up... 17:05:28 INFO - Return code: 0 17:05:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:05: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:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05: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:05:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05: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', '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 0x102d0bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d0e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bf0c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae9d30>, '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 0x102a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102adebe8>, '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.ETNjsGzJRF/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.H993OiK41J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:05: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:05: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 functools32==3.2.3-2 17:05:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ETNjsGzJRF/Render', 17:05:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:28 INFO - 'HOME': '/Users/cltbld', 17:05:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:28 INFO - 'LOGNAME': 'cltbld', 17:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:28 INFO - 'MOZ_NO_REMOTE': '1', 17:05:28 INFO - 'NO_EM_RESTART': '1', 17:05:28 INFO - 'PAGER': '/bin/cat', 17:05:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:28 INFO - 'PWD': '/builds/slave/test', 17:05:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:28 INFO - 'SHELL': '/bin/bash', 17:05:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H993OiK41J/Listeners', 17:05:28 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 17:05:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:28 INFO - 'USER': 'cltbld', 17:05:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:28 INFO - 'XPC_FLAGS': '0x0', 17:05:28 INFO - 'XPC_SERVICE_NAME': '0', 17:05:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:05:29 INFO - Cleaning up... 17:05:29 INFO - Return code: 0 17:05:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:05: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:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05: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:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05: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', '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 0x102d0bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d0e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bf0c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae9d30>, '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 0x102a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102adebe8>, '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.ETNjsGzJRF/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.H993OiK41J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:05: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:05: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 blobuploader==1.2.4 17:05:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ETNjsGzJRF/Render', 17:05:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:29 INFO - 'HOME': '/Users/cltbld', 17:05:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:29 INFO - 'LOGNAME': 'cltbld', 17:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:29 INFO - 'MOZ_NO_REMOTE': '1', 17:05:29 INFO - 'NO_EM_RESTART': '1', 17:05:29 INFO - 'PAGER': '/bin/cat', 17:05:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:29 INFO - 'PWD': '/builds/slave/test', 17:05:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:29 INFO - 'SHELL': '/bin/bash', 17:05:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H993OiK41J/Listeners', 17:05:29 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 17:05:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:29 INFO - 'USER': 'cltbld', 17:05:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:29 INFO - 'XPC_FLAGS': '0x0', 17:05:29 INFO - 'XPC_SERVICE_NAME': '0', 17:05:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:29 INFO - Downloading/unpacking blobuploader==1.2.4 17:05: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:05: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:05: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:05: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:05: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:05: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:05:32 INFO - Downloading blobuploader-1.2.4.tar.gz 17:05:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:05:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:05:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:33 INFO - Downloading docopt-0.6.1.tar.gz 17:05:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:05:33 INFO - Installing collected packages: blobuploader, requests, docopt 17:05:33 INFO - Running setup.py install for blobuploader 17:05:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:05:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:05:33 INFO - Running setup.py install for requests 17:05:34 INFO - Running setup.py install for docopt 17:05:34 INFO - Successfully installed blobuploader requests docopt 17:05:34 INFO - Cleaning up... 17:05:34 INFO - Return code: 0 17:05:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:05: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:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05: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:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:34 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 0x102d0bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d0e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bf0c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae9d30>, '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 0x102a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102adebe8>, '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.ETNjsGzJRF/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.H993OiK41J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:05:34 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:05:34 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:05:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ETNjsGzJRF/Render', 17:05:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:34 INFO - 'HOME': '/Users/cltbld', 17:05:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:34 INFO - 'LOGNAME': 'cltbld', 17:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:34 INFO - 'MOZ_NO_REMOTE': '1', 17:05:34 INFO - 'NO_EM_RESTART': '1', 17:05:34 INFO - 'PAGER': '/bin/cat', 17:05:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:34 INFO - 'PWD': '/builds/slave/test', 17:05:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:34 INFO - 'SHELL': '/bin/bash', 17:05:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H993OiK41J/Listeners', 17:05:34 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 17:05:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:34 INFO - 'USER': 'cltbld', 17:05:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:34 INFO - 'XPC_FLAGS': '0x0', 17:05:34 INFO - 'XPC_SERVICE_NAME': '0', 17:05:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:05:34 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Wx2wMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:05:34 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-qhZpxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:05:34 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-XII33r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:05:34 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-PqZSK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:05:34 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-zBwy6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:05:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-MkgFXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:05:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Q2bxWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:05:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-I_8buK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:05:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-o1a2ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:05:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-FrMRH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:05:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-N7GxMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:05:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-cbMWhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:05:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-NBG70y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:05:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-D4b2uj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:05:36 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-pVQ5iF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:05:36 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-v2kcvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:05:36 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-eN93st-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:05:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:05:36 INFO - Running setup.py install for manifestparser 17:05:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:05:36 INFO - Running setup.py install for mozcrash 17:05:36 INFO - Running setup.py install for mozdebug 17:05:36 INFO - Running setup.py install for mozdevice 17:05:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:05:36 INFO - Running setup.py install for mozfile 17:05:37 INFO - Running setup.py install for mozhttpd 17:05:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:05:37 INFO - Running setup.py install for mozinfo 17:05:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:05:37 INFO - Running setup.py install for mozInstall 17:05:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:05:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:05:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:05:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:05:37 INFO - Running setup.py install for mozleak 17:05:37 INFO - Running setup.py install for mozlog 17:05:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:05:37 INFO - Running setup.py install for moznetwork 17:05:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:05:37 INFO - Running setup.py install for mozprocess 17:05:38 INFO - Running setup.py install for mozprofile 17:05:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:05:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:05:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:05:38 INFO - Running setup.py install for mozrunner 17:05:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:05:38 INFO - Running setup.py install for mozscreenshot 17:05:38 INFO - Running setup.py install for moztest 17:05:38 INFO - Running setup.py install for mozversion 17:05:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:05:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:05:38 INFO - Cleaning up... 17:05:38 INFO - Return code: 0 17:05:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:05:38 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:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:38 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:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:38 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 0x102d0bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d0e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bf0c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae9d30>, '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 0x102a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102adebe8>, '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.ETNjsGzJRF/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.H993OiK41J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:05:38 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:05:38 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:05:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ETNjsGzJRF/Render', 17:05:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:38 INFO - 'HOME': '/Users/cltbld', 17:05:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:38 INFO - 'LOGNAME': 'cltbld', 17:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:38 INFO - 'MOZ_NO_REMOTE': '1', 17:05:38 INFO - 'NO_EM_RESTART': '1', 17:05:38 INFO - 'PAGER': '/bin/cat', 17:05:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:38 INFO - 'PWD': '/builds/slave/test', 17:05:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:38 INFO - 'SHELL': '/bin/bash', 17:05:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H993OiK41J/Listeners', 17:05:38 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 17:05:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:38 INFO - 'USER': 'cltbld', 17:05:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:38 INFO - 'XPC_FLAGS': '0x0', 17:05:38 INFO - 'XPC_SERVICE_NAME': '0', 17:05:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:05:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-nYQfr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:05:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-ouPSGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:05:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-ng3s9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:05:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-c7O1yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:05:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-fFOxcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:05:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-XkyAdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:05:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-qScTM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:05:39 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-pJkP0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:05:40 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:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:05:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-6jUU4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:05:40 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:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:05:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-0gfDcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:05:40 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:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:05:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-SCb_DK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:05:40 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:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:05:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-v3i4Sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:05:40 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:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:05:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Yomh5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:05:40 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:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:05:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-sGHRYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:05:40 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:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:05:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-LkL65Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:05:40 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:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:05:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Wws2cU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:05:40 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:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:05:40 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-tWYW4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:05:41 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:05:41 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:05:41 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:05:41 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:05:41 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:05:41 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:05:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:05:41 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:05:41 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:05:41 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:05:41 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:05:41 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:05:41 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:05:43 INFO - Downloading blessings-1.6.tar.gz 17:05:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:05:43 INFO - Installing collected packages: blessings 17:05:43 INFO - Running setup.py install for blessings 17:05:43 INFO - Successfully installed blessings 17:05:43 INFO - Cleaning up... 17:05:44 INFO - Return code: 0 17:05:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:05:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:05:44 INFO - Reading from file tmpfile_stdout 17:05:44 INFO - Current package versions: 17:05:44 INFO - blessings == 1.6 17:05:44 INFO - blobuploader == 1.2.4 17:05:44 INFO - docopt == 0.6.1 17:05:44 INFO - functools32 == 3.2.3-2 17:05:44 INFO - jsonschema == 2.5.1 17:05:44 INFO - manifestparser == 1.1 17:05:44 INFO - mozInstall == 1.12 17:05:44 INFO - mozcrash == 1.0 17:05:44 INFO - mozdebug == 0.1 17:05:44 INFO - mozdevice == 0.50 17:05:44 INFO - mozfile == 1.2 17:05:44 INFO - mozhttpd == 0.7 17:05:44 INFO - mozinfo == 0.9 17:05:44 INFO - mozleak == 0.1 17:05:44 INFO - mozlog == 3.4 17:05:44 INFO - moznetwork == 0.27 17:05:44 INFO - mozprocess == 0.25 17:05:44 INFO - mozprofile == 0.28 17:05:44 INFO - mozrunner == 6.13 17:05:44 INFO - mozscreenshot == 0.1 17:05:44 INFO - mozsystemmonitor == 0.3 17:05:44 INFO - moztest == 0.8 17:05:44 INFO - mozversion == 1.4 17:05:44 INFO - psutil == 3.1.1 17:05:44 INFO - requests == 1.2.3 17:05:44 INFO - wsgiref == 0.1.2 17:05:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:05:44 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:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:44 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:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:44 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 0x102d0bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d0e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bf0c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae9d30>, '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 0x102a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102adebe8>, '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.ETNjsGzJRF/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.H993OiK41J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:05:44 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:05:44 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:05:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ETNjsGzJRF/Render', 17:05:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:44 INFO - 'HOME': '/Users/cltbld', 17:05:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:44 INFO - 'LOGNAME': 'cltbld', 17:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:44 INFO - 'MOZ_NO_REMOTE': '1', 17:05:44 INFO - 'NO_EM_RESTART': '1', 17:05:44 INFO - 'PAGER': '/bin/cat', 17:05:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:44 INFO - 'PWD': '/builds/slave/test', 17:05:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:44 INFO - 'SHELL': '/bin/bash', 17:05:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H993OiK41J/Listeners', 17:05:44 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 17:05:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:44 INFO - 'USER': 'cltbld', 17:05:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:44 INFO - 'XPC_FLAGS': '0x0', 17:05:44 INFO - 'XPC_SERVICE_NAME': '0', 17:05:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:44 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:05:44 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:05:44 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:05:44 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:05:44 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:05:44 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:05:44 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:05:44 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:05:44 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:05:44 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:05:44 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:05:44 INFO - Cleaning up... 17:05:44 INFO - Return code: 0 17:05:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:05:44 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:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:44 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:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:44 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 0x102d0bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d0e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bf0c6ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ae9d30>, '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 0x102a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102adebe8>, '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.ETNjsGzJRF/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.H993OiK41J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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:05:44 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:05:44 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:05:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ETNjsGzJRF/Render', 17:05:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:44 INFO - 'HOME': '/Users/cltbld', 17:05:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:44 INFO - 'LOGNAME': 'cltbld', 17:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:44 INFO - 'MOZ_NO_REMOTE': '1', 17:05:44 INFO - 'NO_EM_RESTART': '1', 17:05:44 INFO - 'PAGER': '/bin/cat', 17:05:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:44 INFO - 'PWD': '/builds/slave/test', 17:05:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:44 INFO - 'SHELL': '/bin/bash', 17:05:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H993OiK41J/Listeners', 17:05:44 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 17:05:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:44 INFO - 'USER': 'cltbld', 17:05:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:44 INFO - 'XPC_FLAGS': '0x0', 17:05:44 INFO - 'XPC_SERVICE_NAME': '0', 17:05:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:05:44 INFO - Cleaning up... 17:05:44 INFO - Return code: 0 17:05:44 INFO - Running post-action listener: _resource_record_post_action 17:05:44 INFO - Running post-action listener: _start_resource_monitoring 17:05:44 INFO - Starting resource monitoring. 17:05:44 INFO - [mozharness: 2017-04-29 00:05:44.900856Z] Finished create-virtualenv step (success) 17:05:44 INFO - [mozharness: 2017-04-29 00:05:44.901842Z] Running install step. 17:05:44 INFO - Running pre-action listener: _resource_record_pre_action 17:05:44 INFO - Running main action method: install 17:05:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:05:45 INFO - Reading from file tmpfile_stdout 17:05:45 INFO - Detecting whether we're running mozinstall >=1.0... 17:05:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:05:45 INFO - Reading from file tmpfile_stdout 17:05:45 INFO - Output received: 17:05:45 INFO - Usage: mozinstall [options] installer 17:05:45 INFO - Options: 17:05:45 INFO - -h, --help show this help message and exit 17:05:45 INFO - -d DEST, --destination=DEST 17:05:45 INFO - Directory to install application into. [default: 17:05:45 INFO - "/builds/slave/test"] 17:05:45 INFO - --app=APP Application being installed. [default: firefox] 17:05:45 INFO - mkdir: /builds/slave/test/build/application 17:05:45 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:05:45 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:06:05 INFO - Reading from file tmpfile_stdout 17:06:05 INFO - Output received: 17:06:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:06:05 INFO - Running post-action listener: _resource_record_post_action 17:06:05 INFO - [mozharness: 2017-04-29 00:06:05.050624Z] Finished install step (success) 17:06:05 INFO - [mozharness: 2017-04-29 00:06:05.050778Z] Running run-tests step. 17:06:05 INFO - Running pre-action listener: _resource_record_pre_action 17:06:05 INFO - Running main action method: run_tests 17:06:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:06:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:06:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:06:05 INFO - 2017-04-28 17:06:05.065 screenresolution[1624:7429] starting screenresolution argv=screenresolution get 17:06:05 INFO - 2017-04-28 17:06:05.068 screenresolution[1624:7429] Display 0: 1600x1200x32@60 17:06:05 INFO - 2017-04-28 17:06:05.074 screenresolution[1625:7431] starting screenresolution argv=screenresolution list 17:06:05 INFO - Available Modes on Display 0 17:06:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:06:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:06:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:06:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:06:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:06:05 INFO - Intel Iris: 17:06:05 INFO - Chipset Model: Intel Iris 17:06:05 INFO - Type: GPU 17:06:05 INFO - Bus: Built-In 17:06:05 INFO - VRAM (Dynamic, Max): 1536 MB 17:06:05 INFO - Vendor: Intel (0x8086) 17:06:05 INFO - Device ID: 0x0a2e 17:06:05 INFO - Revision ID: 0x0009 17:06:05 INFO - Displays: 17:06:05 INFO - Display: 17:06:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:06:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:06:05 INFO - Display Serial Number: 1600x1200 60 17:06:05 INFO - Main Display: Yes 17:06:05 INFO - Mirror: Off 17:06:05 INFO - Online: Yes 17:06:05 INFO - Rotation: Supported 17:06:05 INFO - Return code: 0 17:06:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:06:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:06:05 INFO - Python 2.7.3 17:06:05 INFO - Return code: 0 17:06:05 INFO - grabbing minidump binary from tooltool 17:06:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:05 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 0x102ae9d30>, '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 0x102a0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102adebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:06:05 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:06:05 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:06:05 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:06:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:06:05 INFO - Return code: 0 17:06:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:06:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:06:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:06:05 INFO - ENV: RUST_BACKTRACE is now 1 17:06:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:06:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:06:05 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-0286', '--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:06:05 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-0286 --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:06:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ETNjsGzJRF/Render', 17:06:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:06:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:06:05 INFO - 'HOME': '/Users/cltbld', 17:06:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:06:05 INFO - 'LOGNAME': 'cltbld', 17:06:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:06:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:06:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:05 INFO - 'MOZ_NO_REMOTE': '1', 17:06:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:06:05 INFO - 'NO_EM_RESTART': '1', 17:06:05 INFO - 'PAGER': '/bin/cat', 17:06:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:06:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:05 INFO - 'PWD': '/builds/slave/test', 17:06:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:06:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:06:05 INFO - 'RUST_BACKTRACE': '1', 17:06:05 INFO - 'SHELL': '/bin/bash', 17:06:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H993OiK41J/Listeners', 17:06:05 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 17:06:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:06:05 INFO - 'USER': 'cltbld', 17:06:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:06:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:06:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:05 INFO - 'XPC_FLAGS': '0x0', 17:06:05 INFO - 'XPC_SERVICE_NAME': '0', 17:06:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:06:05 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-0286', '--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:06:05 INFO - mozversion application_buildid: 20170428075320 17:06:05 INFO - mozversion application_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:06:05 INFO - mozversion application_display_name: Nightly 17:06:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:06:05 INFO - mozversion application_name: Firefox 17:06:05 INFO - mozversion application_remotingname: firefox 17:06:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:06:05 INFO - mozversion application_vendor: Mozilla 17:06:05 INFO - mozversion application_version: 55.0a1 17:06:05 INFO - mozversion platform_buildid: 20170428075320 17:06:05 INFO - mozversion platform_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:06:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:06:05 INFO - mozversion platform_version: 55.0a1 17:06:05 INFO - using testdate: 1493424365 17:06:05 INFO - actual date: 1493424365 17:06:05 INFO - starting webserver on 'localhost:49231' 17:06:05 INFO - SUITE-START | Running 2 tests 17:06:05 INFO - TEST-START | dromaeo_css 17:06:05 INFO - operating with platform_type : mac_ 17:06:05 INFO - Initialising browser for dromaeo_css test... 17:06:05 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpsG_2Oc/profile http://localhost:49231/getInfo.html) 17:06:08 INFO - PID 1635 | 2017-04-28 17:06:08.315 plugin-container[1638:7625] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 17:06:08 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:06:08 INFO - PID 1635 | 2017-04-28 17:06:08.317 plugin-container[1638:7625] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 17:06:08 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:06:08 INFO - PID 1635 | 2017-04-28 17:06:08.317 plugin-container[1638:7625] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:06:08 INFO - PID 1635 | __metrics Screen width/height:1600/1200 17:06:08 INFO - PID 1635 | colorDepth:24 17:06:08 INFO - PID 1635 | Browser inner width/height: 1024/689 17:06:08 INFO - PID 1635 | __metrics 17:06:08 ERROR - PID 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:06:09 INFO - TEST-INFO | 1635: exit 0 17:06:09 INFO - Browser initialized. 17:06:09 INFO - Running cycle 1/1 for dromaeo_css test... 17:06:09 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpsG_2Oc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:06:11 INFO - PID 1639 | 2017-04-28 17:06:11.942 plugin-container[1640:7760] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473f, name = 'com.apple.tsm.portname' 17:06:11 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:06:11 INFO - PID 1639 | 2017-04-28 17:06:11.942 plugin-container[1640:7760] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9243, name = 'com.apple.CFPasteboardClient' 17:06:11 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:06:11 INFO - PID 1639 | 2017-04-28 17:06:11.942 plugin-container[1640:7760] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:06:22 INFO - PID 1639 | RSS: Main: 250740736 17:06:22 INFO - PID 1639 | 17:09:26 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49231/tests/dromaeo/cssquery-ext.html) 17:09:28 INFO - PID 1639 | RSS: Main: 254611456 17:09:28 INFO - PID 1639 | 17:11:10 INFO - PID 1639 | ************************* 17:11:10 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 17:11:10 INFO - PID 1639 | 17:11:10 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:11:10 INFO - PID 1639 | Full stack: 17:11:10 INFO - PID 1639 | ************************* 17:11:10 INFO - PID 1639 | ************************* 17:11:10 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 17:11:10 INFO - PID 1639 | 17:11:10 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:11:10 INFO - PID 1639 | Full stack: 17:11:10 INFO - PID 1639 | ************************* 17:11:10 INFO - PID 1639 | ************************* 17:11:10 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 17:11:10 INFO - PID 1639 | 17:11:10 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:11:10 INFO - PID 1639 | Full stack: 17:11:10 INFO - PID 1639 | ************************* 17:11:10 INFO - PID 1639 | ************************* 17:11:10 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 17:11:10 INFO - PID 1639 | 17:11:10 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:11:10 INFO - PID 1639 | Full stack: 17:11:10 INFO - PID 1639 | ************************* 17:12:30 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-ext.html (next: http://localhost:49231/tests/dromaeo/cssquery-jquery.html) 17:12:30 INFO - PID 1639 | RSS: Main: 259997696 17:12:30 INFO - PID 1639 | 17:15:32 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49231/tests/dromaeo/cssquery-mootools.html) 17:15:33 INFO - PID 1639 | RSS: Main: 268869632 17:15:33 INFO - PID 1639 | 17:18:34 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49231/tests/dromaeo/cssquery-prototype.html) 17:18:35 INFO - PID 1639 | RSS: Main: 262705152 17:18:35 INFO - PID 1639 | 17:21:38 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49231/tests/dromaeo/cssquery-yui.html) 17:21:38 INFO - PID 1639 | RSS: Main: 271880192 17:21:38 INFO - PID 1639 | 17:24:40 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-yui.html (next: http://localhost:49231/tests/dromaeo/cssquery-dojo.html) 17:24:40 INFO - PID 1639 | RSS: Main: 266629120 17:24:40 INFO - PID 1639 | 17:24:40 INFO - PID 1639 | __start_tp_report 17:24:40 INFO - PID 1639 | _x_x_mozilla_page_load 17:24:40 INFO - PID 1639 | _x_x_mozilla_page_load_details 17:24:40 INFO - PID 1639 | |i|pagename|runs| 17:24:40 INFO - PID 1639 | |0;dojo.html;3573.3032058748545;3600.40593302105;3625.7824530528155;3628.6552777486145;3651.543557055368;6097.237845269341;6177.752889884405;6235.59468634539;6236.750529978801;6263.874722505559;6113.233070740836;6137.41694539018;6143.542582966816;6158.476529494998;6267.0286105653695;4544.590986811187;5382.1926710993275;5413.756380962866;5434.184872269162;5455.563554915597;4459.843905463309;4882.170031094704;5221.642372983014;5469.15228139637;5525.889482210353;5504.064309067448;5521.806736764214;5572.219889215513;5579;5591.832245032656;5354.303940487712;5413.404525502231;5413.53984911283;5446.455354464561;5473.233747275384;5418;5430.348358197002;5438.293021907166;5458.399576046631;5483.725813709339;5041.605482134118;5042.714107902498;5997.790077347272;6271.937280627227;6285.340039295899;6637.39140426339;6649.5012874034155;6672.999050142469;6684.197896252481;6699.966500167517;6485.286618471964;6503.6097834130105;6692.297308782557;6762.932370676329;6791.3548212919695;6325.335839736807;6348.269948955837;6357;6374.87250254997;6378.936210637927;6840;6873.140857392826;6895.965520172367;6910.274421185703;6921.238663746984;6359.20509936258;6473.77341793035;6523;6545.247296560861;6557.2131344239;6685.364890335411;6758.087658166085;6768.255491895888;6772.288909664513;6778.4916131289365;6610.603363798091;6612.404883560406;6644.60132392058;6662.6335551545135;6665.900011499832;5266.447023062523;6318.968405157945;6372.203474565679;6552.541322107407;6667.499937504706;5303.734813259399;5316.973415132899;5327.200919862052;5345;5347.0909945309395;4728.550787675165;4749.050189962021;4765.785539650724;4766.713997160182;4816.181249187646;6172.111569711054;6310.968445157745;6336.265038052502;6382.968085159545;6414.230292364853;6448.871022579522;6506.512011599149;6681.398674119353;6682.63245521501;6689;6376.458001069868;6377.64922929234;6397.904031439532;6401.839954001187;6414.454771344488;5877.38287479817;5884.08796636522;5902.970485147633;5904.439078287642;5913.677690078877;6575.736970521126;6781.796546103625;6862.691178897061;6943.166819981635;6957.06079679247;6694.531382803157;6720.361565651256;6744.7639332625085;6750.291219422086;6767.323267673193;6717.899231511434;6753.86492270142;6804.829879253059;6969.303069693193;6986.371226589631;6517.315681853337;6525.673716314071;6651.46788256948;6661.1340525733285;6709.899351509829;4960.50394960501;6538.934610654022;6541.476681865344;6740.561863478858;6765.086713293839;4561.270196768501;6021.729022194099;6442.2269327679105;6501.934980650322;6526.771562995273;6471.449926756279;6474.514411419068;6479.967600161969;6481.4166724994975;6514.1531600891585;6287.96856015717;6352.491800655844;6400.9039864403;6405.0392441132335;6410.294867564658;6303.180586523723;6394.136791533084;6444.162258906312;6518.967405162944;6522.5760325578685;5644.3508996465935;6254.968725156527;6363.401754873664;6382;6388.64862432552 17:24:40 INFO - PID 1639 | |1;ext.html;700.3626699703269;718.971241150354;748.0537120542512;1201.549418967887;1253.9059570532213;36956.81521592392;38030.23939521208;38188.04529886754;38222.044448888795;38243.80878095609;38277.42583861244;38575.84272471823;38580.614193858055;38638.613613863854;38700.41949370762;6539.836504087389;8025.799355016142;8322.292605128572;8574.271186949118;8586.914130858706;7335;8529.147085291452;8579.099194584573;8588.8527868033;8667.263282620985;8625.870611940825;8653.048164701877;8676.739697809076;8688.087750786171;8696.826063478728;8562.87155692662;8623.913760862373;8654.653813847439;8665.436746611513;8685.913140868573;7047.788566343018;8477.152284771473;8483;8484.533350665712;8508.064112947572;37587.43618845719;37625.05937351561;37929;37959.4306085409;37998.81000595007;3579.480975258573;3581.5702115746276;3620.25784714133;3626.3110009098186;3627.52842130524;30972.0708378749;31022.689773102207;31073.533896991565;31119.844400778078;31178.688213117806;3074.062410964655;3122.859471323773;3125.4530457169903;3138.8901388451523;3148.2758965437847;252386;254162;254193.99999999814;301713;301735;35932.820335898155;39339.803300983884;48821.511784881695;49290.75354623276;49636;49120;51329.97340053178;51399.48600514022;51421.48578514242;51433.742831286356;14152;14248.430062797579;14270.500532481521;14290;14319.78520322196;11471.713207169721;11601.419929003685;11623.76752464946;11632.767344653059;11655.417229138679;13404.396802143927;13540.526081587097;13579;13585.030170625205;13664.590062298146;4768.47546769855;5071.340725705634;5971.970140149271;6007.489363404072;6024.427679370453;1025.733309339562;1032.2670903658336;1038.9272750907514;1068.7808999155154;1077.6820837852852;23638.29085127449;24602;24990.625140622906;25026.998920042995;25037.12370067039;1043.8882590041733;1129.5820546397886;1137.9886201137883;1162.6221478019677;1166.9066474682006;12370.010399168044;12793;16743.83256167447;16767.49092581649;16812.663746724997;275869;311986;315084;315303;315305.42347288114;58149.709251453474;58280;58549.70725146347;58596.12105818416;58736.412635873094;1393.9651508712363;1464.3849583175092;1515.1590867068662;1522.292134157622;1543.5600853756625;23809.69046702448;30141.246468838453;30506.932257370885;31176;31206.531902021943;34542;39607.40588891111;39644.810655680376;39654.603453965094;39700.602993970846;295123;297425;297455;298086;298484;32016.67983320137;32290.83854580712;34357;35280.29439411249;35325;43496.782516087216;44097.55902440935;45629;45634.315485267085;45953.77023114863;30230.54654180233;30735.538966915963;31119.377612448076;31190;31499.52750708787;27002.594961075207;29361.85319073391;30445.23886902845;31505;31748;37650.62349376471;37678.2464350706;37938.051548711504;37973.62026379811;38013;25569.872150639127;29785.25536861596;31162;31796.6820331803;31904.202394939384;20292.782433054064;22820.543589127792;24650;24770.90363704537;25010.62484062777 17:24:40 INFO - PID 1639 | |2;jquery.html;426.2859709985773;433.26777745609917;435.8060663004963;451.3500559194761;452.72157623061815;17936.74442789005;18273.634527309463;21636.567268654617;21924;22657;21553.56892862142;22210.66683999741;22437.55124897501;22510;23043;6099.48154406876;6219.626822390648;6291.748330066792;6299.77950771723;6301.684915754194;6013.308469526017;6134.631922084667;6214.502839772807;6217.409346112135;6237.968810155943;5982.3120341160675;6101.206843110412;6150.815475535741;6168.814935551918;6169.074638804171;6081.969590152043;6179.443850053494;6179.62922224668;6181.721822517998;6195.721192546323;5308.628396012281;5445.945540544583;5487.067198576252;5488.780448782044;5548.528375088123;26787.46425071488;26899;27575.31061723453;28173.85913070442;28444.862205511756;2217.7449593296815;2261.694671219397;2286.542691461698;2291.5302363015544;2293.461036656401;17844.821551784575;17959.102044897703;18046.548836279067;18662.440126796217;18764.15561299745;2158.902849371782;2173.250228671106;2205.514786746913;2217.84475086744;2223.9332820015425;160012;161131;162306;163206;165238.9999999988;12736.61790146297;12768.425420856083;12778.744425111632;12877.806832897513;13035.869641303465;16465.25906334218;16625.418110366078;16626.085565293783;16668;16688.165591720608;7525.059367579052;7719.729809456642;8803.339749518811;9126.908730912606;9213.36072251457;8602.096779838152;8602.569871506399;8616.655333786579;8764.780880477912;8798.12018798115;7739.3034626883855;8014.719484818003;8174.632141553644;8236.341092712568;8244.340452763885;2172.9565408692056;2176.4450065233264;2177.4882902517897;2190.299104286613;2216.5345277491583;933.6498812945146;952.2857856607544;958.9712308630752;970.8106919150698;997.0677416615488;16305.918470407572;21143.154273829183;21657.0108334955;21759.803210823557;21780.673289900664;864.9394542382099;945.7304668169538;972.6644307713827;987.3779518903045;1000.0149852395384;8976.41653292534;10883.96602322778;11228.775424491465;11234.49444774987;11775.705607359885;450106;550458;552161;555086;557120;107069;120915;131946;132801.33599332135;134345;1147.3058799426394;1170;1199.6221190325193;1227.7053507158396;1254.2599866079056;22270.443238919157;22509.77490225142;23058.884705576365;23369.883150584137;23413.297601071994;48094.75952620214;55767.605809853456;56791;57147.14279285731;57292.71353643205;76475.47049059099;76589;77584.61207693926;85895;88987;8942.239909607757;9005.729828105168;9220.585073671567;9455.290853185901;9499.287553433382;14494.492692755703;14584.854151458774;14719;14839.554813355617;14959.177245251616;11875.465604047666;12036.217645852992;12173.939130304292;12220.816687749513;12287.56993505223;11519.963203311741;11546.307221566733;11582.015528680156;11724.296542207154;11961.760764784829;10465.790684186426;12139.635810925687;12245.57140500114;12394.81407778866;12458.439370228543;8896.911030889867;8942.86585701202;9142.663097758865;9194.241727481862;9334.06659334061;12059.095567832272;12171.208871423329;12296.81554776697;12455;12507.499700011898 17:24:40 INFO - PID 1639 | |3;mootools.html;1227.6255741998689;1427.7358688642596;1446.2479510654466;1451.2671101093947;1457.4680241711778;11379.544818207272;11751.471183796728;11919.284842909421;11919.880801191995;12130.69673258169;11548.711282217948;11615.6515304541;11721.179517433782;11751.412429378519;11753.823692644595;2498.925032249035;2514.107491840398;2540.1490500682294;2543.135333986444;2552.1705445730117;2448.534778392109;2509.2723110297993;2520.1053625962795;2522.8612426316545;2526.5325914705836;2482.7641374069435;2504.874756262189;2537.1120107962156;2537.314924970257;2550.5791544395247;2458.9877050614723;2490.10356271742;2512.484940587175;2517.4335774450783;2525.7979361651114;2415.867127307997;2475.047106863852;2476.938076548083;2484.527939691453;2521.369657585604;11601.477933493014;11646.301221926711;11724.472398742077;11734.471948762326;11739.47172377245;6583.637899915503;6831.316868313179;6838.760643377458;6844.110265665481;6848.075509806162;3979.80100994952;4318.201132790444;4368.082702632451;4405.185040767468;4411.823527058914;1262.513932136125;1264.8988080953502;1298.681822953373;1304.4195333076784;1310.6002669185893;16154.919225403915;16457.588560285967;16462.917685411616;16634;16704.6659066818;5501.064818980783;5574.470425309589;5710.257666503411;5739.16782066598;5744.655320680772;11794.823077653842;11804.350760708072;11821.586244481401;11842.940785296018;11893.70265743346;6498.960166373357;6499.122618446449;6517.804465866032;6520.739170433129;6531.0856480092825;5212.713300768494;5660.009498337775;5863.149843272701;6162.352952939877;6229.221347331583;4237.87286381409;4379.824807007748;4381.058072514425;4402.713823601455;4404.075144219686;1275.2412314672754;1290.2581015915887;1299.740051989606;1306.3468265867066;1306.9542566010334;795.8527672380628;813.5118928642767;825.2119226139023;832.6835802395051;856.259335674637;5137.717425541631;6148.262208534915;6235.345288744707;6274.717637706223;6426;492.21491720705416;516.176698166424;551.8576546548605;609.8688781911821;616.8396216983616;3764.9458151717563;3827.8468861245797;4239.872803815891;4505.166544189336;4543.477500087466;11907.34509601963;14944.252787360589;15080.924595376953;15237.466688665843;15347.693046139015;14960.102393856403;15107.848921510864;15229.6192595186;15338.539843804703;15341.6164595886;7642.579658118746;8613.22480976715;8760.342974276828;8797.592385218335;8809.33929955256;5635.830925072254;6704.497162712719;6838.487113466609;6883.827904302432;6948.374646281859;12447.93776031114;12456.43946022413;12696.809547856605;12701.301428421526;12703.682407939876;12694;13779.862201377859;13818.723625527635;13978.091424057404;14104.647383815487;8401.15988401155;8445.788855278668;8485.40602157843;8487.957560212159;8492.532910689972;10506.36961782265;10775.245732798629;10863;10867.184961127788;10887.782244355227;9058.547072646474;9209.677661281823;9274.814503709753;9291.581878815334;9399.295052871199;8870.822583548423;8987.056359082202;9042.819143617222;9091.727248182566;9108.499032553273;8166.469179503313;8210;8253.463524870864;8254.876176857471;8348.081711011906;5713.542916566747;6132.233470816148;6190.628562286277;6217.689115544114;6271.216097987752;6068.271807383142;6104.633721976696;6240.251169859646;6270.623762574261;6417.2299324081405 17:24:40 INFO - PID 1639 | |4;prototype.html;385.1046317312248;400.8915349059848;406.19370549459325;410.41310925376735;411.3993569388691;5623.43765623438;6157.938420615793;6243.625382477049;6269.404406581373;6343.587666801666;5261.6316857819875;6039.335673075969;6138.47822935051;6160.845978850531;6173.043178307369;4438.956845141389;4447.288433850585;4458.955410445903;4465.129299786543;4475.552444755531;3644.5262115924893;4333.285007973681;4432.3573081903105;4462.598366147046;4469.977650111745;3054.4501989641853;4188.958110418903;4384.758838263894;4385.254506733863;4408.779561021952;4396.274614688573;4414.624756895684;4420.292753159479;4430.556944305544;4444.644428445717;3396.3037577296595;3663.780173189617;3688.133288677159;3812.694984401241;3815.1225218199693;4573.426573426574;4870.902581948377;5037.848864534069;5150.4334523202415;5468.125099984023;4788.305695674143;5211.879445919108;5225.529702326771;5241.895162096736;5264.184051472028;6427.8393040173905;6438.227412710457;6488;6501.9349806501805;6510.674466276667;4049.5545489996075;4218.346156345771;4252.170826688797;4258.787060646955;4285.678574106954;6783.93216067838;6897.48268879836;6981.860362792767;7009.859802803966;7049.365557099885;4333.198358303724;4341.913161736811;4358.455193100863;4367.45406824147;4370.672199585044;3873.2640798248244;4432.312991486325;4440.489343725449;4452.398926144993;4454.821807127679;4819.421669399624;4989.675671081368;5228.37259528859;5250.4749525047955;5294.97352513235;6539.2479864815205;6628.767993120217;6629.602223866584;6642.169728783902;6644.66776661165;1257.5912828330647;1477.56411858502;1489.47868246113;1496.8727658148962;1499.1155218421184;897.9224930083795;908.981820363589;969.14230905649;981.1856159387692;985.6698006168014;5280.867978300574;5734.741936612779;6280.811575652738;6334.778282760081;6339.080833279241;5666.433356664301;6656.534042617067;6863.38229559342;6912.896306555405;6934.16789985205;6366.363363663597;6383.914734495147;6389.904151437732;6395.331408072134;6399.680015999182;3053.5724998500227;3502.194495266103;3576.8732849152434;3616.9510841856095;3632.766837514614;6620.635865027373;7128.251533589002;7135.785926422215;7153.4277257820295;7169.964150179216;5472.835814925479;6709.798706038827;6860.044887207222;7065.668329451269;7088.362047415757;5960.374160713236;6771.713374458738;7113.679884405215;7116.537425067354;7138;5959.791407300723;6429.32492088324;6754.425873800586;6798.2806877249495;6958.443324534126;6608.4052435281055;6677.499187560855;6780.457563395015;6785.33759228992;6869.690863911036;6128.417800308964;6512.706928188148;7057.153141622833;7206.387457065998;7220.566765994057;4288.957110428981;4372.715773474714;4393.340998850198;4402.273624851863;4409.514953355192;5766.365699773106;6172.111569711054;6517.511186660925;6913.965430172817;6933.826654333682;6554.934450655433;6652.070899439302;6683.532152749261;6683.8997415039785;6688.498362622726;5272.894542109059;6482.611043337415;6674.798536263325;6700.698468569024;6723.361280678328;5977.429705226794;6126.479249263684;6365.822322870284;6553.377429144223;6585.934140658724;5340.946590534045;5932.288125424977;6318.336574659778;6588.538802283986;6678.297034256234;6482.837929051623;6499.447546958561;6500.967495162683;6515.501434669957;6538.8365290868105 17:24:40 INFO - PID 1639 | |5;yui.html;837.9706710265142;844.2233145506134;856.7087190355281;878.222772846031;883.337496877342;16774.245158967846;17157.742633860504;17167.9699218047;18295.90852045738;19435.902820485913;17967.91016044918;18243.908780456113;18313.54216144597;18515.166817493213;18578.44264672062;998.5087207417486;1032.7211652853725;1034.8809886862994;1035.1615191694752;1042.4006196437078;989.9307048506607;1008.369768894441;1027.0140664961646;1029.768302132018;1031.4739482863756;1063.489525027987;1069.7004838645191;1069.9518521666505;1070.5396679427843;1074.5809134437575;1055.8732952045766;1061.5063995242172;1064.0264158295151;1068.6900798768386;1070.0850772589401;775.3719487215374;787.9881801772977;792.8136887831355;793.5080250244828;794.9205079492062;16877;17065.829341706547;17146.828531714647;17445.7383139253;17736.55658608532;14795.408183672747;17336.566585835328;17457.38899138524;17592.032438215894;17593.912030439893;13894.722105557934;14252.358643860947;14258;14326.34962531935;14332.211728354936;3482.3905816482015;3505.631908649606;3528.065062758371;3528.894133176009;3529.5588051493564;97921.5103924483;129764.35117824445;132971;135730;136917;13047.151935124186;13129.409176587076;13336;13368.39842207103;13384.598462046155;23857.52284954326;24876.50246995086;25147.74852251454;25393.492130157294;25458.236252912073;6958.79123626292;7063.858722825515;7949.205079492121;8062.435629505878;8069.87895181561;3808.295465338921;3872.477215575861;3911.4523966644692;4002.3396139637216;4006.539247986519;4293.849715259951;4375.27807911691;4375.431193944767;4400.581944715247;4405.625521830616;935.9251259899191;972.1348000279756;977.8044391121804;978.7895602445366;981.8232718110665;537.4679067722926;622.8446232239222;671.1677519875319;685.807973767346;699.2168770976539;10576.20678449104;11149.832752508719;11890.34603096838;11995.040396768238;11999.88000120005;609.8627808742997;633.7831363781551;666.6133642487346;667.4960404894371;689.0731965506386;5451.890962180734;5591.496765291144;5609.102543593006;5623.606347555632;5761.279840019997;98101;99414;100383.49808251057;101061.98938010525;101120;91499;91714.54142729244;93288;94668;95292;77005;78400;79759.60120199362;79995.40009199783;80229;8697.652093916173;10968.835467468154;11354.602588909349;11378.886211138113;11588.76822463529;43337;43486.782566086964;43570.12859742724;43816.12367752563;44051.67844964656;54421;54812.451875480736;54845;55367.72316138393;55482;16311.836881631032;17008.149592520076;17045;17060.23228954725;17103.315867365167;18114.728279076087;18375.448736537925;18676.43970680882;18821.6235675283;18945.33691320797;16416.261268242717;17332.133393330536;17463.650726985135;17610.031448270467;17659.823401765818;14961.102333859568;15183.772243416137;15276.847231527543;15316.923415382851;15331.233438327816;16674.49976500707;17075.975441473056;17151.57121071983;17161.914190428968;17223.311067557657;11698;11796.528138874692;11984.880151198377;12080.818787718366;12386.75226495447;12838.229706217284;13190.208587484782;13372.264525451194;13390.93304533471;13861.306934653428 17:24:40 INFO - PID 1639 | __end_tp_report 17:24:40 INFO - PID 1639 | __start_cc_report 17:24:40 INFO - PID 1639 | _x_x_mozilla_cycle_collect,420 17:24:40 INFO - PID 1639 | __end_cc_report 17:24:40 INFO - PID 1639 | __startTimestamp1493425480858__endTimestamp 17:24:40 INFO - PID 1639 | 17:24:40 INFO - PID 1639 | ------- Summary: start ------- 17:24:40 INFO - PID 1639 | Number of tests: 6 17:24:40 INFO - PID 1639 | 17:24:40 INFO - PID 1639 | [#0] dojo.html Cycles:165 Average:6100.42 Median:6378.94 stddev:749.63 (11.8%) stddev-sans-first:725.24 17:24:40 INFO - PID 1639 | Values: 3573.3 3600.4 3625.8 3628.7 3651.5 6097.2 6177.8 6235.6 6236.8 6263.9 6113.2 6137.4 6143.5 6158.5 6267.0 4544.6 5382.2 5413.8 5434.2 5455.6 4459.8 4882.2 5221.6 5469.2 5525.9 5504.1 5521.8 5572.2 5579.0 5591.8 5354.3 5413.4 5413.5 5446.5 5473.2 5418.0 5430.3 5438.3 5458.4 5483.7 5041.6 5042.7 5997.8 6271.9 6285.3 6637.4 6649.5 6673.0 6684.2 6700.0 6485.3 6503.6 6692.3 6762.9 6791.4 6325.3 6348.3 6357.0 6374.9 6378.9 6840.0 6873.1 6896.0 6910.3 6921.2 6359.2 6473.8 6523.0 6545.2 6557.2 6685.4 6758.1 6768.3 6772.3 6778.5 6610.6 6612.4 6644.6 6662.6 6665.9 5266.4 6319.0 6372.2 6552.5 6667.5 5303.7 5317.0 5327.2 5345.0 5347.1 4728.6 4749.1 4765.8 4766.7 4816.2 6172.1 6311.0 6336.3 6383.0 6414.2 6448.9 6506.5 6681.4 6682.6 6689.0 6376.5 6377.6 6397.9 6401.8 6414.5 5877.4 5884.1 5903.0 5904.4 5913.7 6575.7 6781.8 6862.7 6943.2 6957.1 6694.5 6720.4 6744.8 6750.3 6767.3 6717.9 6753.9 6804.8 6969.3 6986.4 6517.3 6525.7 6651.5 6661.1 6709.9 4960.5 6538.9 6541.5 6740.6 6765.1 4561.3 6021.7 6442.2 6501.9 6526.8 6471.4 6474.5 6480.0 6481.4 6514.2 6288.0 6352.5 6400.9 6405.0 6410.3 6303.2 6394.1 6444.2 6519.0 6522.6 5644.4 6255.0 6363.4 6382.0 6388.6 17:24:40 INFO - PID 1639 | 17:24:40 INFO - PID 1639 | [#1] ext.html Cycles:180 Average:44814.96 Median:31850.44 stddev:76740.67 (240.9%) stddev-sans-first:76884.47 17:24:40 INFO - PID 1639 | Values: 700.4 719.0 748.1 1201.5 1253.9 36956.8 38030.2 38188.0 38222.0 38243.8 38277.4 38575.8 38580.6 38638.6 38700.4 6539.8 8025.8 8322.3 8574.3 8586.9 7335.0 8529.1 8579.1 8588.9 8667.3 8625.9 8653.0 8676.7 8688.1 8696.8 8562.9 8623.9 8654.7 8665.4 8685.9 7047.8 8477.2 8483.0 8484.5 8508.1 37587.4 37625.1 37929.0 37959.4 37998.8 3579.5 3581.6 3620.3 3626.3 3627.5 30972.1 31022.7 31073.5 31119.8 31178.7 3074.1 3122.9 3125.5 3138.9 3148.3 252386.0 254162.0 254194.0 301713.0 301735.0 35932.8 39339.8 48821.5 49290.8 49636.0 49120.0 51330.0 51399.5 51421.5 51433.7 14152.0 14248.4 14270.5 14290.0 14319.8 11471.7 11601.4 11623.8 11632.8 11655.4 13404.4 13540.5 13579.0 13585.0 13664.6 4768.5 5071.3 5972.0 6007.5 6024.4 1025.7 1032.3 1038.9 1068.8 1077.7 23638.3 24602.0 24990.6 25027.0 25037.1 1043.9 1129.6 1138.0 1162.6 1166.9 12370.0 12793.0 16743.8 16767.5 16812.7 275869.0 311986.0 315084.0 315303.0 315305.4 58149.7 58280.0 58549.7 58596.1 58736.4 1394.0 1464.4 1515.2 1522.3 1543.6 23809.7 30141.2 30506.9 31176.0 31206.5 34542.0 39607.4 39644.8 39654.6 39700.6 295123.0 297425.0 297455.0 298086.0 298484.0 32016.7 32290.8 34357.0 35280.3 35325.0 43496.8 44097.6 45629.0 45634.3 45953.8 30230.5 30735.5 31119.4 31190.0 31499.5 27002.6 29361.9 30445.2 31505.0 31748.0 37650.6 37678.2 37938.1 37973.6 38013.0 25569.9 29785.3 31162.0 31796.7 31904.2 20292.8 22820.5 24650.0 24770.9 25010.6 17:24:40 INFO - PID 1639 | 17:24:40 INFO - PID 1639 | [#2] jquery.html Cycles:180 Average:35443.25 Median:12668.23 stddev:91133.13 (719.4%) stddev-sans-first:91350.85 17:24:40 INFO - PID 1639 | Values: 426.3 433.3 435.8 451.4 452.7 17936.7 18273.6 21636.6 21924.0 22657.0 21553.6 22210.7 22437.6 22510.0 23043.0 6099.5 6219.6 6291.7 6299.8 6301.7 6013.3 6134.6 6214.5 6217.4 6238.0 5982.3 6101.2 6150.8 6168.8 6169.1 6082.0 6179.4 6179.6 6181.7 6195.7 5308.6 5445.9 5487.1 5488.8 5548.5 26787.5 26899.0 27575.3 28173.9 28444.9 2217.7 2261.7 2286.5 2291.5 2293.5 17844.8 17959.1 18046.5 18662.4 18764.2 2158.9 2173.3 2205.5 2217.8 2223.9 160012.0 161131.0 162306.0 163206.0 165239.0 12736.6 12768.4 12778.7 12877.8 13035.9 16465.3 16625.4 16626.1 16668.0 16688.2 7525.1 7719.7 8803.3 9126.9 9213.4 8602.1 8602.6 8616.7 8764.8 8798.1 7739.3 8014.7 8174.6 8236.3 8244.3 2173.0 2176.4 2177.5 2190.3 2216.5 933.6 952.3 959.0 970.8 997.1 16305.9 21143.2 21657.0 21759.8 21780.7 864.9 945.7 972.7 987.4 1000.0 8976.4 10884.0 11228.8 11234.5 11775.7 450106.0 550458.0 552161.0 555086.0 557120.0 107069.0 120915.0 131946.0 132801.3 134345.0 1147.3 1170.0 1199.6 1227.7 1254.3 22270.4 22509.8 23058.9 23369.9 23413.3 48094.8 55767.6 56791.0 57147.1 57292.7 76475.5 76589.0 77584.6 85895.0 88987.0 8942.2 9005.7 9220.6 9455.3 9499.3 14494.5 14584.9 14719.0 14839.6 14959.2 11875.5 12036.2 12173.9 12220.8 12287.6 11520.0 11546.3 11582.0 11724.3 11961.8 10465.8 12139.6 12245.6 12394.8 12458.4 8896.9 8942.9 9142.7 9194.2 9334.1 12059.1 12171.2 12296.8 12455.0 12507.5 17:24:40 INFO - PID 1639 | 17:24:40 INFO - PID 1639 | [#3] mootools.html Cycles:180 Average:7062.43 Median:3796.40 stddev:4476.91 (117.9%) stddev-sans-first:4467.99 17:24:40 INFO - PID 1639 | Values: 1227.6 1427.7 1446.2 1451.3 1457.5 11379.5 11751.5 11919.3 11919.9 12130.7 11548.7 11615.7 11721.2 11751.4 11753.8 2498.9 2514.1 2540.1 2543.1 2552.2 2448.5 2509.3 2520.1 2522.9 2526.5 2482.8 2504.9 2537.1 2537.3 2550.6 2459.0 2490.1 2512.5 2517.4 2525.8 2415.9 2475.0 2476.9 2484.5 2521.4 11601.5 11646.3 11724.5 11734.5 11739.5 6583.6 6831.3 6838.8 6844.1 6848.1 3979.8 4318.2 4368.1 4405.2 4411.8 1262.5 1264.9 1298.7 1304.4 1310.6 16154.9 16457.6 16462.9 16634.0 16704.7 5501.1 5574.5 5710.3 5739.2 5744.7 11794.8 11804.4 11821.6 11842.9 11893.7 6499.0 6499.1 6517.8 6520.7 6531.1 5212.7 5660.0 5863.1 6162.4 6229.2 4237.9 4379.8 4381.1 4402.7 4404.1 1275.2 1290.3 1299.7 1306.3 1307.0 795.9 813.5 825.2 832.7 856.3 5137.7 6148.3 6235.3 6274.7 6426.0 492.2 516.2 551.9 609.9 616.8 3764.9 3827.8 4239.9 4505.2 4543.5 11907.3 14944.3 15080.9 15237.5 15347.7 14960.1 15107.8 15229.6 15338.5 15341.6 7642.6 8613.2 8760.3 8797.6 8809.3 5635.8 6704.5 6838.5 6883.8 6948.4 12447.9 12456.4 12696.8 12701.3 12703.7 12694.0 13779.9 13818.7 13978.1 14104.6 8401.2 8445.8 8485.4 8488.0 8492.5 10506.4 10775.2 10863.0 10867.2 10887.8 9058.5 9209.7 9274.8 9291.6 9399.3 8870.8 8987.1 9042.8 9091.7 9108.5 8166.5 8210.0 8253.5 8254.9 8348.1 5713.5 6132.2 6190.6 6217.7 6271.2 6068.3 6104.6 6240.3 6270.6 6417.2 17:24:40 INFO - PID 1639 | 17:24:40 INFO - PID 1639 | [#4] prototype.html Cycles:180 Average:5277.04 Median:6133.45 stddev:1719.53 (28.0%) stddev-sans-first:1684.70 17:24:40 INFO - PID 1639 | Values: 385.1 400.9 406.2 410.4 411.4 5623.4 6157.9 6243.6 6269.4 6343.6 5261.6 6039.3 6138.5 6160.8 6173.0 4439.0 4447.3 4459.0 4465.1 4475.6 3644.5 4333.3 4432.4 4462.6 4470.0 3054.5 4189.0 4384.8 4385.3 4408.8 4396.3 4414.6 4420.3 4430.6 4444.6 3396.3 3663.8 3688.1 3812.7 3815.1 4573.4 4870.9 5037.8 5150.4 5468.1 4788.3 5211.9 5225.5 5241.9 5264.2 6427.8 6438.2 6488.0 6501.9 6510.7 4049.6 4218.3 4252.2 4258.8 4285.7 6783.9 6897.5 6981.9 7009.9 7049.4 4333.2 4341.9 4358.5 4367.5 4370.7 3873.3 4432.3 4440.5 4452.4 4454.8 4819.4 4989.7 5228.4 5250.5 5295.0 6539.2 6628.8 6629.6 6642.2 6644.7 1257.6 1477.6 1489.5 1496.9 1499.1 897.9 909.0 969.1 981.2 985.7 5280.9 5734.7 6280.8 6334.8 6339.1 5666.4 6656.5 6863.4 6912.9 6934.2 6366.4 6383.9 6389.9 6395.3 6399.7 3053.6 3502.2 3576.9 3617.0 3632.8 6620.6 7128.3 7135.8 7153.4 7170.0 5472.8 6709.8 6860.0 7065.7 7088.4 5960.4 6771.7 7113.7 7116.5 7138.0 5959.8 6429.3 6754.4 6798.3 6958.4 6608.4 6677.5 6780.5 6785.3 6869.7 6128.4 6512.7 7057.2 7206.4 7220.6 4289.0 4372.7 4393.3 4402.3 4409.5 5766.4 6172.1 6517.5 6914.0 6933.8 6554.9 6652.1 6683.5 6683.9 6688.5 5272.9 6482.6 6674.8 6700.7 6723.4 5977.4 6126.5 6365.8 6553.4 6585.9 5340.9 5932.3 6318.3 6588.5 6678.3 6482.8 6499.4 6501.0 6515.5 6538.8 17:24:40 INFO - PID 1639 | 17:24:40 INFO - PID 1639 | [#5] yui.html Cycles:180 Average:21839.60 Median:18304.73 stddev:30538.92 (166.8%) stddev-sans-first:30583.87 17:24:40 INFO - PID 1639 | Values: 838.0 844.2 856.7 878.2 883.3 16774.2 17157.7 17168.0 18295.9 19435.9 17967.9 18243.9 18313.5 18515.2 18578.4 998.5 1032.7 1034.9 1035.2 1042.4 989.9 1008.4 1027.0 1029.8 1031.5 1063.5 1069.7 1070.0 1070.5 1074.6 1055.9 1061.5 1064.0 1068.7 1070.1 775.4 788.0 792.8 793.5 794.9 16877.0 17065.8 17146.8 17445.7 17736.6 14795.4 17336.6 17457.4 17592.0 17593.9 13894.7 14252.4 14258.0 14326.3 14332.2 3482.4 3505.6 3528.1 3528.9 3529.6 97921.5 129764.4 132971.0 135730.0 136917.0 13047.2 13129.4 13336.0 13368.4 13384.6 23857.5 24876.5 25147.7 25393.5 25458.2 6958.8 7063.9 7949.2 8062.4 8069.9 3808.3 3872.5 3911.5 4002.3 4006.5 4293.8 4375.3 4375.4 4400.6 4405.6 935.9 972.1 977.8 978.8 981.8 537.5 622.8 671.2 685.8 699.2 10576.2 11149.8 11890.3 11995.0 11999.9 609.9 633.8 666.6 667.5 689.1 5451.9 5591.5 5609.1 5623.6 5761.3 98101.0 99414.0 100383.5 101062.0 101120.0 91499.0 91714.5 93288.0 94668.0 95292.0 77005.0 78400.0 79759.6 79995.4 80229.0 8697.7 10968.8 11354.6 11378.9 11588.8 43337.0 43486.8 43570.1 43816.1 44051.7 54421.0 54812.5 54845.0 55367.7 55482.0 16311.8 17008.1 17045.0 17060.2 17103.3 18114.7 18375.4 18676.4 18821.6 18945.3 16416.3 17332.1 17463.7 17610.0 17659.8 14961.1 15183.8 15276.8 15316.9 15331.2 16674.5 17076.0 17151.6 17161.9 17223.3 11698.0 11796.5 11984.9 12080.8 12386.8 12838.2 13190.2 13372.3 13390.9 13861.3 17:24:40 INFO - PID 1639 | -------- Summary: end -------- 17:24:40 INFO - PID 1639 | 17:24:41 INFO - TEST-INFO | 1639: exit 0 17:24:41 INFO - TEST-OK | dromaeo_css | took 1115916ms 17:24:41 INFO - TEST-START | kraken 17:24:41 INFO - operating with platform_type : mac_ 17:24:41 INFO - Initialising browser for kraken test... 17:24:41 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpVec1WQ/profile http://localhost:49231/getInfo.html) 17:24:43 INFO - PID 1651 | 2017-04-28 17:24:43.860 plugin-container[1652:10240] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943b, name = 'com.apple.tsm.portname' 17:24:43 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:24:43 INFO - PID 1651 | 2017-04-28 17:24:43.863 plugin-container[1652:10240] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 17:24:43 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:24:43 INFO - PID 1651 | 2017-04-28 17:24:43.863 plugin-container[1652:10240] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:24:44 INFO - PID 1651 | __metrics Screen width/height:1600/1200 17:24:44 INFO - PID 1651 | colorDepth:24 17:24:44 INFO - PID 1651 | Browser inner width/height: 1024/689 17:24:44 INFO - PID 1651 | __metrics 17:24:44 ERROR - PID 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:24:45 INFO - TEST-INFO | 1651: exit 0 17:24:45 INFO - Browser initialized. 17:24:45 INFO - Running cycle 1/1 for kraken test... 17:24:45 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpVec1WQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:24:47 INFO - PID 1653 | 2017-04-28 17:24:47.338 plugin-container[1654:10367] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 17:24:47 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:24:47 INFO - PID 1653 | 2017-04-28 17:24:47.338 plugin-container[1654:10367] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 17:24:47 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:24:47 INFO - PID 1653 | 2017-04-28 17:24:47.338 plugin-container[1654:10367] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:24:57 INFO - PID 1653 | RSS: Main: 251322368 17:24:57 INFO - PID 1653 | 17:25:00 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-beat-detection) 17:25:01 INFO - PID 1653 | RSS: Main: 259391488 17:25:01 INFO - PID 1653 | 17:25:03 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-dft) 17:25:04 INFO - PID 1653 | RSS: Main: 266358784 17:25:04 INFO - PID 1653 | 17:25:07 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-fft) 17:25:08 INFO - PID 1653 | RSS: Main: 261959680 17:25:08 INFO - PID 1653 | 17:25:10 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-oscillator) 17:25:11 INFO - PID 1653 | RSS: Main: 263610368 17:25:11 INFO - PID 1653 | 17:25:13 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:25:13 INFO - PID 1653 | RSS: Main: 265355264 17:25:13 INFO - PID 1653 | 17:25:16 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-darkroom) 17:25:17 INFO - PID 1653 | RSS: Main: 271237120 17:25:17 INFO - PID 1653 | 17:25:20 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-desaturate) 17:25:20 INFO - PID 1653 | RSS: Main: 272424960 17:25:20 INFO - PID 1653 | 17:25:23 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49231/tests/kraken/driver.html?testName=json-parse-financial) 17:25:24 INFO - PID 1653 | RSS: Main: 271667200 17:25:24 INFO - PID 1653 | 17:25:25 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49231/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:25:26 INFO - PID 1653 | RSS: Main: 271134720 17:25:26 INFO - PID 1653 | 17:25:28 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:25:29 INFO - PID 1653 | RSS: Main: 271343616 17:25:29 INFO - PID 1653 | 17:25:31 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:25:31 INFO - PID 1653 | RSS: Main: 270684160 17:25:31 INFO - PID 1653 | 17:25:34 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:25:35 INFO - PID 1653 | RSS: Main: 270708736 17:25:35 INFO - PID 1653 | 17:25:38 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:25:39 INFO - PID 1653 | RSS: Main: 271069184 17:25:39 INFO - PID 1653 | 17:25:40 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49231/tests/kraken/driver.html?testName=ai-astar) 17:25:41 INFO - PID 1653 | RSS: Main: 272515072 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | __start_tp_report 17:25:41 INFO - PID 1653 | _x_x_mozilla_page_load 17:25:41 INFO - PID 1653 | _x_x_mozilla_page_load_details 17:25:41 INFO - PID 1653 | |i|pagename|runs| 17:25:41 INFO - PID 1653 | |0;ai-astar;104;115;112;113;113;115;114;114;113;115 17:25:41 INFO - PID 1653 | |1;audio-beat-detection;142;111;122;182;112;113;108;114;137;111 17:25:41 INFO - PID 1653 | |2;audio-dft;200;202;196;233;216;284;275;197;244;212 17:25:41 INFO - PID 1653 | |3;audio-fft;81;59;48;84;50;59;86;86;95;81 17:25:41 INFO - PID 1653 | |4;audio-oscillator;88;75;118;107;78;69;91;71;77;89 17:25:41 INFO - PID 1653 | |5;imaging-gaussian-blur;89;89;89;90;89;94;103;89;89;88 17:25:41 INFO - PID 1653 | |6;imaging-darkroom;122;95;96;95;96;96;97;96;96;102 17:25:41 INFO - PID 1653 | |7;imaging-desaturate;91;94;93;96;91;92;90;94;90;90 17:25:41 INFO - PID 1653 | |8;json-parse-financial;50;51;52;52;50;49;52;51;50;51 17:25:41 INFO - PID 1653 | |9;json-stringify-tinderbox;56;61;68;81;88;58;64;88;84;83 17:25:41 INFO - PID 1653 | |10;stanford-crypto-aes;81;84;80;85;84;82;82;83;85;84 17:25:41 INFO - PID 1653 | |11;stanford-crypto-ccm;150;134;134;133;156;155;136;137;182;143 17:25:41 INFO - PID 1653 | |12;stanford-crypto-pbkdf2;185;187;187;183;193;240;184;177;186;185 17:25:41 INFO - PID 1653 | |13;stanford-crypto-sha256-iterative;66;69;69;67;71;69;66;70;79;95 17:25:41 INFO - PID 1653 | __end_tp_report 17:25:41 INFO - PID 1653 | __start_cc_report 17:25:41 INFO - PID 1653 | _x_x_mozilla_cycle_collect,995 17:25:41 INFO - PID 1653 | __end_cc_report 17:25:41 INFO - PID 1653 | __startTimestamp1493425541007__endTimestamp 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | ------- Summary: start ------- 17:25:41 INFO - PID 1653 | Number of tests: 14 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:3.26 (2.9%) stddev-sans-first:1.09 17:25:41 INFO - PID 1653 | Values: 104.0 115.0 112.0 113.0 113.0 115.0 114.0 114.0 113.0 115.0 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | [#1] audio-beat-detection Cycles:10 Average:125.20 Median:118.00 stddev:23.11 (19.6%) stddev-sans-first:23.70 17:25:41 INFO - PID 1653 | Values: 142.0 111.0 122.0 182.0 112.0 113.0 108.0 114.0 137.0 111.0 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | [#2] audio-dft Cycles:10 Average:225.90 Median:224.50 stddev:32.36 (14.4%) stddev-sans-first:32.94 17:25:41 INFO - PID 1653 | Values: 200.0 202.0 196.0 233.0 216.0 284.0 275.0 197.0 244.0 212.0 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | [#3] audio-fft Cycles:10 Average:72.90 Median:82.50 stddev:17.05 (20.7%) stddev-sans-first:17.83 17:25:41 INFO - PID 1653 | Values: 81.0 59.0 48.0 84.0 50.0 59.0 86.0 86.0 95.0 81.0 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | [#4] audio-oscillator Cycles:10 Average:86.30 Median:77.50 stddev:15.92 (20.5%) stddev-sans-first:16.88 17:25:41 INFO - PID 1653 | Values: 88.0 75.0 118.0 107.0 78.0 69.0 91.0 71.0 77.0 89.0 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:90.90 Median:89.00 stddev:4.56 (5.1%) stddev-sans-first:4.78 17:25:41 INFO - PID 1653 | Values: 89.0 89.0 89.0 90.0 89.0 94.0 103.0 89.0 89.0 88.0 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | [#6] imaging-darkroom Cycles:10 Average:99.10 Median:96.00 stddev:8.29 (8.6%) stddev-sans-first:2.13 17:25:41 INFO - PID 1653 | Values: 122.0 95.0 96.0 95.0 96.0 96.0 97.0 96.0 96.0 102.0 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:92.50 stddev:2.08 (2.2%) stddev-sans-first:2.17 17:25:41 INFO - PID 1653 | Values: 91.0 94.0 93.0 96.0 91.0 92.0 90.0 94.0 90.0 90.0 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:1.03 (2.0%) stddev-sans-first:1.05 17:25:41 INFO - PID 1653 | Values: 50.0 51.0 52.0 52.0 50.0 49.0 52.0 51.0 50.0 51.0 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:82.00 stddev:12.91 (15.7%) stddev-sans-first:12.11 17:25:41 INFO - PID 1653 | Values: 56.0 61.0 68.0 81.0 88.0 58.0 64.0 88.0 84.0 83.0 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:84.00 stddev:1.70 (2.0%) stddev-sans-first:1.64 17:25:41 INFO - PID 1653 | Values: 81.0 84.0 80.0 85.0 84.0 82.0 82.0 83.0 85.0 84.0 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:146.50 stddev:15.42 (10.5%) stddev-sans-first:16.29 17:25:41 INFO - PID 1653 | Values: 150.0 134.0 134.0 133.0 156.0 155.0 136.0 137.0 182.0 143.0 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.70 Median:186.50 stddev:17.77 (9.5%) stddev-sans-first:18.73 17:25:41 INFO - PID 1653 | Values: 185.0 187.0 187.0 183.0 193.0 240.0 184.0 177.0 186.0 185.0 17:25:41 INFO - PID 1653 | 17:25:41 INFO - PID 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:69.50 stddev:8.86 (12.8%) stddev-sans-first:9.12 17:25:41 INFO - PID 1653 | Values: 66.0 69.0 69.0 67.0 71.0 69.0 66.0 70.0 79.0 95.0 17:25:41 INFO - PID 1653 | -------- Summary: end -------- 17:25:41 INFO - PID 1653 | 17:25:42 INFO - TEST-INFO | 1653: exit 0 17:25:42 INFO - TEST-OK | kraken | took 60501ms 17:25:42 INFO - SUITE-END | took 1176s 17:25:42 INFO - Completed test suite (00:19:36) 17:25:42 INFO - javascript benchmark 17:25:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7887.615064658229, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3573.3032058748545, 3600.40593302105, 3625.7824530528155, 3628.6552777486145, 3651.543557055368, 6097.237845269341, 6177.752889884405, 6235.59468634539, 6236.750529978801, 6263.874722505559, 6113.233070740836, 6137.41694539018, 6143.542582966816, 6158.476529494998, 6267.0286105653695, 4544.590986811187, 5382.1926710993275, 5413.756380962866, 5434.184872269162, 5455.563554915597, 4459.843905463309, 4882.170031094704, 5221.642372983014, 5469.15228139637, 5525.889482210353, 5504.064309067448, 5521.806736764214, 5572.219889215513, 5579.0, 5591.832245032656, 5354.303940487712, 5413.404525502231, 5413.53984911283, 5446.455354464561, 5473.233747275384, 5418.0, 5430.348358197002, 5438.293021907166, 5458.399576046631, 5483.725813709339, 5041.605482134118, 5042.714107902498, 5997.790077347272, 6271.937280627227, 6285.340039295899, 6637.39140426339, 6649.5012874034155, 6672.999050142469, 6684.197896252481, 6699.966500167517, 6485.286618471964, 6503.6097834130105, 6692.297308782557, 6762.932370676329, 6791.3548212919695, 6325.335839736807, 6348.269948955837, 6357.0, 6374.87250254997, 6378.936210637927, 6840.0, 6873.140857392826, 6895.965520172367, 6910.274421185703, 6921.238663746984, 6359.20509936258, 6473.77341793035, 6523.0, 6545.247296560861, 6557.2131344239, 6685.364890335411, 6758.087658166085, 6768.255491895888, 6772.288909664513, 6778.4916131289365, 6610.603363798091, 6612.404883560406, 6644.60132392058, 6662.6335551545135, 6665.900011499832, 5266.447023062523, 6318.968405157945, 6372.203474565679, 6552.541322107407, 6667.499937504706, 5303.734813259399, 5316.973415132899, 5327.200919862052, 5345.0, 5347.0909945309395, 4728.550787675165, 4749.050189962021, 4765.785539650724, 4766.713997160182, 4816.181249187646, 6172.111569711054, 6310.968445157745, 6336.265038052502, 6382.968085159545, 6414.230292364853, 6448.871022579522, 6506.512011599149, 6681.398674119353, 6682.63245521501, 6689.0, 6376.458001069868, 6377.64922929234, 6397.904031439532, 6401.839954001187, 6414.454771344488, 5877.38287479817, 5884.08796636522, 5902.970485147633, 5904.439078287642, 5913.677690078877, 6575.736970521126, 6781.796546103625, 6862.691178897061, 6943.166819981635, 6957.06079679247, 6694.531382803157, 6720.361565651256, 6744.7639332625085, 6750.291219422086, 6767.323267673193, 6717.899231511434, 6753.86492270142, 6804.829879253059, 6969.303069693193, 6986.371226589631, 6517.315681853337, 6525.673716314071, 6651.46788256948, 6661.1340525733285, 6709.899351509829, 4960.50394960501, 6538.934610654022, 6541.476681865344, 6740.561863478858, 6765.086713293839, 4561.270196768501, 6021.729022194099, 6442.2269327679105, 6501.934980650322, 6526.771562995273, 6471.449926756279, 6474.514411419068, 6479.967600161969, 6481.4166724994975, 6514.1531600891585, 6287.96856015717, 6352.491800655844, 6400.9039864403, 6405.0392441132335, 6410.294867564658, 6303.180586523723, 6394.136791533084, 6444.162258906312, 6518.967405162944, 6522.5760325578685, 5644.3508996465935, 6254.968725156527, 6363.401754873664, 6382.0, 6388.64862432552], "value": 6052.9633493130905, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.3626699703269, 718.971241150354, 748.0537120542512, 1201.549418967887, 1253.9059570532213, 36956.81521592392, 38030.23939521208, 38188.04529886754, 38222.044448888795, 38243.80878095609, 38277.42583861244, 38575.84272471823, 38580.614193858055, 38638.613613863854, 38700.41949370762, 6539.836504087389, 8025.799355016142, 8322.292605128572, 8574.271186949118, 8586.914130858706, 7335.0, 8529.147085291452, 8579.099194584573, 8588.8527868033, 8667.263282620985, 8625.870611940825, 8653.048164701877, 8676.739697809076, 8688.087750786171, 8696.826063478728, 8562.87155692662, 8623.913760862373, 8654.653813847439, 8665.436746611513, 8685.913140868573, 7047.788566343018, 8477.152284771473, 8483.0, 8484.533350665712, 8508.064112947572, 37587.43618845719, 37625.05937351561, 37929.0, 37959.4306085409, 37998.81000595007, 3579.480975258573, 3581.5702115746276, 3620.25784714133, 3626.3110009098186, 3627.52842130524, 30972.0708378749, 31022.689773102207, 31073.533896991565, 31119.844400778078, 31178.688213117806, 3074.062410964655, 3122.859471323773, 3125.4530457169903, 3138.8901388451523, 3148.2758965437847, 252386.0, 254162.0, 254193.99999999814, 301713.0, 301735.0, 35932.820335898155, 39339.803300983884, 48821.511784881695, 49290.75354623276, 49636.0, 49120.0, 51329.97340053178, 51399.48600514022, 51421.48578514242, 51433.742831286356, 14152.0, 14248.430062797579, 14270.500532481521, 14290.0, 14319.78520322196, 11471.713207169721, 11601.419929003685, 11623.76752464946, 11632.767344653059, 11655.417229138679, 13404.396802143927, 13540.526081587097, 13579.0, 13585.030170625205, 13664.590062298146, 4768.47546769855, 5071.340725705634, 5971.970140149271, 6007.489363404072, 6024.427679370453, 1025.733309339562, 1032.2670903658336, 1038.9272750907514, 1068.7808999155154, 1077.6820837852852, 23638.29085127449, 24602.0, 24990.625140622906, 25026.998920042995, 25037.12370067039, 1043.8882590041733, 1129.5820546397886, 1137.9886201137883, 1162.6221478019677, 1166.9066474682006, 12370.010399168044, 12793.0, 16743.83256167447, 16767.49092581649, 16812.663746724997, 275869.0, 311986.0, 315084.0, 315303.0, 315305.42347288114, 58149.709251453474, 58280.0, 58549.70725146347, 58596.12105818416, 58736.412635873094, 1393.9651508712363, 1464.3849583175092, 1515.1590867068662, 1522.292134157622, 1543.5600853756625, 23809.69046702448, 30141.246468838453, 30506.932257370885, 31176.0, 31206.531902021943, 34542.0, 39607.40588891111, 39644.810655680376, 39654.603453965094, 39700.602993970846, 295123.0, 297425.0, 297455.0, 298086.0, 298484.0, 32016.67983320137, 32290.83854580712, 34357.0, 35280.29439411249, 35325.0, 43496.782516087216, 44097.55902440935, 45629.0, 45634.315485267085, 45953.77023114863, 30230.54654180233, 30735.538966915963, 31119.377612448076, 31190.0, 31499.52750708787, 27002.594961075207, 29361.85319073391, 30445.23886902845, 31505.0, 31748.0, 37650.62349376471, 37678.2464350706, 37938.051548711504, 37973.62026379811, 38013.0, 25569.872150639127, 29785.25536861596, 31162.0, 31796.6820331803, 31904.202394939384, 20292.782433054064, 22820.543589127792, 24650.0, 24770.90363704537, 25010.62484062777], "value": 17704.970858573528, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.2859709985773, 433.26777745609917, 435.8060663004963, 451.3500559194761, 452.72157623061815, 17936.74442789005, 18273.634527309463, 21636.567268654617, 21924.0, 22657.0, 21553.56892862142, 22210.66683999741, 22437.55124897501, 22510.0, 23043.0, 6099.48154406876, 6219.626822390648, 6291.748330066792, 6299.77950771723, 6301.684915754194, 6013.308469526017, 6134.631922084667, 6214.502839772807, 6217.409346112135, 6237.968810155943, 5982.3120341160675, 6101.206843110412, 6150.815475535741, 6168.814935551918, 6169.074638804171, 6081.969590152043, 6179.443850053494, 6179.62922224668, 6181.721822517998, 6195.721192546323, 5308.628396012281, 5445.945540544583, 5487.067198576252, 5488.780448782044, 5548.528375088123, 26787.46425071488, 26899.0, 27575.31061723453, 28173.85913070442, 28444.862205511756, 2217.7449593296815, 2261.694671219397, 2286.542691461698, 2291.5302363015544, 2293.461036656401, 17844.821551784575, 17959.102044897703, 18046.548836279067, 18662.440126796217, 18764.15561299745, 2158.902849371782, 2173.250228671106, 2205.514786746913, 2217.84475086744, 2223.9332820015425, 160012.0, 161131.0, 162306.0, 163206.0, 165238.9999999988, 12736.61790146297, 12768.425420856083, 12778.744425111632, 12877.806832897513, 13035.869641303465, 16465.25906334218, 16625.418110366078, 16626.085565293783, 16668.0, 16688.165591720608, 7525.059367579052, 7719.729809456642, 8803.339749518811, 9126.908730912606, 9213.36072251457, 8602.096779838152, 8602.569871506399, 8616.655333786579, 8764.780880477912, 8798.12018798115, 7739.3034626883855, 8014.719484818003, 8174.632141553644, 8236.341092712568, 8244.340452763885, 2172.9565408692056, 2176.4450065233264, 2177.4882902517897, 2190.299104286613, 2216.5345277491583, 933.6498812945146, 952.2857856607544, 958.9712308630752, 970.8106919150698, 997.0677416615488, 16305.918470407572, 21143.154273829183, 21657.0108334955, 21759.803210823557, 21780.673289900664, 864.9394542382099, 945.7304668169538, 972.6644307713827, 987.3779518903045, 1000.0149852395384, 8976.41653292534, 10883.96602322778, 11228.775424491465, 11234.49444774987, 11775.705607359885, 450106.0, 550458.0, 552161.0, 555086.0, 557120.0, 107069.0, 120915.0, 131946.0, 132801.33599332135, 134345.0, 1147.3058799426394, 1170.0, 1199.6221190325193, 1227.7053507158396, 1254.2599866079056, 22270.443238919157, 22509.77490225142, 23058.884705576365, 23369.883150584137, 23413.297601071994, 48094.75952620214, 55767.605809853456, 56791.0, 57147.14279285731, 57292.71353643205, 76475.47049059099, 76589.0, 77584.61207693926, 85895.0, 88987.0, 8942.239909607757, 9005.729828105168, 9220.585073671567, 9455.290853185901, 9499.287553433382, 14494.492692755703, 14584.854151458774, 14719.0, 14839.554813355617, 14959.177245251616, 11875.465604047666, 12036.217645852992, 12173.939130304292, 12220.816687749513, 12287.56993505223, 11519.963203311741, 11546.307221566733, 11582.015528680156, 11724.296542207154, 11961.760764784829, 10465.790684186426, 12139.635810925687, 12245.57140500114, 12394.81407778866, 12458.439370228543, 8896.911030889867, 8942.86585701202, 9142.663097758865, 9194.241727481862, 9334.06659334061, 12059.095567832272, 12171.208871423329, 12296.81554776697, 12455.0, 12507.499700011898], "value": 10586.282478687097, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1227.6255741998689, 1427.7358688642596, 1446.2479510654466, 1451.2671101093947, 1457.4680241711778, 11379.544818207272, 11751.471183796728, 11919.284842909421, 11919.880801191995, 12130.69673258169, 11548.711282217948, 11615.6515304541, 11721.179517433782, 11751.412429378519, 11753.823692644595, 2498.925032249035, 2514.107491840398, 2540.1490500682294, 2543.135333986444, 2552.1705445730117, 2448.534778392109, 2509.2723110297993, 2520.1053625962795, 2522.8612426316545, 2526.5325914705836, 2482.7641374069435, 2504.874756262189, 2537.1120107962156, 2537.314924970257, 2550.5791544395247, 2458.9877050614723, 2490.10356271742, 2512.484940587175, 2517.4335774450783, 2525.7979361651114, 2415.867127307997, 2475.047106863852, 2476.938076548083, 2484.527939691453, 2521.369657585604, 11601.477933493014, 11646.301221926711, 11724.472398742077, 11734.471948762326, 11739.47172377245, 6583.637899915503, 6831.316868313179, 6838.760643377458, 6844.110265665481, 6848.075509806162, 3979.80100994952, 4318.201132790444, 4368.082702632451, 4405.185040767468, 4411.823527058914, 1262.513932136125, 1264.8988080953502, 1298.681822953373, 1304.4195333076784, 1310.6002669185893, 16154.919225403915, 16457.588560285967, 16462.917685411616, 16634.0, 16704.6659066818, 5501.064818980783, 5574.470425309589, 5710.257666503411, 5739.16782066598, 5744.655320680772, 11794.823077653842, 11804.350760708072, 11821.586244481401, 11842.940785296018, 11893.70265743346, 6498.960166373357, 6499.122618446449, 6517.804465866032, 6520.739170433129, 6531.0856480092825, 5212.713300768494, 5660.009498337775, 5863.149843272701, 6162.352952939877, 6229.221347331583, 4237.87286381409, 4379.824807007748, 4381.058072514425, 4402.713823601455, 4404.075144219686, 1275.2412314672754, 1290.2581015915887, 1299.740051989606, 1306.3468265867066, 1306.9542566010334, 795.8527672380628, 813.5118928642767, 825.2119226139023, 832.6835802395051, 856.259335674637, 5137.717425541631, 6148.262208534915, 6235.345288744707, 6274.717637706223, 6426.0, 492.21491720705416, 516.176698166424, 551.8576546548605, 609.8688781911821, 616.8396216983616, 3764.9458151717563, 3827.8468861245797, 4239.872803815891, 4505.166544189336, 4543.477500087466, 11907.34509601963, 14944.252787360589, 15080.924595376953, 15237.466688665843, 15347.693046139015, 14960.102393856403, 15107.848921510864, 15229.6192595186, 15338.539843804703, 15341.6164595886, 7642.579658118746, 8613.22480976715, 8760.342974276828, 8797.592385218335, 8809.33929955256, 5635.830925072254, 6704.497162712719, 6838.487113466609, 6883.827904302432, 6948.374646281859, 12447.93776031114, 12456.43946022413, 12696.809547856605, 12701.301428421526, 12703.682407939876, 12694.0, 13779.862201377859, 13818.723625527635, 13978.091424057404, 14104.647383815487, 8401.15988401155, 8445.788855278668, 8485.40602157843, 8487.957560212159, 8492.532910689972, 10506.36961782265, 10775.245732798629, 10863.0, 10867.184961127788, 10887.782244355227, 9058.547072646474, 9209.677661281823, 9274.814503709753, 9291.581878815334, 9399.295052871199, 8870.822583548423, 8987.056359082202, 9042.819143617222, 9091.727248182566, 9108.499032553273, 8166.469179503313, 8210.0, 8253.463524870864, 8254.876176857471, 8348.081711011906, 5713.542916566747, 6132.233470816148, 6190.628562286277, 6217.689115544114, 6271.216097987752, 6068.271807383142, 6104.633721976696, 6240.251169859646, 6270.623762574261, 6417.2299324081405], "value": 5299.290890705147, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.1046317312248, 400.8915349059848, 406.19370549459325, 410.41310925376735, 411.3993569388691, 5623.43765623438, 6157.938420615793, 6243.625382477049, 6269.404406581373, 6343.587666801666, 5261.6316857819875, 6039.335673075969, 6138.47822935051, 6160.845978850531, 6173.043178307369, 4438.956845141389, 4447.288433850585, 4458.955410445903, 4465.129299786543, 4475.552444755531, 3644.5262115924893, 4333.285007973681, 4432.3573081903105, 4462.598366147046, 4469.977650111745, 3054.4501989641853, 4188.958110418903, 4384.758838263894, 4385.254506733863, 4408.779561021952, 4396.274614688573, 4414.624756895684, 4420.292753159479, 4430.556944305544, 4444.644428445717, 3396.3037577296595, 3663.780173189617, 3688.133288677159, 3812.694984401241, 3815.1225218199693, 4573.426573426574, 4870.902581948377, 5037.848864534069, 5150.4334523202415, 5468.125099984023, 4788.305695674143, 5211.879445919108, 5225.529702326771, 5241.895162096736, 5264.184051472028, 6427.8393040173905, 6438.227412710457, 6488.0, 6501.9349806501805, 6510.674466276667, 4049.5545489996075, 4218.346156345771, 4252.170826688797, 4258.787060646955, 4285.678574106954, 6783.93216067838, 6897.48268879836, 6981.860362792767, 7009.859802803966, 7049.365557099885, 4333.198358303724, 4341.913161736811, 4358.455193100863, 4367.45406824147, 4370.672199585044, 3873.2640798248244, 4432.312991486325, 4440.489343725449, 4452.398926144993, 4454.821807127679, 4819.421669399624, 4989.675671081368, 5228.37259528859, 5250.4749525047955, 5294.97352513235, 6539.2479864815205, 6628.767993120217, 6629.602223866584, 6642.169728783902, 6644.66776661165, 1257.5912828330647, 1477.56411858502, 1489.47868246113, 1496.8727658148962, 1499.1155218421184, 897.9224930083795, 908.981820363589, 969.14230905649, 981.1856159387692, 985.6698006168014, 5280.867978300574, 5734.741936612779, 6280.811575652738, 6334.778282760081, 6339.080833279241, 5666.433356664301, 6656.534042617067, 6863.38229559342, 6912.896306555405, 6934.16789985205, 6366.363363663597, 6383.914734495147, 6389.904151437732, 6395.331408072134, 6399.680015999182, 3053.5724998500227, 3502.194495266103, 3576.8732849152434, 3616.9510841856095, 3632.766837514614, 6620.635865027373, 7128.251533589002, 7135.785926422215, 7153.4277257820295, 7169.964150179216, 5472.835814925479, 6709.798706038827, 6860.044887207222, 7065.668329451269, 7088.362047415757, 5960.374160713236, 6771.713374458738, 7113.679884405215, 7116.537425067354, 7138.0, 5959.791407300723, 6429.32492088324, 6754.425873800586, 6798.2806877249495, 6958.443324534126, 6608.4052435281055, 6677.499187560855, 6780.457563395015, 6785.33759228992, 6869.690863911036, 6128.417800308964, 6512.706928188148, 7057.153141622833, 7206.387457065998, 7220.566765994057, 4288.957110428981, 4372.715773474714, 4393.340998850198, 4402.273624851863, 4409.514953355192, 5766.365699773106, 6172.111569711054, 6517.511186660925, 6913.965430172817, 6933.826654333682, 6554.934450655433, 6652.070899439302, 6683.532152749261, 6683.8997415039785, 6688.498362622726, 5272.894542109059, 6482.611043337415, 6674.798536263325, 6700.698468569024, 6723.361280678328, 5977.429705226794, 6126.479249263684, 6365.822322870284, 6553.377429144223, 6585.934140658724, 5340.946590534045, 5932.288125424977, 6318.336574659778, 6588.538802283986, 6678.297034256234, 6482.837929051623, 6499.447546958561, 6500.967495162683, 6515.501434669957, 6538.8365290868105], "value": 4735.453999917979, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [837.9706710265142, 844.2233145506134, 856.7087190355281, 878.222772846031, 883.337496877342, 16774.245158967846, 17157.742633860504, 17167.9699218047, 18295.90852045738, 19435.902820485913, 17967.91016044918, 18243.908780456113, 18313.54216144597, 18515.166817493213, 18578.44264672062, 998.5087207417486, 1032.7211652853725, 1034.8809886862994, 1035.1615191694752, 1042.4006196437078, 989.9307048506607, 1008.369768894441, 1027.0140664961646, 1029.768302132018, 1031.4739482863756, 1063.489525027987, 1069.7004838645191, 1069.9518521666505, 1070.5396679427843, 1074.5809134437575, 1055.8732952045766, 1061.5063995242172, 1064.0264158295151, 1068.6900798768386, 1070.0850772589401, 775.3719487215374, 787.9881801772977, 792.8136887831355, 793.5080250244828, 794.9205079492062, 16877.0, 17065.829341706547, 17146.828531714647, 17445.7383139253, 17736.55658608532, 14795.408183672747, 17336.566585835328, 17457.38899138524, 17592.032438215894, 17593.912030439893, 13894.722105557934, 14252.358643860947, 14258.0, 14326.34962531935, 14332.211728354936, 3482.3905816482015, 3505.631908649606, 3528.065062758371, 3528.894133176009, 3529.5588051493564, 97921.5103924483, 129764.35117824445, 132971.0, 135730.0, 136917.0, 13047.151935124186, 13129.409176587076, 13336.0, 13368.39842207103, 13384.598462046155, 23857.52284954326, 24876.50246995086, 25147.74852251454, 25393.492130157294, 25458.236252912073, 6958.79123626292, 7063.858722825515, 7949.205079492121, 8062.435629505878, 8069.87895181561, 3808.295465338921, 3872.477215575861, 3911.4523966644692, 4002.3396139637216, 4006.539247986519, 4293.849715259951, 4375.27807911691, 4375.431193944767, 4400.581944715247, 4405.625521830616, 935.9251259899191, 972.1348000279756, 977.8044391121804, 978.7895602445366, 981.8232718110665, 537.4679067722926, 622.8446232239222, 671.1677519875319, 685.807973767346, 699.2168770976539, 10576.20678449104, 11149.832752508719, 11890.34603096838, 11995.040396768238, 11999.88000120005, 609.8627808742997, 633.7831363781551, 666.6133642487346, 667.4960404894371, 689.0731965506386, 5451.890962180734, 5591.496765291144, 5609.102543593006, 5623.606347555632, 5761.279840019997, 98101.0, 99414.0, 100383.49808251057, 101061.98938010525, 101120.0, 91499.0, 91714.54142729244, 93288.0, 94668.0, 95292.0, 77005.0, 78400.0, 79759.60120199362, 79995.40009199783, 80229.0, 8697.652093916173, 10968.835467468154, 11354.602588909349, 11378.886211138113, 11588.76822463529, 43337.0, 43486.782566086964, 43570.12859742724, 43816.12367752563, 44051.67844964656, 54421.0, 54812.451875480736, 54845.0, 55367.72316138393, 55482.0, 16311.836881631032, 17008.149592520076, 17045.0, 17060.23228954725, 17103.315867365167, 18114.728279076087, 18375.448736537925, 18676.43970680882, 18821.6235675283, 18945.33691320797, 16416.261268242717, 17332.133393330536, 17463.650726985135, 17610.031448270467, 17659.823401765818, 14961.102333859568, 15183.772243416137, 15276.847231527543, 15316.923415382851, 15331.233438327816, 16674.49976500707, 17075.975441473056, 17151.57121071983, 17161.914190428968, 17223.311067557657, 11698.0, 11796.528138874692, 11984.880151198377, 12080.818787718366, 12386.75226495447, 12838.229706217284, 13190.208587484782, 13372.264525451194, 13390.93304533471, 13861.306934653428], "value": 8457.776020890899, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1520.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 112.0, 113.0, 113.0, 115.0, 114.0, 114.0, 113.0, 115.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 111.0, 122.0, 182.0, 112.0, 113.0, 108.0, 114.0, 137.0, 111.0], "value": 125.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 202.0, 196.0, 233.0, 216.0, 284.0, 275.0, 197.0, 244.0, 212.0], "value": 225.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 48.0, 84.0, 50.0, 59.0, 86.0, 86.0, 95.0, 81.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 75.0, 118.0, 107.0, 78.0, 69.0, 91.0, 71.0, 77.0, 89.0], "value": 86.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 89.0, 90.0, 89.0, 94.0, 103.0, 89.0, 89.0, 88.0], "value": 90.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 95.0, 96.0, 95.0, 96.0, 96.0, 97.0, 96.0, 96.0, 102.0], "value": 99.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 93.0, 96.0, 91.0, 92.0, 90.0, 94.0, 90.0, 90.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 52.0, 52.0, 50.0, 49.0, 52.0, 51.0, 50.0, 51.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 68.0, 81.0, 88.0, 58.0, 64.0, 88.0, 84.0, 83.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 80.0, 85.0, 84.0, 82.0, 82.0, 83.0, 85.0, 84.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 134.0, 134.0, 133.0, 156.0, 155.0, 136.0, 137.0, 182.0, 143.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 187.0, 183.0, 193.0, 240.0, 184.0, 177.0, 186.0, 185.0], "value": 190.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 69.0, 67.0, 71.0, 69.0, 66.0, 70.0, 79.0, 95.0], "value": 72.1, "unit": "score"}]}]} 17:25:42 INFO - Return code: 0 17:25:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:25:42 ERROR - # TBPL SUCCESS # 17:25:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:25:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:25:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:25:42 INFO - 2017-04-28 17:25:42.327 screenresolution[1658:10751] starting screenresolution argv=screenresolution get 17:25:42 INFO - 2017-04-28 17:25:42.330 screenresolution[1658:10751] Display 0: 1600x1200x32@60 17:25:42 INFO - 2017-04-28 17:25:42.336 screenresolution[1659:10754] starting screenresolution argv=screenresolution list 17:25:42 INFO - Available Modes on Display 0 17:25:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:25:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:25:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:25:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:25:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:25:42 INFO - Intel Iris: 17:25:42 INFO - Chipset Model: Intel Iris 17:25:42 INFO - Type: GPU 17:25:42 INFO - Bus: Built-In 17:25:42 INFO - VRAM (Dynamic, Max): 1536 MB 17:25:42 INFO - Vendor: Intel (0x8086) 17:25:42 INFO - Device ID: 0x0a2e 17:25:42 INFO - Revision ID: 0x0009 17:25:42 INFO - Displays: 17:25:42 INFO - Display: 17:25:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:25:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:25:42 INFO - Display Serial Number: 1600x1200 60 17:25:42 INFO - Main Display: Yes 17:25:42 INFO - Mirror: Off 17:25:42 INFO - Online: Yes 17:25:42 INFO - Rotation: Supported 17:25:42 INFO - Return code: 0 17:25:42 INFO - Running post-action listener: _resource_record_post_action 17:25:42 INFO - [mozharness: 2017-04-29 00:25:42.577587Z] Finished run-tests step (success) 17:25:42 INFO - Running post-run listener: _resource_record_post_run 17:25:42 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 40323584; Write bytes: 516278272; Read time: 264; Write time: 1622 17:25:42 INFO - TinderboxPrint: CPU usage
54.2% 17:25:42 INFO - TinderboxPrint: I/O read bytes / time
40,323,584 / 264 17:25:42 INFO - TinderboxPrint: I/O write bytes / time
516,278,272 / 1,622 17:25:42 INFO - TinderboxPrint: CPU idle
2,195.7 (45.8%) 17:25:42 INFO - TinderboxPrint: CPU system
965.6 (20.2%) 17:25:42 INFO - TinderboxPrint: CPU user
1,628.4 (34.0%) 17:25:42 INFO - TinderboxPrint: Swap in / out
262,033,408 / 0 17:25:42 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208474624; Write bytes: 206329856; Read time: 16070; Write time: 435 17:25:42 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36157952; Write bytes: 302718976; Read time: 236; Write time: 1158 17:25:43 INFO - Running post-run listener: _upload_blobber_files 17:25:43 INFO - Blob upload gear active. 17:25:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:25:43 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:25:43 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:25:43 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:25:43 INFO - (blobuploader) - INFO - Open directory for files ... 17:25:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:25:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:25:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:44 INFO - (blobuploader) - INFO - Done attempting. 17:25:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:25:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:25:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:45 INFO - (blobuploader) - INFO - Done attempting. 17:25:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:25:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:25:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:46 INFO - (blobuploader) - INFO - Done attempting. 17:25:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:25:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:25:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:48 INFO - (blobuploader) - INFO - Done attempting. 17:25:48 INFO - (blobuploader) - INFO - Iteration through files over. 17:25:48 INFO - Return code: 0 17:25:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:25:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:25:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c0a15b829120ea11937370c621700fcf25485db4dd06e1fc20ea421834bdef23ba010d026b88d16bd089c58fb5769574b1fc943769cec0da26304f717d9736a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/306716dd56b1c2e1a40c25e5b31f55c8397383d54fa069b838da102e53fd4c65d946df033c6644c69726a7b279b3061a9457ee918851c60fadb12d9e758c08e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3d79cd7d159edcd42fc8fb96fa570a64caaad7a7fb200e5f3b9e2af8760acaacf1cef473921335d4579c3e38fb47b94afe25a720c52d382ab14d27209452212", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77849fbe9885d7d5707fef5d532ebc6c199dfe3d2fece67167603024e041befa62c88d33983e7026cdc6e3be3d89de06a2a2bd1d17041ffa2fdfd19eeadcfd9"} 17:25:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:25:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:25:48 INFO - Contents: 17:25:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c0a15b829120ea11937370c621700fcf25485db4dd06e1fc20ea421834bdef23ba010d026b88d16bd089c58fb5769574b1fc943769cec0da26304f717d9736a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/306716dd56b1c2e1a40c25e5b31f55c8397383d54fa069b838da102e53fd4c65d946df033c6644c69726a7b279b3061a9457ee918851c60fadb12d9e758c08e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3d79cd7d159edcd42fc8fb96fa570a64caaad7a7fb200e5f3b9e2af8760acaacf1cef473921335d4579c3e38fb47b94afe25a720c52d382ab14d27209452212", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77849fbe9885d7d5707fef5d532ebc6c199dfe3d2fece67167603024e041befa62c88d33983e7026cdc6e3be3d89de06a2a2bd1d17041ffa2fdfd19eeadcfd9"} 17:25:48 INFO - Running post-run listener: copy_logs_to_upload_dir 17:25:48 INFO - Copying logs to upload dir... 17:25:48 INFO - mkdir: /builds/slave/test/build/upload/logs 17:25:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.623546 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-04-28 17:25:48.904233) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 17:25:48.908105) ========= 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.ETNjsGzJRF/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.H993OiK41J/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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/3c0a15b829120ea11937370c621700fcf25485db4dd06e1fc20ea421834bdef23ba010d026b88d16bd089c58fb5769574b1fc943769cec0da26304f717d9736a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/306716dd56b1c2e1a40c25e5b31f55c8397383d54fa069b838da102e53fd4c65d946df033c6644c69726a7b279b3061a9457ee918851c60fadb12d9e758c08e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3d79cd7d159edcd42fc8fb96fa570a64caaad7a7fb200e5f3b9e2af8760acaacf1cef473921335d4579c3e38fb47b94afe25a720c52d382ab14d27209452212", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77849fbe9885d7d5707fef5d532ebc6c199dfe3d2fece67167603024e041befa62c88d33983e7026cdc6e3be3d89de06a2a2bd1d17041ffa2fdfd19eeadcfd9"} 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.011093 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/3c0a15b829120ea11937370c621700fcf25485db4dd06e1fc20ea421834bdef23ba010d026b88d16bd089c58fb5769574b1fc943769cec0da26304f717d9736a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/306716dd56b1c2e1a40c25e5b31f55c8397383d54fa069b838da102e53fd4c65d946df033c6644c69726a7b279b3061a9457ee918851c60fadb12d9e758c08e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3d79cd7d159edcd42fc8fb96fa570a64caaad7a7fb200e5f3b9e2af8760acaacf1cef473921335d4579c3e38fb47b94afe25a720c52d382ab14d27209452212", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77849fbe9885d7d5707fef5d532ebc6c199dfe3d2fece67167603024e041befa62c88d33983e7026cdc6e3be3d89de06a2a2bd1d17041ffa2fdfd19eeadcfd9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 17:25:48.942997) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:25:48.943393) ========= 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.ETNjsGzJRF/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.H993OiK41J/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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.005299 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:25:48.991241) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 17:25:48.991524) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 17:25:48.991831) ========= ========= Total master_lag: 0.18 =========