builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0276 starttime: 1493424284.05 results: success (0) buildid: 20170428170226 builduid: aad5576c695f45f5adc7fda70ee6cf05 revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:44.053167) ========= 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:44.053602) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:44.053898) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:44.081100) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:44.081496) ========= 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.956zrp6BoG/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.muqUVL7vPN/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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:44-- 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% 190M=0s 2017-04-28 17:04:44 (190 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.157556 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:44.261726) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:44.262048) ========= 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.956zrp6BoG/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.muqUVL7vPN/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.025837 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:44.310826) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-28 17:04:44.311188) ========= 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.956zrp6BoG/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.muqUVL7vPN/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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:44,419 truncating revision to first 12 chars 2017-04-28 17:04:44,419 Setting DEBUG logging. 2017-04-28 17:04:44,419 attempt 1/10 2017-04-28 17:04:44,419 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:46,488 unpacking tar archive at: mozilla-central-b3b32894d932/testing/mozharness/ program finished with exit code 0 elapsedTime=2.346908 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-28 17:04:46.683729) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:46.684194) ========= script_repo_revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:46.684762) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:46.685161) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:04:46.706353) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-04-28 17:04:46.706811) ========= /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.956zrp6BoG/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.muqUVL7vPN/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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:46 INFO - MultiFileLogger online at 20170428 17:04:46 in /builds/slave/test 17:04:46 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:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:04:46 INFO - {'append_to_log': False, 17:04:46 INFO - 'base_work_dir': '/builds/slave/test', 17:04:46 INFO - 'blob_upload_branch': 'Firefox', 17:04:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:04:46 INFO - 'branch': 'Firefox', 17:04:46 INFO - 'buildbot_json_path': 'buildprops.json', 17:04:46 INFO - 'config_files': ('talos/mac_config.py',), 17:04:46 INFO - 'default_actions': ('clobber', 17:04:46 INFO - 'read-buildbot-config', 17:04:46 INFO - 'download-and-extract', 17:04:46 INFO - 'populate-webroot', 17:04:46 INFO - 'create-virtualenv', 17:04:46 INFO - 'install', 17:04:46 INFO - 'run-tests'), 17:04:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:04:46 INFO - 'download_minidump_stackwalk': True, 17:04:46 INFO - 'download_symbols': 'ondemand', 17:04:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:04:46 INFO - 'tooltool.py': '/tools/tooltool.py', 17:04:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:04:46 INFO - '/tools/misc-python/virtualenv.py')}, 17:04:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:04:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:04:46 INFO - 'gecko_profile': False, 17:04:46 INFO - 'gecko_profile_interval': 0, 17:04:46 INFO - 'installer_path': 'installer.exe', 17:04:46 INFO - 'log_level': 'info', 17:04:46 INFO - 'log_name': 'talos', 17:04:46 INFO - 'log_to_console': True, 17:04:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:04:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:04:46 INFO - 'opt_config_files': (), 17:04:46 INFO - 'pip_index': False, 17:04:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:04:46 INFO - 'cmd': ('bash', 17:04:46 INFO - '-c', 17:04:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:04:46 INFO - 'enabled': True, 17:04:46 INFO - 'halt_on_failure': False, 17:04:46 INFO - 'name': 'check_screen_resolution'},), 17:04:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:04:46 INFO - 'cmd': ('bash', 17:04:46 INFO - '-c', 17:04:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:04:46 INFO - 'enabled': True, 17:04:46 INFO - 'halt_on_failure': False, 17:04:46 INFO - 'name': 'check_screen_resolution'},), 17:04:46 INFO - 'run_cmd_checks_enabled': True, 17:04:46 INFO - 'suite': 'dromaeojs-e10s', 17:04:46 INFO - 'system_bits': '32', 17:04:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:04:46 INFO - 'title': 't-yosemite-r7-0276', 17:04:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:04:46 INFO - 'use_talos_json': True, 17:04:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:04:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:04:46 INFO - 'work_dir': 'build'} 17:04:46 INFO - [mozharness: 2017-04-29 00:04:46.923724Z] Running clobber step. 17:04:46 INFO - Running pre-action listener: _resource_record_pre_action 17:04:46 INFO - Running main action method: clobber 17:04:46 INFO - rmtree: /builds/slave/test/build 17:04:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:04:49 INFO - Running post-action listener: _resource_record_post_action 17:04:49 INFO - [mozharness: 2017-04-29 00:04:49.326833Z] Finished clobber step (success) 17:04:49 INFO - [mozharness: 2017-04-29 00:04:49.327001Z] Running read-buildbot-config step. 17:04:49 INFO - Running pre-action listener: _resource_record_pre_action 17:04:49 INFO - Running main action method: read_buildbot_config 17:04:49 INFO - Using buildbot properties: 17:04:49 INFO - { 17:04:49 INFO - "project": "", 17:04:49 INFO - "product": "firefox", 17:04:49 INFO - "script_repo_revision": "production", 17:04:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:04:49 INFO - "repository": "", 17:04:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:04:49 INFO - "buildid": "20170428170226", 17:04:49 INFO - "stage_platform": "macosx64", 17:04:49 INFO - "basedir": "/builds/slave/test", 17:04:49 INFO - "buildnumber": 50, 17:04:49 INFO - "platform": "yosemite_r7", 17:04:49 INFO - "mozci_request": { 17:04:49 INFO - "priority": 0, 17:04:49 INFO - "type": "trigger_all_talos_jobs", 17:04:49 INFO - "times": 6 17:04:49 INFO - }, 17:04:49 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:04:49 INFO - "slavebuilddir": "test", 17:04:49 INFO - "branch": "mozilla-central", 17:04:49 INFO - "revision": "b3b32894d9326d147238d9f071e1814ab3fdc850", 17:04:49 INFO - "slavename": "t-yosemite-r7-0276", 17:04:49 INFO - "builduid": "aad5576c695f45f5adc7fda70ee6cf05", 17:04:49 INFO - "repo_path": "mozilla-central" 17:04:49 INFO - } 17:04:49 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:49 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:49 INFO - Running post-action listener: _resource_record_post_action 17:04:49 INFO - [mozharness: 2017-04-29 00:04:49.330836Z] Finished read-buildbot-config step (success) 17:04:49 INFO - [mozharness: 2017-04-29 00:04:49.330944Z] Running download-and-extract step. 17:04:49 INFO - Running pre-action listener: _resource_record_pre_action 17:04:49 INFO - Running main action method: download_and_extract 17:04:49 INFO - mkdir: /builds/slave/test/build/tests 17:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:49 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:49 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:49 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:49 INFO - Downloaded 1325 bytes. 17:04:49 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:04:49 INFO - Using the following test package requirements: 17:04:49 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:49 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:04:49 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:04:49 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:49 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:04:49 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:49 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:04:49 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:49 INFO - u'jsshell-mac.zip'], 17:04:49 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:49 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:04:49 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:04:49 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:49 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:04:49 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:49 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:04:49 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:49 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:04:49 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:04:49 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:04:49 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:04:49 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:49 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:49 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:50 INFO - Content-Length response header: 52698163 17:04:50 INFO - Bytes received: 52698163 17:05:06 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:05:06 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:05:06 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:05:06 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:05:07 INFO - Content-Length response header: 52698163 17:05:07 INFO - Bytes received: 52698163 17:05:23 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:23 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:23 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:23 INFO - Content-Length response header: 14408809 17:05:23 INFO - Bytes received: 14408809 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 - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:05:24 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:24 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:25 INFO - Downloaded 71219323 bytes. 17:05:25 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:25 INFO - mkdir: /builds/slave/test/properties 17:05:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:05:25 INFO - Writing to file /builds/slave/test/properties/build_url 17:05:25 INFO - Contents: 17:05:25 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:05:25 INFO - Running post-action listener: _resource_record_post_action 17:05:25 INFO - Running post-action listener: set_extra_try_arguments 17:05:25 INFO - [mozharness: 2017-04-29 00:05:25.813436Z] Finished download-and-extract step (success) 17:05:25 INFO - [mozharness: 2017-04-29 00:05:25.813559Z] Running populate-webroot step. 17:05:25 INFO - Running pre-action listener: _resource_record_pre_action 17:05:25 INFO - Running main action method: populate_webroot 17:05:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:05:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:05:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:05:25 INFO - '--tppagecycles', 17:05:25 INFO - '7'], 17:05:25 INFO - 'tests': ['tsvgm']}}, 17:05:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:05:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:05:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:05:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:05:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:05:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:05:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:05:25 INFO - 'talos_options': ['--disable-e10s'], 17:05:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:05:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:05:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:05:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:05:25 INFO - 'talos_options': ['--disable-e10s'], 17:05:25 INFO - 'tests': ['damp', 'tps']}, 17:05:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:05:25 INFO - 'tests': ['damp', 'tps']}, 17:05:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:05:25 INFO - 'tests': ['dromaeo_dom']}, 17:05:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:05:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:05:25 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:05:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:05:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:05:25 INFO - 'tests': ['a11yr', 17:05:25 INFO - 'ts_paint', 17:05:25 INFO - 'tpaint', 17:05:25 INFO - 'sessionrestore', 17:05:25 INFO - 'sessionrestore_no_auto_restore', 17:05:25 INFO - 'tabpaint']}, 17:05:25 INFO - 'other-e10s': {'tests': ['a11yr', 17:05:25 INFO - 'ts_paint', 17:05:25 INFO - 'tpaint', 17:05:25 INFO - 'sessionrestore', 17:05:25 INFO - 'sessionrestore_no_auto_restore', 17:05:25 INFO - 'tabpaint']}, 17:05:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:05:25 INFO - 'ts_paint', 17:05:25 INFO - 'tpaint', 17:05:25 INFO - 'sessionrestore', 17:05:25 INFO - 'sessionrestore_no_auto_restore', 17:05:25 INFO - 'tabpaint']}, 17:05:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:05:25 INFO - 'ts_paint', 17:05:25 INFO - 'tpaint', 17:05:25 INFO - 'sessionrestore', 17:05:25 INFO - 'sessionrestore_no_auto_restore', 17:05:25 INFO - 'tabpaint']}, 17:05:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:05:25 INFO - 'tests': ['a11yr', 17:05:25 INFO - 'ts_paint', 17:05:25 INFO - 'tpaint', 17:05:25 INFO - 'sessionrestore', 17:05:25 INFO - 'sessionrestore_no_auto_restore', 17:05:25 INFO - 'tabpaint']}, 17:05:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:05:25 INFO - 'tests': ['a11yr', 17:05:25 INFO - 'ts_paint', 17:05:25 INFO - 'tpaint', 17:05:25 INFO - 'sessionrestore', 17:05:25 INFO - 'sessionrestore_no_auto_restore', 17:05:25 INFO - 'tabpaint']}, 17:05:25 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:05:25 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:05:25 INFO - 'bloom_basic_ref']}, 17:05:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:05:25 INFO - 'tests': ['tsvgx', 17:05:25 INFO - 'tsvgr_opacity', 17:05:25 INFO - 'tart', 17:05:25 INFO - 'tscrollx', 17:05:25 INFO - 'cart', 17:05:25 INFO - 'tsvg_static']}, 17:05:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:05:25 INFO - 'tsvgr_opacity', 17:05:25 INFO - 'tart', 17:05:25 INFO - 'tscrollx', 17:05:25 INFO - 'cart', 17:05:25 INFO - 'tsvg_static']}, 17:05:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:05:25 INFO - 'talos_options': ['--disable-e10s'], 17:05:25 INFO - 'tests': ['tp5o']}, 17:05:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:05:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:05:25 INFO - 'talos_options': ['--disable-e10s', 17:05:25 INFO - '--xperf_path', 17:05:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:05:25 INFO - 'tests': ['tp5n']}, 17:05:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:05:25 INFO - 'talos_options': ['--xperf_path', 17:05:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:05:25 INFO - 'tests': ['tp5n']}}, 17:05:25 INFO - 'talos.zip': {'path': '', 17:05:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:05:25 INFO - Running post-action listener: _resource_record_post_action 17:05:25 INFO - [mozharness: 2017-04-29 00:05:25.833044Z] Finished populate-webroot step (success) 17:05:25 INFO - [mozharness: 2017-04-29 00:05:25.833185Z] Running create-virtualenv step. 17:05:25 INFO - Running pre-action listener: _resource_record_pre_action 17:05:25 INFO - Running main action method: create_virtualenv 17:05:25 INFO - Creating virtualenv /builds/slave/test/build/venv 17:05:25 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:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:05:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:05:25 INFO - Using real prefix '/tools/python27' 17:05:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:05:26 INFO - Installing distribute.............................................................................................................................................................................................done. 17:05:29 INFO - Installing pip.................done. 17:05:29 INFO - Return code: 0 17:05:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:05:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:05:29 INFO - Reading from file tmpfile_stdout 17:05:29 INFO - Output received: 17:05:29 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:29 INFO - Installing pip>=1.5 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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105276b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0ba5a3d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105054d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105049be8>, '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.956zrp6BoG/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.muqUVL7vPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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', 'pip>=1.5'] 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 pip>=1.5 17:05:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.956zrp6BoG/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.muqUVL7vPN/Listeners', 17:05:29 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:30 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:30 INFO - Cleaning up... 17:05:30 INFO - Return code: 0 17:05:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:05:30 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:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:30 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:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:30 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 0x105276b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0ba5a3d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105054d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105049be8>, '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.956zrp6BoG/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.muqUVL7vPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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:30 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:30 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:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.956zrp6BoG/Render', 17:05:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:30 INFO - 'HOME': '/Users/cltbld', 17:05:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:30 INFO - 'LOGNAME': 'cltbld', 17:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:30 INFO - 'MOZ_NO_REMOTE': '1', 17:05:30 INFO - 'NO_EM_RESTART': '1', 17:05:30 INFO - 'PAGER': '/bin/cat', 17:05:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:30 INFO - 'PWD': '/builds/slave/test', 17:05:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:30 INFO - 'SHELL': '/bin/bash', 17:05:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.muqUVL7vPN/Listeners', 17:05:30 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:05:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:30 INFO - 'USER': 'cltbld', 17:05:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:30 INFO - 'XPC_FLAGS': '0x0', 17:05:30 INFO - 'XPC_SERVICE_NAME': '0', 17:05:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:30 INFO - Downloading/unpacking psutil>=3.1.1 17:05:30 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:30 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:30 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:30 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:30 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:30 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 - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:05:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:05:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:05:33 INFO - Installing collected packages: psutil 17:05:33 INFO - Running setup.py install for psutil 17:05:33 INFO - building 'psutil._psutil_osx' extension 17:05:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:05:34 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:34 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:34 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:34 INFO - building 'psutil._psutil_posix' extension 17:05:34 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:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:05:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:05:34 INFO - ^ 17:05:34 INFO - 1 warning generated. 17:05:34 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:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:05:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:05:34 INFO - Successfully installed psutil 17:05:34 INFO - Cleaning up... 17:05:35 INFO - Return code: 0 17:05:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:05:35 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:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:35 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:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:35 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 0x105276b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0ba5a3d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105054d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105049be8>, '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.956zrp6BoG/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.muqUVL7vPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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:35 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:35 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:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.956zrp6BoG/Render', 17:05:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:35 INFO - 'HOME': '/Users/cltbld', 17:05:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:35 INFO - 'LOGNAME': 'cltbld', 17:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:35 INFO - 'MOZ_NO_REMOTE': '1', 17:05:35 INFO - 'NO_EM_RESTART': '1', 17:05:35 INFO - 'PAGER': '/bin/cat', 17:05:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:35 INFO - 'PWD': '/builds/slave/test', 17:05:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:35 INFO - 'SHELL': '/bin/bash', 17:05:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.muqUVL7vPN/Listeners', 17:05:35 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:05:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:35 INFO - 'USER': 'cltbld', 17:05:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:35 INFO - 'XPC_FLAGS': '0x0', 17:05:35 INFO - 'XPC_SERVICE_NAME': '0', 17:05:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:05:35 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:35 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:35 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:35 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:35 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:35 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:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:05:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:05:38 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:38 INFO - Installing collected packages: mozsystemmonitor 17:05:38 INFO - Running setup.py install for mozsystemmonitor 17:05:38 INFO - Successfully installed mozsystemmonitor 17:05:38 INFO - Cleaning up... 17:05:38 INFO - Return code: 0 17:05:38 INFO - Installing jsonschema==2.5.1 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', '--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 0x105276b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0ba5a3d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105054d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105049be8>, '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.956zrp6BoG/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.muqUVL7vPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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', '--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:38 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:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.956zrp6BoG/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.muqUVL7vPN/Listeners', 17:05:38 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:38 INFO - Downloading/unpacking jsonschema==2.5.1 17:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:05:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 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:41 INFO - Downloading functools32-3.2.3-2.tar.gz 17:05:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:05:41 INFO - warning: no files found matching '*.txt' 17:05:41 INFO - no previously-included directories found matching 'build' 17:05:41 INFO - no previously-included directories found matching 'dist' 17:05:41 INFO - no previously-included directories found matching '.git*' 17:05:41 INFO - Installing collected packages: jsonschema, functools32 17:05:41 INFO - Running setup.py install for functools32 17:05:41 INFO - warning: no files found matching '*.txt' 17:05:41 INFO - no previously-included directories found matching 'build' 17:05:41 INFO - no previously-included directories found matching 'dist' 17:05:41 INFO - no previously-included directories found matching '.git*' 17:05:41 INFO - Successfully installed jsonschema functools32 17:05:41 INFO - Cleaning up... 17:05:42 INFO - Return code: 0 17:05:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105276b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0ba5a3d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105054d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105049be8>, '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.956zrp6BoG/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.muqUVL7vPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:05:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:05:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.956zrp6BoG/Render', 17:05:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:42 INFO - 'HOME': '/Users/cltbld', 17:05:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:42 INFO - 'LOGNAME': 'cltbld', 17:05:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:42 INFO - 'MOZ_NO_REMOTE': '1', 17:05:42 INFO - 'NO_EM_RESTART': '1', 17:05:42 INFO - 'PAGER': '/bin/cat', 17:05:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:42 INFO - 'PWD': '/builds/slave/test', 17:05:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:42 INFO - 'SHELL': '/bin/bash', 17:05:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.muqUVL7vPN/Listeners', 17:05:42 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:05:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:42 INFO - 'USER': 'cltbld', 17:05:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:42 INFO - 'XPC_FLAGS': '0x0', 17:05:42 INFO - 'XPC_SERVICE_NAME': '0', 17:05:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:05:43 INFO - Cleaning up... 17:05:43 INFO - Return code: 0 17:05:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:05:43 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:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:43 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:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:43 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 0x105276b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0ba5a3d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105054d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105049be8>, '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.956zrp6BoG/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.muqUVL7vPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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:43 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:43 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:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.956zrp6BoG/Render', 17:05:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:43 INFO - 'HOME': '/Users/cltbld', 17:05:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:43 INFO - 'LOGNAME': 'cltbld', 17:05:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:43 INFO - 'MOZ_NO_REMOTE': '1', 17:05:43 INFO - 'NO_EM_RESTART': '1', 17:05:43 INFO - 'PAGER': '/bin/cat', 17:05:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:43 INFO - 'PWD': '/builds/slave/test', 17:05:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:43 INFO - 'SHELL': '/bin/bash', 17:05:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.muqUVL7vPN/Listeners', 17:05:43 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:05:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:43 INFO - 'USER': 'cltbld', 17:05:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:43 INFO - 'XPC_FLAGS': '0x0', 17:05:43 INFO - 'XPC_SERVICE_NAME': '0', 17:05:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:43 INFO - Downloading/unpacking blobuploader==1.2.4 17:05:43 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:43 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 - 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:43 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:43 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:43 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:46 INFO - Downloading blobuploader-1.2.4.tar.gz 17:05:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:05:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:05:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:47 INFO - Downloading docopt-0.6.1.tar.gz 17:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:05:47 INFO - Installing collected packages: blobuploader, requests, docopt 17:05:47 INFO - Running setup.py install for blobuploader 17:05:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:05:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:05:48 INFO - Running setup.py install for requests 17:05:48 INFO - Running setup.py install for docopt 17:05:48 INFO - Successfully installed blobuploader requests docopt 17:05:48 INFO - Cleaning up... 17:05:48 INFO - Return code: 0 17:05:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:48 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 0x105276b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0ba5a3d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105054d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105049be8>, '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.956zrp6BoG/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.muqUVL7vPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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:48 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:48 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:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.956zrp6BoG/Render', 17:05:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:48 INFO - 'HOME': '/Users/cltbld', 17:05:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:48 INFO - 'LOGNAME': 'cltbld', 17:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:48 INFO - 'MOZ_NO_REMOTE': '1', 17:05:48 INFO - 'NO_EM_RESTART': '1', 17:05:48 INFO - 'PAGER': '/bin/cat', 17:05:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:48 INFO - 'PWD': '/builds/slave/test', 17:05:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:48 INFO - 'SHELL': '/bin/bash', 17:05:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.muqUVL7vPN/Listeners', 17:05:48 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:05:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:48 INFO - 'USER': 'cltbld', 17:05:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:48 INFO - 'XPC_FLAGS': '0x0', 17:05:48 INFO - 'XPC_SERVICE_NAME': '0', 17:05:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:05:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-phxK1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:05:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-QXZeqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:05:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-uXCTH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:05:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ghoGhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:05:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-0SHTdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:05:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-RYZ_EQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:05:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-DCLZfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:05:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-KCK01m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:05:49 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-5us4Se-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:05:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-QoEq2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:05:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-V3kPAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:05:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-xI2b5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:05:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-lPvCsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:05:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-LoJwv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:05:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-e7mi1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:05:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-6zoCwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:05:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-yPXJe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:05:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:05:50 INFO - Running setup.py install for manifestparser 17:05:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:05:51 INFO - Running setup.py install for mozcrash 17:05:51 INFO - Running setup.py install for mozdebug 17:05:51 INFO - Running setup.py install for mozdevice 17:05:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:05:51 INFO - Running setup.py install for mozfile 17:05:51 INFO - Running setup.py install for mozhttpd 17:05:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:05:51 INFO - Running setup.py install for mozinfo 17:05:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:05:51 INFO - Running setup.py install for mozInstall 17:05:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:05:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:05:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:05:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:05:52 INFO - Running setup.py install for mozleak 17:05:52 INFO - Running setup.py install for mozlog 17:05:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:05:52 INFO - Running setup.py install for moznetwork 17:05:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:05:52 INFO - Running setup.py install for mozprocess 17:05:52 INFO - Running setup.py install for mozprofile 17:05:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:05:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:05:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:05:52 INFO - Running setup.py install for mozrunner 17:05:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:05:53 INFO - Running setup.py install for mozscreenshot 17:05:53 INFO - Running setup.py install for moztest 17:05:53 INFO - Running setup.py install for mozversion 17:05:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:05:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:05:53 INFO - Cleaning up... 17:05:53 INFO - Return code: 0 17:05:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:05:53 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:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:53 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:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:53 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 0x105276b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0ba5a3d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105054d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105049be8>, '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.956zrp6BoG/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.muqUVL7vPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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:53 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:53 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:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.956zrp6BoG/Render', 17:05:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:53 INFO - 'HOME': '/Users/cltbld', 17:05:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:53 INFO - 'LOGNAME': 'cltbld', 17:05:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:53 INFO - 'MOZ_NO_REMOTE': '1', 17:05:53 INFO - 'NO_EM_RESTART': '1', 17:05:53 INFO - 'PAGER': '/bin/cat', 17:05:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:53 INFO - 'PWD': '/builds/slave/test', 17:05:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:53 INFO - 'SHELL': '/bin/bash', 17:05:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.muqUVL7vPN/Listeners', 17:05:53 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:05:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:53 INFO - 'USER': 'cltbld', 17:05:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:53 INFO - 'XPC_FLAGS': '0x0', 17:05:53 INFO - 'XPC_SERVICE_NAME': '0', 17:05:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:05:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-KrUaHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:05:53 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:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:05:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-b2QjCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:05:53 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:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:05:53 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-fonn1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:05:54 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:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:05:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-YpDJCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:05:54 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:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:05:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Ws2W5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:05:54 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:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:05:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-_5EQaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:05:54 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:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:05:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-_yEwYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:05:54 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:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:05:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-0hfP2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:05:54 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:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:05:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-lGns6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:05:54 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:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:05:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-dIdwzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:05:54 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:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:05:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Ty3Gzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:05:54 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:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:05:54 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-iG_qEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:05:55 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:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:05:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-1U8vuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:05:55 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:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:05:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-nrTXjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:05:55 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:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:05:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ePURjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:05:55 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:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:05:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-vJbkOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:05:55 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:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:05:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-pi03oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:05:55 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:55 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:55 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:55 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:55 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:55 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:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:05:58 INFO - Downloading blessings-1.6.tar.gz 17:05:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:05:58 INFO - Installing collected packages: blessings 17:05:58 INFO - Running setup.py install for blessings 17:05:58 INFO - Successfully installed blessings 17:05:58 INFO - Cleaning up... 17:05:58 INFO - Return code: 0 17:05:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:05:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:05:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:05:58 INFO - Reading from file tmpfile_stdout 17:05:58 INFO - Current package versions: 17:05:58 INFO - blessings == 1.6 17:05:58 INFO - blobuploader == 1.2.4 17:05:58 INFO - docopt == 0.6.1 17:05:58 INFO - functools32 == 3.2.3-2 17:05:58 INFO - jsonschema == 2.5.1 17:05:58 INFO - manifestparser == 1.1 17:05:58 INFO - mozInstall == 1.12 17:05:58 INFO - mozcrash == 1.0 17:05:58 INFO - mozdebug == 0.1 17:05:58 INFO - mozdevice == 0.50 17:05:58 INFO - mozfile == 1.2 17:05:58 INFO - mozhttpd == 0.7 17:05:58 INFO - mozinfo == 0.9 17:05:58 INFO - mozleak == 0.1 17:05:58 INFO - mozlog == 3.4 17:05:58 INFO - moznetwork == 0.27 17:05:58 INFO - mozprocess == 0.25 17:05:58 INFO - mozprofile == 0.28 17:05:58 INFO - mozrunner == 6.13 17:05:58 INFO - mozscreenshot == 0.1 17:05:58 INFO - mozsystemmonitor == 0.3 17:05:58 INFO - moztest == 0.8 17:05:58 INFO - mozversion == 1.4 17:05:58 INFO - psutil == 3.1.1 17:05:58 INFO - requests == 1.2.3 17:05:58 INFO - wsgiref == 0.1.2 17:05:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:58 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 0x105276b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0ba5a3d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105054d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105049be8>, '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.956zrp6BoG/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.muqUVL7vPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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:58 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:58 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:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.956zrp6BoG/Render', 17:05:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:58 INFO - 'HOME': '/Users/cltbld', 17:05:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:58 INFO - 'LOGNAME': 'cltbld', 17:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:58 INFO - 'MOZ_NO_REMOTE': '1', 17:05:58 INFO - 'NO_EM_RESTART': '1', 17:05:58 INFO - 'PAGER': '/bin/cat', 17:05:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:58 INFO - 'PWD': '/builds/slave/test', 17:05:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:58 INFO - 'SHELL': '/bin/bash', 17:05:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.muqUVL7vPN/Listeners', 17:05:58 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:05:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:58 INFO - 'USER': 'cltbld', 17:05:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:58 INFO - 'XPC_FLAGS': '0x0', 17:05:58 INFO - 'XPC_SERVICE_NAME': '0', 17:05:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:59 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:59 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:59 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:59 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:59 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:59 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:59 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:59 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:59 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:59 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:59 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:59 INFO - Cleaning up... 17:05:59 INFO - Return code: 0 17:05:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:05:59 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 0x105276b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0ba5a3d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105054d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105049be8>, '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.956zrp6BoG/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.muqUVL7vPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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:59 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:59 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:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.956zrp6BoG/Render', 17:05:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:05:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:05:59 INFO - 'HOME': '/Users/cltbld', 17:05:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:05:59 INFO - 'LOGNAME': 'cltbld', 17:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:59 INFO - 'MOZ_NO_REMOTE': '1', 17:05:59 INFO - 'NO_EM_RESTART': '1', 17:05:59 INFO - 'PAGER': '/bin/cat', 17:05:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:05:59 INFO - 'PWD': '/builds/slave/test', 17:05:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:05:59 INFO - 'SHELL': '/bin/bash', 17:05:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.muqUVL7vPN/Listeners', 17:05:59 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:05:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:05:59 INFO - 'USER': 'cltbld', 17:05:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:05:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:05:59 INFO - 'XPC_FLAGS': '0x0', 17:05:59 INFO - 'XPC_SERVICE_NAME': '0', 17:05:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:05:59 INFO - Cleaning up... 17:05:59 INFO - Return code: 0 17:05:59 INFO - Running post-action listener: _resource_record_post_action 17:05:59 INFO - Running post-action listener: _start_resource_monitoring 17:05:59 INFO - Starting resource monitoring. 17:05:59 INFO - [mozharness: 2017-04-29 00:05:59.442248Z] Finished create-virtualenv step (success) 17:05:59 INFO - [mozharness: 2017-04-29 00:05:59.443176Z] Running install step. 17:05:59 INFO - Running pre-action listener: _resource_record_pre_action 17:05:59 INFO - Running main action method: install 17:05:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:05:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:05:59 INFO - Reading from file tmpfile_stdout 17:05:59 INFO - Detecting whether we're running mozinstall >=1.0... 17:05:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:05:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:05:59 INFO - Reading from file tmpfile_stdout 17:05:59 INFO - Output received: 17:05:59 INFO - Usage: mozinstall [options] installer 17:05:59 INFO - Options: 17:05:59 INFO - -h, --help show this help message and exit 17:05:59 INFO - -d DEST, --destination=DEST 17:05:59 INFO - Directory to install application into. [default: 17:05:59 INFO - "/builds/slave/test"] 17:05:59 INFO - --app=APP Application being installed. [default: firefox] 17:05:59 INFO - mkdir: /builds/slave/test/build/application 17:05:59 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:59 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:19 INFO - Reading from file tmpfile_stdout 17:06:19 INFO - Output received: 17:06:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:06:19 INFO - Running post-action listener: _resource_record_post_action 17:06:19 INFO - [mozharness: 2017-04-29 00:06:19.371274Z] Finished install step (success) 17:06:19 INFO - [mozharness: 2017-04-29 00:06:19.371389Z] Running run-tests step. 17:06:19 INFO - Running pre-action listener: _resource_record_pre_action 17:06:19 INFO - Running main action method: run_tests 17:06:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:06:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:06:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:06:19 INFO - 2017-04-28 17:06:19.383 screenresolution[1623:7531] starting screenresolution argv=screenresolution get 17:06:19 INFO - 2017-04-28 17:06:19.386 screenresolution[1623:7531] Display 0: 1600x1200x32@60 17:06:19 INFO - 2017-04-28 17:06:19.391 screenresolution[1624:7534] starting screenresolution argv=screenresolution list 17:06:19 INFO - Available Modes on Display 0 17:06:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:06:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:06:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:06:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:06:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:06:19 INFO - Intel Iris: 17:06:19 INFO - Chipset Model: Intel Iris 17:06:19 INFO - Type: GPU 17:06:19 INFO - Bus: Built-In 17:06:19 INFO - VRAM (Dynamic, Max): 1536 MB 17:06:19 INFO - Vendor: Intel (0x8086) 17:06:19 INFO - Device ID: 0x0a2e 17:06:19 INFO - Revision ID: 0x0009 17:06:19 INFO - Displays: 17:06:19 INFO - Display: 17:06:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:06:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:06:19 INFO - Display Serial Number: 1600x1200 60 17:06:19 INFO - Main Display: Yes 17:06:19 INFO - Mirror: Off 17:06:19 INFO - Online: Yes 17:06:19 INFO - Rotation: Supported 17:06:19 INFO - Return code: 0 17:06:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:06:19 INFO - Python 2.7.3 17:06:19 INFO - Return code: 0 17:06:19 INFO - grabbing minidump binary from tooltool 17:06:19 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:19 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 0x105054d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105049be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:06:19 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:19 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:19 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:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:06:19 INFO - Return code: 0 17:06:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:06:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:06:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:06:19 INFO - ENV: RUST_BACKTRACE is now 1 17:06:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:06:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:06:19 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-0276', '--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:19 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-0276 --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:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.956zrp6BoG/Render', 17:06:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:06:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:06:19 INFO - 'HOME': '/Users/cltbld', 17:06:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:06:19 INFO - 'LOGNAME': 'cltbld', 17:06:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:06:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:19 INFO - 'MOZ_NO_REMOTE': '1', 17:06:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:06:19 INFO - 'NO_EM_RESTART': '1', 17:06:19 INFO - 'PAGER': '/bin/cat', 17:06:19 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:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:19 INFO - 'PWD': '/builds/slave/test', 17:06:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:06:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:06:19 INFO - 'RUST_BACKTRACE': '1', 17:06:19 INFO - 'SHELL': '/bin/bash', 17:06:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.muqUVL7vPN/Listeners', 17:06:19 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:06:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:06:19 INFO - 'USER': 'cltbld', 17:06:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:06:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:19 INFO - 'XPC_FLAGS': '0x0', 17:06:19 INFO - 'XPC_SERVICE_NAME': '0', 17:06:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:06:19 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-0276', '--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:19 INFO - mozversion application_buildid: 20170428075320 17:06:19 INFO - mozversion application_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:06:19 INFO - mozversion application_display_name: Nightly 17:06:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:06:19 INFO - mozversion application_name: Firefox 17:06:19 INFO - mozversion application_remotingname: firefox 17:06:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:06:19 INFO - mozversion application_vendor: Mozilla 17:06:19 INFO - mozversion application_version: 55.0a1 17:06:19 INFO - mozversion platform_buildid: 20170428075320 17:06:19 INFO - mozversion platform_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:06:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:06:19 INFO - mozversion platform_version: 55.0a1 17:06:19 INFO - using testdate: 1493424379 17:06:19 INFO - actual date: 1493424379 17:06:19 INFO - starting webserver on 'localhost:49231' 17:06:19 INFO - SUITE-START | Running 2 tests 17:06:19 INFO - TEST-START | dromaeo_css 17:06:19 INFO - operating with platform_type : mac_ 17:06:19 INFO - Initialising browser for dromaeo_css test... 17:06:19 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpKxGMah/profile http://localhost:49231/getInfo.html) 17:06:22 INFO - PID 1634 | 2017-04-28 17:06:22.519 plugin-container[1636:7714] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 17:06:22 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:06:22 INFO - PID 1634 | 2017-04-28 17:06:22.523 plugin-container[1636:7714] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 17:06:22 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:06:22 INFO - PID 1634 | 2017-04-28 17:06:22.524 plugin-container[1636:7714] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:06:22 INFO - PID 1634 | __metrics Screen width/height:1600/1200 17:06:22 INFO - PID 1634 | colorDepth:24 17:06:22 INFO - PID 1634 | Browser inner width/height: 1024/689 17:06:22 INFO - PID 1634 | __metrics 17:06:22 ERROR - PID 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:06:23 INFO - TEST-INFO | 1634: exit 0 17:06:23 INFO - Browser initialized. 17:06:23 INFO - Running cycle 1/1 for dromaeo_css test... 17:06:23 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpKxGMah/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:06:26 INFO - PID 1637 | 2017-04-28 17:06:26.298 plugin-container[1638:7844] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943b, name = 'com.apple.tsm.portname' 17:06:26 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:06:26 INFO - PID 1637 | 2017-04-28 17:06:26.299 plugin-container[1638:7844] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 17:06:26 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:06:26 INFO - PID 1637 | 2017-04-28 17:06:26.299 plugin-container[1638:7844] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:06:36 INFO - PID 1637 | RSS: Main: 252006400 17:06:36 INFO - PID 1637 | 17:09:40 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49231/tests/dromaeo/cssquery-ext.html) 17:09:42 INFO - PID 1637 | RSS: Main: 254537728 17:09:42 INFO - PID 1637 | 17:11:24 INFO - PID 1637 | ************************* 17:11:24 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 17:11:24 INFO - PID 1637 | 17:11:24 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:11:24 INFO - PID 1637 | Full stack: 17:11:24 INFO - PID 1637 | ************************* 17:11:24 INFO - PID 1637 | ************************* 17:11:24 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 17:11:24 INFO - PID 1637 | 17:11:24 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:11:24 INFO - PID 1637 | Full stack: 17:11:24 INFO - PID 1637 | ************************* 17:11:24 INFO - PID 1637 | ************************* 17:11:24 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 17:11:24 INFO - PID 1637 | 17:11:24 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:11:24 INFO - PID 1637 | Full stack: 17:11:24 INFO - PID 1637 | ************************* 17:11:24 INFO - PID 1637 | ************************* 17:11:24 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 17:11:24 INFO - PID 1637 | 17:11:24 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:11:24 INFO - PID 1637 | Full stack: 17:11:24 INFO - PID 1637 | ************************* 17:12:44 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-ext.html (next: http://localhost:49231/tests/dromaeo/cssquery-jquery.html) 17:12:44 INFO - PID 1637 | RSS: Main: 259854336 17:12:44 INFO - PID 1637 | 17:15:46 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49231/tests/dromaeo/cssquery-mootools.html) 17:15:47 INFO - PID 1637 | RSS: Main: 269967360 17:15:47 INFO - PID 1637 | 17:18:48 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49231/tests/dromaeo/cssquery-prototype.html) 17:18:49 INFO - PID 1637 | RSS: Main: 262373376 17:18:49 INFO - PID 1637 | 17:21:52 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49231/tests/dromaeo/cssquery-yui.html) 17:21:52 INFO - PID 1637 | RSS: Main: 271482880 17:21:52 INFO - PID 1637 | 17:24:54 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-yui.html (next: http://localhost:49231/tests/dromaeo/cssquery-dojo.html) 17:24:54 INFO - PID 1637 | RSS: Main: 266211328 17:24:54 INFO - PID 1637 | 17:24:54 INFO - PID 1637 | __start_tp_report 17:24:54 INFO - PID 1637 | _x_x_mozilla_page_load 17:24:54 INFO - PID 1637 | _x_x_mozilla_page_load_details 17:24:54 INFO - PID 1637 | |i|pagename|runs| 17:24:54 INFO - PID 1637 | |0;dojo.html;3578.123359776855;3619.384704600218;3620.0406892173573;3639.963600363996;3647.9817600911992;5082.644214904958;5719.428057194278;6136.601120927147;6173.197484327036;6210.78262260821;5310.628256022071;6161.907571386421;6174.289956654986;6184.288806787221;6207.689615519217;5339.5728341732565;5420.214068959998;5428.45715428456;5470.863228419292;5591.580631452637;5593.105103183491;5598.748056337454;5604.523615492688;5648.604597678164;5677.801276955307;5489.286392768954;5503.147012213073;5504;5521.972390138024;5528;5504.981578408007;5534.4742249486235;5539.058360078756;5539.224508568804;5556.027695153332;5411.323584551931;5468.0977638689565;5473.01485732568;5479.506844383985;5479.561635069224;4711.575958163748;6127.662978536178;6150;6195.132681424604;6218.81343559694;6677.332266773284;6703.296153903819;6716.194056713177;6722.058911752359;6726.529142959995;6770.661466926634;6810.829729256759;6813.080234168373;6816.863662726767;6823.761168359133;6126.823043872739;6305.369463053704;6307.621542707452;6311.084892690534;6324.968375158095;6897.965510172417;6940.479464040217;6964.4428445724225;6996.825079373057;7004.3696067354185;6372.776952806629;6428.228612566522;6496.317886621931;6540.2805691373915;6621.370969757865;6711.630860302633;6796.25241223465;6810.080639113756;6825.863482730318;6829.419499342513;6334.936650633527;6419.967900160469;6663.86672266552;6701.597904125768;6702.865942681216;6337.556371054075;6630.900536491957;6642.136522252076;6693.653173413293;6714.462842972551;4367.192069467159;5193.246979188072;5340.839774806762;5347.86630334237;5349.946500535023;4663.020765639186;4743.169945259566;4764.523547645208;4786.832460863922;4807.879803004953;6110.847228819226;6271.404216599416;6280.214973128359;6284.622922624657;6305.558610897193;6760.18877734675;6804.081449004321;6810.31896810315;6810.727570897207;6820.420264277493;6249.593776404519;6333.904991425133;6347.841303967345;6362.331955144643;6366.808995730135;5726.3700992890745;5795.1886735857015;5805.158252053428;5808.128780682847;5898.734556944948;5862.970685146717;6926.307369263134;6980.616066116412;7013.193482749549;7036.155661320674;6720.596764194164;6798.388145066968;6805.591664500146;6811.455083593201;6826.8634627308165;6690.364415380531;6984.720611175496;7010.929890701028;7012.158540975116;7022.4382049436945;6522.934770652233;6561.704723287368;6610.2398224204835;6642.335766423513;6652.068710380648;6430.164078669743;6757.189137303555;6769.4922880785125;6774.187097548326;6804.795856124324;5694.971525142348;6096.786612468542;6290.465310448663;6337.809865703852;6600.768973085919;5420.322459692538;5449.645773024899;5586.022446072003;6517.087607734826;6582.3746744059235;4727.503612120678;6233.906491402542;6246.562740608114;6271.560990730605;6282.937170628235;6511.902321465276;6563.901541476977;6610.3720146586;6615.17310336208;6627.867442651216;5619.550435965031;6377.139086223486;6388.361163883575;6390.7763228286785;6396.552241343062 17:24:54 INFO - PID 1637 | |1;ext.html;689.6379400814571;982.6609819612231;1172.3434876469173;1207.6256360528237;1258.465152310268;37340.81329593352;37880.62119378805;37984.43023354648;38055.42916856249;38055.61944380555;37931;38328.233435331276;38394.616053839454;38400.61599384005;38482.80758596203;7096;7130.930360445936;8484.533350665712;8585.441946273504;8592.441491303045;8498;8501.19238672325;8600.741977740678;8601.784955376103;8602.741917742447;7858.7426011838115;8513.148685131498;8535.231829135377;8606.139386061406;8608.698695545658;7983.680652773882;8519.57402129891;8586.828263434696;8588.269997050258;8604.52675102869;8339.54132522711;8340.791480212982;8398.874016889751;8402.327813774884;8420.24217820393;36679;36718.81640591806;36752.632473675185;36800.631993680254;36822.89531314038;3645.7994810285422;3689.2805902848954;3689.8524059037604;3699.815009249554;3700.0749812546865;30585.847070764503;30593;30687.079387618407;30691.232719181975;30769.692303076907;3160.6997335253113;3180.0777774445382;3182.252170739874;3183.442897492953;3186.633537143212;253517;308782;310037.4498127495;310212;311753;48130.75934620375;48134.03731925342;48409;48452.5154748448;48536;37732.62267377346;41171.38242926358;49787.25319120216;49977.75011124921;51017.48982510127;14172.291385430894;14241.85758142426;14250.358733857001;14254.928725356307;14262.072965257224;11179.4969226385;11410.42947852604;11416.486258118404;11419.143564232716;11421.42892855354;11929;12504.124711270124;13443.529476468473;13497.460101596023;13574.93212533931;5963.2844058713235;5975.342712301644;6034.793041391651;6040.879182416327;6060;1032.3547782635853;1057;1061.368485750977;1083.4041277297456;1091.2743025887855;20703.861287629024;21245.70459515342;22237.110515579196;24531.773411329363;24802.751972480044;1111.555377848867;1148.5004023249912;1160.7620437810228;1188.7563049574817;1192.797224471842;11952.521899123938;12281.324527151093;12289.69275768113;12348.629541113782;12363.381830908598;331376.3431182877;334014;334487;335184;336180;51885.740571297654;52178.47821521812;52244;52274.693132671986;52430.213546796826;1465.2746890289375;1498.8501149884924;1534.90023148495;1543.2746609093708;1559.7894284271706;23334.416639584146;23359;23416.06335746619;23516.647250290916;23580.646290306002;37573.24267573221;50504.74747626238;51183;51253.231201531264;51333.89328853608;311526;312638;316344;316430;316885;36011;36309.818450908635;36402;36468;36863.63136368709;43281.56718432901;43718.907027324574;43751.56248437475;44962.87592810206;45070;29329.85335073311;29785.334939974906;30275.545866812456;30860.84569577138;31202;27491.86254068717;30040.849795751754;30109;30240.395192095595;30622;35953.101172470895;36668.26663466769;37205.62794372022;37483.06292342713;37523.2495350086;29750.404991899606;30301.393972120874;31106;31191.532127017657;31238.21904452316;22626.208082717025;22769.430764231027;23180.76819231786;24019.399515012265;24271.393215169763 17:24:54 INFO - PID 1637 | |2;jquery.html;397.02530288142606;441.4680310226176;441.7989814634342;443.680550003997;463.1732357741432;21096.683549746747;22000.229991950284;22238;22736.317910462712;22838.2006629768;22192.334229973087;22995.310140695805;23613.5277294454;23683.5263294734;23774.76225237747;5949.167116603679;6053.394660533955;6199.380061993787;6240.063990401431;6245.313015568284;5976.790812321569;6121.938780612181;6143.569950103494;6157.876842463148;6183.876322473548;5015.84952451427;6091.299500557426;6115.602485838429;6124.6325220486915;6131.754729810825;6088.878222435548;6125.5712100153005;6133.723982420779;6138.785142519991;6140.293866205354;4918.434380046269;5028.245763135523;5256.369235691704;5477.178423236546;5570.805021824257;23555.646665300035;25622.359441014123;27322.31694207641;28744.856275718488;28970;2203.0747086223823;2271.239134889814;2272.0457407888725;2277.829162812796;2279.2706333973133;18037.549061273443;18122.63754724898;18673.626527469376;18698.345557905544;18862.056897155086;2115.153938424627;2141.7001619773246;2177.074743234119;2179.531400748831;2186.6173419651495;131701;133691.3100648455;159062.20468897698;160248.78258522024;165195;11353.772924541629;12529.686757830945;13087.803682944763;13192.538261160813;13234.272115033766;16953.321867125178;17282.567935801453;17302;17307.740383894252;17328;9147.54262286883;9237.630494780267;9346.439213647203;9350.57922393494;9424.151826335663;8687.478751274944;8717.215450609348;8789.956050219836;8962.283017358595;9003.594838232295;8121.431499794958;8219;8228.711995080144;8248.8350232995;8255.215754503313;2273.46508642862;2284.5202507473286;2288.164819840746;2290.931272061841;2436.6354841288935;867.3754896474528;877.5963056993727;974.6247694637474;981.2444417798398;992.8510723391549;20575.074121664562;20665.483362916046;20671.483212919797;21251.68122478164;21483.46291342729;1008.621766837436;1018.2363227579316;1046.1944302886886;1054.467493915583;1061.570064124031;7571.583562903983;9149.862752058725;10845.403502807263;10905.454727263605;10926.836097458545;501901;522774;530185.7453563615;538585;542319;124516;131957;139571;142737.1403487768;147588;1192.8031874740743;1221.2855479544567;1277.770001399757;1278.360819590205;1281.9807702884464;19199.232030719173;20116.59766804685;22473.88763056174;22657.527260728;22900.969456374838;59191.92828250991;60066;60439;61146.694266528386;61471.077933830136;82755;82942.75585866097;83510;84085;85234;8097.39269554774;8260.380471464785;8685.652573897227;8990.505522196341;9198.862017069616;13689.041767076209;13840.584782456543;14055.297235138407;14228;14254.572362829133;11692.74438175572;11824.527018919147;11863.703407414883;11951.581694640647;11991.700207494883;11138.220324577202;11214.102871770401;11404.771904562029;11508.309501429607;11545;11759.76480470403;12025.218360806519;12058.095642826647;12177.208481449032;12288;8703.825923481369;8745.56272186401;8859.3798434109;9088.591013404544;9241.722748317561;10618.831928487705;11728.648140555797;11931.642050738492;12076.09429292789;12152.331621760535 17:24:54 INFO - PID 1637 | |3;mootools.html;1249.4877100388842;1300.7073408483093;1412.6468382904275;1426.785982102685;1436.2172615924321;10408.531616077276;11293.797709446671;11454.541818327267;11831.645050648473;11892.405379731023;11477.942610286937;11762.882371176285;11822.349770762605;11846.7630647387;11863.050955923505;2121.809037186653;2499.150288901773;2547.7961763059;2547.821652484327;2553.5914253719407;2471.9134830280946;2520.4329025969196;2527.41869370045;2532.012515119105;2537.3910261537285;2483.7392073832257;2514.0697941761573;2537.6827896512937;2538.4034751833306;2545.185540627;2463.7536246375307;2498.775110240069;2518.445941892781;2522.4828910073393;2528.886200120999;2428.890699918508;2464.6056630939142;2477.962830557543;2487.216526794054;2518.8110891683195;11688.298702077818;11797.528098876035;11805.52777888892;11815.94092029543;11830.704232394173;6573.211214654272;6745.3591908768585;6823.829404264883;6830.112085428863;6836.4530837532875;3819.9427008594894;4089.9182016359805;4293.184294983943;4365.236083685374;4381.978090109561;1261.5962891874603;1267.8098285257192;1291.9354032298347;1296.5591698822445;1309.6856754378975;16109.83890161095;16434.260458279405;16551.83448165515;16585.83414165867;16732;5552.444755524415;5585.860353491195;5628.887422251532;5761.798337058183;5834.591578589458;11865.940670296593;11893.76212475763;11971.760564788656;11974.58088966882;11981.101417393731;6582.835429114215;6622.072909792633;6655.534112612071;6656.234533028667;6659.766908158288;6032.668203248863;6124.755009799647;6219.284782250009;6231.22109736283;6246.625202487865;3810.1046254130256;4226.682998775104;4336.1978034063795;4366.978165109154;4397.626201772821;1228.6989687526614;1288.571043582853;1289.6131160651767;1295.2681734819985;1300.4213125159401;702.3994484715495;778.3578547698172;782.8121250899743;823.8970128733908;830.2071521696886;6728.697208625624;7003.089598352181;7031.363238785456;7068.646567671699;7075.363217310467;933.8179055084329;952.6713283917038;954.9570269337896;957.6073809737974;960.9423434593947;3813.9427908581392;3893.761087374771;3980.482537270136;4156.688248381383;4315.223259813201;14845.92577037108;14910.552683419515;14944.70110597782;15250.542483725507;15268.847311526964;14490.203038832755;15029.549113526611;15062.849371506363;15065.096094234163;15111.31999060045;8326.958365208135;8515.74452766418;8672.913270867337;8745.956270218609;8751.168638979287;5693.601447898773;6821.624810635462;6967.233604303624;7158.677859496439;7207.963960180376;13178.736425271632;13318.400671969592;13431.059825812099;13493.122947008413;13667.726645466835;13683;13862.86137138656;14147.43410263578;14152.78770818418;14179.14925104497;7808.609569521387;9258.074192580689;9524.095210954949;9576.329656923948;9598.136167744937;10882.891171088188;10883.564657413615;10884;11031.944840275748;11052.778944421227;9048.68329608487;9189.540522973959;9219.49292788903;9230.815383692154;9338;7513.436492262993;8044.718434854752;9148.268138549034;9214.907850921672;9228.815423691623;8196.836063278583;8243.422960392954;8266.834663306821;8351.624176911931;8363.03825060126;4767.475577686521;6070.271567411939;6102.420270074336;6142.293636231891;6259.311475737574;6130.601510901777;6158.107074474086;6179.28938172116;6216.409441103088;6304.810855674337 17:24:54 INFO - PID 1637 | |4;prototype.html;371.40760487023186;409.2714967358104;414.57920210985844;414.8278464437259;421.4500077398995;5258.789648414068;5488.914212599212;6281.591696539733;6373.139626150468;6386.680665966706;5571.359293681227;5660.433956604337;5926.851828704284;6038.426349496802;6144.279601711517;4149.958500415002;4410.09593033429;4659.88350291242;4709.293605959099;4711.59708548641;3868.9613103869024;4187.183499217654;4677.976610116944;4693.741844198567;4715.575598196162;4411.5808998145285;4488.932666010012;4634.258518637019;4653.6044436222965;4676.298555216727;4348.130234045515;4367.563243675639;4649.279361698925;4662.790174442124;4701.106789709944;4042.312806822818;4151.91696166075;4162.543109911537;4173.916521669549;4177.060161463646;5200.413873768499;5780.393058728815;5988.610250774294;5991.147170791673;6077.696115194222;4418.381426600278;4648.697834640771;5558.944410555883;5559.861003474905;5568.470995255444;6257.655828929407;6461.450776683988;6585.308542603054;6675.499337549703;6676.866462670768;4009.979950100231;4680.906381872377;4719.646026547988;4729.628407761779;4729.905401891943;6390.421364036155;6799.150106236671;6970.686319115652;6990.126234220723;7017.2982701729925;4173.853915112956;4275.273203555403;4326.6538676905775;4340.826366945287;4366.253498600522;4343.435353404037;4384.4081049058605;4398.824047038147;4415.66882483815;4417.182821178092;4780.808767649255;5149.7940082397035;5230.607704422184;5238.371395432497;5243.5542978847225;6514.869702605922;6526.575772574767;6531.6081035137095;6544.803655890331;6558.081868538408;1265.373640048182;1313.8094976228585;1482.5478229140215;1499.6026053095939;1500.272367901567;947.2232769129248;969.6654654144309;980.2745967983641;985.8866230383454;995.2833959549113;5285.365756109291;5523.944760552343;6275.811725648146;6280.026595877646;6356.4279214870885;6251.906221406592;6295.464885484785;6816.352446517573;6852.657367131624;6861.828454288583;5876.118582212703;6085.726142323606;6169.81490555284;6329.841753956188;6442.871142577123;3478.89563313101;3566.554180727409;3609.5488063992;3619.1675914539805;3626.401643728808;5858.970705146532;6526.641034743135;6788.626625535544;7125.1093613298335;7139.357457828821;5730.7421166047625;5902.4097590240635;5915.911261331084;6839.589624622538;6895.620740859301;7137.89293160603;7154.35610795031;7172.063542843403;7201.4238860891;7225.5664660120565;6882.208546017272;6959.130108736408;6962.512624116263;6969.4424446045205;6978.232394436709;6790.830229244309;6796.626185559841;6820.283870193558;6847.828804279933;6873.250699720152;6080.422359875804;6972.407345375497;6995.335443132894;7073.257307982588;7162.283771623004;3355.798652080883;3917;4323.24343239938;4349.630281426114;4377.190219809346;6035.36628653985;6926.272741362084;6964.79105626832;6970.211915233947;6978.581285122909;6524.314946930694;6649.335066493505;6652.467802575879;6661.16735408074;6675.7997260082275;5546.473085056913;6636.369544893228;6665.700333384974;6678.922180815434;6720.596764194164;6198.752049918133;6359.20509936258;6480.837979050561;6495.642739649365;6507.917466410703;6308.495320374451;6621.735130594722;6677.966610166918;6688.632125232964;6708.295628958889;6189.257289125154;6215.005599104303;6428.260750013808;6503.764284785875;6521.445677117308 17:24:54 INFO - PID 1637 | |5;yui.html;851.7572491839827;860.1054902900979;864.5029108262748;887.6050157679836;890.2877697841727;16588.170591470425;17417.738733919003;17472.65054698905;18833.717494237597;19187.61624767504;18007.459776206735;18415.631687366244;18425.170867310968;18470.076496175203;18629.534261643465;998.0319090482238;1020.214434885138;1023.5087158164085;1024;1028.7325295423207;1024.2727663359024;1029.9948500257487;1038.3354653022072;1047.1570385839395;1047.984280235797;1073.382804887193;1083.9620613278537;1085.9782804343909;1087.26066274933;1088.6679562733364;1070.3631339353071;1077.709018564987;1077.8814330423725;1079.6707004363666;1083.3445765311951;772.447699894578;786.3748320085546;789.3961119743401;797.8922845415852;800.8238187598719;15478.690426191413;16770.245338959776;17068.658626827393;17321;17513.299468021265;17225.483235502954;17283.30866765328;17666.381676641256;17728.202230899642;17812.554686132822;14028.158310501403;14130.293485325692;14283.857161428357;14351.425942962269;14356.497522586764;3552;3576.7317451191266;3582.5342705448384;3582.7850328980344;3586.3006713690843;96812.54781178288;115307.2703909442;122731.3863430686;125360;125476;13667.31663416845;13762.380692868845;13937.372818223204;13985.860141398456;13988.650283742785;25178.622320665203;25915.22254332373;26022.219333420027;26097;26279;7196.96401517989;7915.485493442908;8173.5504547250475;8188.549629770419;8210.671573137128;4049.0484736086996;4067.8576249831112;4080.367543030835;4114.156597897424;4114.855980040684;4448.1770872388715;4458.866234012985;4519.841805536857;4524.117797029548;4536.455625324982;957.5690939077443;962.2782912815388;968.2883080935507;975;976.799756050008;597.2265915639258;652.4747578199594;665.6239224837951;668.5220067651652;670.299536983853;9965.55155018026;11228.270162439416;11434.714132146597;11922.403879806148;12081.879181208076;536.6994483089404;539.3204562251591;541.6695815552509;563.0681222576642;567.8637127089552;5422.132458806572;5496.367917689437;5579.972100139474;5679.403662615448;5743.0811070228565;101096;102821.48589257005;103460;104802.47598761957;117662;85109.57445212858;85564.57217713995;93447.0655293452;94756;95814.52092739631;85231;85675;86206;86419.56790216008;87569.24861502735;10904.691437027608;11952.940235298767;12212.694682633006;12667.873321267038;12676.605573387105;35709.28581428305;40406;41153.588464114975;41304.38043429411;41461.79269103635;47392.63036848075;53897;54377.72811135919;55354;55911.720441397534;16268.51194464168;16300.836991630405;16541.50375488689;16666.250018748942;16710.331586736393;17866.196021178817;18043.458696239133;18182.45452636423;18221.81778182254;18367.908160459112;15984.680306394039;16139.273732682292;16287.918560407123;16895.07077110771;16964.830351696324;13491.32543372847;15699.686006279582;16162.67674646524;16623;16690.49928502147;16058.43795467153;16756.497305080866;16759;16840.905341152786;16970;11692.532298708296;12053.939730301292;12067;12242.510299588273;12329;12879.291638959947;13039.413226404646;13052.955763539085;13069.542566010145;13117.93441032827 17:24:54 INFO - PID 1637 | __end_tp_report 17:24:54 INFO - PID 1637 | __start_cc_report 17:24:54 INFO - PID 1637 | _x_x_mozilla_cycle_collect,416 17:24:54 INFO - PID 1637 | __end_cc_report 17:24:54 INFO - PID 1637 | __startTimestamp1493425494802__endTimestamp 17:24:54 INFO - PID 1637 | 17:24:54 INFO - PID 1637 | ------- Summary: start ------- 17:24:54 INFO - PID 1637 | Number of tests: 6 17:24:54 INFO - PID 1637 | 17:24:54 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6118.76 Median:6311.08 stddev:754.58 (12.0%) stddev-sans-first:730.10 17:24:54 INFO - PID 1637 | Values: 3578.1 3619.4 3620.0 3640.0 3648.0 5082.6 5719.4 6136.6 6173.2 6210.8 5310.6 6161.9 6174.3 6184.3 6207.7 5339.6 5420.2 5428.5 5470.9 5591.6 5593.1 5598.7 5604.5 5648.6 5677.8 5489.3 5503.1 5504.0 5522.0 5528.0 5505.0 5534.5 5539.1 5539.2 5556.0 5411.3 5468.1 5473.0 5479.5 5479.6 4711.6 6127.7 6150.0 6195.1 6218.8 6677.3 6703.3 6716.2 6722.1 6726.5 6770.7 6810.8 6813.1 6816.9 6823.8 6126.8 6305.4 6307.6 6311.1 6325.0 6898.0 6940.5 6964.4 6996.8 7004.4 6372.8 6428.2 6496.3 6540.3 6621.4 6711.6 6796.3 6810.1 6825.9 6829.4 6334.9 6420.0 6663.9 6701.6 6702.9 6337.6 6630.9 6642.1 6693.7 6714.5 4367.2 5193.2 5340.8 5347.9 5349.9 4663.0 4743.2 4764.5 4786.8 4807.9 6110.8 6271.4 6280.2 6284.6 6305.6 6760.2 6804.1 6810.3 6810.7 6820.4 6249.6 6333.9 6347.8 6362.3 6366.8 5726.4 5795.2 5805.2 5808.1 5898.7 5863.0 6926.3 6980.6 7013.2 7036.2 6720.6 6798.4 6805.6 6811.5 6826.9 6690.4 6984.7 7010.9 7012.2 7022.4 6522.9 6561.7 6610.2 6642.3 6652.1 6430.2 6757.2 6769.5 6774.2 6804.8 5695.0 6096.8 6290.5 6337.8 6600.8 5420.3 5449.6 5586.0 6517.1 6582.4 4727.5 6233.9 6246.6 6271.6 6282.9 6511.9 6563.9 6610.4 6615.2 6627.9 5619.6 6377.1 6388.4 6390.8 6396.6 17:24:54 INFO - PID 1637 | 17:24:54 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:46511.06 Median:334835.50 stddev:83162.51 (24.8%) stddev-sans-first:83324.64 17:24:54 INFO - PID 1637 | Values: 689.6 982.7 1172.3 1207.6 1258.5 37340.8 37880.6 37984.4 38055.4 38055.6 37931.0 38328.2 38394.6 38400.6 38482.8 7096.0 7130.9 8484.5 8585.4 8592.4 8498.0 8501.2 8600.7 8601.8 8602.7 7858.7 8513.1 8535.2 8606.1 8608.7 7983.7 8519.6 8586.8 8588.3 8604.5 8339.5 8340.8 8398.9 8402.3 8420.2 36679.0 36718.8 36752.6 36800.6 36822.9 3645.8 3689.3 3689.9 3699.8 3700.1 30585.8 30593.0 30687.1 30691.2 30769.7 3160.7 3180.1 3182.3 3183.4 3186.6 253517.0 308782.0 310037.4 310212.0 311753.0 48130.8 48134.0 48409.0 48452.5 48536.0 37732.6 41171.4 49787.3 49977.8 51017.5 14172.3 14241.9 14250.4 14254.9 14262.1 11179.5 11410.4 11416.5 11419.1 11421.4 11929.0 12504.1 13443.5 13497.5 13574.9 5963.3 5975.3 6034.8 6040.9 6060.0 1032.4 1057.0 1061.4 1083.4 1091.3 20703.9 21245.7 22237.1 24531.8 24802.8 1111.6 1148.5 1160.8 1188.8 1192.8 11952.5 12281.3 12289.7 12348.6 12363.4 331376.3 334014.0 334487.0 335184.0 336180.0 51885.7 52178.5 52244.0 52274.7 52430.2 1465.3 1498.9 1534.9 1543.3 1559.8 23334.4 23359.0 23416.1 23516.6 23580.6 37573.2 50504.7 51183.0 51253.2 51333.9 311526.0 312638.0 316344.0 316430.0 316885.0 36011.0 36309.8 36402.0 36468.0 36863.6 43281.6 43718.9 43751.6 44962.9 45070.0 29329.9 29785.3 30275.5 30860.8 31202.0 27491.9 30040.8 30109.0 30240.4 30622.0 35953.1 36668.3 37205.6 37483.1 37523.2 29750.4 30301.4 31106.0 31191.5 31238.2 22626.2 22769.4 23180.8 24019.4 24271.4 17:24:54 INFO - PID 1637 | 17:24:54 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:35454.87 Median:2289.55 stddev:90003.52 (3931.1%) stddev-sans-first:90217.51 17:24:54 INFO - PID 1637 | Values: 397.0 441.5 441.8 443.7 463.2 21096.7 22000.2 22238.0 22736.3 22838.2 22192.3 22995.3 23613.5 23683.5 23774.8 5949.2 6053.4 6199.4 6240.1 6245.3 5976.8 6121.9 6143.6 6157.9 6183.9 5015.8 6091.3 6115.6 6124.6 6131.8 6088.9 6125.6 6133.7 6138.8 6140.3 4918.4 5028.2 5256.4 5477.2 5570.8 23555.6 25622.4 27322.3 28744.9 28970.0 2203.1 2271.2 2272.0 2277.8 2279.3 18037.5 18122.6 18673.6 18698.3 18862.1 2115.2 2141.7 2177.1 2179.5 2186.6 131701.0 133691.3 159062.2 160248.8 165195.0 11353.8 12529.7 13087.8 13192.5 13234.3 16953.3 17282.6 17302.0 17307.7 17328.0 9147.5 9237.6 9346.4 9350.6 9424.2 8687.5 8717.2 8790.0 8962.3 9003.6 8121.4 8219.0 8228.7 8248.8 8255.2 2273.5 2284.5 2288.2 2290.9 2436.6 867.4 877.6 974.6 981.2 992.9 20575.1 20665.5 20671.5 21251.7 21483.5 1008.6 1018.2 1046.2 1054.5 1061.6 7571.6 9149.9 10845.4 10905.5 10926.8 501901.0 522774.0 530185.7 538585.0 542319.0 124516.0 131957.0 139571.0 142737.1 147588.0 1192.8 1221.3 1277.8 1278.4 1282.0 19199.2 20116.6 22473.9 22657.5 22901.0 59191.9 60066.0 60439.0 61146.7 61471.1 82755.0 82942.8 83510.0 84085.0 85234.0 8097.4 8260.4 8685.7 8990.5 9198.9 13689.0 13840.6 14055.3 14228.0 14254.6 11692.7 11824.5 11863.7 11951.6 11991.7 11138.2 11214.1 11404.8 11508.3 11545.0 11759.8 12025.2 12058.1 12177.2 12288.0 8703.8 8745.6 8859.4 9088.6 9241.7 10618.8 11728.6 11931.6 12076.1 12152.3 17:24:54 INFO - PID 1637 | 17:24:54 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:7145.33 Median:3812.02 stddev:4526.56 (118.7%) stddev-sans-first:4517.57 17:24:54 INFO - PID 1637 | Values: 1249.5 1300.7 1412.6 1426.8 1436.2 10408.5 11293.8 11454.5 11831.6 11892.4 11477.9 11762.9 11822.3 11846.8 11863.1 2121.8 2499.2 2547.8 2547.8 2553.6 2471.9 2520.4 2527.4 2532.0 2537.4 2483.7 2514.1 2537.7 2538.4 2545.2 2463.8 2498.8 2518.4 2522.5 2528.9 2428.9 2464.6 2478.0 2487.2 2518.8 11688.3 11797.5 11805.5 11815.9 11830.7 6573.2 6745.4 6823.8 6830.1 6836.5 3819.9 4089.9 4293.2 4365.2 4382.0 1261.6 1267.8 1291.9 1296.6 1309.7 16109.8 16434.3 16551.8 16585.8 16732.0 5552.4 5585.9 5628.9 5761.8 5834.6 11865.9 11893.8 11971.8 11974.6 11981.1 6582.8 6622.1 6655.5 6656.2 6659.8 6032.7 6124.8 6219.3 6231.2 6246.6 3810.1 4226.7 4336.2 4367.0 4397.6 1228.7 1288.6 1289.6 1295.3 1300.4 702.4 778.4 782.8 823.9 830.2 6728.7 7003.1 7031.4 7068.6 7075.4 933.8 952.7 955.0 957.6 960.9 3813.9 3893.8 3980.5 4156.7 4315.2 14845.9 14910.6 14944.7 15250.5 15268.8 14490.2 15029.5 15062.8 15065.1 15111.3 8327.0 8515.7 8672.9 8746.0 8751.2 5693.6 6821.6 6967.2 7158.7 7208.0 13178.7 13318.4 13431.1 13493.1 13667.7 13683.0 13862.9 14147.4 14152.8 14179.1 7808.6 9258.1 9524.1 9576.3 9598.1 10882.9 10883.6 10884.0 11031.9 11052.8 9048.7 9189.5 9219.5 9230.8 9338.0 7513.4 8044.7 9148.3 9214.9 9228.8 8196.8 8243.4 8266.8 8351.6 8363.0 4767.5 6070.3 6102.4 6142.3 6259.3 6130.6 6158.1 6179.3 6216.4 6304.8 17:24:54 INFO - PID 1637 | 17:24:54 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5346.30 Median:6083.07 stddev:1699.93 (27.9%) stddev-sans-first:1663.19 17:24:54 INFO - PID 1637 | Values: 371.4 409.3 414.6 414.8 421.5 5258.8 5488.9 6281.6 6373.1 6386.7 5571.4 5660.4 5926.9 6038.4 6144.3 4150.0 4410.1 4659.9 4709.3 4711.6 3869.0 4187.2 4678.0 4693.7 4715.6 4411.6 4488.9 4634.3 4653.6 4676.3 4348.1 4367.6 4649.3 4662.8 4701.1 4042.3 4151.9 4162.5 4173.9 4177.1 5200.4 5780.4 5988.6 5991.1 6077.7 4418.4 4648.7 5558.9 5559.9 5568.5 6257.7 6461.5 6585.3 6675.5 6676.9 4010.0 4680.9 4719.6 4729.6 4729.9 6390.4 6799.2 6970.7 6990.1 7017.3 4173.9 4275.3 4326.7 4340.8 4366.3 4343.4 4384.4 4398.8 4415.7 4417.2 4780.8 5149.8 5230.6 5238.4 5243.6 6514.9 6526.6 6531.6 6544.8 6558.1 1265.4 1313.8 1482.5 1499.6 1500.3 947.2 969.7 980.3 985.9 995.3 5285.4 5523.9 6275.8 6280.0 6356.4 6251.9 6295.5 6816.4 6852.7 6861.8 5876.1 6085.7 6169.8 6329.8 6442.9 3478.9 3566.6 3609.5 3619.2 3626.4 5859.0 6526.6 6788.6 7125.1 7139.4 5730.7 5902.4 5915.9 6839.6 6895.6 7137.9 7154.4 7172.1 7201.4 7225.6 6882.2 6959.1 6962.5 6969.4 6978.2 6790.8 6796.6 6820.3 6847.8 6873.3 6080.4 6972.4 6995.3 7073.3 7162.3 3355.8 3917.0 4323.2 4349.6 4377.2 6035.4 6926.3 6964.8 6970.2 6978.6 6524.3 6649.3 6652.5 6661.2 6675.8 5546.5 6636.4 6665.7 6678.9 6720.6 6198.8 6359.2 6480.8 6495.6 6507.9 6308.5 6621.7 6678.0 6688.6 6708.3 6189.3 6215.0 6428.3 6503.8 6521.4 17:24:54 INFO - PID 1637 | 17:24:54 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:21779.49 Median:17936.83 stddev:30211.55 (168.4%) stddev-sans-first:30255.43 17:24:54 INFO - PID 1637 | Values: 851.8 860.1 864.5 887.6 890.3 16588.2 17417.7 17472.7 18833.7 19187.6 18007.5 18415.6 18425.2 18470.1 18629.5 998.0 1020.2 1023.5 1024.0 1028.7 1024.3 1030.0 1038.3 1047.2 1048.0 1073.4 1084.0 1086.0 1087.3 1088.7 1070.4 1077.7 1077.9 1079.7 1083.3 772.4 786.4 789.4 797.9 800.8 15478.7 16770.2 17068.7 17321.0 17513.3 17225.5 17283.3 17666.4 17728.2 17812.6 14028.2 14130.3 14283.9 14351.4 14356.5 3552.0 3576.7 3582.5 3582.8 3586.3 96812.5 115307.3 122731.4 125360.0 125476.0 13667.3 13762.4 13937.4 13985.9 13988.7 25178.6 25915.2 26022.2 26097.0 26279.0 7197.0 7915.5 8173.6 8188.5 8210.7 4049.0 4067.9 4080.4 4114.2 4114.9 4448.2 4458.9 4519.8 4524.1 4536.5 957.6 962.3 968.3 975.0 976.8 597.2 652.5 665.6 668.5 670.3 9965.6 11228.3 11434.7 11922.4 12081.9 536.7 539.3 541.7 563.1 567.9 5422.1 5496.4 5580.0 5679.4 5743.1 101096.0 102821.5 103460.0 104802.5 117662.0 85109.6 85564.6 93447.1 94756.0 95814.5 85231.0 85675.0 86206.0 86419.6 87569.2 10904.7 11952.9 12212.7 12667.9 12676.6 35709.3 40406.0 41153.6 41304.4 41461.8 47392.6 53897.0 54377.7 55354.0 55911.7 16268.5 16300.8 16541.5 16666.3 16710.3 17866.2 18043.5 18182.5 18221.8 18367.9 15984.7 16139.3 16287.9 16895.1 16964.8 13491.3 15699.7 16162.7 16623.0 16690.5 16058.4 16756.5 16759.0 16840.9 16970.0 11692.5 12053.9 12067.0 12242.5 12329.0 12879.3 13039.4 13053.0 13069.5 13117.9 17:24:54 INFO - PID 1637 | -------- Summary: end -------- 17:24:54 INFO - PID 1637 | 17:24:55 INFO - TEST-INFO | 1637: exit 0 17:24:55 INFO - TEST-OK | dromaeo_css | took 1115455ms 17:24:55 INFO - TEST-START | kraken 17:24:55 INFO - operating with platform_type : mac_ 17:24:55 INFO - Initialising browser for kraken test... 17:24:55 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpik39Kq/profile http://localhost:49231/getInfo.html) 17:24:57 INFO - PID 1649 | 2017-04-28 17:24:57.813 plugin-container[1650:10294] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913f, name = 'com.apple.tsm.portname' 17:24:57 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:24:57 INFO - PID 1649 | 2017-04-28 17:24:57.816 plugin-container[1650:10294] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 17:24:57 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:24:57 INFO - PID 1649 | 2017-04-28 17:24:57.816 plugin-container[1650:10294] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:24:58 INFO - PID 1649 | __metrics Screen width/height:1600/1200 17:24:58 INFO - PID 1649 | colorDepth:24 17:24:58 INFO - PID 1649 | Browser inner width/height: 1024/689 17:24:58 INFO - PID 1649 | __metrics 17:24:58 ERROR - PID 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:24:59 INFO - TEST-INFO | 1649: exit 0 17:24:59 INFO - Browser initialized. 17:24:59 INFO - Running cycle 1/1 for kraken test... 17:24:59 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpik39Kq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:25:01 INFO - PID 1651 | 2017-04-28 17:25:01.313 plugin-container[1652:10416] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 17:25:01 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:25:01 INFO - PID 1651 | 2017-04-28 17:25:01.315 plugin-container[1652:10416] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 17:25:01 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:25:01 INFO - PID 1651 | 2017-04-28 17:25:01.315 plugin-container[1652:10416] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:25:11 INFO - PID 1651 | RSS: Main: 251564032 17:25:11 INFO - PID 1651 | 17:25:14 INFO - PID 1651 | 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:15 INFO - PID 1651 | RSS: Main: 259448832 17:25:15 INFO - PID 1651 | 17:25:17 INFO - PID 1651 | 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:18 INFO - PID 1651 | RSS: Main: 266301440 17:25:18 INFO - PID 1651 | 17:25:21 INFO - PID 1651 | 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:22 INFO - PID 1651 | RSS: Main: 262848512 17:25:22 INFO - PID 1651 | 17:25:23 INFO - PID 1651 | 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:24 INFO - PID 1651 | RSS: Main: 264781824 17:25:24 INFO - PID 1651 | 17:25:26 INFO - PID 1651 | 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:27 INFO - PID 1651 | RSS: Main: 265519104 17:25:27 INFO - PID 1651 | 17:25:29 INFO - PID 1651 | 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:30 INFO - PID 1651 | RSS: Main: 270766080 17:25:30 INFO - PID 1651 | 17:25:33 INFO - PID 1651 | 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:34 INFO - PID 1651 | RSS: Main: 273211392 17:25:34 INFO - PID 1651 | 17:25:37 INFO - PID 1651 | 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:38 INFO - PID 1651 | RSS: Main: 272207872 17:25:38 INFO - PID 1651 | 17:25:39 INFO - PID 1651 | 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:40 INFO - PID 1651 | RSS: Main: 271282176 17:25:40 INFO - PID 1651 | 17:25:41 INFO - PID 1651 | 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:42 INFO - PID 1651 | RSS: Main: 271118336 17:25:42 INFO - PID 1651 | 17:25:44 INFO - PID 1651 | 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:45 INFO - PID 1651 | RSS: Main: 270909440 17:25:45 INFO - PID 1651 | 17:25:48 INFO - PID 1651 | 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:48 INFO - PID 1651 | RSS: Main: 271028224 17:25:48 INFO - PID 1651 | 17:25:52 INFO - PID 1651 | 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:52 INFO - PID 1651 | RSS: Main: 268034048 17:25:52 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | 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:54 INFO - PID 1651 | RSS: Main: 270090240 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | __start_tp_report 17:25:54 INFO - PID 1651 | _x_x_mozilla_page_load 17:25:54 INFO - PID 1651 | _x_x_mozilla_page_load_details 17:25:54 INFO - PID 1651 | |i|pagename|runs| 17:25:54 INFO - PID 1651 | |0;ai-astar;105;115;114;114;114;114;114;114;115;113 17:25:54 INFO - PID 1651 | |1;audio-beat-detection;142;109;110;141;119;106;111;111;145;140 17:25:54 INFO - PID 1651 | |2;audio-dft;199;198;202;222;204;222;197;199;195;204 17:25:54 INFO - PID 1651 | |3;audio-fft;79;55;57;80;59;61;58;56;77;59 17:25:54 INFO - PID 1651 | |4;audio-oscillator;87;69;83;71;69;68;85;69;72;88 17:25:54 INFO - PID 1651 | |5;imaging-gaussian-blur;88;90;88;88;95;88;89;89;88;93 17:25:54 INFO - PID 1651 | |6;imaging-darkroom;96;95;96;94;96;95;121;151;128;96 17:25:54 INFO - PID 1651 | |7;imaging-desaturate;114;93;90;90;108;145;127;92;87;89 17:25:54 INFO - PID 1651 | |8;json-parse-financial;44;48;78;40;42;64;55;47;41;41 17:25:54 INFO - PID 1651 | |9;json-stringify-tinderbox;56;61;65;62;68;62;59;56;59;62 17:25:54 INFO - PID 1651 | |10;stanford-crypto-aes;82;85;83;83;84;82;78;79;82;95 17:25:54 INFO - PID 1651 | |11;stanford-crypto-ccm;136;136;137;138;124;157;139;126;187;166 17:25:54 INFO - PID 1651 | |12;stanford-crypto-pbkdf2;245;225;183;222;183;160;247;168;224;234 17:25:54 INFO - PID 1651 | |13;stanford-crypto-sha256-iterative;66;81;97;72;56;55;76;98;56;71 17:25:54 INFO - PID 1651 | __end_tp_report 17:25:54 INFO - PID 1651 | __start_cc_report 17:25:54 INFO - PID 1651 | _x_x_mozilla_cycle_collect,996 17:25:54 INFO - PID 1651 | __end_cc_report 17:25:54 INFO - PID 1651 | __startTimestamp1493425554819__endTimestamp 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | ------- Summary: start ------- 17:25:54 INFO - PID 1651 | Number of tests: 14 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:2.94 (2.6%) stddev-sans-first:0.60 17:25:54 INFO - PID 1651 | Values: 105.0 115.0 114.0 114.0 114.0 114.0 114.0 114.0 115.0 113.0 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | [#1] audio-beat-detection Cycles:10 Average:123.40 Median:129.50 stddev:16.38 (12.6%) stddev-sans-first:15.93 17:25:54 INFO - PID 1651 | Values: 142.0 109.0 110.0 141.0 119.0 106.0 111.0 111.0 145.0 140.0 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | [#2] audio-dft Cycles:10 Average:204.20 Median:203.00 stddev:9.82 (4.8%) stddev-sans-first:10.23 17:25:54 INFO - PID 1651 | Values: 199.0 198.0 202.0 222.0 204.0 222.0 197.0 199.0 195.0 204.0 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | [#3] audio-fft Cycles:10 Average:64.10 Median:60.00 stddev:10.21 (17.0%) stddev-sans-first:9.30 17:25:54 INFO - PID 1651 | Values: 79.0 55.0 57.0 80.0 59.0 61.0 58.0 56.0 77.0 59.0 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:77.50 stddev:8.48 (10.9%) stddev-sans-first:8.02 17:25:54 INFO - PID 1651 | Values: 87.0 69.0 83.0 71.0 69.0 68.0 85.0 69.0 72.0 88.0 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:89.00 stddev:2.46 (2.8%) stddev-sans-first:2.54 17:25:54 INFO - PID 1651 | Values: 88.0 90.0 88.0 88.0 95.0 88.0 89.0 89.0 88.0 93.0 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | [#6] imaging-darkroom Cycles:10 Average:106.80 Median:95.50 stddev:19.76 (20.7%) stddev-sans-first:20.57 17:25:54 INFO - PID 1651 | Values: 96.0 95.0 96.0 94.0 96.0 95.0 121.0 151.0 128.0 96.0 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | [#7] imaging-desaturate Cycles:10 Average:103.50 Median:89.50 stddev:19.70 (22.0%) stddev-sans-first:20.53 17:25:54 INFO - PID 1651 | Values: 114.0 93.0 90.0 90.0 108.0 145.0 127.0 92.0 87.0 89.0 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:47.50 stddev:12.38 (26.1%) stddev-sans-first:12.94 17:25:54 INFO - PID 1651 | Values: 44.0 48.0 78.0 40.0 42.0 64.0 55.0 47.0 41.0 41.0 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:62.00 stddev:3.74 (6.0%) stddev-sans-first:3.50 17:25:54 INFO - PID 1651 | Values: 56.0 61.0 65.0 62.0 68.0 62.0 59.0 56.0 59.0 62.0 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:83.00 stddev:4.62 (5.6%) stddev-sans-first:4.88 17:25:54 INFO - PID 1651 | Values: 82.0 85.0 83.0 83.0 84.0 82.0 78.0 79.0 82.0 95.0 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:144.60 Median:138.50 stddev:19.61 (14.2%) stddev-sans-first:20.55 17:25:54 INFO - PID 1651 | Values: 136.0 136.0 137.0 138.0 124.0 157.0 139.0 126.0 187.0 166.0 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:209.10 Median:224.50 stddev:32.40 (14.4%) stddev-sans-first:31.66 17:25:54 INFO - PID 1651 | Values: 245.0 225.0 183.0 222.0 183.0 160.0 247.0 168.0 224.0 234.0 17:25:54 INFO - PID 1651 | 17:25:54 INFO - PID 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:74.00 stddev:15.74 (21.3%) stddev-sans-first:16.50 17:25:54 INFO - PID 1651 | Values: 66.0 81.0 97.0 72.0 56.0 55.0 76.0 98.0 56.0 71.0 17:25:54 INFO - PID 1651 | -------- Summary: end -------- 17:25:54 INFO - PID 1651 | 17:25:55 INFO - TEST-INFO | 1651: exit 0 17:25:55 INFO - TEST-OK | kraken | took 60456ms 17:25:55 INFO - SUITE-END | took 1175s 17:25:55 INFO - Completed test suite (00:19:35) 17:25:55 INFO - javascript benchmark 17:25:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7940.022894181097, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3578.123359776855, 3619.384704600218, 3620.0406892173573, 3639.963600363996, 3647.9817600911992, 5082.644214904958, 5719.428057194278, 6136.601120927147, 6173.197484327036, 6210.78262260821, 5310.628256022071, 6161.907571386421, 6174.289956654986, 6184.288806787221, 6207.689615519217, 5339.5728341732565, 5420.214068959998, 5428.45715428456, 5470.863228419292, 5591.580631452637, 5593.105103183491, 5598.748056337454, 5604.523615492688, 5648.604597678164, 5677.801276955307, 5489.286392768954, 5503.147012213073, 5504.0, 5521.972390138024, 5528.0, 5504.981578408007, 5534.4742249486235, 5539.058360078756, 5539.224508568804, 5556.027695153332, 5411.323584551931, 5468.0977638689565, 5473.01485732568, 5479.506844383985, 5479.561635069224, 4711.575958163748, 6127.662978536178, 6150.0, 6195.132681424604, 6218.81343559694, 6677.332266773284, 6703.296153903819, 6716.194056713177, 6722.058911752359, 6726.529142959995, 6770.661466926634, 6810.829729256759, 6813.080234168373, 6816.863662726767, 6823.761168359133, 6126.823043872739, 6305.369463053704, 6307.621542707452, 6311.084892690534, 6324.968375158095, 6897.965510172417, 6940.479464040217, 6964.4428445724225, 6996.825079373057, 7004.3696067354185, 6372.776952806629, 6428.228612566522, 6496.317886621931, 6540.2805691373915, 6621.370969757865, 6711.630860302633, 6796.25241223465, 6810.080639113756, 6825.863482730318, 6829.419499342513, 6334.936650633527, 6419.967900160469, 6663.86672266552, 6701.597904125768, 6702.865942681216, 6337.556371054075, 6630.900536491957, 6642.136522252076, 6693.653173413293, 6714.462842972551, 4367.192069467159, 5193.246979188072, 5340.839774806762, 5347.86630334237, 5349.946500535023, 4663.020765639186, 4743.169945259566, 4764.523547645208, 4786.832460863922, 4807.879803004953, 6110.847228819226, 6271.404216599416, 6280.214973128359, 6284.622922624657, 6305.558610897193, 6760.18877734675, 6804.081449004321, 6810.31896810315, 6810.727570897207, 6820.420264277493, 6249.593776404519, 6333.904991425133, 6347.841303967345, 6362.331955144643, 6366.808995730135, 5726.3700992890745, 5795.1886735857015, 5805.158252053428, 5808.128780682847, 5898.734556944948, 5862.970685146717, 6926.307369263134, 6980.616066116412, 7013.193482749549, 7036.155661320674, 6720.596764194164, 6798.388145066968, 6805.591664500146, 6811.455083593201, 6826.8634627308165, 6690.364415380531, 6984.720611175496, 7010.929890701028, 7012.158540975116, 7022.4382049436945, 6522.934770652233, 6561.704723287368, 6610.2398224204835, 6642.335766423513, 6652.068710380648, 6430.164078669743, 6757.189137303555, 6769.4922880785125, 6774.187097548326, 6804.795856124324, 5694.971525142348, 6096.786612468542, 6290.465310448663, 6337.809865703852, 6600.768973085919, 5420.322459692538, 5449.645773024899, 5586.022446072003, 6517.087607734826, 6582.3746744059235, 4727.503612120678, 6233.906491402542, 6246.562740608114, 6271.560990730605, 6282.937170628235, 6511.902321465276, 6563.901541476977, 6610.3720146586, 6615.17310336208, 6627.867442651216, 5619.550435965031, 6377.139086223486, 6388.361163883575, 6390.7763228286785, 6396.552241343062], "value": 6070.83325949752, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.6379400814571, 982.6609819612231, 1172.3434876469173, 1207.6256360528237, 1258.465152310268, 37340.81329593352, 37880.62119378805, 37984.43023354648, 38055.42916856249, 38055.61944380555, 37931.0, 38328.233435331276, 38394.616053839454, 38400.61599384005, 38482.80758596203, 7096.0, 7130.930360445936, 8484.533350665712, 8585.441946273504, 8592.441491303045, 8498.0, 8501.19238672325, 8600.741977740678, 8601.784955376103, 8602.741917742447, 7858.7426011838115, 8513.148685131498, 8535.231829135377, 8606.139386061406, 8608.698695545658, 7983.680652773882, 8519.57402129891, 8586.828263434696, 8588.269997050258, 8604.52675102869, 8339.54132522711, 8340.791480212982, 8398.874016889751, 8402.327813774884, 8420.24217820393, 36679.0, 36718.81640591806, 36752.632473675185, 36800.631993680254, 36822.89531314038, 3645.7994810285422, 3689.2805902848954, 3689.8524059037604, 3699.815009249554, 3700.0749812546865, 30585.847070764503, 30593.0, 30687.079387618407, 30691.232719181975, 30769.692303076907, 3160.6997335253113, 3180.0777774445382, 3182.252170739874, 3183.442897492953, 3186.633537143212, 253517.0, 308782.0, 310037.4498127495, 310212.0, 311753.0, 48130.75934620375, 48134.03731925342, 48409.0, 48452.5154748448, 48536.0, 37732.62267377346, 41171.38242926358, 49787.25319120216, 49977.75011124921, 51017.48982510127, 14172.291385430894, 14241.85758142426, 14250.358733857001, 14254.928725356307, 14262.072965257224, 11179.4969226385, 11410.42947852604, 11416.486258118404, 11419.143564232716, 11421.42892855354, 11929.0, 12504.124711270124, 13443.529476468473, 13497.460101596023, 13574.93212533931, 5963.2844058713235, 5975.342712301644, 6034.793041391651, 6040.879182416327, 6060.0, 1032.3547782635853, 1057.0, 1061.368485750977, 1083.4041277297456, 1091.2743025887855, 20703.861287629024, 21245.70459515342, 22237.110515579196, 24531.773411329363, 24802.751972480044, 1111.555377848867, 1148.5004023249912, 1160.7620437810228, 1188.7563049574817, 1192.797224471842, 11952.521899123938, 12281.324527151093, 12289.69275768113, 12348.629541113782, 12363.381830908598, 331376.3431182877, 334014.0, 334487.0, 335184.0, 336180.0, 51885.740571297654, 52178.47821521812, 52244.0, 52274.693132671986, 52430.213546796826, 1465.2746890289375, 1498.8501149884924, 1534.90023148495, 1543.2746609093708, 1559.7894284271706, 23334.416639584146, 23359.0, 23416.06335746619, 23516.647250290916, 23580.646290306002, 37573.24267573221, 50504.74747626238, 51183.0, 51253.231201531264, 51333.89328853608, 311526.0, 312638.0, 316344.0, 316430.0, 316885.0, 36011.0, 36309.818450908635, 36402.0, 36468.0, 36863.63136368709, 43281.56718432901, 43718.907027324574, 43751.56248437475, 44962.87592810206, 45070.0, 29329.85335073311, 29785.334939974906, 30275.545866812456, 30860.84569577138, 31202.0, 27491.86254068717, 30040.849795751754, 30109.0, 30240.395192095595, 30622.0, 35953.101172470895, 36668.26663466769, 37205.62794372022, 37483.06292342713, 37523.2495350086, 29750.404991899606, 30301.393972120874, 31106.0, 31191.532127017657, 31238.21904452316, 22626.208082717025, 22769.430764231027, 23180.76819231786, 24019.399515012265, 24271.393215169763], "value": 17715.529904112173, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.02530288142606, 441.4680310226176, 441.7989814634342, 443.680550003997, 463.1732357741432, 21096.683549746747, 22000.229991950284, 22238.0, 22736.317910462712, 22838.2006629768, 22192.334229973087, 22995.310140695805, 23613.5277294454, 23683.5263294734, 23774.76225237747, 5949.167116603679, 6053.394660533955, 6199.380061993787, 6240.063990401431, 6245.313015568284, 5976.790812321569, 6121.938780612181, 6143.569950103494, 6157.876842463148, 6183.876322473548, 5015.84952451427, 6091.299500557426, 6115.602485838429, 6124.6325220486915, 6131.754729810825, 6088.878222435548, 6125.5712100153005, 6133.723982420779, 6138.785142519991, 6140.293866205354, 4918.434380046269, 5028.245763135523, 5256.369235691704, 5477.178423236546, 5570.805021824257, 23555.646665300035, 25622.359441014123, 27322.31694207641, 28744.856275718488, 28970.0, 2203.0747086223823, 2271.239134889814, 2272.0457407888725, 2277.829162812796, 2279.2706333973133, 18037.549061273443, 18122.63754724898, 18673.626527469376, 18698.345557905544, 18862.056897155086, 2115.153938424627, 2141.7001619773246, 2177.074743234119, 2179.531400748831, 2186.6173419651495, 131701.0, 133691.3100648455, 159062.20468897698, 160248.78258522024, 165195.0, 11353.772924541629, 12529.686757830945, 13087.803682944763, 13192.538261160813, 13234.272115033766, 16953.321867125178, 17282.567935801453, 17302.0, 17307.740383894252, 17328.0, 9147.54262286883, 9237.630494780267, 9346.439213647203, 9350.57922393494, 9424.151826335663, 8687.478751274944, 8717.215450609348, 8789.956050219836, 8962.283017358595, 9003.594838232295, 8121.431499794958, 8219.0, 8228.711995080144, 8248.8350232995, 8255.215754503313, 2273.46508642862, 2284.5202507473286, 2288.164819840746, 2290.931272061841, 2436.6354841288935, 867.3754896474528, 877.5963056993727, 974.6247694637474, 981.2444417798398, 992.8510723391549, 20575.074121664562, 20665.483362916046, 20671.483212919797, 21251.68122478164, 21483.46291342729, 1008.621766837436, 1018.2363227579316, 1046.1944302886886, 1054.467493915583, 1061.570064124031, 7571.583562903983, 9149.862752058725, 10845.403502807263, 10905.454727263605, 10926.836097458545, 501901.0, 522774.0, 530185.7453563615, 538585.0, 542319.0, 124516.0, 131957.0, 139571.0, 142737.1403487768, 147588.0, 1192.8031874740743, 1221.2855479544567, 1277.770001399757, 1278.360819590205, 1281.9807702884464, 19199.232030719173, 20116.59766804685, 22473.88763056174, 22657.527260728, 22900.969456374838, 59191.92828250991, 60066.0, 60439.0, 61146.694266528386, 61471.077933830136, 82755.0, 82942.75585866097, 83510.0, 84085.0, 85234.0, 8097.39269554774, 8260.380471464785, 8685.652573897227, 8990.505522196341, 9198.862017069616, 13689.041767076209, 13840.584782456543, 14055.297235138407, 14228.0, 14254.572362829133, 11692.74438175572, 11824.527018919147, 11863.703407414883, 11951.581694640647, 11991.700207494883, 11138.220324577202, 11214.102871770401, 11404.771904562029, 11508.309501429607, 11545.0, 11759.76480470403, 12025.218360806519, 12058.095642826647, 12177.208481449032, 12288.0, 8703.825923481369, 8745.56272186401, 8859.3798434109, 9088.591013404544, 9241.722748317561, 10618.831928487705, 11728.648140555797, 11931.642050738492, 12076.09429292789, 12152.331621760535], "value": 10600.587026136138, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1249.4877100388842, 1300.7073408483093, 1412.6468382904275, 1426.785982102685, 1436.2172615924321, 10408.531616077276, 11293.797709446671, 11454.541818327267, 11831.645050648473, 11892.405379731023, 11477.942610286937, 11762.882371176285, 11822.349770762605, 11846.7630647387, 11863.050955923505, 2121.809037186653, 2499.150288901773, 2547.7961763059, 2547.821652484327, 2553.5914253719407, 2471.9134830280946, 2520.4329025969196, 2527.41869370045, 2532.012515119105, 2537.3910261537285, 2483.7392073832257, 2514.0697941761573, 2537.6827896512937, 2538.4034751833306, 2545.185540627, 2463.7536246375307, 2498.775110240069, 2518.445941892781, 2522.4828910073393, 2528.886200120999, 2428.890699918508, 2464.6056630939142, 2477.962830557543, 2487.216526794054, 2518.8110891683195, 11688.298702077818, 11797.528098876035, 11805.52777888892, 11815.94092029543, 11830.704232394173, 6573.211214654272, 6745.3591908768585, 6823.829404264883, 6830.112085428863, 6836.4530837532875, 3819.9427008594894, 4089.9182016359805, 4293.184294983943, 4365.236083685374, 4381.978090109561, 1261.5962891874603, 1267.8098285257192, 1291.9354032298347, 1296.5591698822445, 1309.6856754378975, 16109.83890161095, 16434.260458279405, 16551.83448165515, 16585.83414165867, 16732.0, 5552.444755524415, 5585.860353491195, 5628.887422251532, 5761.798337058183, 5834.591578589458, 11865.940670296593, 11893.76212475763, 11971.760564788656, 11974.58088966882, 11981.101417393731, 6582.835429114215, 6622.072909792633, 6655.534112612071, 6656.234533028667, 6659.766908158288, 6032.668203248863, 6124.755009799647, 6219.284782250009, 6231.22109736283, 6246.625202487865, 3810.1046254130256, 4226.682998775104, 4336.1978034063795, 4366.978165109154, 4397.626201772821, 1228.6989687526614, 1288.571043582853, 1289.6131160651767, 1295.2681734819985, 1300.4213125159401, 702.3994484715495, 778.3578547698172, 782.8121250899743, 823.8970128733908, 830.2071521696886, 6728.697208625624, 7003.089598352181, 7031.363238785456, 7068.646567671699, 7075.363217310467, 933.8179055084329, 952.6713283917038, 954.9570269337896, 957.6073809737974, 960.9423434593947, 3813.9427908581392, 3893.761087374771, 3980.482537270136, 4156.688248381383, 4315.223259813201, 14845.92577037108, 14910.552683419515, 14944.70110597782, 15250.542483725507, 15268.847311526964, 14490.203038832755, 15029.549113526611, 15062.849371506363, 15065.096094234163, 15111.31999060045, 8326.958365208135, 8515.74452766418, 8672.913270867337, 8745.956270218609, 8751.168638979287, 5693.601447898773, 6821.624810635462, 6967.233604303624, 7158.677859496439, 7207.963960180376, 13178.736425271632, 13318.400671969592, 13431.059825812099, 13493.122947008413, 13667.726645466835, 13683.0, 13862.86137138656, 14147.43410263578, 14152.78770818418, 14179.14925104497, 7808.609569521387, 9258.074192580689, 9524.095210954949, 9576.329656923948, 9598.136167744937, 10882.891171088188, 10883.564657413615, 10884.0, 11031.944840275748, 11052.778944421227, 9048.68329608487, 9189.540522973959, 9219.49292788903, 9230.815383692154, 9338.0, 7513.436492262993, 8044.718434854752, 9148.268138549034, 9214.907850921672, 9228.815423691623, 8196.836063278583, 8243.422960392954, 8266.834663306821, 8351.624176911931, 8363.03825060126, 4767.475577686521, 6070.271567411939, 6102.420270074336, 6142.293636231891, 6259.311475737574, 6130.601510901777, 6158.107074474086, 6179.28938172116, 6216.409441103088, 6304.810855674337], "value": 5400.637714184415, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.40760487023186, 409.2714967358104, 414.57920210985844, 414.8278464437259, 421.4500077398995, 5258.789648414068, 5488.914212599212, 6281.591696539733, 6373.139626150468, 6386.680665966706, 5571.359293681227, 5660.433956604337, 5926.851828704284, 6038.426349496802, 6144.279601711517, 4149.958500415002, 4410.09593033429, 4659.88350291242, 4709.293605959099, 4711.59708548641, 3868.9613103869024, 4187.183499217654, 4677.976610116944, 4693.741844198567, 4715.575598196162, 4411.5808998145285, 4488.932666010012, 4634.258518637019, 4653.6044436222965, 4676.298555216727, 4348.130234045515, 4367.563243675639, 4649.279361698925, 4662.790174442124, 4701.106789709944, 4042.312806822818, 4151.91696166075, 4162.543109911537, 4173.916521669549, 4177.060161463646, 5200.413873768499, 5780.393058728815, 5988.610250774294, 5991.147170791673, 6077.696115194222, 4418.381426600278, 4648.697834640771, 5558.944410555883, 5559.861003474905, 5568.470995255444, 6257.655828929407, 6461.450776683988, 6585.308542603054, 6675.499337549703, 6676.866462670768, 4009.979950100231, 4680.906381872377, 4719.646026547988, 4729.628407761779, 4729.905401891943, 6390.421364036155, 6799.150106236671, 6970.686319115652, 6990.126234220723, 7017.2982701729925, 4173.853915112956, 4275.273203555403, 4326.6538676905775, 4340.826366945287, 4366.253498600522, 4343.435353404037, 4384.4081049058605, 4398.824047038147, 4415.66882483815, 4417.182821178092, 4780.808767649255, 5149.7940082397035, 5230.607704422184, 5238.371395432497, 5243.5542978847225, 6514.869702605922, 6526.575772574767, 6531.6081035137095, 6544.803655890331, 6558.081868538408, 1265.373640048182, 1313.8094976228585, 1482.5478229140215, 1499.6026053095939, 1500.272367901567, 947.2232769129248, 969.6654654144309, 980.2745967983641, 985.8866230383454, 995.2833959549113, 5285.365756109291, 5523.944760552343, 6275.811725648146, 6280.026595877646, 6356.4279214870885, 6251.906221406592, 6295.464885484785, 6816.352446517573, 6852.657367131624, 6861.828454288583, 5876.118582212703, 6085.726142323606, 6169.81490555284, 6329.841753956188, 6442.871142577123, 3478.89563313101, 3566.554180727409, 3609.5488063992, 3619.1675914539805, 3626.401643728808, 5858.970705146532, 6526.641034743135, 6788.626625535544, 7125.1093613298335, 7139.357457828821, 5730.7421166047625, 5902.4097590240635, 5915.911261331084, 6839.589624622538, 6895.620740859301, 7137.89293160603, 7154.35610795031, 7172.063542843403, 7201.4238860891, 7225.5664660120565, 6882.208546017272, 6959.130108736408, 6962.512624116263, 6969.4424446045205, 6978.232394436709, 6790.830229244309, 6796.626185559841, 6820.283870193558, 6847.828804279933, 6873.250699720152, 6080.422359875804, 6972.407345375497, 6995.335443132894, 7073.257307982588, 7162.283771623004, 3355.798652080883, 3917.0, 4323.24343239938, 4349.630281426114, 4377.190219809346, 6035.36628653985, 6926.272741362084, 6964.79105626832, 6970.211915233947, 6978.581285122909, 6524.314946930694, 6649.335066493505, 6652.467802575879, 6661.16735408074, 6675.7997260082275, 5546.473085056913, 6636.369544893228, 6665.700333384974, 6678.922180815434, 6720.596764194164, 6198.752049918133, 6359.20509936258, 6480.837979050561, 6495.642739649365, 6507.917466410703, 6308.495320374451, 6621.735130594722, 6677.966610166918, 6688.632125232964, 6708.295628958889, 6189.257289125154, 6215.005599104303, 6428.260750013808, 6503.764284785875, 6521.445677117308], "value": 4807.217458838415, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [851.7572491839827, 860.1054902900979, 864.5029108262748, 887.6050157679836, 890.2877697841727, 16588.170591470425, 17417.738733919003, 17472.65054698905, 18833.717494237597, 19187.61624767504, 18007.459776206735, 18415.631687366244, 18425.170867310968, 18470.076496175203, 18629.534261643465, 998.0319090482238, 1020.214434885138, 1023.5087158164085, 1024.0, 1028.7325295423207, 1024.2727663359024, 1029.9948500257487, 1038.3354653022072, 1047.1570385839395, 1047.984280235797, 1073.382804887193, 1083.9620613278537, 1085.9782804343909, 1087.26066274933, 1088.6679562733364, 1070.3631339353071, 1077.709018564987, 1077.8814330423725, 1079.6707004363666, 1083.3445765311951, 772.447699894578, 786.3748320085546, 789.3961119743401, 797.8922845415852, 800.8238187598719, 15478.690426191413, 16770.245338959776, 17068.658626827393, 17321.0, 17513.299468021265, 17225.483235502954, 17283.30866765328, 17666.381676641256, 17728.202230899642, 17812.554686132822, 14028.158310501403, 14130.293485325692, 14283.857161428357, 14351.425942962269, 14356.497522586764, 3552.0, 3576.7317451191266, 3582.5342705448384, 3582.7850328980344, 3586.3006713690843, 96812.54781178288, 115307.2703909442, 122731.3863430686, 125360.0, 125476.0, 13667.31663416845, 13762.380692868845, 13937.372818223204, 13985.860141398456, 13988.650283742785, 25178.622320665203, 25915.22254332373, 26022.219333420027, 26097.0, 26279.0, 7196.96401517989, 7915.485493442908, 8173.5504547250475, 8188.549629770419, 8210.671573137128, 4049.0484736086996, 4067.8576249831112, 4080.367543030835, 4114.156597897424, 4114.855980040684, 4448.1770872388715, 4458.866234012985, 4519.841805536857, 4524.117797029548, 4536.455625324982, 957.5690939077443, 962.2782912815388, 968.2883080935507, 975.0, 976.799756050008, 597.2265915639258, 652.4747578199594, 665.6239224837951, 668.5220067651652, 670.299536983853, 9965.55155018026, 11228.270162439416, 11434.714132146597, 11922.403879806148, 12081.879181208076, 536.6994483089404, 539.3204562251591, 541.6695815552509, 563.0681222576642, 567.8637127089552, 5422.132458806572, 5496.367917689437, 5579.972100139474, 5679.403662615448, 5743.0811070228565, 101096.0, 102821.48589257005, 103460.0, 104802.47598761957, 117662.0, 85109.57445212858, 85564.57217713995, 93447.0655293452, 94756.0, 95814.52092739631, 85231.0, 85675.0, 86206.0, 86419.56790216008, 87569.24861502735, 10904.691437027608, 11952.940235298767, 12212.694682633006, 12667.873321267038, 12676.605573387105, 35709.28581428305, 40406.0, 41153.588464114975, 41304.38043429411, 41461.79269103635, 47392.63036848075, 53897.0, 54377.72811135919, 55354.0, 55911.720441397534, 16268.51194464168, 16300.836991630405, 16541.50375488689, 16666.250018748942, 16710.331586736393, 17866.196021178817, 18043.458696239133, 18182.45452636423, 18221.81778182254, 18367.908160459112, 15984.680306394039, 16139.273732682292, 16287.918560407123, 16895.07077110771, 16964.830351696324, 13491.32543372847, 15699.686006279582, 16162.67674646524, 16623.0, 16690.49928502147, 16058.43795467153, 16756.497305080866, 16759.0, 16840.905341152786, 16970.0, 11692.532298708296, 12053.939730301292, 12067.0, 12242.510299588273, 12329.0, 12879.291638959947, 13039.413226404646, 13052.955763539085, 13069.542566010145, 13117.93441032827], "value": 8465.029035368845, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1501.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 109.0, 110.0, 141.0, 119.0, 106.0, 111.0, 111.0, 145.0, 140.0], "value": 123.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 198.0, 202.0, 222.0, 204.0, 222.0, 197.0, 199.0, 195.0, 204.0], "value": 204.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 55.0, 57.0, 80.0, 59.0, 61.0, 58.0, 56.0, 77.0, 59.0], "value": 64.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 83.0, 71.0, 69.0, 68.0, 85.0, 69.0, 72.0, 88.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 88.0, 88.0, 95.0, 88.0, 89.0, 89.0, 88.0, 93.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 94.0, 96.0, 95.0, 121.0, 151.0, 128.0, 96.0], "value": 106.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 93.0, 90.0, 90.0, 108.0, 145.0, 127.0, 92.0, 87.0, 89.0], "value": 103.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 48.0, 78.0, 40.0, 42.0, 64.0, 55.0, 47.0, 41.0, 41.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 65.0, 62.0, 68.0, 62.0, 59.0, 56.0, 59.0, 62.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 83.0, 83.0, 84.0, 82.0, 78.0, 79.0, 82.0, 95.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 137.0, 138.0, 124.0, 157.0, 139.0, 126.0, 187.0, 166.0], "value": 144.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [245.0, 225.0, 183.0, 222.0, 183.0, 160.0, 247.0, 168.0, 224.0, 234.0], "value": 209.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 81.0, 97.0, 72.0, 56.0, 55.0, 76.0, 98.0, 56.0, 71.0], "value": 72.8, "unit": "score"}]}]} 17:25:56 INFO - Return code: 0 17:25:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:25:56 ERROR - # TBPL SUCCESS # 17:25:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:25:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:25:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:25:56 INFO - 2017-04-28 17:25:56.119 screenresolution[1656:10818] starting screenresolution argv=screenresolution get 17:25:56 INFO - 2017-04-28 17:25:56.122 screenresolution[1656:10818] Display 0: 1600x1200x32@60 17:25:56 INFO - 2017-04-28 17:25:56.127 screenresolution[1657:10821] starting screenresolution argv=screenresolution list 17:25:56 INFO - Available Modes on Display 0 17:25:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:25:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:25:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:25:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:25:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:25:56 INFO - Intel Iris: 17:25:56 INFO - Chipset Model: Intel Iris 17:25:56 INFO - Type: GPU 17:25:56 INFO - Bus: Built-In 17:25:56 INFO - VRAM (Dynamic, Max): 1536 MB 17:25:56 INFO - Vendor: Intel (0x8086) 17:25:56 INFO - Device ID: 0x0a2e 17:25:56 INFO - Revision ID: 0x0009 17:25:56 INFO - Displays: 17:25:56 INFO - Display: 17:25:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:25:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:25:56 INFO - Display Serial Number: 1600x1200 60 17:25:56 INFO - Main Display: Yes 17:25:56 INFO - Mirror: Off 17:25:56 INFO - Online: Yes 17:25:56 INFO - Rotation: Supported 17:25:56 INFO - Return code: 0 17:25:56 INFO - Running post-action listener: _resource_record_post_action 17:25:56 INFO - [mozharness: 2017-04-29 00:25:56.369583Z] Finished run-tests step (success) 17:25:56 INFO - Running post-run listener: _resource_record_post_run 17:25:56 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 37759488; Write bytes: 514049024; Read time: 249; Write time: 1612 17:25:56 INFO - TinderboxPrint: CPU usage
54.2% 17:25:56 INFO - TinderboxPrint: I/O read bytes / time
37,759,488 / 249 17:25:56 INFO - TinderboxPrint: I/O write bytes / time
514,049,024 / 1,612 17:25:56 INFO - TinderboxPrint: CPU idle
2,191.3 (45.8%) 17:25:56 INFO - TinderboxPrint: CPU system
962.8 (20.1%) 17:25:56 INFO - TinderboxPrint: CPU user
1,631.6 (34.1%) 17:25:56 INFO - TinderboxPrint: Swap in / out
246,525,952 / 0 17:25:56 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 194384384; Write bytes: 195917824; Read time: 15177; Write time: 405 17:25:56 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36772352; Write bytes: 301288448; Read time: 236; Write time: 1155 17:25:56 INFO - Running post-run listener: _upload_blobber_files 17:25:56 INFO - Blob upload gear active. 17:25:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:25:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:25:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:25:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:25:57 INFO - (blobuploader) - INFO - Open directory for files ... 17:25:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:25:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:25:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:58 INFO - (blobuploader) - INFO - Done attempting. 17:25:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:25:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:25:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:59 INFO - (blobuploader) - INFO - Done attempting. 17:25:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:25:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:26:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:26:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:26:00 INFO - (blobuploader) - INFO - Done attempting. 17:26:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:26:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:26:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:26:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:26:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:26:03 INFO - (blobuploader) - INFO - Done attempting. 17:26:03 INFO - (blobuploader) - INFO - Iteration through files over. 17:26:03 INFO - Return code: 0 17:26:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:26:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:26:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ec44aec2413fea762020fce85b44f4cc2ec8dd2f56b2efc790420a783bf1e42bd07d7cfc861801ee2f007256ba035d5f8dbc1e68088fc880acc3bae51ee1358", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ba5f0334e2c4254501de6678289b453bc5516ddf715583e694e898a3b4e67edbeddfaae3b288449ab38c271a53ffd71aa21a34abee101694cd9e5875e8c138d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/389c7aa8d80bb06f9947dc1ff71069430f6c1286a330d2d01f062f5713ed4f6bdd92147f85c8063cccdc2d78cbc3dcf3fd9bb4c5723e93c77a6c66ea8fafc488", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84b11173275a64e115ceacae115634b4aaf96f38cbda252ed1932a49174a67ae13b0d500dfa0d44149d1fc2c9a822d27b2231e1f687f934ec82d0791cdca40b2"} 17:26:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:26:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:26:03 INFO - Contents: 17:26:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ec44aec2413fea762020fce85b44f4cc2ec8dd2f56b2efc790420a783bf1e42bd07d7cfc861801ee2f007256ba035d5f8dbc1e68088fc880acc3bae51ee1358", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ba5f0334e2c4254501de6678289b453bc5516ddf715583e694e898a3b4e67edbeddfaae3b288449ab38c271a53ffd71aa21a34abee101694cd9e5875e8c138d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/389c7aa8d80bb06f9947dc1ff71069430f6c1286a330d2d01f062f5713ed4f6bdd92147f85c8063cccdc2d78cbc3dcf3fd9bb4c5723e93c77a6c66ea8fafc488", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84b11173275a64e115ceacae115634b4aaf96f38cbda252ed1932a49174a67ae13b0d500dfa0d44149d1fc2c9a822d27b2231e1f687f934ec82d0791cdca40b2"} 17:26:03 INFO - Running post-run listener: copy_logs_to_upload_dir 17:26:03 INFO - Copying logs to upload dir... 17:26:03 INFO - mkdir: /builds/slave/test/build/upload/logs 17:26:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.429892 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-04-28 17:26:03.185266) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 17:26:03.189233) ========= 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.956zrp6BoG/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.muqUVL7vPN/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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/1ec44aec2413fea762020fce85b44f4cc2ec8dd2f56b2efc790420a783bf1e42bd07d7cfc861801ee2f007256ba035d5f8dbc1e68088fc880acc3bae51ee1358", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ba5f0334e2c4254501de6678289b453bc5516ddf715583e694e898a3b4e67edbeddfaae3b288449ab38c271a53ffd71aa21a34abee101694cd9e5875e8c138d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/389c7aa8d80bb06f9947dc1ff71069430f6c1286a330d2d01f062f5713ed4f6bdd92147f85c8063cccdc2d78cbc3dcf3fd9bb4c5723e93c77a6c66ea8fafc488", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84b11173275a64e115ceacae115634b4aaf96f38cbda252ed1932a49174a67ae13b0d500dfa0d44149d1fc2c9a822d27b2231e1f687f934ec82d0791cdca40b2"} 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.010853 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/1ec44aec2413fea762020fce85b44f4cc2ec8dd2f56b2efc790420a783bf1e42bd07d7cfc861801ee2f007256ba035d5f8dbc1e68088fc880acc3bae51ee1358", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ba5f0334e2c4254501de6678289b453bc5516ddf715583e694e898a3b4e67edbeddfaae3b288449ab38c271a53ffd71aa21a34abee101694cd9e5875e8c138d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/389c7aa8d80bb06f9947dc1ff71069430f6c1286a330d2d01f062f5713ed4f6bdd92147f85c8063cccdc2d78cbc3dcf3fd9bb4c5723e93c77a6c66ea8fafc488", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84b11173275a64e115ceacae115634b4aaf96f38cbda252ed1932a49174a67ae13b0d500dfa0d44149d1fc2c9a822d27b2231e1f687f934ec82d0791cdca40b2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 17:26:03.228387) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:26:03.228757) ========= 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.956zrp6BoG/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.muqUVL7vPN/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.005040 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:26:03.280843) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 17:26:03.281487) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 17:26:03.282261) ========= ========= Total master_lag: 0.20 =========