builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0021 starttime: 1493425897.6 results: success (0) buildid: 20170428170827 builduid: d6a16d7538e24750a836096c0255662d revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:37.595341) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:37.595765) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:37.596055) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:37.621635) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:37.621961) ========= 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.HLTizRFm0L/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.h3JmSgm23t/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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:31:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.87M=0.001s 2017-04-28 17:31:37 (7.87 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.154817 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:37.801187) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:37.801499) ========= 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.HLTizRFm0L/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.h3JmSgm23t/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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.021217 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:37.849715) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 17:31:37.850075) ========= 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.HLTizRFm0L/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.h3JmSgm23t/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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:31:37,940 truncating revision to first 12 chars 2017-04-28 17:31:37,940 Setting DEBUG logging. 2017-04-28 17:31:37,940 attempt 1/10 2017-04-28 17:31:37,940 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:31:39,481 unpacking tar archive at: mozilla-central-b3b32894d932/testing/mozharness/ program finished with exit code 0 elapsedTime=1.805461 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 17:31:39.679968) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:39.680325) ========= script_repo_revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:39.680728) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:39.681010) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:31:39.702039) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-04-28 17:31:39.702395) ========= /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.HLTizRFm0L/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.h3JmSgm23t/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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:31:39 INFO - MultiFileLogger online at 20170428 17:31:39 in /builds/slave/test 17:31:39 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:31:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:31:39 INFO - {'append_to_log': False, 17:31:39 INFO - 'base_work_dir': '/builds/slave/test', 17:31:39 INFO - 'blob_upload_branch': 'Firefox', 17:31:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:31:39 INFO - 'branch': 'Firefox', 17:31:39 INFO - 'buildbot_json_path': 'buildprops.json', 17:31:39 INFO - 'config_files': ('talos/mac_config.py',), 17:31:39 INFO - 'default_actions': ('clobber', 17:31:39 INFO - 'read-buildbot-config', 17:31:39 INFO - 'download-and-extract', 17:31:39 INFO - 'populate-webroot', 17:31:39 INFO - 'create-virtualenv', 17:31:39 INFO - 'install', 17:31:39 INFO - 'run-tests'), 17:31:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:31:39 INFO - 'download_minidump_stackwalk': True, 17:31:39 INFO - 'download_symbols': 'ondemand', 17:31:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:31:39 INFO - 'tooltool.py': '/tools/tooltool.py', 17:31:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:31:39 INFO - '/tools/misc-python/virtualenv.py')}, 17:31:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:31:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:31:39 INFO - 'gecko_profile': False, 17:31:39 INFO - 'gecko_profile_interval': 0, 17:31:39 INFO - 'installer_path': 'installer.exe', 17:31:39 INFO - 'log_level': 'info', 17:31:39 INFO - 'log_name': 'talos', 17:31:39 INFO - 'log_to_console': True, 17:31:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:31:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:31:39 INFO - 'opt_config_files': (), 17:31:39 INFO - 'pip_index': False, 17:31:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:31:39 INFO - 'cmd': ('bash', 17:31:39 INFO - '-c', 17:31:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:31:39 INFO - 'enabled': True, 17:31:39 INFO - 'halt_on_failure': False, 17:31:39 INFO - 'name': 'check_screen_resolution'},), 17:31:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:31:39 INFO - 'cmd': ('bash', 17:31:39 INFO - '-c', 17:31:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:31:39 INFO - 'enabled': True, 17:31:39 INFO - 'halt_on_failure': False, 17:31:39 INFO - 'name': 'check_screen_resolution'},), 17:31:39 INFO - 'run_cmd_checks_enabled': True, 17:31:39 INFO - 'suite': 'dromaeojs-e10s', 17:31:39 INFO - 'system_bits': '32', 17:31:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:31:39 INFO - 'title': 't-yosemite-r7-0021', 17:31:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:31:39 INFO - 'use_talos_json': True, 17:31:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:31:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:31:39 INFO - 'work_dir': 'build'} 17:31:39 INFO - [mozharness: 2017-04-29 00:31:39.915603Z] Running clobber step. 17:31:39 INFO - Running pre-action listener: _resource_record_pre_action 17:31:39 INFO - Running main action method: clobber 17:31:39 INFO - rmtree: /builds/slave/test/build 17:31:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:31:41 INFO - Running post-action listener: _resource_record_post_action 17:31:41 INFO - [mozharness: 2017-04-29 00:31:41.836160Z] Finished clobber step (success) 17:31:41 INFO - [mozharness: 2017-04-29 00:31:41.836296Z] Running read-buildbot-config step. 17:31:41 INFO - Running pre-action listener: _resource_record_pre_action 17:31:41 INFO - Running main action method: read_buildbot_config 17:31:41 INFO - Using buildbot properties: 17:31:41 INFO - { 17:31:41 INFO - "project": "", 17:31:41 INFO - "product": "firefox", 17:31:41 INFO - "script_repo_revision": "production", 17:31:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:31:41 INFO - "repository": "", 17:31:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:31:41 INFO - "buildid": "20170428170827", 17:31:41 INFO - "stage_platform": "macosx64", 17:31:41 INFO - "basedir": "/builds/slave/test", 17:31:41 INFO - "buildnumber": 206, 17:31:41 INFO - "platform": "yosemite_r7", 17:31:41 INFO - "mozci_request": { 17:31:41 INFO - "priority": 0, 17:31:41 INFO - "type": "trigger_all_talos_jobs", 17:31:41 INFO - "times": 6 17:31:41 INFO - }, 17:31:41 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 17:31:41 INFO - "slavebuilddir": "test", 17:31:41 INFO - "branch": "mozilla-central", 17:31:41 INFO - "revision": "b3b32894d9326d147238d9f071e1814ab3fdc850", 17:31:41 INFO - "slavename": "t-yosemite-r7-0021", 17:31:41 INFO - "builduid": "d6a16d7538e24750a836096c0255662d", 17:31:41 INFO - "repo_path": "mozilla-central" 17:31:41 INFO - } 17:31:41 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg. 17:31:41 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:31:41 INFO - Running post-action listener: _resource_record_post_action 17:31:41 INFO - [mozharness: 2017-04-29 00:31:41.840118Z] Finished read-buildbot-config step (success) 17:31:41 INFO - [mozharness: 2017-04-29 00:31:41.840249Z] Running download-and-extract step. 17:31:41 INFO - Running pre-action listener: _resource_record_pre_action 17:31:41 INFO - Running main action method: download_and_extract 17:31:41 INFO - mkdir: /builds/slave/test/build/tests 17:31:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:41 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json 17:31:41 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:31:41 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:31:42 INFO - Downloaded 1325 bytes. 17:31:42 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:31:42 INFO - Using the following test package requirements: 17:31:42 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:42 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:31:42 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:31:42 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:42 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:31:42 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:42 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:31:42 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:42 INFO - u'jsshell-mac.zip'], 17:31:42 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:42 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:31:42 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:31:42 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:42 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:31:42 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:42 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:31:42 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:42 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:31:42 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:31:42 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:31:42 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:31:42 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:31:42 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:31:42 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:31:43 INFO - Content-Length response header: 52698163 17:31:43 INFO - Bytes received: 52698163 17:32:00 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:32:00 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:32:00 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:32:00 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:32:01 INFO - Content-Length response header: 52698163 17:32:01 INFO - Bytes received: 52698163 17:32:17 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:32:17 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:32:17 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:32:17 INFO - Content-Length response header: 14408809 17:32:17 INFO - Bytes received: 14408809 17:32:17 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:32:17 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:32:17 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:32:17 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:32:19 INFO - Downloaded 71219323 bytes. 17:32:19 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:32:19 INFO - mkdir: /builds/slave/test/properties 17:32:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:32:19 INFO - Writing to file /builds/slave/test/properties/build_url 17:32:19 INFO - Contents: 17:32:19 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:32:19 INFO - Running post-action listener: _resource_record_post_action 17:32:19 INFO - Running post-action listener: set_extra_try_arguments 17:32:19 INFO - [mozharness: 2017-04-29 00:32:19.638580Z] Finished download-and-extract step (success) 17:32:19 INFO - [mozharness: 2017-04-29 00:32:19.638704Z] Running populate-webroot step. 17:32:19 INFO - Running pre-action listener: _resource_record_pre_action 17:32:19 INFO - Running main action method: populate_webroot 17:32:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:32:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:32:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:32:19 INFO - '--tppagecycles', 17:32:19 INFO - '7'], 17:32:19 INFO - 'tests': ['tsvgm']}}, 17:32:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:32:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:32:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:32:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:32:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:32:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:32:19 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:32:19 INFO - 'talos_options': ['--disable-e10s'], 17:32:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:32:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:32:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:32:19 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:32:19 INFO - 'talos_options': ['--disable-e10s'], 17:32:19 INFO - 'tests': ['damp', 'tps']}, 17:32:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:32:19 INFO - 'tests': ['damp', 'tps']}, 17:32:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:32:19 INFO - 'tests': ['dromaeo_dom']}, 17:32:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:32:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:32:19 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:32:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:32:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:32:19 INFO - 'tests': ['a11yr', 17:32:19 INFO - 'ts_paint', 17:32:19 INFO - 'tpaint', 17:32:19 INFO - 'sessionrestore', 17:32:19 INFO - 'sessionrestore_no_auto_restore', 17:32:19 INFO - 'tabpaint']}, 17:32:19 INFO - 'other-e10s': {'tests': ['a11yr', 17:32:19 INFO - 'ts_paint', 17:32:19 INFO - 'tpaint', 17:32:19 INFO - 'sessionrestore', 17:32:19 INFO - 'sessionrestore_no_auto_restore', 17:32:19 INFO - 'tabpaint']}, 17:32:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:32:19 INFO - 'ts_paint', 17:32:19 INFO - 'tpaint', 17:32:19 INFO - 'sessionrestore', 17:32:19 INFO - 'sessionrestore_no_auto_restore', 17:32:19 INFO - 'tabpaint']}, 17:32:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:32:19 INFO - 'ts_paint', 17:32:19 INFO - 'tpaint', 17:32:19 INFO - 'sessionrestore', 17:32:19 INFO - 'sessionrestore_no_auto_restore', 17:32:19 INFO - 'tabpaint']}, 17:32:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:32:19 INFO - 'tests': ['a11yr', 17:32:19 INFO - 'ts_paint', 17:32:19 INFO - 'tpaint', 17:32:19 INFO - 'sessionrestore', 17:32:19 INFO - 'sessionrestore_no_auto_restore', 17:32:19 INFO - 'tabpaint']}, 17:32:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:32:19 INFO - 'tests': ['a11yr', 17:32:19 INFO - 'ts_paint', 17:32:19 INFO - 'tpaint', 17:32:19 INFO - 'sessionrestore', 17:32:19 INFO - 'sessionrestore_no_auto_restore', 17:32:19 INFO - 'tabpaint']}, 17:32:19 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:32:19 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:32:19 INFO - 'bloom_basic_ref']}, 17:32:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:32:19 INFO - 'tests': ['tsvgx', 17:32:19 INFO - 'tsvgr_opacity', 17:32:19 INFO - 'tart', 17:32:19 INFO - 'tscrollx', 17:32:19 INFO - 'cart', 17:32:19 INFO - 'tsvg_static']}, 17:32:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:32:19 INFO - 'tsvgr_opacity', 17:32:19 INFO - 'tart', 17:32:19 INFO - 'tscrollx', 17:32:19 INFO - 'cart', 17:32:19 INFO - 'tsvg_static']}, 17:32:19 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:32:19 INFO - 'talos_options': ['--disable-e10s'], 17:32:19 INFO - 'tests': ['tp5o']}, 17:32:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:32:19 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:32:19 INFO - 'talos_options': ['--disable-e10s', 17:32:19 INFO - '--xperf_path', 17:32:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:32:19 INFO - 'tests': ['tp5n']}, 17:32:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:32:19 INFO - 'talos_options': ['--xperf_path', 17:32:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:32:19 INFO - 'tests': ['tp5n']}}, 17:32:19 INFO - 'talos.zip': {'path': '', 17:32:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:32:19 INFO - Running post-action listener: _resource_record_post_action 17:32:19 INFO - [mozharness: 2017-04-29 00:32:19.658078Z] Finished populate-webroot step (success) 17:32:19 INFO - [mozharness: 2017-04-29 00:32:19.658211Z] Running create-virtualenv step. 17:32:19 INFO - Running pre-action listener: _resource_record_pre_action 17:32:19 INFO - Running main action method: create_virtualenv 17:32:19 INFO - Creating virtualenv /builds/slave/test/build/venv 17:32:19 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:32:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:32:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:32:19 INFO - Using real prefix '/tools/python27' 17:32:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:32:20 INFO - Installing distribute.............................................................................................................................................................................................done. 17:32:23 INFO - Installing pip.................done. 17:32:23 INFO - Return code: 0 17:32:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:32:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:32:23 INFO - Reading from file tmpfile_stdout 17:32:23 INFO - Output received: 17:32:23 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:32:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:32:23 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:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:23 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:32:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:23 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 0x10ee50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdc2cb3e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2ed30>, '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 0x10eb54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec23be8>, '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.HLTizRFm0L/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.h3JmSgm23t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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:32:23 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:32:23 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:32:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLTizRFm0L/Render', 17:32:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:23 INFO - 'HOME': '/Users/cltbld', 17:32:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:23 INFO - 'LOGNAME': 'cltbld', 17:32:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:23 INFO - 'MOZ_NO_REMOTE': '1', 17:32:23 INFO - 'NO_EM_RESTART': '1', 17:32:23 INFO - 'PAGER': '/bin/cat', 17:32:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:23 INFO - 'PWD': '/builds/slave/test', 17:32:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:23 INFO - 'SHELL': '/bin/bash', 17:32:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h3JmSgm23t/Listeners', 17:32:23 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 17:32:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:23 INFO - 'USER': 'cltbld', 17:32:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:23 INFO - 'XPC_FLAGS': '0x0', 17:32:23 INFO - 'XPC_SERVICE_NAME': '0', 17:32:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:23 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:32:23 INFO - Cleaning up... 17:32:23 INFO - Return code: 0 17:32:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:32:23 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:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:23 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:32:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:23 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 0x10ee50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdc2cb3e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2ed30>, '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 0x10eb54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec23be8>, '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.HLTizRFm0L/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.h3JmSgm23t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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:32:23 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:32:23 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:32:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLTizRFm0L/Render', 17:32:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:23 INFO - 'HOME': '/Users/cltbld', 17:32:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:23 INFO - 'LOGNAME': 'cltbld', 17:32:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:23 INFO - 'MOZ_NO_REMOTE': '1', 17:32:23 INFO - 'NO_EM_RESTART': '1', 17:32:23 INFO - 'PAGER': '/bin/cat', 17:32:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:23 INFO - 'PWD': '/builds/slave/test', 17:32:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:23 INFO - 'SHELL': '/bin/bash', 17:32:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h3JmSgm23t/Listeners', 17:32:23 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 17:32:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:23 INFO - 'USER': 'cltbld', 17:32:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:23 INFO - 'XPC_FLAGS': '0x0', 17:32:23 INFO - 'XPC_SERVICE_NAME': '0', 17:32:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:24 INFO - Downloading/unpacking psutil>=3.1.1 17:32:24 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:32:24 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:32:24 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:32:24 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:32:24 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:32:24 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:32:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:32:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:32:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:32:27 INFO - Installing collected packages: psutil 17:32:27 INFO - Running setup.py install for psutil 17:32:27 INFO - building 'psutil._psutil_osx' extension 17:32:27 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:32:28 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:32:28 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:32:28 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:32:28 INFO - building 'psutil._psutil_posix' extension 17:32:28 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:32:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:32:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:32:28 INFO - ^ 17:32:28 INFO - 1 warning generated. 17:32:28 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:32:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:32:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:32:28 INFO - Successfully installed psutil 17:32:28 INFO - Cleaning up... 17:32:28 INFO - Return code: 0 17:32:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:32:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdc2cb3e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2ed30>, '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 0x10eb54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec23be8>, '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.HLTizRFm0L/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.h3JmSgm23t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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:32:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:32:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLTizRFm0L/Render', 17:32:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:28 INFO - 'HOME': '/Users/cltbld', 17:32:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:28 INFO - 'LOGNAME': 'cltbld', 17:32:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:28 INFO - 'MOZ_NO_REMOTE': '1', 17:32:28 INFO - 'NO_EM_RESTART': '1', 17:32:28 INFO - 'PAGER': '/bin/cat', 17:32:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:28 INFO - 'PWD': '/builds/slave/test', 17:32:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:28 INFO - 'SHELL': '/bin/bash', 17:32:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h3JmSgm23t/Listeners', 17:32:28 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 17:32:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:28 INFO - 'USER': 'cltbld', 17:32:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:28 INFO - 'XPC_FLAGS': '0x0', 17:32:28 INFO - 'XPC_SERVICE_NAME': '0', 17:32:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:32:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:32:31 INFO - Installing collected packages: mozsystemmonitor 17:32:31 INFO - Running setup.py install for mozsystemmonitor 17:32:31 INFO - Successfully installed mozsystemmonitor 17:32:31 INFO - Cleaning up... 17:32:31 INFO - Return code: 0 17:32:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:32:31 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:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:31 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:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:31 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 0x10ee50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdc2cb3e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2ed30>, '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 0x10eb54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec23be8>, '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.HLTizRFm0L/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.h3JmSgm23t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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:32:31 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:32:31 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:32:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLTizRFm0L/Render', 17:32:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:31 INFO - 'HOME': '/Users/cltbld', 17:32:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:31 INFO - 'LOGNAME': 'cltbld', 17:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:31 INFO - 'MOZ_NO_REMOTE': '1', 17:32:31 INFO - 'NO_EM_RESTART': '1', 17:32:31 INFO - 'PAGER': '/bin/cat', 17:32:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:31 INFO - 'PWD': '/builds/slave/test', 17:32:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:31 INFO - 'SHELL': '/bin/bash', 17:32:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h3JmSgm23t/Listeners', 17:32:31 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 17:32:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:31 INFO - 'USER': 'cltbld', 17:32:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:31 INFO - 'XPC_FLAGS': '0x0', 17:32:31 INFO - 'XPC_SERVICE_NAME': '0', 17:32:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:31 INFO - Downloading/unpacking jsonschema==2.5.1 17:32:31 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:32:31 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:32:31 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:32:31 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:32:31 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:32:31 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:32:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:32:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:34 INFO - Downloading functools32-3.2.3-2.tar.gz 17:32:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:32:35 INFO - warning: no files found matching '*.txt' 17:32:35 INFO - no previously-included directories found matching 'build' 17:32:35 INFO - no previously-included directories found matching 'dist' 17:32:35 INFO - no previously-included directories found matching '.git*' 17:32:35 INFO - Installing collected packages: jsonschema, functools32 17:32:35 INFO - Running setup.py install for functools32 17:32:35 INFO - warning: no files found matching '*.txt' 17:32:35 INFO - no previously-included directories found matching 'build' 17:32:35 INFO - no previously-included directories found matching 'dist' 17:32:35 INFO - no previously-included directories found matching '.git*' 17:32:35 INFO - Successfully installed jsonschema functools32 17:32:35 INFO - Cleaning up... 17:32:35 INFO - Return code: 0 17:32:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:32: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:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32: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:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:36 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 0x10ee50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdc2cb3e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2ed30>, '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 0x10eb54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec23be8>, '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.HLTizRFm0L/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.h3JmSgm23t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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:32:36 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:32:36 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:32:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLTizRFm0L/Render', 17:32:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:36 INFO - 'HOME': '/Users/cltbld', 17:32:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:36 INFO - 'LOGNAME': 'cltbld', 17:32:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:36 INFO - 'MOZ_NO_REMOTE': '1', 17:32:36 INFO - 'NO_EM_RESTART': '1', 17:32:36 INFO - 'PAGER': '/bin/cat', 17:32:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:36 INFO - 'PWD': '/builds/slave/test', 17:32:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:36 INFO - 'SHELL': '/bin/bash', 17:32:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h3JmSgm23t/Listeners', 17:32:36 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 17:32:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:36 INFO - 'USER': 'cltbld', 17:32:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:36 INFO - 'XPC_FLAGS': '0x0', 17:32:36 INFO - 'XPC_SERVICE_NAME': '0', 17:32:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:32:36 INFO - Cleaning up... 17:32:36 INFO - Return code: 0 17:32:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:32:36 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:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:36 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:32:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:36 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 0x10ee50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdc2cb3e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2ed30>, '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 0x10eb54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec23be8>, '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.HLTizRFm0L/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.h3JmSgm23t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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:32:36 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:32:36 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:32:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLTizRFm0L/Render', 17:32:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:36 INFO - 'HOME': '/Users/cltbld', 17:32:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:36 INFO - 'LOGNAME': 'cltbld', 17:32:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:36 INFO - 'MOZ_NO_REMOTE': '1', 17:32:36 INFO - 'NO_EM_RESTART': '1', 17:32:36 INFO - 'PAGER': '/bin/cat', 17:32:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:36 INFO - 'PWD': '/builds/slave/test', 17:32:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:36 INFO - 'SHELL': '/bin/bash', 17:32:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h3JmSgm23t/Listeners', 17:32:36 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 17:32:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:36 INFO - 'USER': 'cltbld', 17:32:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:36 INFO - 'XPC_FLAGS': '0x0', 17:32:36 INFO - 'XPC_SERVICE_NAME': '0', 17:32:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:36 INFO - Downloading/unpacking blobuploader==1.2.4 17:32:36 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:32:36 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:32:36 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:32:36 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:32:36 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:32:36 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:32:39 INFO - Downloading blobuploader-1.2.4.tar.gz 17:32:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:32:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:32:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:41 INFO - Downloading docopt-0.6.1.tar.gz 17:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:32:41 INFO - Installing collected packages: blobuploader, requests, docopt 17:32:41 INFO - Running setup.py install for blobuploader 17:32:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:32:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:32:41 INFO - Running setup.py install for requests 17:32:41 INFO - Running setup.py install for docopt 17:32:41 INFO - Successfully installed blobuploader requests docopt 17:32:42 INFO - Cleaning up... 17:32:42 INFO - Return code: 0 17:32:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:32: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:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32: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:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:42 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 0x10ee50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdc2cb3e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2ed30>, '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 0x10eb54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec23be8>, '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.HLTizRFm0L/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.h3JmSgm23t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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:32:42 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:32:42 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:32:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLTizRFm0L/Render', 17:32:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:42 INFO - 'HOME': '/Users/cltbld', 17:32:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:42 INFO - 'LOGNAME': 'cltbld', 17:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:42 INFO - 'MOZ_NO_REMOTE': '1', 17:32:42 INFO - 'NO_EM_RESTART': '1', 17:32:42 INFO - 'PAGER': '/bin/cat', 17:32:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:42 INFO - 'PWD': '/builds/slave/test', 17:32:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:42 INFO - 'SHELL': '/bin/bash', 17:32:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h3JmSgm23t/Listeners', 17:32:42 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 17:32:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:42 INFO - 'USER': 'cltbld', 17:32:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:42 INFO - 'XPC_FLAGS': '0x0', 17:32:42 INFO - 'XPC_SERVICE_NAME': '0', 17:32:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:32:42 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-Q3mcC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:32:42 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-DUucnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:32:42 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-syja5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:32:42 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-Ex5SNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:32:42 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-c5AhuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:32:42 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-jdEPCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:32:42 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-ETPXuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:32:43 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-5mkQB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:32:43 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-HiHaWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:32:43 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-72EJE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:32:43 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-NmD89V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:32:43 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-PCgj82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:32:43 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-5MbQXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:32:43 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-_5Rv59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:32:43 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-YgycXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:32:43 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-y1SZMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:32:44 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-BVS3HC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:32:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:32:44 INFO - Running setup.py install for manifestparser 17:32:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:32:44 INFO - Running setup.py install for mozcrash 17:32:44 INFO - Running setup.py install for mozdebug 17:32:44 INFO - Running setup.py install for mozdevice 17:32:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:32:44 INFO - Running setup.py install for mozfile 17:32:44 INFO - Running setup.py install for mozhttpd 17:32:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:32:44 INFO - Running setup.py install for mozinfo 17:32:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:32:45 INFO - Running setup.py install for mozInstall 17:32:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:32:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:32:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:32:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:32:45 INFO - Running setup.py install for mozleak 17:32:45 INFO - Running setup.py install for mozlog 17:32:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:32:45 INFO - Running setup.py install for moznetwork 17:32:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:32:45 INFO - Running setup.py install for mozprocess 17:32:45 INFO - Running setup.py install for mozprofile 17:32:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:32:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:32:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:32:46 INFO - Running setup.py install for mozrunner 17:32:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:32:46 INFO - Running setup.py install for mozscreenshot 17:32:46 INFO - Running setup.py install for moztest 17:32:46 INFO - Running setup.py install for mozversion 17:32:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:32:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:32:46 INFO - Cleaning up... 17:32:46 INFO - Return code: 0 17:32:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:46 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 0x10ee50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdc2cb3e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2ed30>, '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 0x10eb54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec23be8>, '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.HLTizRFm0L/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.h3JmSgm23t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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:32:46 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:32:46 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:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLTizRFm0L/Render', 17:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:46 INFO - 'HOME': '/Users/cltbld', 17:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:46 INFO - 'LOGNAME': 'cltbld', 17:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:46 INFO - 'MOZ_NO_REMOTE': '1', 17:32:46 INFO - 'NO_EM_RESTART': '1', 17:32:46 INFO - 'PAGER': '/bin/cat', 17:32:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:46 INFO - 'PWD': '/builds/slave/test', 17:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:46 INFO - 'SHELL': '/bin/bash', 17:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h3JmSgm23t/Listeners', 17:32:46 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 17:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:46 INFO - 'USER': 'cltbld', 17:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:46 INFO - 'XPC_FLAGS': '0x0', 17:32:46 INFO - 'XPC_SERVICE_NAME': '0', 17:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:32:46 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-ZmUlhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:32:47 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:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:32:47 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-N4MCCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:32:47 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:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:32:47 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-XqV8fJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:32:47 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:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:32:47 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-brmykZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:32:47 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:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:32:47 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-UtKeYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:32:47 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:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:32:47 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-jX9y1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:32:47 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:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:32:47 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-AS7puO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:32:47 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:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:32:47 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-zkTRLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:32:47 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:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:32:47 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-PNexOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:32:47 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:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:32:47 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-bW1OeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:32:47 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:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:32:48 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-XWqBUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:32:48 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:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:32:48 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-kvtlnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:32:48 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:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:32:48 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-lhKnaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:32:48 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:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:32:48 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-ULOhkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:32:48 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:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:32:48 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-Wr4AHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:32:48 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:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:32:48 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-B9CQrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:32:48 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:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:32:48 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-rMJ_92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:32:48 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:32:48 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:32:48 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:32:48 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:32:48 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:32:48 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:32:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:51 INFO - Downloading blessings-1.6.tar.gz 17:32:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:32:51 INFO - Installing collected packages: blessings 17:32:51 INFO - Running setup.py install for blessings 17:32:51 INFO - Successfully installed blessings 17:32:51 INFO - Cleaning up... 17:32:51 INFO - Return code: 0 17:32:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:32:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:32:51 INFO - Reading from file tmpfile_stdout 17:32:51 INFO - Current package versions: 17:32:51 INFO - blessings == 1.6 17:32:51 INFO - blobuploader == 1.2.4 17:32:51 INFO - docopt == 0.6.1 17:32:51 INFO - functools32 == 3.2.3-2 17:32:51 INFO - jsonschema == 2.5.1 17:32:51 INFO - manifestparser == 1.1 17:32:51 INFO - mozInstall == 1.12 17:32:51 INFO - mozcrash == 1.0 17:32:51 INFO - mozdebug == 0.1 17:32:51 INFO - mozdevice == 0.50 17:32:51 INFO - mozfile == 1.2 17:32:51 INFO - mozhttpd == 0.7 17:32:51 INFO - mozinfo == 0.9 17:32:51 INFO - mozleak == 0.1 17:32:51 INFO - mozlog == 3.4 17:32:51 INFO - moznetwork == 0.27 17:32:51 INFO - mozprocess == 0.25 17:32:51 INFO - mozprofile == 0.28 17:32:51 INFO - mozrunner == 6.13 17:32:51 INFO - mozscreenshot == 0.1 17:32:51 INFO - mozsystemmonitor == 0.3 17:32:51 INFO - moztest == 0.8 17:32:51 INFO - mozversion == 1.4 17:32:51 INFO - psutil == 3.1.1 17:32:51 INFO - requests == 1.2.3 17:32:51 INFO - wsgiref == 0.1.2 17:32:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:52 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 0x10ee50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdc2cb3e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2ed30>, '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 0x10eb54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec23be8>, '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.HLTizRFm0L/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.h3JmSgm23t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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:32:52 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:32:52 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:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLTizRFm0L/Render', 17:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:52 INFO - 'HOME': '/Users/cltbld', 17:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:52 INFO - 'LOGNAME': 'cltbld', 17:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:52 INFO - 'MOZ_NO_REMOTE': '1', 17:32:52 INFO - 'NO_EM_RESTART': '1', 17:32:52 INFO - 'PAGER': '/bin/cat', 17:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:52 INFO - 'PWD': '/builds/slave/test', 17:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:52 INFO - 'SHELL': '/bin/bash', 17:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h3JmSgm23t/Listeners', 17:32:52 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 17:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:52 INFO - 'USER': 'cltbld', 17:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:52 INFO - 'XPC_FLAGS': '0x0', 17:32:52 INFO - 'XPC_SERVICE_NAME': '0', 17:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:52 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:32:52 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:32:52 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:32:52 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:32:52 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:32:52 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:32:52 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:32:52 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:32:52 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:32:52 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:32:52 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:32:52 INFO - Cleaning up... 17:32:52 INFO - Return code: 0 17:32:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:32:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:52 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 0x10ee50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdc2cb3e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec2ed30>, '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 0x10eb54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec23be8>, '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.HLTizRFm0L/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.h3JmSgm23t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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:32:52 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:32:52 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:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLTizRFm0L/Render', 17:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:32:52 INFO - 'HOME': '/Users/cltbld', 17:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:32:52 INFO - 'LOGNAME': 'cltbld', 17:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:52 INFO - 'MOZ_NO_REMOTE': '1', 17:32:52 INFO - 'NO_EM_RESTART': '1', 17:32:52 INFO - 'PAGER': '/bin/cat', 17:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:52 INFO - 'PWD': '/builds/slave/test', 17:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:32:52 INFO - 'SHELL': '/bin/bash', 17:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h3JmSgm23t/Listeners', 17:32:52 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 17:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:32:52 INFO - 'USER': 'cltbld', 17:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:52 INFO - 'XPC_FLAGS': '0x0', 17:32:52 INFO - 'XPC_SERVICE_NAME': '0', 17:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:32:52 INFO - Cleaning up... 17:32:52 INFO - Return code: 0 17:32:52 INFO - Running post-action listener: _resource_record_post_action 17:32:52 INFO - Running post-action listener: _start_resource_monitoring 17:32:52 INFO - Starting resource monitoring. 17:32:52 INFO - [mozharness: 2017-04-29 00:32:52.560179Z] Finished create-virtualenv step (success) 17:32:52 INFO - [mozharness: 2017-04-29 00:32:52.561188Z] Running install step. 17:32:52 INFO - Running pre-action listener: _resource_record_pre_action 17:32:52 INFO - Running main action method: install 17:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:32:52 INFO - Reading from file tmpfile_stdout 17:32:52 INFO - Detecting whether we're running mozinstall >=1.0... 17:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:32:52 INFO - Reading from file tmpfile_stdout 17:32:52 INFO - Output received: 17:32:52 INFO - Usage: mozinstall [options] installer 17:32:52 INFO - Options: 17:32:52 INFO - -h, --help show this help message and exit 17:32:52 INFO - -d DEST, --destination=DEST 17:32:52 INFO - Directory to install application into. [default: 17:32:52 INFO - "/builds/slave/test"] 17:32:52 INFO - --app=APP Application being installed. [default: firefox] 17:32:52 INFO - mkdir: /builds/slave/test/build/application 17:32:52 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:32:52 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:33:12 INFO - Reading from file tmpfile_stdout 17:33:12 INFO - Output received: 17:33:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:33:12 INFO - Running post-action listener: _resource_record_post_action 17:33:12 INFO - [mozharness: 2017-04-29 00:33:12.610084Z] Finished install step (success) 17:33:12 INFO - [mozharness: 2017-04-29 00:33:12.610229Z] Running run-tests step. 17:33:12 INFO - Running pre-action listener: _resource_record_pre_action 17:33:12 INFO - Running main action method: run_tests 17:33:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:33:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:33:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:33:12 INFO - 2017-04-28 17:33:12.625 screenresolution[2571:30380] starting screenresolution argv=screenresolution get 17:33:12 INFO - 2017-04-28 17:33:12.627 screenresolution[2571:30380] Display 0: 1600x1200x32@60 17:33:12 INFO - 2017-04-28 17:33:12.633 screenresolution[2572:30382] starting screenresolution argv=screenresolution list 17:33:12 INFO - Available Modes on Display 0 17:33:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:33:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:33:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:33:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:33:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:33:12 INFO - Intel Iris: 17:33:12 INFO - Chipset Model: Intel Iris 17:33:12 INFO - Type: GPU 17:33:12 INFO - Bus: Built-In 17:33:12 INFO - VRAM (Dynamic, Max): 1536 MB 17:33:12 INFO - Vendor: Intel (0x8086) 17:33:12 INFO - Device ID: 0x0a2e 17:33:12 INFO - Revision ID: 0x0009 17:33:12 INFO - Displays: 17:33:12 INFO - Display: 17:33:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:33:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:33:12 INFO - Display Serial Number: 1600x1200 60 17:33:12 INFO - Main Display: Yes 17:33:12 INFO - Mirror: Off 17:33:12 INFO - Online: Yes 17:33:12 INFO - Rotation: Supported 17:33:12 INFO - Return code: 0 17:33:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:33:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:33:12 INFO - Python 2.7.3 17:33:12 INFO - Return code: 0 17:33:12 INFO - grabbing minidump binary from tooltool 17:33:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:33:12 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 0x10ec2ed30>, '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 0x10eb54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec23be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:33:12 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:33:12 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:33:12 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:33:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:33:12 INFO - Return code: 0 17:33:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:33:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:33:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:33:12 INFO - ENV: RUST_BACKTRACE is now 1 17:33:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:33:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:33:12 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-0021', '--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:33:12 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-0021 --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:33:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLTizRFm0L/Render', 17:33:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:12 INFO - 'HOME': '/Users/cltbld', 17:33:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:12 INFO - 'LOGNAME': 'cltbld', 17:33:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:33:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:33:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:12 INFO - 'MOZ_NO_REMOTE': '1', 17:33:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:33:12 INFO - 'NO_EM_RESTART': '1', 17:33:12 INFO - 'PAGER': '/bin/cat', 17:33:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:12 INFO - 'PWD': '/builds/slave/test', 17:33:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:33:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:12 INFO - 'RUST_BACKTRACE': '1', 17:33:12 INFO - 'SHELL': '/bin/bash', 17:33:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h3JmSgm23t/Listeners', 17:33:12 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 17:33:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:12 INFO - 'USER': 'cltbld', 17:33:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:12 INFO - 'XPC_FLAGS': '0x0', 17:33:12 INFO - 'XPC_SERVICE_NAME': '0', 17:33:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:12 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-0021', '--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:33:13 INFO - mozversion application_buildid: 20170428075320 17:33:13 INFO - mozversion application_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:33:13 INFO - mozversion application_display_name: Nightly 17:33:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:33:13 INFO - mozversion application_name: Firefox 17:33:13 INFO - mozversion application_remotingname: firefox 17:33:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:33:13 INFO - mozversion application_vendor: Mozilla 17:33:13 INFO - mozversion application_version: 55.0a1 17:33:13 INFO - mozversion platform_buildid: 20170428075320 17:33:13 INFO - mozversion platform_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:33:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:33:13 INFO - mozversion platform_version: 55.0a1 17:33:13 INFO - using testdate: 1493425993 17:33:13 INFO - actual date: 1493425993 17:33:13 INFO - starting webserver on 'localhost:58914' 17:33:13 INFO - SUITE-START | Running 2 tests 17:33:13 INFO - TEST-START | dromaeo_css 17:33:13 INFO - operating with platform_type : mac_ 17:33:13 INFO - Initialising browser for dromaeo_css test... 17:33:13 INFO - TEST-INFO | started process 2582 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpK8nyXp/profile http://localhost:58914/getInfo.html) 17:33:15 INFO - PID 2582 | 2017-04-28 17:33:15.987 plugin-container[2584:30552] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9837, name = 'com.apple.tsm.portname' 17:33:15 INFO - PID 2582 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:33:15 INFO - PID 2582 | 2017-04-28 17:33:15.991 plugin-container[2584:30552] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 17:33:15 INFO - PID 2582 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:33:15 INFO - PID 2582 | 2017-04-28 17:33:15.991 plugin-container[2584:30552] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:33:16 INFO - PID 2582 | __metrics Screen width/height:1600/1200 17:33:16 INFO - PID 2582 | colorDepth:24 17:33:16 INFO - PID 2582 | Browser inner width/height: 1024/689 17:33:16 INFO - PID 2582 | __metrics 17:33:16 ERROR - PID 2582 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:33:17 INFO - TEST-INFO | 2582: exit 0 17:33:17 INFO - Browser initialized. 17:33:17 INFO - Running cycle 1/1 for dromaeo_css test... 17:33:17 INFO - TEST-INFO | started process 2586 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpK8nyXp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:33:19 INFO - PID 2586 | 2017-04-28 17:33:19.614 plugin-container[2587:30678] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 17:33:19 INFO - PID 2586 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:33:19 INFO - PID 2586 | 2017-04-28 17:33:19.618 plugin-container[2587:30678] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431f, name = 'com.apple.CFPasteboardClient' 17:33:19 INFO - PID 2586 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:33:19 INFO - PID 2586 | 2017-04-28 17:33:19.618 plugin-container[2587:30678] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:33:29 INFO - PID 2586 | RSS: Main: 250433536 17:33:29 INFO - PID 2586 | 17:36:33 INFO - PID 2586 | Cycle 1(1): loaded http://localhost:58914/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58914/tests/dromaeo/cssquery-ext.html) 17:36:35 INFO - PID 2586 | RSS: Main: 258084864 17:36:35 INFO - PID 2586 | 17:38:17 INFO - PID 2586 | ************************* 17:38:17 INFO - PID 2586 | A coding exception was thrown and uncaught in a Task. 17:38:17 INFO - PID 2586 | 17:38:17 ERROR - PID 2586 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:38:17 INFO - PID 2586 | Full stack: 17:38:17 INFO - PID 2586 | ************************* 17:38:17 INFO - PID 2586 | ************************* 17:38:17 INFO - PID 2586 | A coding exception was thrown and uncaught in a Task. 17:38:17 INFO - PID 2586 | 17:38:17 ERROR - PID 2586 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:38:17 INFO - PID 2586 | Full stack: 17:38:17 INFO - PID 2586 | ************************* 17:38:17 INFO - PID 2586 | ************************* 17:38:17 INFO - PID 2586 | A coding exception was thrown and uncaught in a Task. 17:38:17 INFO - PID 2586 | 17:38:17 ERROR - PID 2586 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:38:17 INFO - PID 2586 | Full stack: 17:38:17 INFO - PID 2586 | ************************* 17:38:17 INFO - PID 2586 | ************************* 17:38:17 INFO - PID 2586 | A coding exception was thrown and uncaught in a Task. 17:38:17 INFO - PID 2586 | 17:38:17 ERROR - PID 2586 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:38:17 INFO - PID 2586 | Full stack: 17:38:17 INFO - PID 2586 | ************************* 17:39:37 INFO - PID 2586 | Cycle 1(1): loaded http://localhost:58914/tests/dromaeo/cssquery-ext.html (next: http://localhost:58914/tests/dromaeo/cssquery-jquery.html) 17:39:38 INFO - PID 2586 | RSS: Main: 265023488 17:39:38 INFO - PID 2586 | 17:42:39 INFO - PID 2586 | Cycle 1(1): loaded http://localhost:58914/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58914/tests/dromaeo/cssquery-mootools.html) 17:42:40 INFO - PID 2586 | RSS: Main: 273817600 17:42:40 INFO - PID 2586 | 17:45:42 INFO - PID 2586 | Cycle 1(1): loaded http://localhost:58914/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58914/tests/dromaeo/cssquery-prototype.html) 17:45:43 INFO - PID 2586 | RSS: Main: 266911744 17:45:43 INFO - PID 2586 | 17:48:45 INFO - PID 2586 | Cycle 1(1): loaded http://localhost:58914/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58914/tests/dromaeo/cssquery-yui.html) 17:48:46 INFO - PID 2586 | RSS: Main: 276004864 17:48:46 INFO - PID 2586 | 17:51:48 INFO - PID 2586 | Cycle 1(1): loaded http://localhost:58914/tests/dromaeo/cssquery-yui.html (next: http://localhost:58914/tests/dromaeo/cssquery-dojo.html) 17:51:48 INFO - PID 2586 | RSS: Main: 270667776 17:51:48 INFO - PID 2586 | 17:51:48 INFO - PID 2586 | __start_tp_report 17:51:48 INFO - PID 2586 | _x_x_mozilla_page_load 17:51:48 INFO - PID 2586 | _x_x_mozilla_page_load_details 17:51:48 INFO - PID 2586 | |i|pagename|runs| 17:51:48 INFO - PID 2586 | |0;dojo.html;3062.249748811541;3567.215212653217;3622.655847694469;3625.8187090645492;3635.709143268539;5259.579233661303;6064.696765161735;6110.816675499742;6152.692365381729;6210.161628180194;6140.013486850318;6199.163112979758;6208.875822483547;6214.943459611865;6218.937810621893;5402.8919421611745;5417.45825417457;5461.262729531502;5480.068388373966;5499.202615620753;4254.553271906451;5053.36832895888;5243.767714587069;5578.30271216098;5588.720563971806;5461.453854614547;5477.7261136943;5513.228148059275;5544.722763861811;5553.666779993173;5482.643628164156;5494;5517.751701173457;5522.641028333145;5549.916751248735;4217.97891010546;5417.918731219035;5439.755211015514;5462.2079798429;5501;6068.423499767559;6082.178905847698;6180.752769889244;6241.095041219043;6245.687715614201;6308.211473565804;6603.735850565971;6653.2681405045405;6660.600363978177;6671.766488172891;6761.830954226184;6783.152105986752;6792.76225332116;6798.694058767367;6807;6255.499560035186;6332.968335158294;6342.112104305401;6344.619322840645;6368.904466433008;6685.899711504234;6789.083473730983;6832.0776695146515;6842.897356539655;6917;6139.154842350747;6347.650879201688;6430.099786029959;6496.675166241669;6635.369639884203;6719.294474080249;6742.696578653972;6790.354916282945;6791.049253104569;6800;5562.3881373048935;6662.967240077795;6680.83297917556;6693.598384096969;6703.296153903867;5342.198670199501;6454.644994525346;6534.379233972765;6585.769498067544;6651.401373876374;5302.1781623848365;5317.30874986257;5317.8936421271355;5322.760475778599;5364.704941228192;4666.743329116931;4685.250359942393;4701.835435759732;4717.080169367009;4729.9054018920115;5196.558292545101;6081.391860813883;6276.592021518587;6287.842803929847;6392.0731493934045;6739.0902228198565;6778.22050464193;6794.660266986631;6801.1158549388265;6825.215100263434;6321.336259692758;6345.397187267202;6363.077353783768;6389.9361006389345;6406.231252249667;5120.334356533627;5696.601237913306;5804.564657650693;5891.941080589225;5917.585768996215;6801.35387138231;6803.4557235422035;6888.138982627172;6893.414059804973;6911.688973996082;6799.422049125879;6809.965950170217;6819.556728812611;6840.589564626139;6849;5352.919706204332;5963.403659634002;6822.283660215804;6905.895056791091;7031.296870313133;6426.196725409324;6537.803865884031;6573.408393244631;6624.337566243337;6712.160979877515;4875.000624871964;6539.673016349068;6736.02327662496;6755.966220168868;6809.489288303496;5024.723640199824;6345.650989195638;6413.134226879311;6430.196225471816;6434.324395938359;6480.513961453004;6575.375339342755;6579.078928950046;6583.604983700801;6583.835404114935;6222.377762223922;6295.150154729053;6394.1687580614225;6440.066190402459;6469.385408386196;6421.8073457796345;6473.902891456726;6502.284748677547;6542.1168142300185;6543.574667646588;6373.681315934092;6420.903686444801;6422.486201103807;6445.6454894981225;6447.161868957193 17:51:48 INFO - PID 2586 | |1;ext.html;738.5937734246163;802.6709049289791;826.7189155687067;998.2762497189534;1189.7798907202173;30250;36804;37989.62010379895;38025.619743802556;38123.04692382692;38199.427008594896;38522.807385963104;38591.61408385915;38733.41899871504;38962.415563766495;7869.921300787005;8048.154943730911;8231.670733170666;8465.619047142862;8484.321254299672;7440.851182976337;8465.576721163949;8529.701460448885;8592.613332400057;8604.52675102869;8455.830883382328;8586.699465518708;8619.612117454699;8651.826963460728;8658.177473140042;8509.276711479532;8548.230659240668;8600.311975042014;8604.440711353805;8604.655813767442;8322.16778322163;8327.37544684145;8354.958225208835;8389.412741108124;8404.957975210145;36832;37042.259154817024;37054.814725926466;37089.07277318062;37103.4434483483;3007.8646460909313;3595.982020089883;3624.1120925373452;3624.3838547447;3672.3573374659595;30475.085747427613;30484.847575761978;30534;30593;30597;3122.406742718899;3160.7787454878166;3164.335489547198;3172.762042846796;3184.792988455766;248771;250968.49031509634;281655;303829;306168;46341.30488042682;46648.53351466442;46672.599822005395;46704.299435508496;46838.76580617075;48766;48916.51083489191;49499.50500494949;49503.75248123737;49569;13645.38595763193;13752.243626600433;13757.38091785872;13762.036657433884;13808.516701915385;11230.382328971986;11270.985611295022;11271.661850144508;11286.943565282121;11290.604828830952;13065.216087034809;13240.73518529624;13273.867261327456;13376.197428154343;13426.120343151966;5000.649954503223;5336.439673834191;5722.37053924068;5977.342492325841;6035.758569657164;985.261054209343;1015.5836107196014;1038.9168866490663;1062.6546372428836;1069.7806949575318;22933.42666433319;23932.04271829107;24075.277741667778;24288.45339774418;24493.265202043967;937.9155875971195;1130.3952385473733;1132.247055707945;1155.3183621663259;1174.6006357838376;9274.93338266108;10814.783704326026;11165.330080195214;11771.234869733611;12380.380980950917;321067;321679;322948;323487;323992;53462;53767.46232537625;53775.73112134414;53881;53903.46096539063;1495.4317359403574;1505.7878407881542;1545.737224671786;1548.9845101549065;1557.688462307543;18057;19897.502562436053;22840.428989275402;23405.297841064796;23961.520769584164;36938.44592331063;37067.8146609276;37081.81459092687;37159;37216.255674886896;287135;287156;288675;289896;290300;31753.36493270168;32543.837280813445;32802.50796238011;33234.16914577155;33352.83323583366;33271.16822079467;35285.47071793977;43554.78222608867;44100;44459.33311000402;22634.981425836206;28818.711812881604;29826.85086574553;30734.692653073183;30783.2304192397;24313.513729724953;26300.21099367022;27404.492752898783;30593.23516912006;30634.84682576573;35912.64087359093;36718.44922326221;36722;36882;36980.07549811276;28984.565231521123;29194.270143246587;30017.649205785354;30083;31116.755329786556;22070.006849692123;23832.88083559571;23837.284881453583;24196.153134640204;24303.027878884648 17:51:48 INFO - PID 2586 | |2;jquery.html;374.606663003846;420.2204909892148;421.1408726198555;428.75132423194543;444.30688126522625;19371;20954;21439.356819295426;21686.457838554004;21789.564208715856;22442.88778556109;22531;23164.8841755791;23164.957576909037;23512.764872351272;6058.939410605893;6303.968480157593;6314.968425157867;6332.556721029529;6341.270753863296;6078.452939235468;6084.634921904678;6189.969050154766;6244.219472565929;6275.341089185638;6149.385061493837;6176.135341052234;6214.720337584797;6215.658138802364;6216.316205217422;5973.253343332083;6244.812655620293;6246.312905580383;6250.3124656288;6253.53098517613;5349.732513374316;5546.084895992157;5567.331920169565;5586.24585680932;5601.719914004324;26619;26651.333716657045;26735.930562777467;27125;27430;2264.1735766445295;2334.789868911789;2335.7781010803947;2337.649352597107;2345.1088586337255;17998.550036249067;18096.276148954024;18355.990420527;18564.886106833634;18850.81149188518;2143.421276255418;2169.3275084723787;2207.2452400341767;2209.0611490116635;2214.047959377467;116832.24751628647;117357;160696.19651901783;169068.15465922715;177449.1127544367;12285.815712764315;12710.792474714888;12771.233725976472;12867.806982895265;12992.610221693365;16177.757333640006;16297.511074667778;16492.83507164937;16547.586310342336;16619.41832035897;8880.333974951904;9017.188453039258;9092.136247056518;9163.862542061874;9184.63261469547;8591.09793471689;8601.698940537051;8609.182127697859;8767.210951014311;8770.517621530877;7905.920940790634;8008.1991800820615;8070.354371650254;8079.798005049921;8086.272235498715;1894.2612381171355;2232.8883555822144;2235.61994460942;2238.40682219212;2391.5336509380504;941.924646028316;945.8413443531756;947.6162154327509;952.1668540027476;989.3964681544252;20558.38324850227;21200.78799212019;21253.68119478209;21348;21467.785322146578;980.774421882971;987.9308448408686;996.6661168508485;1022.7085280694967;1024.6772266735954;10404;10660.573577056986;10668.30656007357;10741.516631751434;10774.892251077546;548669;556470;556684;557102;560324;116280.25579616312;118575.40712296384;126938;130226;137764;1186.3830807979803;1252.7932891072871;1264.9747005059846;1268.131330038908;1268.5686866465446;21726.23958161457;21871.67192492146;21931.27329517973;21967.890160549094;22087.668684969416;55898;56125.438745612024;57480.275591732316;58032.70983645055;58382.83234335205;74811.51130931944;76194;76677;77158;77244.61377693075;7956.323712484504;8609.612567434606;8850.33622478304;8943.508107054175;9159.77100572491;13702.52041178554;14243.430262789787;14281;14712.779308310597;14774.409023639364;11097.667069987914;11769.88186122317;11850.585229516926;11972.64082077539;12034.699132521757;9672.274579406432;10588;10642.361458312525;11437.313761174357;11741.29552226835;10241.539130739284;12113.757724845278;12160.331181784733;12449.502019919464;12569.93715031419;8799.91200087991;8815.691450799191;8825.161609647073;9185.44887306737;9277.814443711222;9546.331756776959;12169.574064907685;12275.570355037531;12278.754424911273;12385.690357741129 17:51:48 INFO - PID 2586 | |3;mootools.html;1270.9046821488387;1384.5984664447308;1401.5094716849103;1425.4084554909714;1433.634423222078;11404.258723182998;11666;11725.413729313543;11885.940570297158;12052.51789928404;9543.809123817537;11764.882351176486;11772.47023883925;11786.233894796833;11820.822687659693;2472.0606169655507;2500.462400583874;2510.8242423030397;2513.396784771651;2518.181590982929;2461.6430617560445;2488.2908371114236;2506.5989441689335;2510.899564017437;2513.5098655762135;2459.950800983979;2478.925632231036;2501.9749802501924;2525.949481010379;2528.115159694111;2452.411421258894;2459.02868366994;2499.0628514307136;2499.6750422445148;2500.6749122614124;2388.6058800298106;2465.29739024379;2465.716442609105;2488.079410618083;2495.301315631626;9555.331126821126;11696.883031169664;11729;11735;11752.294862308288;6393.2328120625825;6599.868002639968;6609.206895172562;6611.900821487681;6615.437687796495;3961.722679412442;4297.419848320499;4300.569943005706;4374.628156606692;4382.737035777832;1113.4488428227999;1259.937003149848;1270.2315099364937;1276.3235485192863;1305.673581604599;16086.517404477885;16299.266533006043;16440.424585139583;16455.83544164555;16697.91651041737;5513.228148059275;5538.47384498472;5664.716764161775;5684.8010319638615;5722.77108915647;9791.951040244754;11810.055195584333;11890.40547972598;11904.988076013462;11904.988076013462;6557.409833115043;6591.307912669196;6632.0383544385795;6645.06969024337;6646.534742567974;5199.246109314128;6045.637261764308;6296.590721603081;6297.622142671455;6325.082862984934;3587.94618080729;4291.785410729513;4303.655707543389;4374.540673229329;4403.4495688039;1087.6465148826505;1267.0054007604172;1274.4710944957924;1286.9549565765153;1288.433089440643;733.3436574266002;757.7650928212272;787.8266781308103;789.7433334166304;800.6477150053958;6415.839604009937;6605.976073708583;6673.589200846598;6930.7227710891;7022.332878376546;793.3653077537947;880.251785981923;919.8758167647281;931.7297983584684;937.5968333616474;3616.1321282891918;3620.5474315710535;3670.284294562535;4256.808443620044;4755.667103302805;14945.775813362809;15028.173450460112;15297;15315.540533783782;15325.770113448307;14514;14843.480478183212;15038.774418383733;15168.620784480476;15208.695826083416;8150.673973041012;8238.752837414768;8660.956695216484;8691.261242794433;8771.824563508693;5566.721663916869;6555.049517819984;6737.29258427858;7028.789136325919;7043.471739619446;12954.80567791465;13303.26832024248;13501;13674.31628418595;13725;13586.524471643444;13701;13796.448142074607;13994;14209.786853197416;9236.399634023766;9259.444433334022;9347.112024357675;9442.008589098046;9550.092741189575;10858.077063449695;10875.673729788119;10991.175661825555;10993.780124397626;11015.228933974866;9011.188992990663;9173.266138709021;9313.767155820888;9341.486218258062;9354.95322523383;9036.728898133066;9061.320400970086;9102.453852768855;9196.35625506208;9275.304352173749;6074.696265186812;8040.195980401913;8063.233992770677;8224.09534951143;8255.174482551696;6006.129111278928;6014.6391216527145;6051.122587224916;6137.294211165773;6217.8807814592;5124.718140502308;5202.8959420812125;6022.1870047543025;6247.281562620356;6264.624122552661 17:51:48 INFO - PID 2586 | |4;prototype.html;376.85679441812107;395.73956947123406;403.70126106681056;406.5243664912053;412.2826282268853;5461.972690136554;5615.887682246358;5623;6015;6265.216847894013;5501.944980550194;5648.322201335835;5975.551833612475;6097.2073630427985;6163.784267550636;4091.1408604193157;4381.802818873159;4639.814407423716;4654.930176047362;4690.90618187636;4634.814607415717;4693.436787585494;4727.479977202505;4733.479317275097;4737.336772851803;4636.582707556337;4640.930386044212;4655;4656.1153380857695;4687.554682305175;4380.12397520494;4664.32367306742;4664.530672838046;4667.906641867177;4680.25115981443;4086;4163.91672166555;4166.208420400114;4176.812043458051;4180.895477613054;5444.210589464545;5737.328110009597;5860.501857342131;5914.852128696817;5987;4550.180967425862;5528.806491772809;5536.916946245809;5538.975289571402;5546.502444340046;5437.803683189692;6228.5017198624455;6459.418652321314;6532.673366331712;6560.737570497175;3531.6291789362003;4285.892852678677;4290.227759003378;4303.91392172158;4305.74165550068;6218.347073557302;6630.403263706241;6898.793036208922;7009.08881845362;7009.2990700929495;3664.4686520454384;4309.8363441870515;4319.164355149049;4332.1985432695055;4342.435483387139;4041.6968727345566;4416.492103408085;4418.911621767611;4425.734455932655;4435.179491794029;5089.923651145235;5138.07514647367;5214.504622060898;5234.607404444682;5251.448597897166;5914.526837852962;6459.838504037437;6573.276939536647;6603;6610.338966103352;1493.805805245333;1499.8275198352328;1500.8574185452364;1502.5792778022171;1503.1056521369767;924.8150369926042;973.1582181413027;976.7606936300651;986.4032260482305;988.9258305627106;5820.70896455184;5841.649501029951;6306;6359.491240700733;6364.849399547254;6706.295838936939;6848;6870.20992585859;6881.449484041264;6889.552179108342;5034.521720436553;6220;6269.874602508015;6280.748770049238;6319.905201421982;3165.525171224289;3519.3665140274484;3541.8937431877084;3574.803385813805;3576.445650924111;6840.110785597841;7013.860772464345;7025.803259516908;7075.575465471985;7083.327083927019;6955.0178199214;7010.158780946217;7027.156741190987;7036.150359104571;7077.3984211341585;6403.743850246031;6806.217285012287;7010.684519196547;7023.157221133497;7054.329838665319;6314.526410519321;6798.320167983162;6859.897101543381;6861.519693621399;6884.586924784529;6410;6559.508036897157;6704.161979752531;6734.730610775514;6748.93251067483;7026.521871673554;7036.964815175891;7056.364927156581;7067.717291308496;7071.82320441993;4124.803806895967;4199.895002624959;4303.763293018914;4356.869293921188;4381.342798580238;6123.938760612336;6837.692303846239;6849.1780986281965;6893.75871844483;6918.8616227676175;5043.193089105725;5144.7942082316285;6318.778842740482;6419.679016049272;6585.703643335965;6280.497560195265;6621.9337806621315;6627.801165965029;6628.867422651423;6657.40083392497;5331.920021199608;5933.851653708692;6214.254289485109;6286.868363694579;6468.320826313357;6585.308542603149;6587.769428069994;6609.405153536205;6642.568233064835;6649.335066493312;4839.41926968766;5534.91697624544;6255.906161407492;6439.452646525097;6488.772892948913 17:51:48 INFO - PID 2586 | |5;yui.html;820.8481430935282;836.5273620404471;850.1795767084764;860.6573744957866;870.7431307764209;16934.322627094916;17434.476965691018;17480.650386992267;19190.42428727137;19639.70540441891;17960.461186164404;18379.356722514716;18402.81597184028;18466.353677621286;18474.815251847478;982.2583949118424;992.4640694025218;1001.5242759689125;1004.4023805835516;1005.2510879394861;845.5603086395101;960.490939801906;1011.6813203840803;1013.6654903881738;1014.3102690170681;1058.095328494134;1067.8505009298703;1070.5985255529176;1071.6570697376842;1075.2688172043008;879.846026945282;1051.4374809476892;1052.6052730226165;1066.9573217071309;1068.9144868410585;758.6244808819614;771.3058247577169;774.2953911940108;776.5263189454429;781.0978320040363;16996.490105296736;17305.961642301503;17483.912580437143;17497;17544.824551754446;14869.776953345709;17258.741118883227;17519.5984321254;17760.733588996176;17812.46562603124;12338.5064597416;13855;14041.087329323664;14452.205128717917;14460.49388271416;3446.551948246737;3506.6493350664987;3531.1348719563616;3536.3811333016874;3537.5224344713392;86687;115090;131097;133129.3343533276;133178;12889.806652900214;13032.348382580833;13104;13143.277119758504;13166.868331316564;25110.995560177755;25179.24462266135;25273;25484;25521.48957020849;7644.388448924073;7739.729109481141;7784.3772498200005;7851.960740196262;7864.331531819859;3946.723729338976;4007.719459637797;4039.979800100981;4043.5552089270745;4051.0277533391773;4333.891652708708;4374.78126093694;4442.666799990014;4450.532694067141;4455.975125721037;870.6299822575506;894.8120894612074;898.7977705016319;900.1853322742928;907.0249481807084;630.130420020369;667.2626747444004;674.430106559956;675.8040168351222;690.5649440852231;11214.495347709373;11818.054555635528;11847.88152118485;12056.63830085099;12188.329641869783;646.9644169570624;649.7043845050586;655.8425977765397;667.502710480695;678.4470656415006;4927.531884470969;5365.409804921455;5415.512603865671;5449.100898351807;5595.160725891149;110344.89655103347;110675;112155;112542;114821;91788.5410572943;97360;99678;100501.49749251353;100507;58021.709891451115;59963;76684.84972725414;77767.61116194383;80824.59587702142;10036.447995360033;10634.255602107778;11681;12173.087018473472;12701.491940322283;27918.581221282104;36475.71718545344;42213.15573688447;43369.78315108404;43682.563174367846;38348.61651383562;54016;54204;54841;55510.44489555053;16078.678426431172;16315.755263671292;16316.592085197964;16341.264643091326;16405.671886561962;17525.47423577244;17547.1226438675;18088.27646894162;18238.996855173093;18320.541986450444;16345.182740863147;16438.260278287267;16554.172291385625;16627.83372166311;16782.32870685159;13378.665533361744;16149.354025838835;16404;16531;16658.50024499267;15645;15674.13792241392;15789.921050394674;15978.520644380687;16045;11491.195616306779;11534.365609891536;11939.136941880768;11965.521379145084;12123.333216672814;13111.73776524445;14106.365213565208;14145;14175.362108704927;14201.218932958787 17:51:48 INFO - PID 2586 | __end_tp_report 17:51:48 INFO - PID 2586 | __start_cc_report 17:51:48 INFO - PID 2586 | _x_x_mozilla_cycle_collect,426 17:51:48 INFO - PID 2586 | __end_cc_report 17:51:48 INFO - PID 2586 | __startTimestamp1493427108329__endTimestamp 17:51:48 INFO - PID 2586 | 17:51:48 INFO - PID 2586 | ------- Summary: start ------- 17:51:48 INFO - PID 2586 | Number of tests: 6 17:51:48 INFO - PID 2586 | 17:51:48 INFO - PID 2586 | [#0] dojo.html Cycles:165 Average:6091.31 Median:6363.08 stddev:773.71 (12.2%) stddev-sans-first:738.70 17:51:48 INFO - PID 2586 | Values: 3062.2 3567.2 3622.7 3625.8 3635.7 5259.6 6064.7 6110.8 6152.7 6210.2 6140.0 6199.2 6208.9 6214.9 6218.9 5402.9 5417.5 5461.3 5480.1 5499.2 4254.6 5053.4 5243.8 5578.3 5588.7 5461.5 5477.7 5513.2 5544.7 5553.7 5482.6 5494.0 5517.8 5522.6 5549.9 4218.0 5417.9 5439.8 5462.2 5501.0 6068.4 6082.2 6180.8 6241.1 6245.7 6308.2 6603.7 6653.3 6660.6 6671.8 6761.8 6783.2 6792.8 6798.7 6807.0 6255.5 6333.0 6342.1 6344.6 6368.9 6685.9 6789.1 6832.1 6842.9 6917.0 6139.2 6347.7 6430.1 6496.7 6635.4 6719.3 6742.7 6790.4 6791.0 6800.0 5562.4 6663.0 6680.8 6693.6 6703.3 5342.2 6454.6 6534.4 6585.8 6651.4 5302.2 5317.3 5317.9 5322.8 5364.7 4666.7 4685.3 4701.8 4717.1 4729.9 5196.6 6081.4 6276.6 6287.8 6392.1 6739.1 6778.2 6794.7 6801.1 6825.2 6321.3 6345.4 6363.1 6389.9 6406.2 5120.3 5696.6 5804.6 5891.9 5917.6 6801.4 6803.5 6888.1 6893.4 6911.7 6799.4 6810.0 6819.6 6840.6 6849.0 5352.9 5963.4 6822.3 6905.9 7031.3 6426.2 6537.8 6573.4 6624.3 6712.2 4875.0 6539.7 6736.0 6756.0 6809.5 5024.7 6345.7 6413.1 6430.2 6434.3 6480.5 6575.4 6579.1 6583.6 6583.8 6222.4 6295.2 6394.2 6440.1 6469.4 6421.8 6473.9 6502.3 6542.1 6543.6 6373.7 6420.9 6422.5 6445.6 6447.2 17:51:48 INFO - PID 2586 | 17:51:48 INFO - PID 2586 | [#1] ext.html Cycles:180 Average:44222.97 Median:178267.92 stddev:78073.82 (43.8%) stddev-sans-first:78224.57 17:51:48 INFO - PID 2586 | Values: 738.6 802.7 826.7 998.3 1189.8 30250.0 36804.0 37989.6 38025.6 38123.0 38199.4 38522.8 38591.6 38733.4 38962.4 7869.9 8048.2 8231.7 8465.6 8484.3 7440.9 8465.6 8529.7 8592.6 8604.5 8455.8 8586.7 8619.6 8651.8 8658.2 8509.3 8548.2 8600.3 8604.4 8604.7 8322.2 8327.4 8355.0 8389.4 8405.0 36832.0 37042.3 37054.8 37089.1 37103.4 3007.9 3596.0 3624.1 3624.4 3672.4 30475.1 30484.8 30534.0 30593.0 30597.0 3122.4 3160.8 3164.3 3172.8 3184.8 248771.0 250968.5 281655.0 303829.0 306168.0 46341.3 46648.5 46672.6 46704.3 46838.8 48766.0 48916.5 49499.5 49503.8 49569.0 13645.4 13752.2 13757.4 13762.0 13808.5 11230.4 11271.0 11271.7 11286.9 11290.6 13065.2 13240.7 13273.9 13376.2 13426.1 5000.6 5336.4 5722.4 5977.3 6035.8 985.3 1015.6 1038.9 1062.7 1069.8 22933.4 23932.0 24075.3 24288.5 24493.3 937.9 1130.4 1132.2 1155.3 1174.6 9274.9 10814.8 11165.3 11771.2 12380.4 321067.0 321679.0 322948.0 323487.0 323992.0 53462.0 53767.5 53775.7 53881.0 53903.5 1495.4 1505.8 1545.7 1549.0 1557.7 18057.0 19897.5 22840.4 23405.3 23961.5 36938.4 37067.8 37081.8 37159.0 37216.3 287135.0 287156.0 288675.0 289896.0 290300.0 31753.4 32543.8 32802.5 33234.2 33352.8 33271.2 35285.5 43554.8 44100.0 44459.3 22635.0 28818.7 29826.9 30734.7 30783.2 24313.5 26300.2 27404.5 30593.2 30634.8 35912.6 36718.4 36722.0 36882.0 36980.1 28984.6 29194.3 30017.6 30083.0 31116.8 22070.0 23832.9 23837.3 24196.2 24303.0 17:51:48 INFO - PID 2586 | 17:51:48 INFO - PID 2586 | [#2] jquery.html Cycles:180 Average:35571.13 Median:2336.71 stddev:93946.09 (4020.4%) stddev-sans-first:94172.47 17:51:48 INFO - PID 2586 | Values: 374.6 420.2 421.1 428.8 444.3 19371.0 20954.0 21439.4 21686.5 21789.6 22442.9 22531.0 23164.9 23165.0 23512.8 6058.9 6304.0 6315.0 6332.6 6341.3 6078.5 6084.6 6190.0 6244.2 6275.3 6149.4 6176.1 6214.7 6215.7 6216.3 5973.3 6244.8 6246.3 6250.3 6253.5 5349.7 5546.1 5567.3 5586.2 5601.7 26619.0 26651.3 26735.9 27125.0 27430.0 2264.2 2334.8 2335.8 2337.6 2345.1 17998.6 18096.3 18356.0 18564.9 18850.8 2143.4 2169.3 2207.2 2209.1 2214.0 116832.2 117357.0 160696.2 169068.2 177449.1 12285.8 12710.8 12771.2 12867.8 12992.6 16177.8 16297.5 16492.8 16547.6 16619.4 8880.3 9017.2 9092.1 9163.9 9184.6 8591.1 8601.7 8609.2 8767.2 8770.5 7905.9 8008.2 8070.4 8079.8 8086.3 1894.3 2232.9 2235.6 2238.4 2391.5 941.9 945.8 947.6 952.2 989.4 20558.4 21200.8 21253.7 21348.0 21467.8 980.8 987.9 996.7 1022.7 1024.7 10404.0 10660.6 10668.3 10741.5 10774.9 548669.0 556470.0 556684.0 557102.0 560324.0 116280.3 118575.4 126938.0 130226.0 137764.0 1186.4 1252.8 1265.0 1268.1 1268.6 21726.2 21871.7 21931.3 21967.9 22087.7 55898.0 56125.4 57480.3 58032.7 58382.8 74811.5 76194.0 76677.0 77158.0 77244.6 7956.3 8609.6 8850.3 8943.5 9159.8 13702.5 14243.4 14281.0 14712.8 14774.4 11097.7 11769.9 11850.6 11972.6 12034.7 9672.3 10588.0 10642.4 11437.3 11741.3 10241.5 12113.8 12160.3 12449.5 12569.9 8799.9 8815.7 8825.2 9185.4 9277.8 9546.3 12169.6 12275.6 12278.8 12385.7 17:51:48 INFO - PID 2586 | 17:51:48 INFO - PID 2586 | [#3] mootools.html Cycles:180 Average:7083.62 Median:3816.00 stddev:4516.56 (118.4%) stddev-sans-first:4508.11 17:51:48 INFO - PID 2586 | Values: 1270.9 1384.6 1401.5 1425.4 1433.6 11404.3 11666.0 11725.4 11885.9 12052.5 9543.8 11764.9 11772.5 11786.2 11820.8 2472.1 2500.5 2510.8 2513.4 2518.2 2461.6 2488.3 2506.6 2510.9 2513.5 2460.0 2478.9 2502.0 2525.9 2528.1 2452.4 2459.0 2499.1 2499.7 2500.7 2388.6 2465.3 2465.7 2488.1 2495.3 9555.3 11696.9 11729.0 11735.0 11752.3 6393.2 6599.9 6609.2 6611.9 6615.4 3961.7 4297.4 4300.6 4374.6 4382.7 1113.4 1259.9 1270.2 1276.3 1305.7 16086.5 16299.3 16440.4 16455.8 16697.9 5513.2 5538.5 5664.7 5684.8 5722.8 9792.0 11810.1 11890.4 11905.0 11905.0 6557.4 6591.3 6632.0 6645.1 6646.5 5199.2 6045.6 6296.6 6297.6 6325.1 3587.9 4291.8 4303.7 4374.5 4403.4 1087.6 1267.0 1274.5 1287.0 1288.4 733.3 757.8 787.8 789.7 800.6 6415.8 6606.0 6673.6 6930.7 7022.3 793.4 880.3 919.9 931.7 937.6 3616.1 3620.5 3670.3 4256.8 4755.7 14945.8 15028.2 15297.0 15315.5 15325.8 14514.0 14843.5 15038.8 15168.6 15208.7 8150.7 8238.8 8661.0 8691.3 8771.8 5566.7 6555.0 6737.3 7028.8 7043.5 12954.8 13303.3 13501.0 13674.3 13725.0 13586.5 13701.0 13796.4 13994.0 14209.8 9236.4 9259.4 9347.1 9442.0 9550.1 10858.1 10875.7 10991.2 10993.8 11015.2 9011.2 9173.3 9313.8 9341.5 9355.0 9036.7 9061.3 9102.5 9196.4 9275.3 6074.7 8040.2 8063.2 8224.1 8255.2 6006.1 6014.6 6051.1 6137.3 6217.9 5124.7 5202.9 6022.2 6247.3 6264.6 17:51:48 INFO - PID 2586 | 17:51:48 INFO - PID 2586 | [#4] prototype.html Cycles:180 Average:5317.25 Median:5981.28 stddev:1683.00 (28.1%) stddev-sans-first:1646.36 17:51:48 INFO - PID 2586 | Values: 376.9 395.7 403.7 406.5 412.3 5462.0 5615.9 5623.0 6015.0 6265.2 5501.9 5648.3 5975.6 6097.2 6163.8 4091.1 4381.8 4639.8 4654.9 4690.9 4634.8 4693.4 4727.5 4733.5 4737.3 4636.6 4640.9 4655.0 4656.1 4687.6 4380.1 4664.3 4664.5 4667.9 4680.3 4086.0 4163.9 4166.2 4176.8 4180.9 5444.2 5737.3 5860.5 5914.9 5987.0 4550.2 5528.8 5536.9 5539.0 5546.5 5437.8 6228.5 6459.4 6532.7 6560.7 3531.6 4285.9 4290.2 4303.9 4305.7 6218.3 6630.4 6898.8 7009.1 7009.3 3664.5 4309.8 4319.2 4332.2 4342.4 4041.7 4416.5 4418.9 4425.7 4435.2 5089.9 5138.1 5214.5 5234.6 5251.4 5914.5 6459.8 6573.3 6603.0 6610.3 1493.8 1499.8 1500.9 1502.6 1503.1 924.8 973.2 976.8 986.4 988.9 5820.7 5841.6 6306.0 6359.5 6364.8 6706.3 6848.0 6870.2 6881.4 6889.6 5034.5 6220.0 6269.9 6280.7 6319.9 3165.5 3519.4 3541.9 3574.8 3576.4 6840.1 7013.9 7025.8 7075.6 7083.3 6955.0 7010.2 7027.2 7036.2 7077.4 6403.7 6806.2 7010.7 7023.2 7054.3 6314.5 6798.3 6859.9 6861.5 6884.6 6410.0 6559.5 6704.2 6734.7 6748.9 7026.5 7037.0 7056.4 7067.7 7071.8 4124.8 4199.9 4303.8 4356.9 4381.3 6123.9 6837.7 6849.2 6893.8 6918.9 5043.2 5144.8 6318.8 6419.7 6585.7 6280.5 6621.9 6627.8 6628.9 6657.4 5331.9 5933.9 6214.3 6286.9 6468.3 6585.3 6587.8 6609.4 6642.6 6649.3 4839.4 5534.9 6255.9 6439.5 6488.8 17:51:48 INFO - PID 2586 | 17:51:48 INFO - PID 2586 | [#5] yui.html Cycles:180 Average:21616.85 Median:17886.46 stddev:30690.05 (171.6%) stddev-sans-first:30736.42 17:51:48 INFO - PID 2586 | Values: 820.8 836.5 850.2 860.7 870.7 16934.3 17434.5 17480.7 19190.4 19639.7 17960.5 18379.4 18402.8 18466.4 18474.8 982.3 992.5 1001.5 1004.4 1005.3 845.6 960.5 1011.7 1013.7 1014.3 1058.1 1067.9 1070.6 1071.7 1075.3 879.8 1051.4 1052.6 1067.0 1068.9 758.6 771.3 774.3 776.5 781.1 16996.5 17306.0 17483.9 17497.0 17544.8 14869.8 17258.7 17519.6 17760.7 17812.5 12338.5 13855.0 14041.1 14452.2 14460.5 3446.6 3506.6 3531.1 3536.4 3537.5 86687.0 115090.0 131097.0 133129.3 133178.0 12889.8 13032.3 13104.0 13143.3 13166.9 25111.0 25179.2 25273.0 25484.0 25521.5 7644.4 7739.7 7784.4 7852.0 7864.3 3946.7 4007.7 4040.0 4043.6 4051.0 4333.9 4374.8 4442.7 4450.5 4456.0 870.6 894.8 898.8 900.2 907.0 630.1 667.3 674.4 675.8 690.6 11214.5 11818.1 11847.9 12056.6 12188.3 647.0 649.7 655.8 667.5 678.4 4927.5 5365.4 5415.5 5449.1 5595.2 110344.9 110675.0 112155.0 112542.0 114821.0 91788.5 97360.0 99678.0 100501.5 100507.0 58021.7 59963.0 76684.8 77767.6 80824.6 10036.4 10634.3 11681.0 12173.1 12701.5 27918.6 36475.7 42213.2 43369.8 43682.6 38348.6 54016.0 54204.0 54841.0 55510.4 16078.7 16315.8 16316.6 16341.3 16405.7 17525.5 17547.1 18088.3 18239.0 18320.5 16345.2 16438.3 16554.2 16627.8 16782.3 13378.7 16149.4 16404.0 16531.0 16658.5 15645.0 15674.1 15789.9 15978.5 16045.0 11491.2 11534.4 11939.1 11965.5 12123.3 13111.7 14106.4 14145.0 14175.4 14201.2 17:51:48 INFO - PID 2586 | -------- Summary: end -------- 17:51:48 INFO - PID 2586 | 17:51:48 INFO - TEST-INFO | 2586: exit 0 17:51:48 INFO - TEST-OK | dromaeo_css | took 1115765ms 17:51:48 INFO - TEST-START | kraken 17:51:48 INFO - operating with platform_type : mac_ 17:51:48 INFO - Initialising browser for kraken test... 17:51:49 INFO - TEST-INFO | started process 2702 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmp7ugvZV/profile http://localhost:58914/getInfo.html) 17:51:51 INFO - PID 2702 | 2017-04-28 17:51:51.313 plugin-container[2703:33054] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9547, name = 'com.apple.tsm.portname' 17:51:51 INFO - PID 2702 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:51:51 INFO - PID 2702 | 2017-04-28 17:51:51.318 plugin-container[2703:33054] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x954b, name = 'com.apple.CFPasteboardClient' 17:51:51 INFO - PID 2702 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:51:51 INFO - PID 2702 | 2017-04-28 17:51:51.318 plugin-container[2703:33054] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:51:51 INFO - PID 2702 | __metrics Screen width/height:1600/1200 17:51:51 INFO - PID 2702 | colorDepth:24 17:51:51 INFO - PID 2702 | Browser inner width/height: 1024/689 17:51:51 INFO - PID 2702 | __metrics 17:51:51 ERROR - PID 2702 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:51:52 INFO - TEST-INFO | 2702: exit 0 17:51:52 INFO - Browser initialized. 17:51:52 INFO - Running cycle 1/1 for kraken test... 17:51:52 INFO - TEST-INFO | started process 2704 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmp7ugvZV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:51:54 INFO - PID 2704 | 2017-04-28 17:51:54.910 plugin-container[2705:33180] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x954f, name = 'com.apple.tsm.portname' 17:51:54 INFO - PID 2704 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:51:54 INFO - PID 2704 | 2017-04-28 17:51:54.911 plugin-container[2705:33180] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.CFPasteboardClient' 17:51:54 INFO - PID 2704 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:51:54 INFO - PID 2704 | 2017-04-28 17:51:54.911 plugin-container[2705:33180] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:52:05 INFO - PID 2704 | RSS: Main: 251871232 17:52:05 INFO - PID 2704 | 17:52:07 INFO - PID 2704 | Cycle 1(1): loaded http://localhost:58914/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58914/tests/kraken/driver.html?testName=audio-beat-detection) 17:52:08 INFO - PID 2704 | RSS: Main: 259645440 17:52:08 INFO - PID 2704 | 17:52:11 INFO - PID 2704 | Cycle 1(1): loaded http://localhost:58914/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58914/tests/kraken/driver.html?testName=audio-dft) 17:52:11 INFO - PID 2704 | RSS: Main: 266477568 17:52:11 INFO - PID 2704 | 17:52:15 INFO - PID 2704 | Cycle 1(1): loaded http://localhost:58914/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58914/tests/kraken/driver.html?testName=audio-fft) 17:52:15 INFO - PID 2704 | RSS: Main: 262639616 17:52:15 INFO - PID 2704 | 17:52:17 INFO - PID 2704 | Cycle 1(1): loaded http://localhost:58914/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58914/tests/kraken/driver.html?testName=audio-oscillator) 17:52:18 INFO - PID 2704 | RSS: Main: 264552448 17:52:18 INFO - PID 2704 | 17:52:20 INFO - PID 2704 | Cycle 1(1): loaded http://localhost:58914/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58914/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:52:21 INFO - PID 2704 | RSS: Main: 265117696 17:52:21 INFO - PID 2704 | 17:52:23 INFO - PID 2704 | Cycle 1(1): loaded http://localhost:58914/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58914/tests/kraken/driver.html?testName=imaging-darkroom) 17:52:24 INFO - PID 2704 | RSS: Main: 271036416 17:52:24 INFO - PID 2704 | 17:52:27 INFO - PID 2704 | Cycle 1(1): loaded http://localhost:58914/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58914/tests/kraken/driver.html?testName=imaging-desaturate) 17:52:28 INFO - PID 2704 | RSS: Main: 272474112 17:52:28 INFO - PID 2704 | 17:52:31 INFO - PID 2704 | Cycle 1(1): loaded http://localhost:58914/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58914/tests/kraken/driver.html?testName=json-parse-financial) 17:52:31 INFO - PID 2704 | RSS: Main: 271654912 17:52:31 INFO - PID 2704 | 17:52:33 INFO - PID 2704 | Cycle 1(1): loaded http://localhost:58914/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58914/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:52:33 INFO - PID 2704 | RSS: Main: 271216640 17:52:33 INFO - PID 2704 | 17:52:35 INFO - PID 2704 | Cycle 1(1): loaded http://localhost:58914/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58914/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:52:36 INFO - PID 2704 | RSS: Main: 271364096 17:52:36 INFO - PID 2704 | 17:52:38 INFO - PID 2704 | Cycle 1(1): loaded http://localhost:58914/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58914/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:52:39 INFO - PID 2704 | RSS: Main: 270761984 17:52:39 INFO - PID 2704 | 17:52:42 INFO - PID 2704 | Cycle 1(1): loaded http://localhost:58914/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58914/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:52:43 INFO - PID 2704 | RSS: Main: 271044608 17:52:43 INFO - PID 2704 | 17:52:46 INFO - PID 2704 | Cycle 1(1): loaded http://localhost:58914/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58914/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:52:46 INFO - PID 2704 | RSS: Main: 271134720 17:52:46 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | Cycle 1(1): loaded http://localhost:58914/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58914/tests/kraken/driver.html?testName=ai-astar) 17:52:48 INFO - PID 2704 | RSS: Main: 272785408 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | __start_tp_report 17:52:48 INFO - PID 2704 | _x_x_mozilla_page_load 17:52:48 INFO - PID 2704 | _x_x_mozilla_page_load_details 17:52:48 INFO - PID 2704 | |i|pagename|runs| 17:52:48 INFO - PID 2704 | |0;ai-astar;102;116;117;112;115;125;169;121;116;114 17:52:48 INFO - PID 2704 | |1;audio-beat-detection;150;112;114;159;114;111;110;107;139;116 17:52:48 INFO - PID 2704 | |2;audio-dft;193;199;205;211;210;220;199;194;195;195 17:52:48 INFO - PID 2704 | |3;audio-fft;80;64;91;115;86;65;60;60;85;86 17:52:48 INFO - PID 2704 | |4;audio-oscillator;85;70;83;71;69;70;86;70;70;91 17:52:48 INFO - PID 2704 | |5;imaging-gaussian-blur;89;90;89;90;90;89;94;102;90;90 17:52:48 INFO - PID 2704 | |6;imaging-darkroom;100;96;97;97;97;97;96;97;112;155 17:52:48 INFO - PID 2704 | |7;imaging-desaturate;92;93;90;90;93;90;89;91;92;92 17:52:48 INFO - PID 2704 | |8;json-parse-financial;51;49;48;50;51;53;53;53;51;50 17:52:48 INFO - PID 2704 | |9;json-stringify-tinderbox;62;63;62;67;71;96;86;52;68;82 17:52:48 INFO - PID 2704 | |10;stanford-crypto-aes;83;78;83;82;83;82;86;78;80;100 17:52:48 INFO - PID 2704 | |11;stanford-crypto-ccm;137;155;139;128;164;150;170;162;262;176 17:52:48 INFO - PID 2704 | |12;stanford-crypto-pbkdf2;196;237;243;193;190;185;188;189;220;194 17:52:48 INFO - PID 2704 | |13;stanford-crypto-sha256-iterative;67;67;72;69;68;69;70;70;68;67 17:52:48 INFO - PID 2704 | __end_tp_report 17:52:48 INFO - PID 2704 | __start_cc_report 17:52:48 INFO - PID 2704 | _x_x_mozilla_cycle_collect,1018 17:52:48 INFO - PID 2704 | __end_cc_report 17:52:48 INFO - PID 2704 | __startTimestamp1493427168822__endTimestamp 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | ------- Summary: start ------- 17:52:48 INFO - PID 2704 | Number of tests: 14 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | [#0] ai-astar Cycles:10 Average:120.70 Median:116.50 stddev:17.99 (15.4%) stddev-sans-first:17.76 17:52:48 INFO - PID 2704 | Values: 102.0 116.0 117.0 112.0 115.0 125.0 169.0 121.0 116.0 114.0 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | [#1] audio-beat-detection Cycles:10 Average:123.20 Median:115.00 stddev:18.80 (16.3%) stddev-sans-first:17.26 17:52:48 INFO - PID 2704 | Values: 150.0 112.0 114.0 159.0 114.0 111.0 110.0 107.0 139.0 116.0 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | [#2] audio-dft Cycles:10 Average:202.10 Median:202.00 stddev:9.06 (4.5%) stddev-sans-first:8.99 17:52:48 INFO - PID 2704 | Values: 193.0 199.0 205.0 211.0 210.0 220.0 199.0 194.0 195.0 195.0 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | [#3] audio-fft Cycles:10 Average:79.20 Median:82.50 stddev:17.38 (21.1%) stddev-sans-first:18.43 17:52:48 INFO - PID 2704 | Values: 80.0 64.0 91.0 115.0 86.0 65.0 60.0 60.0 85.0 86.0 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:77.00 stddev:8.63 (11.2%) stddev-sans-first:8.59 17:52:48 INFO - PID 2704 | Values: 85.0 70.0 83.0 71.0 69.0 70.0 86.0 70.0 70.0 91.0 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:90.00 stddev:4.03 (4.5%) stddev-sans-first:4.19 17:52:48 INFO - PID 2704 | Values: 89.0 90.0 89.0 90.0 90.0 89.0 94.0 102.0 90.0 90.0 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | [#6] imaging-darkroom Cycles:10 Average:104.40 Median:97.00 stddev:18.42 (19.0%) stddev-sans-first:19.46 17:52:48 INFO - PID 2704 | Values: 100.0 96.0 97.0 97.0 97.0 97.0 96.0 97.0 112.0 155.0 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | [#7] imaging-desaturate Cycles:10 Average:91.20 Median:92.00 stddev:1.40 (1.5%) stddev-sans-first:1.45 17:52:48 INFO - PID 2704 | Values: 92.0 93.0 90.0 90.0 93.0 90.0 89.0 91.0 92.0 92.0 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:1.73 (3.4%) stddev-sans-first:1.83 17:52:48 INFO - PID 2704 | Values: 51.0 49.0 48.0 50.0 51.0 53.0 53.0 53.0 51.0 50.0 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:69.50 stddev:13.26 (19.1%) stddev-sans-first:13.67 17:52:48 INFO - PID 2704 | Values: 62.0 63.0 62.0 67.0 71.0 96.0 86.0 52.0 68.0 82.0 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:82.50 stddev:6.29 (7.6%) stddev-sans-first:6.67 17:52:48 INFO - PID 2704 | Values: 83.0 78.0 83.0 82.0 83.0 82.0 86.0 78.0 80.0 100.0 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | [#11] stanford-crypto-ccm Cycles:10 Average:164.30 Median:163.00 stddev:37.59 (23.1%) stddev-sans-first:38.55 17:52:48 INFO - PID 2704 | Values: 137.0 155.0 139.0 128.0 164.0 150.0 170.0 162.0 262.0 176.0 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:203.50 Median:195.00 stddev:21.57 (11.1%) stddev-sans-first:22.70 17:52:48 INFO - PID 2704 | Values: 196.0 237.0 243.0 193.0 190.0 185.0 188.0 189.0 220.0 194.0 17:52:48 INFO - PID 2704 | 17:52:48 INFO - PID 2704 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:1.64 (2.4%) stddev-sans-first:1.62 17:52:48 INFO - PID 2704 | Values: 67.0 67.0 72.0 69.0 68.0 69.0 70.0 70.0 68.0 67.0 17:52:48 INFO - PID 2704 | -------- Summary: end -------- 17:52:48 INFO - PID 2704 | 17:52:49 INFO - TEST-INFO | 2704: exit 0 17:52:49 INFO - TEST-OK | kraken | took 60895ms 17:52:49 INFO - SUITE-END | took 1176s 17:52:49 INFO - Completed test suite (00:19:36) 17:52:49 INFO - javascript benchmark 17:52:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7843.714119588105, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3062.249748811541, 3567.215212653217, 3622.655847694469, 3625.8187090645492, 3635.709143268539, 5259.579233661303, 6064.696765161735, 6110.816675499742, 6152.692365381729, 6210.161628180194, 6140.013486850318, 6199.163112979758, 6208.875822483547, 6214.943459611865, 6218.937810621893, 5402.8919421611745, 5417.45825417457, 5461.262729531502, 5480.068388373966, 5499.202615620753, 4254.553271906451, 5053.36832895888, 5243.767714587069, 5578.30271216098, 5588.720563971806, 5461.453854614547, 5477.7261136943, 5513.228148059275, 5544.722763861811, 5553.666779993173, 5482.643628164156, 5494.0, 5517.751701173457, 5522.641028333145, 5549.916751248735, 4217.97891010546, 5417.918731219035, 5439.755211015514, 5462.2079798429, 5501.0, 6068.423499767559, 6082.178905847698, 6180.752769889244, 6241.095041219043, 6245.687715614201, 6308.211473565804, 6603.735850565971, 6653.2681405045405, 6660.600363978177, 6671.766488172891, 6761.830954226184, 6783.152105986752, 6792.76225332116, 6798.694058767367, 6807.0, 6255.499560035186, 6332.968335158294, 6342.112104305401, 6344.619322840645, 6368.904466433008, 6685.899711504234, 6789.083473730983, 6832.0776695146515, 6842.897356539655, 6917.0, 6139.154842350747, 6347.650879201688, 6430.099786029959, 6496.675166241669, 6635.369639884203, 6719.294474080249, 6742.696578653972, 6790.354916282945, 6791.049253104569, 6800.0, 5562.3881373048935, 6662.967240077795, 6680.83297917556, 6693.598384096969, 6703.296153903867, 5342.198670199501, 6454.644994525346, 6534.379233972765, 6585.769498067544, 6651.401373876374, 5302.1781623848365, 5317.30874986257, 5317.8936421271355, 5322.760475778599, 5364.704941228192, 4666.743329116931, 4685.250359942393, 4701.835435759732, 4717.080169367009, 4729.9054018920115, 5196.558292545101, 6081.391860813883, 6276.592021518587, 6287.842803929847, 6392.0731493934045, 6739.0902228198565, 6778.22050464193, 6794.660266986631, 6801.1158549388265, 6825.215100263434, 6321.336259692758, 6345.397187267202, 6363.077353783768, 6389.9361006389345, 6406.231252249667, 5120.334356533627, 5696.601237913306, 5804.564657650693, 5891.941080589225, 5917.585768996215, 6801.35387138231, 6803.4557235422035, 6888.138982627172, 6893.414059804973, 6911.688973996082, 6799.422049125879, 6809.965950170217, 6819.556728812611, 6840.589564626139, 6849.0, 5352.919706204332, 5963.403659634002, 6822.283660215804, 6905.895056791091, 7031.296870313133, 6426.196725409324, 6537.803865884031, 6573.408393244631, 6624.337566243337, 6712.160979877515, 4875.000624871964, 6539.673016349068, 6736.02327662496, 6755.966220168868, 6809.489288303496, 5024.723640199824, 6345.650989195638, 6413.134226879311, 6430.196225471816, 6434.324395938359, 6480.513961453004, 6575.375339342755, 6579.078928950046, 6583.604983700801, 6583.835404114935, 6222.377762223922, 6295.150154729053, 6394.1687580614225, 6440.066190402459, 6469.385408386196, 6421.8073457796345, 6473.902891456726, 6502.284748677547, 6542.1168142300185, 6543.574667646588, 6373.681315934092, 6420.903686444801, 6422.486201103807, 6445.6454894981225, 6447.161868957193], "value": 6041.505047639728, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.5937734246163, 802.6709049289791, 826.7189155687067, 998.2762497189534, 1189.7798907202173, 30250.0, 36804.0, 37989.62010379895, 38025.619743802556, 38123.04692382692, 38199.427008594896, 38522.807385963104, 38591.61408385915, 38733.41899871504, 38962.415563766495, 7869.921300787005, 8048.154943730911, 8231.670733170666, 8465.619047142862, 8484.321254299672, 7440.851182976337, 8465.576721163949, 8529.701460448885, 8592.613332400057, 8604.52675102869, 8455.830883382328, 8586.699465518708, 8619.612117454699, 8651.826963460728, 8658.177473140042, 8509.276711479532, 8548.230659240668, 8600.311975042014, 8604.440711353805, 8604.655813767442, 8322.16778322163, 8327.37544684145, 8354.958225208835, 8389.412741108124, 8404.957975210145, 36832.0, 37042.259154817024, 37054.814725926466, 37089.07277318062, 37103.4434483483, 3007.8646460909313, 3595.982020089883, 3624.1120925373452, 3624.3838547447, 3672.3573374659595, 30475.085747427613, 30484.847575761978, 30534.0, 30593.0, 30597.0, 3122.406742718899, 3160.7787454878166, 3164.335489547198, 3172.762042846796, 3184.792988455766, 248771.0, 250968.49031509634, 281655.0, 303829.0, 306168.0, 46341.30488042682, 46648.53351466442, 46672.599822005395, 46704.299435508496, 46838.76580617075, 48766.0, 48916.51083489191, 49499.50500494949, 49503.75248123737, 49569.0, 13645.38595763193, 13752.243626600433, 13757.38091785872, 13762.036657433884, 13808.516701915385, 11230.382328971986, 11270.985611295022, 11271.661850144508, 11286.943565282121, 11290.604828830952, 13065.216087034809, 13240.73518529624, 13273.867261327456, 13376.197428154343, 13426.120343151966, 5000.649954503223, 5336.439673834191, 5722.37053924068, 5977.342492325841, 6035.758569657164, 985.261054209343, 1015.5836107196014, 1038.9168866490663, 1062.6546372428836, 1069.7806949575318, 22933.42666433319, 23932.04271829107, 24075.277741667778, 24288.45339774418, 24493.265202043967, 937.9155875971195, 1130.3952385473733, 1132.247055707945, 1155.3183621663259, 1174.6006357838376, 9274.93338266108, 10814.783704326026, 11165.330080195214, 11771.234869733611, 12380.380980950917, 321067.0, 321679.0, 322948.0, 323487.0, 323992.0, 53462.0, 53767.46232537625, 53775.73112134414, 53881.0, 53903.46096539063, 1495.4317359403574, 1505.7878407881542, 1545.737224671786, 1548.9845101549065, 1557.688462307543, 18057.0, 19897.502562436053, 22840.428989275402, 23405.297841064796, 23961.520769584164, 36938.44592331063, 37067.8146609276, 37081.81459092687, 37159.0, 37216.255674886896, 287135.0, 287156.0, 288675.0, 289896.0, 290300.0, 31753.36493270168, 32543.837280813445, 32802.50796238011, 33234.16914577155, 33352.83323583366, 33271.16822079467, 35285.47071793977, 43554.78222608867, 44100.0, 44459.33311000402, 22634.981425836206, 28818.711812881604, 29826.85086574553, 30734.692653073183, 30783.2304192397, 24313.513729724953, 26300.21099367022, 27404.492752898783, 30593.23516912006, 30634.84682576573, 35912.64087359093, 36718.44922326221, 36722.0, 36882.0, 36980.07549811276, 28984.565231521123, 29194.270143246587, 30017.649205785354, 30083.0, 31116.755329786556, 22070.006849692123, 23832.88083559571, 23837.284881453583, 24196.153134640204, 24303.027878884648], "value": 17114.637060610243, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.606663003846, 420.2204909892148, 421.1408726198555, 428.75132423194543, 444.30688126522625, 19371.0, 20954.0, 21439.356819295426, 21686.457838554004, 21789.564208715856, 22442.88778556109, 22531.0, 23164.8841755791, 23164.957576909037, 23512.764872351272, 6058.939410605893, 6303.968480157593, 6314.968425157867, 6332.556721029529, 6341.270753863296, 6078.452939235468, 6084.634921904678, 6189.969050154766, 6244.219472565929, 6275.341089185638, 6149.385061493837, 6176.135341052234, 6214.720337584797, 6215.658138802364, 6216.316205217422, 5973.253343332083, 6244.812655620293, 6246.312905580383, 6250.3124656288, 6253.53098517613, 5349.732513374316, 5546.084895992157, 5567.331920169565, 5586.24585680932, 5601.719914004324, 26619.0, 26651.333716657045, 26735.930562777467, 27125.0, 27430.0, 2264.1735766445295, 2334.789868911789, 2335.7781010803947, 2337.649352597107, 2345.1088586337255, 17998.550036249067, 18096.276148954024, 18355.990420527, 18564.886106833634, 18850.81149188518, 2143.421276255418, 2169.3275084723787, 2207.2452400341767, 2209.0611490116635, 2214.047959377467, 116832.24751628647, 117357.0, 160696.19651901783, 169068.15465922715, 177449.1127544367, 12285.815712764315, 12710.792474714888, 12771.233725976472, 12867.806982895265, 12992.610221693365, 16177.757333640006, 16297.511074667778, 16492.83507164937, 16547.586310342336, 16619.41832035897, 8880.333974951904, 9017.188453039258, 9092.136247056518, 9163.862542061874, 9184.63261469547, 8591.09793471689, 8601.698940537051, 8609.182127697859, 8767.210951014311, 8770.517621530877, 7905.920940790634, 8008.1991800820615, 8070.354371650254, 8079.798005049921, 8086.272235498715, 1894.2612381171355, 2232.8883555822144, 2235.61994460942, 2238.40682219212, 2391.5336509380504, 941.924646028316, 945.8413443531756, 947.6162154327509, 952.1668540027476, 989.3964681544252, 20558.38324850227, 21200.78799212019, 21253.68119478209, 21348.0, 21467.785322146578, 980.774421882971, 987.9308448408686, 996.6661168508485, 1022.7085280694967, 1024.6772266735954, 10404.0, 10660.573577056986, 10668.30656007357, 10741.516631751434, 10774.892251077546, 548669.0, 556470.0, 556684.0, 557102.0, 560324.0, 116280.25579616312, 118575.40712296384, 126938.0, 130226.0, 137764.0, 1186.3830807979803, 1252.7932891072871, 1264.9747005059846, 1268.131330038908, 1268.5686866465446, 21726.23958161457, 21871.67192492146, 21931.27329517973, 21967.890160549094, 22087.668684969416, 55898.0, 56125.438745612024, 57480.275591732316, 58032.70983645055, 58382.83234335205, 74811.51130931944, 76194.0, 76677.0, 77158.0, 77244.61377693075, 7956.323712484504, 8609.612567434606, 8850.33622478304, 8943.508107054175, 9159.77100572491, 13702.52041178554, 14243.430262789787, 14281.0, 14712.779308310597, 14774.409023639364, 11097.667069987914, 11769.88186122317, 11850.585229516926, 11972.64082077539, 12034.699132521757, 9672.274579406432, 10588.0, 10642.361458312525, 11437.313761174357, 11741.29552226835, 10241.539130739284, 12113.757724845278, 12160.331181784733, 12449.502019919464, 12569.93715031419, 8799.91200087991, 8815.691450799191, 8825.161609647073, 9185.44887306737, 9277.814443711222, 9546.331756776959, 12169.574064907685, 12275.570355037531, 12278.754424911273, 12385.690357741129], "value": 10533.05134397317, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1270.9046821488387, 1384.5984664447308, 1401.5094716849103, 1425.4084554909714, 1433.634423222078, 11404.258723182998, 11666.0, 11725.413729313543, 11885.940570297158, 12052.51789928404, 9543.809123817537, 11764.882351176486, 11772.47023883925, 11786.233894796833, 11820.822687659693, 2472.0606169655507, 2500.462400583874, 2510.8242423030397, 2513.396784771651, 2518.181590982929, 2461.6430617560445, 2488.2908371114236, 2506.5989441689335, 2510.899564017437, 2513.5098655762135, 2459.950800983979, 2478.925632231036, 2501.9749802501924, 2525.949481010379, 2528.115159694111, 2452.411421258894, 2459.02868366994, 2499.0628514307136, 2499.6750422445148, 2500.6749122614124, 2388.6058800298106, 2465.29739024379, 2465.716442609105, 2488.079410618083, 2495.301315631626, 9555.331126821126, 11696.883031169664, 11729.0, 11735.0, 11752.294862308288, 6393.2328120625825, 6599.868002639968, 6609.206895172562, 6611.900821487681, 6615.437687796495, 3961.722679412442, 4297.419848320499, 4300.569943005706, 4374.628156606692, 4382.737035777832, 1113.4488428227999, 1259.937003149848, 1270.2315099364937, 1276.3235485192863, 1305.673581604599, 16086.517404477885, 16299.266533006043, 16440.424585139583, 16455.83544164555, 16697.91651041737, 5513.228148059275, 5538.47384498472, 5664.716764161775, 5684.8010319638615, 5722.77108915647, 9791.951040244754, 11810.055195584333, 11890.40547972598, 11904.988076013462, 11904.988076013462, 6557.409833115043, 6591.307912669196, 6632.0383544385795, 6645.06969024337, 6646.534742567974, 5199.246109314128, 6045.637261764308, 6296.590721603081, 6297.622142671455, 6325.082862984934, 3587.94618080729, 4291.785410729513, 4303.655707543389, 4374.540673229329, 4403.4495688039, 1087.6465148826505, 1267.0054007604172, 1274.4710944957924, 1286.9549565765153, 1288.433089440643, 733.3436574266002, 757.7650928212272, 787.8266781308103, 789.7433334166304, 800.6477150053958, 6415.839604009937, 6605.976073708583, 6673.589200846598, 6930.7227710891, 7022.332878376546, 793.3653077537947, 880.251785981923, 919.8758167647281, 931.7297983584684, 937.5968333616474, 3616.1321282891918, 3620.5474315710535, 3670.284294562535, 4256.808443620044, 4755.667103302805, 14945.775813362809, 15028.173450460112, 15297.0, 15315.540533783782, 15325.770113448307, 14514.0, 14843.480478183212, 15038.774418383733, 15168.620784480476, 15208.695826083416, 8150.673973041012, 8238.752837414768, 8660.956695216484, 8691.261242794433, 8771.824563508693, 5566.721663916869, 6555.049517819984, 6737.29258427858, 7028.789136325919, 7043.471739619446, 12954.80567791465, 13303.26832024248, 13501.0, 13674.31628418595, 13725.0, 13586.524471643444, 13701.0, 13796.448142074607, 13994.0, 14209.786853197416, 9236.399634023766, 9259.444433334022, 9347.112024357675, 9442.008589098046, 9550.092741189575, 10858.077063449695, 10875.673729788119, 10991.175661825555, 10993.780124397626, 11015.228933974866, 9011.188992990663, 9173.266138709021, 9313.767155820888, 9341.486218258062, 9354.95322523383, 9036.728898133066, 9061.320400970086, 9102.453852768855, 9196.35625506208, 9275.304352173749, 6074.696265186812, 8040.195980401913, 8063.233992770677, 8224.09534951143, 8255.174482551696, 6006.129111278928, 6014.6391216527145, 6051.122587224916, 6137.294211165773, 6217.8807814592, 5124.718140502308, 5202.8959420812125, 6022.1870047543025, 6247.281562620356, 6264.624122552661], "value": 5341.322746340932, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.85679441812107, 395.73956947123406, 403.70126106681056, 406.5243664912053, 412.2826282268853, 5461.972690136554, 5615.887682246358, 5623.0, 6015.0, 6265.216847894013, 5501.944980550194, 5648.322201335835, 5975.551833612475, 6097.2073630427985, 6163.784267550636, 4091.1408604193157, 4381.802818873159, 4639.814407423716, 4654.930176047362, 4690.90618187636, 4634.814607415717, 4693.436787585494, 4727.479977202505, 4733.479317275097, 4737.336772851803, 4636.582707556337, 4640.930386044212, 4655.0, 4656.1153380857695, 4687.554682305175, 4380.12397520494, 4664.32367306742, 4664.530672838046, 4667.906641867177, 4680.25115981443, 4086.0, 4163.91672166555, 4166.208420400114, 4176.812043458051, 4180.895477613054, 5444.210589464545, 5737.328110009597, 5860.501857342131, 5914.852128696817, 5987.0, 4550.180967425862, 5528.806491772809, 5536.916946245809, 5538.975289571402, 5546.502444340046, 5437.803683189692, 6228.5017198624455, 6459.418652321314, 6532.673366331712, 6560.737570497175, 3531.6291789362003, 4285.892852678677, 4290.227759003378, 4303.91392172158, 4305.74165550068, 6218.347073557302, 6630.403263706241, 6898.793036208922, 7009.08881845362, 7009.2990700929495, 3664.4686520454384, 4309.8363441870515, 4319.164355149049, 4332.1985432695055, 4342.435483387139, 4041.6968727345566, 4416.492103408085, 4418.911621767611, 4425.734455932655, 4435.179491794029, 5089.923651145235, 5138.07514647367, 5214.504622060898, 5234.607404444682, 5251.448597897166, 5914.526837852962, 6459.838504037437, 6573.276939536647, 6603.0, 6610.338966103352, 1493.805805245333, 1499.8275198352328, 1500.8574185452364, 1502.5792778022171, 1503.1056521369767, 924.8150369926042, 973.1582181413027, 976.7606936300651, 986.4032260482305, 988.9258305627106, 5820.70896455184, 5841.649501029951, 6306.0, 6359.491240700733, 6364.849399547254, 6706.295838936939, 6848.0, 6870.20992585859, 6881.449484041264, 6889.552179108342, 5034.521720436553, 6220.0, 6269.874602508015, 6280.748770049238, 6319.905201421982, 3165.525171224289, 3519.3665140274484, 3541.8937431877084, 3574.803385813805, 3576.445650924111, 6840.110785597841, 7013.860772464345, 7025.803259516908, 7075.575465471985, 7083.327083927019, 6955.0178199214, 7010.158780946217, 7027.156741190987, 7036.150359104571, 7077.3984211341585, 6403.743850246031, 6806.217285012287, 7010.684519196547, 7023.157221133497, 7054.329838665319, 6314.526410519321, 6798.320167983162, 6859.897101543381, 6861.519693621399, 6884.586924784529, 6410.0, 6559.508036897157, 6704.161979752531, 6734.730610775514, 6748.93251067483, 7026.521871673554, 7036.964815175891, 7056.364927156581, 7067.717291308496, 7071.82320441993, 4124.803806895967, 4199.895002624959, 4303.763293018914, 4356.869293921188, 4381.342798580238, 6123.938760612336, 6837.692303846239, 6849.1780986281965, 6893.75871844483, 6918.8616227676175, 5043.193089105725, 5144.7942082316285, 6318.778842740482, 6419.679016049272, 6585.703643335965, 6280.497560195265, 6621.9337806621315, 6627.801165965029, 6628.867422651423, 6657.40083392497, 5331.920021199608, 5933.851653708692, 6214.254289485109, 6286.868363694579, 6468.320826313357, 6585.308542603149, 6587.769428069994, 6609.405153536205, 6642.568233064835, 6649.335066493312, 4839.41926968766, 5534.91697624544, 6255.906161407492, 6439.452646525097, 6488.772892948913], "value": 4786.514865943788, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.8481430935282, 836.5273620404471, 850.1795767084764, 860.6573744957866, 870.7431307764209, 16934.322627094916, 17434.476965691018, 17480.650386992267, 19190.42428727137, 19639.70540441891, 17960.461186164404, 18379.356722514716, 18402.81597184028, 18466.353677621286, 18474.815251847478, 982.2583949118424, 992.4640694025218, 1001.5242759689125, 1004.4023805835516, 1005.2510879394861, 845.5603086395101, 960.490939801906, 1011.6813203840803, 1013.6654903881738, 1014.3102690170681, 1058.095328494134, 1067.8505009298703, 1070.5985255529176, 1071.6570697376842, 1075.2688172043008, 879.846026945282, 1051.4374809476892, 1052.6052730226165, 1066.9573217071309, 1068.9144868410585, 758.6244808819614, 771.3058247577169, 774.2953911940108, 776.5263189454429, 781.0978320040363, 16996.490105296736, 17305.961642301503, 17483.912580437143, 17497.0, 17544.824551754446, 14869.776953345709, 17258.741118883227, 17519.5984321254, 17760.733588996176, 17812.46562603124, 12338.5064597416, 13855.0, 14041.087329323664, 14452.205128717917, 14460.49388271416, 3446.551948246737, 3506.6493350664987, 3531.1348719563616, 3536.3811333016874, 3537.5224344713392, 86687.0, 115090.0, 131097.0, 133129.3343533276, 133178.0, 12889.806652900214, 13032.348382580833, 13104.0, 13143.277119758504, 13166.868331316564, 25110.995560177755, 25179.24462266135, 25273.0, 25484.0, 25521.48957020849, 7644.388448924073, 7739.729109481141, 7784.3772498200005, 7851.960740196262, 7864.331531819859, 3946.723729338976, 4007.719459637797, 4039.979800100981, 4043.5552089270745, 4051.0277533391773, 4333.891652708708, 4374.78126093694, 4442.666799990014, 4450.532694067141, 4455.975125721037, 870.6299822575506, 894.8120894612074, 898.7977705016319, 900.1853322742928, 907.0249481807084, 630.130420020369, 667.2626747444004, 674.430106559956, 675.8040168351222, 690.5649440852231, 11214.495347709373, 11818.054555635528, 11847.88152118485, 12056.63830085099, 12188.329641869783, 646.9644169570624, 649.7043845050586, 655.8425977765397, 667.502710480695, 678.4470656415006, 4927.531884470969, 5365.409804921455, 5415.512603865671, 5449.100898351807, 5595.160725891149, 110344.89655103347, 110675.0, 112155.0, 112542.0, 114821.0, 91788.5410572943, 97360.0, 99678.0, 100501.49749251353, 100507.0, 58021.709891451115, 59963.0, 76684.84972725414, 77767.61116194383, 80824.59587702142, 10036.447995360033, 10634.255602107778, 11681.0, 12173.087018473472, 12701.491940322283, 27918.581221282104, 36475.71718545344, 42213.15573688447, 43369.78315108404, 43682.563174367846, 38348.61651383562, 54016.0, 54204.0, 54841.0, 55510.44489555053, 16078.678426431172, 16315.755263671292, 16316.592085197964, 16341.264643091326, 16405.671886561962, 17525.47423577244, 17547.1226438675, 18088.27646894162, 18238.996855173093, 18320.541986450444, 16345.182740863147, 16438.260278287267, 16554.172291385625, 16627.83372166311, 16782.32870685159, 13378.665533361744, 16149.354025838835, 16404.0, 16531.0, 16658.50024499267, 15645.0, 15674.13792241392, 15789.921050394674, 15978.520644380687, 16045.0, 11491.195616306779, 11534.365609891536, 11939.136941880768, 11965.521379145084, 12123.333216672814, 13111.73776524445, 14106.365213565208, 14145.0, 14175.362108704927, 14201.218932958787], "value": 8363.056356186866, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 116.0, 117.0, 112.0, 115.0, 125.0, 169.0, 121.0, 116.0, 114.0], "value": 120.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 112.0, 114.0, 159.0, 114.0, 111.0, 110.0, 107.0, 139.0, 116.0], "value": 123.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 199.0, 205.0, 211.0, 210.0, 220.0, 199.0, 194.0, 195.0, 195.0], "value": 202.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 64.0, 91.0, 115.0, 86.0, 65.0, 60.0, 60.0, 85.0, 86.0], "value": 79.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 83.0, 71.0, 69.0, 70.0, 86.0, 70.0, 70.0, 91.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 89.0, 90.0, 90.0, 89.0, 94.0, 102.0, 90.0, 90.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 96.0, 97.0, 97.0, 97.0, 97.0, 96.0, 97.0, 112.0, 155.0], "value": 104.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 90.0, 90.0, 93.0, 90.0, 89.0, 91.0, 92.0, 92.0], "value": 91.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 48.0, 50.0, 51.0, 53.0, 53.0, 53.0, 51.0, 50.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 67.0, 71.0, 96.0, 86.0, 52.0, 68.0, 82.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 78.0, 83.0, 82.0, 83.0, 82.0, 86.0, 78.0, 80.0, 100.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 155.0, 139.0, 128.0, 164.0, 150.0, 170.0, 162.0, 262.0, 176.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 237.0, 243.0, 193.0, 190.0, 185.0, 188.0, 189.0, 220.0, 194.0], "value": 203.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 72.0, 69.0, 68.0, 69.0, 70.0, 70.0, 68.0, 67.0], "value": 68.7, "unit": "score"}]}]} 17:52:50 INFO - Return code: 0 17:52:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:52:50 ERROR - # TBPL SUCCESS # 17:52:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:52:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:52:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:52:50 INFO - 2017-04-28 17:52:50.173 screenresolution[2715:33571] starting screenresolution argv=screenresolution get 17:52:50 INFO - 2017-04-28 17:52:50.176 screenresolution[2715:33571] Display 0: 1600x1200x32@60 17:52:50 INFO - 2017-04-28 17:52:50.183 screenresolution[2716:33574] starting screenresolution argv=screenresolution list 17:52:50 INFO - Available Modes on Display 0 17:52:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:52:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:52:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:52:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:52:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:52:50 INFO - Intel Iris: 17:52:50 INFO - Chipset Model: Intel Iris 17:52:50 INFO - Type: GPU 17:52:50 INFO - Bus: Built-In 17:52:50 INFO - VRAM (Dynamic, Max): 1536 MB 17:52:50 INFO - Vendor: Intel (0x8086) 17:52:50 INFO - Device ID: 0x0a2e 17:52:50 INFO - Revision ID: 0x0009 17:52:50 INFO - Displays: 17:52:50 INFO - Display: 17:52:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:52:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:52:50 INFO - Display Serial Number: 1600x1200 60 17:52:50 INFO - Main Display: Yes 17:52:50 INFO - Mirror: Off 17:52:50 INFO - Online: Yes 17:52:50 INFO - Rotation: Supported 17:52:50 INFO - Return code: 0 17:52:50 INFO - Running post-action listener: _resource_record_post_action 17:52:50 INFO - [mozharness: 2017-04-29 00:52:50.427305Z] Finished run-tests step (success) 17:52:50 INFO - Running post-run listener: _resource_record_post_run 17:52:50 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 1433600; Write bytes: 517680640; Read time: 10; Write time: 1671 17:52:50 INFO - TinderboxPrint: CPU usage
53.9% 17:52:50 INFO - TinderboxPrint: I/O read bytes / time
1,433,600 / 10 17:52:50 INFO - TinderboxPrint: I/O write bytes / time
517,680,640 / 1,671 17:52:50 INFO - TinderboxPrint: CPU idle
2,208.3 (46.1%) 17:52:50 INFO - TinderboxPrint: CPU system
961.1 (20.1%) 17:52:50 INFO - TinderboxPrint: CPU user
1,620.1 (33.8%) 17:52:50 INFO - TinderboxPrint: Swap in / out
209,731,584 / 0 17:52:50 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209779200; Write bytes: 209735680; Read time: 16063; Write time: 474 17:52:50 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 495616; Write bytes: 306355712; Read time: 5; Write time: 1190 17:52:51 INFO - Running post-run listener: _upload_blobber_files 17:52:51 INFO - Blob upload gear active. 17:52:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:52:51 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:52:51 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:52:51 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:52:51 INFO - (blobuploader) - INFO - Open directory for files ... 17:52:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:52:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:52:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:52:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:52:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:52:52 INFO - (blobuploader) - INFO - Done attempting. 17:52:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:52:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:52:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:52:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:52:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:52:53 INFO - (blobuploader) - INFO - Done attempting. 17:52:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:52:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:52:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:52:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:52:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:52:54 INFO - (blobuploader) - INFO - Done attempting. 17:52:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:52:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:52:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:52:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:52:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:52:56 INFO - (blobuploader) - INFO - Done attempting. 17:52:56 INFO - (blobuploader) - INFO - Iteration through files over. 17:52:56 INFO - Return code: 0 17:52:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:52:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:52:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b322c5b40a38570806f690f0eb578d8429ed64b2cce070b4d80eb724c250bbb4b0c03beed86bbf2e6c01f0f1cc0131b8b871a94b5a42f4ddd49f9ab99e031639", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c34bf11e2db54a5391ddfaeab3987a4937a6155ae470f091ec03828a31e8dfaf9b74d918adbe2935338dfa14c46b64e6fbb61599707b25e35fe1f172c41851d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70f995396d0f5e77c95531eeb68153df7e39ad443e1e9a1a49c6a599d03581833b2ec700788cda268d49ad7af03032276539bbd524665b2009a9195d7930509b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd75cea74251a580fe50310fa2d3d6a9ecf122c2e67cde6d0b6f5e57f70098b209dfbf46d32b8c5d403641b0140ba0dc74b2597f67d1b4d6af724091ada731e1"} 17:52:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:52:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:52:56 INFO - Contents: 17:52:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b322c5b40a38570806f690f0eb578d8429ed64b2cce070b4d80eb724c250bbb4b0c03beed86bbf2e6c01f0f1cc0131b8b871a94b5a42f4ddd49f9ab99e031639", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c34bf11e2db54a5391ddfaeab3987a4937a6155ae470f091ec03828a31e8dfaf9b74d918adbe2935338dfa14c46b64e6fbb61599707b25e35fe1f172c41851d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70f995396d0f5e77c95531eeb68153df7e39ad443e1e9a1a49c6a599d03581833b2ec700788cda268d49ad7af03032276539bbd524665b2009a9195d7930509b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd75cea74251a580fe50310fa2d3d6a9ecf122c2e67cde6d0b6f5e57f70098b209dfbf46d32b8c5d403641b0140ba0dc74b2597f67d1b4d6af724091ada731e1"} 17:52:56 INFO - Running post-run listener: copy_logs_to_upload_dir 17:52:56 INFO - Copying logs to upload dir... 17:52:56 INFO - mkdir: /builds/slave/test/build/upload/logs 17:52:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.616136 ========= master_lag: 3.22 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-04-28 17:52:59.540179) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 17:52:59.543266) ========= 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.HLTizRFm0L/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.h3JmSgm23t/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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/b322c5b40a38570806f690f0eb578d8429ed64b2cce070b4d80eb724c250bbb4b0c03beed86bbf2e6c01f0f1cc0131b8b871a94b5a42f4ddd49f9ab99e031639", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c34bf11e2db54a5391ddfaeab3987a4937a6155ae470f091ec03828a31e8dfaf9b74d918adbe2935338dfa14c46b64e6fbb61599707b25e35fe1f172c41851d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70f995396d0f5e77c95531eeb68153df7e39ad443e1e9a1a49c6a599d03581833b2ec700788cda268d49ad7af03032276539bbd524665b2009a9195d7930509b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd75cea74251a580fe50310fa2d3d6a9ecf122c2e67cde6d0b6f5e57f70098b209dfbf46d32b8c5d403641b0140ba0dc74b2597f67d1b4d6af724091ada731e1"} 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.010616 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/b322c5b40a38570806f690f0eb578d8429ed64b2cce070b4d80eb724c250bbb4b0c03beed86bbf2e6c01f0f1cc0131b8b871a94b5a42f4ddd49f9ab99e031639", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c34bf11e2db54a5391ddfaeab3987a4937a6155ae470f091ec03828a31e8dfaf9b74d918adbe2935338dfa14c46b64e6fbb61599707b25e35fe1f172c41851d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70f995396d0f5e77c95531eeb68153df7e39ad443e1e9a1a49c6a599d03581833b2ec700788cda268d49ad7af03032276539bbd524665b2009a9195d7930509b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd75cea74251a580fe50310fa2d3d6a9ecf122c2e67cde6d0b6f5e57f70098b209dfbf46d32b8c5d403641b0140ba0dc74b2597f67d1b4d6af724091ada731e1"}' ========= master_lag: 0.21 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 17:52:59.768041) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:52:59.768378) ========= 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.HLTizRFm0L/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.h3JmSgm23t/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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.005326 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:52:59.796935) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 17:52:59.797251) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 17:52:59.797574) ========= ========= Total master_lag: 3.53 =========