builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0038 starttime: 1489163302.75 results: success (0) buildid: 20170310065428 builduid: f446337c98714a41a516b506f70d363a revision: 528e9dbbb882 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-10 08:28:22.747454) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-10 08:28:22.747978) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-10 08:28:22.748267) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-10 08:28:22.775029) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 08:28:22.775420) ========= 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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners TMPDIR=/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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-03-10 08:28:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.8M=0.001s 2017-03-10 08:28:22 (13.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.116864 ========= master_lag: 0.67 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 08:28:23.563523) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 08:28:23.563939) ========= 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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners TMPDIR=/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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.027657 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 08:28:23.613952) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-10 08:28:23.614307) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 528e9dbbb882 --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 528e9dbbb882 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.F4gpdWtWfm/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.eqhMlIUWgS/Listeners TMPDIR=/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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-03-10 08:28:23,726 Setting DEBUG logging. 2017-03-10 08:28:23,726 attempt 1/10 2017-03-10 08:28:23,726 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/528e9dbbb882?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-10 08:28:25,186 unpacking tar archive at: mozilla-central-528e9dbbb882/testing/mozharness/ program finished with exit code 0 elapsedTime=1.753227 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-10 08:28:25.391686) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-10 08:28:25.392019) ========= script_repo_revision: 528e9dbbb882 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-10 08:28:25.392664) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-10 08:28:25.392955) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-10 08:28:25.415230) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-10 08:28:25.415549) ========= /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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners TMPDIR=/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:28:25 INFO - MultiFileLogger online at 20170310 08:28:25 in /builds/slave/test 08:28:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:28:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:28:25 INFO - {'append_to_log': False, 08:28:25 INFO - 'base_work_dir': '/builds/slave/test', 08:28:25 INFO - 'blob_upload_branch': 'Firefox', 08:28:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:28:25 INFO - 'branch': 'Firefox', 08:28:25 INFO - 'buildbot_json_path': 'buildprops.json', 08:28:25 INFO - 'config_files': ('talos/mac_config.py',), 08:28:25 INFO - 'default_actions': ('clobber', 08:28:25 INFO - 'read-buildbot-config', 08:28:25 INFO - 'download-and-extract', 08:28:25 INFO - 'populate-webroot', 08:28:25 INFO - 'create-virtualenv', 08:28:25 INFO - 'install', 08:28:25 INFO - 'run-tests'), 08:28:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:28:25 INFO - 'download_minidump_stackwalk': True, 08:28:25 INFO - 'download_symbols': 'ondemand', 08:28:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:28:25 INFO - 'tooltool.py': '/tools/tooltool.py', 08:28:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:28:25 INFO - '/tools/misc-python/virtualenv.py')}, 08:28:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:28:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:28:25 INFO - 'gecko_profile': False, 08:28:25 INFO - 'gecko_profile_interval': 0, 08:28:25 INFO - 'installer_path': 'installer.exe', 08:28:25 INFO - 'log_level': 'info', 08:28:25 INFO - 'log_name': 'talos', 08:28:25 INFO - 'log_to_console': True, 08:28:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:28:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:28:25 INFO - 'opt_config_files': (), 08:28:25 INFO - 'pip_index': False, 08:28:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:28:25 INFO - 'cmd': ('bash', 08:28:25 INFO - '-c', 08:28:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:28:25 INFO - 'enabled': True, 08:28:25 INFO - 'halt_on_failure': False, 08:28:25 INFO - 'name': 'check_screen_resolution'},), 08:28:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:28:25 INFO - 'cmd': ('bash', 08:28:25 INFO - '-c', 08:28:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:28:25 INFO - 'enabled': True, 08:28:25 INFO - 'halt_on_failure': False, 08:28:25 INFO - 'name': 'check_screen_resolution'},), 08:28:25 INFO - 'run_cmd_checks_enabled': True, 08:28:25 INFO - 'suite': 'dromaeojs-e10s', 08:28:25 INFO - 'system_bits': '32', 08:28:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:28:25 INFO - 'title': 't-yosemite-r7-0038', 08:28:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:28:25 INFO - 'use_talos_json': True, 08:28:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:28:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:28:25 INFO - 'work_dir': 'build'} 08:28:25 INFO - [mozharness: 2017-03-10 16:28:25.633268Z] Running clobber step. 08:28:25 INFO - Running pre-action listener: _resource_record_pre_action 08:28:25 INFO - Running main action method: clobber 08:28:25 INFO - rmtree: /builds/slave/test/build 08:28:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:28:29 INFO - Running post-action listener: _resource_record_post_action 08:28:29 INFO - [mozharness: 2017-03-10 16:28:29.737242Z] Finished clobber step (success) 08:28:29 INFO - [mozharness: 2017-03-10 16:28:29.737421Z] Running read-buildbot-config step. 08:28:29 INFO - Running pre-action listener: _resource_record_pre_action 08:28:29 INFO - Running main action method: read_buildbot_config 08:28:29 INFO - Using buildbot properties: 08:28:29 INFO - { 08:28:29 INFO - "project": "", 08:28:29 INFO - "product": "firefox", 08:28:29 INFO - "script_repo_revision": "production", 08:28:29 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:28:29 INFO - "repository": "", 08:28:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:28:29 INFO - "buildid": "20170310065428", 08:28:29 INFO - "stage_platform": "macosx64", 08:28:29 INFO - "basedir": "/builds/slave/test", 08:28:29 INFO - "nightly_build": "True", 08:28:29 INFO - "buildnumber": 95, 08:28:29 INFO - "slavename": "t-yosemite-r7-0038", 08:28:29 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:28:29 INFO - "platform": "yosemite_r7", 08:28:29 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:28:29 INFO - "branch": "mozilla-central", 08:28:29 INFO - "repo_path": "mozilla-central", 08:28:29 INFO - "revision": "528e9dbbb882", 08:28:29 INFO - "pgo_build": "False", 08:28:29 INFO - "builduid": "f446337c98714a41a516b506f70d363a", 08:28:29 INFO - "slavebuilddir": "test" 08:28:29 INFO - } 08:28:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 08:28:29 INFO - Running post-action listener: _resource_record_post_action 08:28:29 INFO - [mozharness: 2017-03-10 16:28:29.740804Z] Finished read-buildbot-config step (success) 08:28:29 INFO - [mozharness: 2017-03-10 16:28:29.740926Z] Running download-and-extract step. 08:28:29 INFO - Running pre-action listener: _resource_record_pre_action 08:28:29 INFO - Running main action method: download_and_extract 08:28:29 INFO - mkdir: /builds/slave/test/build/tests 08:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:29 INFO - trying https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:28:29 INFO - Downloading https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:28:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/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 08:28:30 INFO - Downloaded 1199 bytes. 08:28:30 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:28:30 INFO - Using the following test package requirements: 08:28:30 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:28:30 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:28:30 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:28:30 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:28:30 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:28:30 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:28:30 INFO - u'jsshell-mac.zip'], 08:28:30 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:28:30 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:28:30 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:28:30 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:28:30 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:28:30 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:28:30 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:28:30 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:28:30 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 08:28:30 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:28:30 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:28:30 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:28:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:28:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:28:30 INFO - Fetch https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:28:32 INFO - Expected file size: 51788689 08:28:32 INFO - Obtained file size: 51788689 08:28:48 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 08:28:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:28:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:28:48 INFO - Fetch https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:28:50 INFO - Expected file size: 51788689 08:28:50 INFO - Obtained file size: 51788689 08:29:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:29:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:29:06 INFO - Fetch https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:29:07 INFO - Expected file size: 14404424 08:29:07 INFO - Obtained file size: 14404424 08:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:07 INFO - trying https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:29:07 INFO - Downloading https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:29:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 08:29:10 INFO - Downloaded 75022005 bytes. 08:29:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:29:10 INFO - mkdir: /builds/slave/test/properties 08:29:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:29:10 INFO - Writing to file /builds/slave/test/properties/build_url 08:29:10 INFO - Contents: 08:29:10 INFO - build_url:https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:29:10 INFO - Running post-action listener: _resource_record_post_action 08:29:10 INFO - Running post-action listener: set_extra_try_arguments 08:29:10 INFO - [mozharness: 2017-03-10 16:29:10.898828Z] Finished download-and-extract step (success) 08:29:10 INFO - [mozharness: 2017-03-10 16:29:10.898948Z] Running populate-webroot step. 08:29:10 INFO - Running pre-action listener: _resource_record_pre_action 08:29:10 INFO - Running main action method: populate_webroot 08:29:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:29:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:29:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:29:10 INFO - '--tppagecycles', 08:29:10 INFO - '7'], 08:29:10 INFO - 'tests': ['tsvgm']}}, 08:29:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:29:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:29:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:29:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:29:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:29:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:29:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:29:10 INFO - 'talos_options': ['--disable-e10s'], 08:29:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:29:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:29:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:29:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:29:10 INFO - 'talos_options': ['--disable-e10s'], 08:29:10 INFO - 'tests': ['damp', 'tps']}, 08:29:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:29:10 INFO - 'tests': ['damp', 'tps']}, 08:29:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:29:10 INFO - 'tests': ['dromaeo_dom']}, 08:29:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:29:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:29:10 INFO - 'tests': ['basic_compositor_video']}, 08:29:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:29:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:29:10 INFO - 'tests': ['a11yr', 08:29:10 INFO - 'ts_paint', 08:29:10 INFO - 'tpaint', 08:29:10 INFO - 'sessionrestore', 08:29:10 INFO - 'sessionrestore_no_auto_restore', 08:29:10 INFO - 'tabpaint']}, 08:29:10 INFO - 'other-e10s': {'tests': ['a11yr', 08:29:10 INFO - 'ts_paint', 08:29:10 INFO - 'tpaint', 08:29:10 INFO - 'sessionrestore', 08:29:10 INFO - 'sessionrestore_no_auto_restore', 08:29:10 INFO - 'tabpaint']}, 08:29:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:29:10 INFO - 'ts_paint', 08:29:10 INFO - 'tpaint', 08:29:10 INFO - 'sessionrestore', 08:29:10 INFO - 'sessionrestore_no_auto_restore', 08:29:10 INFO - 'tabpaint']}, 08:29:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:29:10 INFO - 'ts_paint', 08:29:10 INFO - 'tpaint', 08:29:10 INFO - 'sessionrestore', 08:29:10 INFO - 'sessionrestore_no_auto_restore', 08:29:10 INFO - 'tabpaint']}, 08:29:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:29:10 INFO - 'tests': ['a11yr', 08:29:10 INFO - 'ts_paint', 08:29:10 INFO - 'tpaint', 08:29:10 INFO - 'sessionrestore', 08:29:10 INFO - 'sessionrestore_no_auto_restore', 08:29:10 INFO - 'tabpaint']}, 08:29:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:29:10 INFO - 'tests': ['a11yr', 08:29:10 INFO - 'ts_paint', 08:29:10 INFO - 'tpaint', 08:29:10 INFO - 'sessionrestore', 08:29:10 INFO - 'sessionrestore_no_auto_restore', 08:29:10 INFO - 'tabpaint']}, 08:29:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:29:10 INFO - 'tests': ['tsvgx', 08:29:10 INFO - 'tsvgr_opacity', 08:29:10 INFO - 'tart', 08:29:10 INFO - 'tscrollx', 08:29:10 INFO - 'cart', 08:29:10 INFO - 'tsvg_static']}, 08:29:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:29:10 INFO - 'tsvgr_opacity', 08:29:10 INFO - 'tart', 08:29:10 INFO - 'tscrollx', 08:29:10 INFO - 'cart', 08:29:10 INFO - 'tsvg_static']}, 08:29:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:29:10 INFO - 'talos_options': ['--disable-e10s'], 08:29:10 INFO - 'tests': ['tp5o']}, 08:29:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:29:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:29:10 INFO - 'talos_options': ['--disable-e10s', 08:29:10 INFO - '--xperf_path', 08:29:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:29:10 INFO - 'tests': ['tp5n']}, 08:29:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:29:10 INFO - 'talos_options': ['--xperf_path', 08:29:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:29:10 INFO - 'tests': ['tp5n']}}, 08:29:10 INFO - 'talos.zip': {'path': '', 08:29:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:29:10 INFO - Running post-action listener: _resource_record_post_action 08:29:10 INFO - [mozharness: 2017-03-10 16:29:10.917505Z] Finished populate-webroot step (success) 08:29:10 INFO - [mozharness: 2017-03-10 16:29:10.917619Z] Running create-virtualenv step. 08:29:10 INFO - Running pre-action listener: _resource_record_pre_action 08:29:10 INFO - Running main action method: create_virtualenv 08:29:10 INFO - Creating virtualenv /builds/slave/test/build/venv 08:29:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:29:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:29:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:29:11 INFO - Using real prefix '/tools/python27' 08:29:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:29:11 INFO - Installing distribute.............................................................................................................................................................................................done. 08:29:14 INFO - Installing pip.................done. 08:29:14 INFO - Return code: 0 08:29:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:29:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:29:14 INFO - Reading from file tmpfile_stdout 08:29:14 INFO - Output received: 08:29:14 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:29:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110631b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110634768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f185c6da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11040fd30>, '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 0x110336db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110404be8>, '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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:29:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:29:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:29:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F4gpdWtWfm/Render', 08:29:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:29:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:29:15 INFO - 'HOME': '/Users/cltbld', 08:29:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:29:15 INFO - 'LOGNAME': 'cltbld', 08:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:15 INFO - 'MOZ_NO_REMOTE': '1', 08:29:15 INFO - 'NO_EM_RESTART': '1', 08:29:15 INFO - 'PAGER': '/bin/cat', 08:29:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:15 INFO - 'PWD': '/builds/slave/test', 08:29:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:29:15 INFO - 'SHELL': '/bin/bash', 08:29:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqhMlIUWgS/Listeners', 08:29:15 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 08:29:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:29:15 INFO - 'USER': 'cltbld', 08:29:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:29:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:15 INFO - 'XPC_FLAGS': '0x0', 08:29:15 INFO - 'XPC_SERVICE_NAME': '0', 08:29:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:15 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:29:15 INFO - Cleaning up... 08:29:15 INFO - Return code: 0 08:29:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110631b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110634768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f185c6da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11040fd30>, '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 0x110336db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110404be8>, '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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:29:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:29:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:29:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F4gpdWtWfm/Render', 08:29:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:29:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:29:16 INFO - 'HOME': '/Users/cltbld', 08:29:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:29:16 INFO - 'LOGNAME': 'cltbld', 08:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:16 INFO - 'MOZ_NO_REMOTE': '1', 08:29:16 INFO - 'NO_EM_RESTART': '1', 08:29:16 INFO - 'PAGER': '/bin/cat', 08:29:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:16 INFO - 'PWD': '/builds/slave/test', 08:29:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:29:16 INFO - 'SHELL': '/bin/bash', 08:29:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqhMlIUWgS/Listeners', 08:29:16 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 08:29:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:29:16 INFO - 'USER': 'cltbld', 08:29:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:29:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:16 INFO - 'XPC_FLAGS': '0x0', 08:29:16 INFO - 'XPC_SERVICE_NAME': '0', 08:29:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:16 INFO - Downloading/unpacking psutil>=3.1.1 08:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:29:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:29:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:29:20 INFO - Installing collected packages: psutil 08:29:20 INFO - Running setup.py install for psutil 08:29:20 INFO - building 'psutil._psutil_osx' extension 08:29:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:29:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:29:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:29:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:29:21 INFO - building 'psutil._psutil_posix' extension 08:29:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:29:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:29:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:29:21 INFO - ^ 08:29:21 INFO - 1 warning generated. 08:29:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:29:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:29:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:29:21 INFO - Successfully installed psutil 08:29:21 INFO - Cleaning up... 08:29:21 INFO - Return code: 0 08:29:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:21 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 0x110631b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110634768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f185c6da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11040fd30>, '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 0x110336db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110404be8>, '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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F4gpdWtWfm/Render', 08:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:29:21 INFO - 'HOME': '/Users/cltbld', 08:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:29:21 INFO - 'LOGNAME': 'cltbld', 08:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:21 INFO - 'MOZ_NO_REMOTE': '1', 08:29:21 INFO - 'NO_EM_RESTART': '1', 08:29:21 INFO - 'PAGER': '/bin/cat', 08:29:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:21 INFO - 'PWD': '/builds/slave/test', 08:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:29:21 INFO - 'SHELL': '/bin/bash', 08:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqhMlIUWgS/Listeners', 08:29:21 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 08:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:29:21 INFO - 'USER': 'cltbld', 08:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:21 INFO - 'XPC_FLAGS': '0x0', 08:29:21 INFO - 'XPC_SERVICE_NAME': '0', 08:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:29:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:29:24 INFO - Installing collected packages: mozsystemmonitor 08:29:24 INFO - Running setup.py install for mozsystemmonitor 08:29:24 INFO - Successfully installed mozsystemmonitor 08:29:24 INFO - Cleaning up... 08:29:24 INFO - Return code: 0 08:29:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110631b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110634768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f185c6da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11040fd30>, '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 0x110336db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110404be8>, '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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F4gpdWtWfm/Render', 08:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:29:24 INFO - 'HOME': '/Users/cltbld', 08:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:29:24 INFO - 'LOGNAME': 'cltbld', 08:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:24 INFO - 'MOZ_NO_REMOTE': '1', 08:29:24 INFO - 'NO_EM_RESTART': '1', 08:29:24 INFO - 'PAGER': '/bin/cat', 08:29:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:24 INFO - 'PWD': '/builds/slave/test', 08:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:29:24 INFO - 'SHELL': '/bin/bash', 08:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqhMlIUWgS/Listeners', 08:29:24 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 08:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:29:24 INFO - 'USER': 'cltbld', 08:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:24 INFO - 'XPC_FLAGS': '0x0', 08:29:24 INFO - 'XPC_SERVICE_NAME': '0', 08:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:24 INFO - Downloading/unpacking jsonschema==2.5.1 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:29:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:27 INFO - Downloading functools32-3.2.3-2.tar.gz 08:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:29:28 INFO - warning: no files found matching '*.txt' 08:29:28 INFO - no previously-included directories found matching 'build' 08:29:28 INFO - no previously-included directories found matching 'dist' 08:29:28 INFO - no previously-included directories found matching '.git*' 08:29:28 INFO - Installing collected packages: jsonschema, functools32 08:29:28 INFO - Running setup.py install for functools32 08:29:28 INFO - warning: no files found matching '*.txt' 08:29:28 INFO - no previously-included directories found matching 'build' 08:29:28 INFO - no previously-included directories found matching 'dist' 08:29:28 INFO - no previously-included directories found matching '.git*' 08:29:28 INFO - Successfully installed jsonschema functools32 08:29:28 INFO - Cleaning up... 08:29:28 INFO - Return code: 0 08:29:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:29: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')]} 08:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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')]} 08:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110631b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110634768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f185c6da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11040fd30>, '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 0x110336db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110404be8>, '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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:29:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:29:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:29:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F4gpdWtWfm/Render', 08:29:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:29:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:29:28 INFO - 'HOME': '/Users/cltbld', 08:29:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:29:28 INFO - 'LOGNAME': 'cltbld', 08:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:28 INFO - 'MOZ_NO_REMOTE': '1', 08:29:28 INFO - 'NO_EM_RESTART': '1', 08:29:28 INFO - 'PAGER': '/bin/cat', 08:29:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:28 INFO - 'PWD': '/builds/slave/test', 08:29:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:29:28 INFO - 'SHELL': '/bin/bash', 08:29:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqhMlIUWgS/Listeners', 08:29:28 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 08:29:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:29:28 INFO - 'USER': 'cltbld', 08:29:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:29:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:28 INFO - 'XPC_FLAGS': '0x0', 08:29:28 INFO - 'XPC_SERVICE_NAME': '0', 08:29:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:29:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:29:28 INFO - Cleaning up... 08:29:28 INFO - Return code: 0 08:29:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:29: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')]} 08:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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')]} 08:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29: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', '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 0x110631b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110634768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f185c6da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11040fd30>, '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 0x110336db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110404be8>, '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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:29: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:29: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 blobuploader==1.2.4 08:29:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F4gpdWtWfm/Render', 08:29:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:29:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:29:28 INFO - 'HOME': '/Users/cltbld', 08:29:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:29:28 INFO - 'LOGNAME': 'cltbld', 08:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:28 INFO - 'MOZ_NO_REMOTE': '1', 08:29:28 INFO - 'NO_EM_RESTART': '1', 08:29:28 INFO - 'PAGER': '/bin/cat', 08:29:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:28 INFO - 'PWD': '/builds/slave/test', 08:29:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:29:28 INFO - 'SHELL': '/bin/bash', 08:29:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqhMlIUWgS/Listeners', 08:29:28 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 08:29:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:29:28 INFO - 'USER': 'cltbld', 08:29:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:29:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:28 INFO - 'XPC_FLAGS': '0x0', 08:29:28 INFO - 'XPC_SERVICE_NAME': '0', 08:29:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:29:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:28 INFO - Downloading/unpacking blobuploader==1.2.4 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29:31 INFO - Downloading blobuploader-1.2.4.tar.gz 08:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:29:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:29:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:29:32 INFO - Downloading docopt-0.6.1.tar.gz 08:29:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:29:32 INFO - Installing collected packages: blobuploader, requests, docopt 08:29:32 INFO - Running setup.py install for blobuploader 08:29:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:29:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:29:32 INFO - Running setup.py install for requests 08:29:33 INFO - Running setup.py install for docopt 08:29:33 INFO - Successfully installed blobuploader requests docopt 08:29:33 INFO - Cleaning up... 08:29:33 INFO - Return code: 0 08:29:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:33 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 0x110631b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110634768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f185c6da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11040fd30>, '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 0x110336db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110404be8>, '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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:29:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:29:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:29:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F4gpdWtWfm/Render', 08:29:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:29:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:29:33 INFO - 'HOME': '/Users/cltbld', 08:29:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:29:33 INFO - 'LOGNAME': 'cltbld', 08:29:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:33 INFO - 'MOZ_NO_REMOTE': '1', 08:29:33 INFO - 'NO_EM_RESTART': '1', 08:29:33 INFO - 'PAGER': '/bin/cat', 08:29:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:29:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:33 INFO - 'PWD': '/builds/slave/test', 08:29:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:29:33 INFO - 'SHELL': '/bin/bash', 08:29:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqhMlIUWgS/Listeners', 08:29:33 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 08:29:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:29:33 INFO - 'USER': 'cltbld', 08:29:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:29:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:29:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:33 INFO - 'XPC_FLAGS': '0x0', 08:29:33 INFO - 'XPC_SERVICE_NAME': '0', 08:29:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:29:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:29:33 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-hoozsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:29:34 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-AWT2sL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:29:34 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-ABs7ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:29:34 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-WJE5c9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:29:34 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-aCrCwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:29:34 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-jNJLoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:29:34 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-ZXduqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:29:34 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-1yP5HP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:29:34 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-__FuEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:29:34 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-KL4OPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:29:35 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-SckFVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:29:35 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-t3OF2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:29:35 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-qY5sdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:29:35 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-Te_rYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:29:35 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-r73fpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:29:35 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-5LhZaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:29:35 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-PdJ8kv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:29:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:29:35 INFO - Running setup.py install for manifestparser 08:29:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:29:35 INFO - Running setup.py install for mozcrash 08:29:36 INFO - Running setup.py install for mozdebug 08:29:36 INFO - Running setup.py install for mozdevice 08:29:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:29:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:29:36 INFO - Running setup.py install for mozfile 08:29:36 INFO - Running setup.py install for mozhttpd 08:29:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:29:36 INFO - Running setup.py install for mozinfo 08:29:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:29:36 INFO - Running setup.py install for mozInstall 08:29:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:29:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:29:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:29:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:29:36 INFO - Running setup.py install for mozleak 08:29:37 INFO - Running setup.py install for mozlog 08:29:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:29:37 INFO - Running setup.py install for moznetwork 08:29:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:29:37 INFO - Running setup.py install for mozprocess 08:29:37 INFO - Running setup.py install for mozprofile 08:29:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:29:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:29:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:29:37 INFO - Running setup.py install for mozrunner 08:29:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:29:37 INFO - Running setup.py install for mozscreenshot 08:29:38 INFO - Running setup.py install for moztest 08:29:38 INFO - Running setup.py install for mozversion 08:29:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:29:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:29:38 INFO - Cleaning up... 08:29:38 INFO - Return code: 0 08:29:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110631b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110634768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f185c6da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11040fd30>, '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 0x110336db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110404be8>, '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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:29:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:29:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F4gpdWtWfm/Render', 08:29:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:29:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:29:38 INFO - 'HOME': '/Users/cltbld', 08:29:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:29:38 INFO - 'LOGNAME': 'cltbld', 08:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:38 INFO - 'MOZ_NO_REMOTE': '1', 08:29:38 INFO - 'NO_EM_RESTART': '1', 08:29:38 INFO - 'PAGER': '/bin/cat', 08:29:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:38 INFO - 'PWD': '/builds/slave/test', 08:29:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:29:38 INFO - 'SHELL': '/bin/bash', 08:29:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqhMlIUWgS/Listeners', 08:29:38 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 08:29:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:29:38 INFO - 'USER': 'cltbld', 08:29:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:29:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:38 INFO - 'XPC_FLAGS': '0x0', 08:29:38 INFO - 'XPC_SERVICE_NAME': '0', 08:29:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:29:38 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-dAK5nO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:29:38 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-Zq3pcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:29:38 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-92V44Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:29:38 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-BZ0q44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:29:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-gL1rGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:29:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-XOiZZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:29:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-BWUM8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:29:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-zCM1Xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:29:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-KqXDvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:29:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-y8UD18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:29:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-ylytjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:29:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-Jl2YKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:29:39 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)) 08:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:29:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-btpk6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:29:40 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-bm3XwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:29:40 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-f9iLeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:29:40 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-DIhbd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:29:40 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-bna8ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:29:40 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:29:40 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:29:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29: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 08:29:43 INFO - Downloading blessings-1.6.tar.gz 08:29:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:29:43 INFO - Installing collected packages: blessings 08:29:43 INFO - Running setup.py install for blessings 08:29:43 INFO - Successfully installed blessings 08:29:43 INFO - Cleaning up... 08:29:43 INFO - Return code: 0 08:29:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:29:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:29:43 INFO - Reading from file tmpfile_stdout 08:29:43 INFO - Current package versions: 08:29:43 INFO - blessings == 1.6 08:29:43 INFO - blobuploader == 1.2.4 08:29:43 INFO - docopt == 0.6.1 08:29:43 INFO - functools32 == 3.2.3-2 08:29:43 INFO - jsonschema == 2.5.1 08:29:43 INFO - manifestparser == 1.1 08:29:43 INFO - mozInstall == 1.12 08:29:43 INFO - mozcrash == 1.0 08:29:43 INFO - mozdebug == 0.1 08:29:43 INFO - mozdevice == 0.49 08:29:43 INFO - mozfile == 1.2 08:29:43 INFO - mozhttpd == 0.7 08:29:43 INFO - mozinfo == 0.9 08:29:43 INFO - mozleak == 0.1 08:29:43 INFO - mozlog == 3.4 08:29:43 INFO - moznetwork == 0.27 08:29:43 INFO - mozprocess == 0.25 08:29:43 INFO - mozprofile == 0.28 08:29:43 INFO - mozrunner == 6.13 08:29:43 INFO - mozscreenshot == 0.1 08:29:43 INFO - mozsystemmonitor == 0.3 08:29:43 INFO - moztest == 0.8 08:29:43 INFO - mozversion == 1.4 08:29:43 INFO - psutil == 3.1.1 08:29:43 INFO - requests == 1.2.3 08:29:43 INFO - wsgiref == 0.1.2 08:29:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:43 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 0x110631b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110634768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f185c6da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11040fd30>, '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 0x110336db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110404be8>, '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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:29:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F4gpdWtWfm/Render', 08:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:29:43 INFO - 'HOME': '/Users/cltbld', 08:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:29:43 INFO - 'LOGNAME': 'cltbld', 08:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:43 INFO - 'MOZ_NO_REMOTE': '1', 08:29:43 INFO - 'NO_EM_RESTART': '1', 08:29:43 INFO - 'PAGER': '/bin/cat', 08:29:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:43 INFO - 'PWD': '/builds/slave/test', 08:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:29:43 INFO - 'SHELL': '/bin/bash', 08:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqhMlIUWgS/Listeners', 08:29:43 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 08:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:29:43 INFO - 'USER': 'cltbld', 08:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:43 INFO - 'XPC_FLAGS': '0x0', 08:29:43 INFO - 'XPC_SERVICE_NAME': '0', 08:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:29:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:29:44 INFO - Cleaning up... 08:29:44 INFO - Return code: 0 08:29:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:29:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:29:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:29:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110631b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110634768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f185c6da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11040fd30>, '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 0x110336db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110404be8>, '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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:29:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:29:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F4gpdWtWfm/Render', 08:29:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:29:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:29:44 INFO - 'HOME': '/Users/cltbld', 08:29:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:29:44 INFO - 'LOGNAME': 'cltbld', 08:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:44 INFO - 'MOZ_NO_REMOTE': '1', 08:29:44 INFO - 'NO_EM_RESTART': '1', 08:29:44 INFO - 'PAGER': '/bin/cat', 08:29:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:44 INFO - 'PWD': '/builds/slave/test', 08:29:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:29:44 INFO - 'SHELL': '/bin/bash', 08:29:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqhMlIUWgS/Listeners', 08:29:44 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 08:29:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:29:44 INFO - 'USER': 'cltbld', 08:29:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:29:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:44 INFO - 'XPC_FLAGS': '0x0', 08:29:44 INFO - 'XPC_SERVICE_NAME': '0', 08:29:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:29:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:29:44 INFO - Cleaning up... 08:29:44 INFO - Return code: 0 08:29:44 INFO - Running post-action listener: _resource_record_post_action 08:29:44 INFO - Running post-action listener: _start_resource_monitoring 08:29:44 INFO - Starting resource monitoring. 08:29:44 INFO - [mozharness: 2017-03-10 16:29:44.361101Z] Finished create-virtualenv step (success) 08:29:44 INFO - [mozharness: 2017-03-10 16:29:44.362054Z] Running install step. 08:29:44 INFO - Running pre-action listener: _resource_record_pre_action 08:29:44 INFO - Running main action method: install 08:29:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:29:44 INFO - Reading from file tmpfile_stdout 08:29:44 INFO - Detecting whether we're running mozinstall >=1.0... 08:29:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:29:44 INFO - Reading from file tmpfile_stdout 08:29:44 INFO - Output received: 08:29:44 INFO - Usage: mozinstall [options] installer 08:29:44 INFO - Options: 08:29:44 INFO - -h, --help show this help message and exit 08:29:44 INFO - -d DEST, --destination=DEST 08:29:44 INFO - Directory to install application into. [default: 08:29:44 INFO - "/builds/slave/test"] 08:29:44 INFO - --app=APP Application being installed. [default: firefox] 08:29:44 INFO - mkdir: /builds/slave/test/build/application 08:29:44 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'] 08:29:44 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 08:30:04 INFO - Reading from file tmpfile_stdout 08:30:04 INFO - Output received: 08:30:04 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 08:30:04 INFO - Running post-action listener: _resource_record_post_action 08:30:04 INFO - [mozharness: 2017-03-10 16:30:04.972017Z] Finished install step (success) 08:30:04 INFO - [mozharness: 2017-03-10 16:30:04.972163Z] Running run-tests step. 08:30:04 INFO - Running pre-action listener: _resource_record_pre_action 08:30:04 INFO - Running main action method: run_tests 08:30:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:30:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:30:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:30:04 INFO - 2017-03-10 08:30:04.985 screenresolution[1625:7507] starting screenresolution argv=screenresolution get 08:30:04 INFO - 2017-03-10 08:30:04.987 screenresolution[1625:7507] Display 0: 1600x1200x32@60 08:30:04 INFO - 2017-03-10 08:30:04.993 screenresolution[1626:7510] starting screenresolution argv=screenresolution list 08:30:04 INFO - Available Modes on Display 0 08:30:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:30:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:30:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:30:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:30:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:30:05 INFO - Intel Iris: 08:30:05 INFO - Chipset Model: Intel Iris 08:30:05 INFO - Type: GPU 08:30:05 INFO - Bus: Built-In 08:30:05 INFO - VRAM (Dynamic, Max): 1536 MB 08:30:05 INFO - Vendor: Intel (0x8086) 08:30:05 INFO - Device ID: 0x0a2e 08:30:05 INFO - Revision ID: 0x0009 08:30:05 INFO - Displays: 08:30:05 INFO - Display: 08:30:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:30:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:30:05 INFO - Display Serial Number: 1600x1200 60 08:30:05 INFO - Main Display: Yes 08:30:05 INFO - Mirror: Off 08:30:05 INFO - Online: Yes 08:30:05 INFO - Rotation: Supported 08:30:05 INFO - Return code: 0 08:30:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:30:05 INFO - Python 2.7.3 08:30:05 INFO - Return code: 0 08:30:05 INFO - grabbing minidump binary from tooltool 08:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:05 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11040fd30>, '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 0x110336db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110404be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:30:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:30:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:30:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:30:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:30:05 INFO - Return code: 0 08:30:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:30:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:30:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:30:05 INFO - ENV: RUST_BACKTRACE is now 1 08:30:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:30:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:30:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:30:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F4gpdWtWfm/Render', 08:30:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:05 INFO - 'HOME': '/Users/cltbld', 08:30:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:05 INFO - 'LOGNAME': 'cltbld', 08:30:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:30:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:05 INFO - 'MOZ_NO_REMOTE': '1', 08:30:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:30:05 INFO - 'NO_EM_RESTART': '1', 08:30:05 INFO - 'PAGER': '/bin/cat', 08:30:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:05 INFO - 'PWD': '/builds/slave/test', 08:30:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:30:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:05 INFO - 'RUST_BACKTRACE': '1', 08:30:05 INFO - 'SHELL': '/bin/bash', 08:30:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eqhMlIUWgS/Listeners', 08:30:05 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 08:30:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:05 INFO - 'USER': 'cltbld', 08:30:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:05 INFO - 'XPC_FLAGS': '0x0', 08:30:05 INFO - 'XPC_SERVICE_NAME': '0', 08:30:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:30:05 INFO - mozversion application_buildid: 20170310065428 08:30:05 INFO - mozversion application_changeset: 528e9dbbb882db0b32792d44b5be9cc539afa1a8 08:30:05 INFO - mozversion application_display_name: Nightly 08:30:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:30:05 INFO - mozversion application_name: Firefox 08:30:05 INFO - mozversion application_remotingname: firefox 08:30:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:30:05 INFO - mozversion application_vendor: Mozilla 08:30:05 INFO - mozversion application_version: 55.0a1 08:30:05 INFO - mozversion platform_buildid: 20170310065428 08:30:05 INFO - mozversion platform_changeset: 528e9dbbb882db0b32792d44b5be9cc539afa1a8 08:30:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:30:05 INFO - mozversion platform_version: 55.0a1 08:30:05 INFO - using testdate: 1489163405 08:30:05 INFO - actual date: 1489163405 08:30:05 INFO - starting webserver on 'localhost:49240' 08:30:05 INFO - SUITE-START | Running 2 tests 08:30:05 INFO - TEST-START | dromaeo_css 08:30:05 INFO - operating with platform_type : mac_ 08:30:05 INFO - Initialising browser for dromaeo_css test... 08:30:05 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/tmp3zGeJA/profile http://localhost:49240/getInfo.html) 08:30:08 INFO - PROCESS | 1636 | 2017-03-10 08:30:08.865 plugin-container[1640:7688] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4f, name = 'com.apple.tsm.portname' 08:30:08 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:30:08 INFO - PROCESS | 1636 | 2017-03-10 08:30:08.867 plugin-container[1640:7688] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 08:30:08 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:30:08 INFO - PROCESS | 1636 | 2017-03-10 08:30:08.867 plugin-container[1640:7688] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:30:08 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 08:30:08 INFO - PROCESS | 1636 | colorDepth:24 08:30:08 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 08:30:08 INFO - PROCESS | 1636 | __metrics 08:30:09 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 08:30:09 INFO - TEST-INFO | 1636: exit 0 08:30:09 INFO - Browser initialized. 08:30:09 INFO - Running cycle 1/1 for dromaeo_css test... 08:30:09 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/tmp3zGeJA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:30:12 INFO - PROCESS | 1642 | 2017-03-10 08:30:12.584 plugin-container[1645:7881] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 08:30:12 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:30:12 INFO - PROCESS | 1642 | 2017-03-10 08:30:12.585 plugin-container[1645:7881] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 08:30:12 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:30:12 INFO - PROCESS | 1642 | 2017-03-10 08:30:12.585 plugin-container[1645:7881] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:30:22 INFO - PROCESS | 1642 | RSS: Main: 227885056 08:30:22 INFO - PROCESS | 1642 | 08:33:25 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 08:33:26 INFO - PROCESS | 1642 | RSS: Main: 257114112 08:33:26 INFO - PROCESS | 1642 | 08:35:10 INFO - PROCESS | 1642 | ************************* 08:35:10 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 08:35:10 INFO - PROCESS | 1642 | 08:35:10 ERROR - PROCESS | 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:35:10 INFO - PROCESS | 1642 | Full stack: 08:35:10 INFO - PROCESS | 1642 | ************************* 08:36:29 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 08:36:30 INFO - PROCESS | 1642 | RSS: Main: 264253440 08:36:30 INFO - PROCESS | 1642 | 08:39:32 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 08:39:32 INFO - PROCESS | 1642 | RSS: Main: 275468288 08:39:32 INFO - PROCESS | 1642 | 08:42:34 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 08:42:35 INFO - PROCESS | 1642 | RSS: Main: 269033472 08:42:35 INFO - PROCESS | 1642 | 08:45:38 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 08:45:39 INFO - PROCESS | 1642 | RSS: Main: 278773760 08:45:39 INFO - PROCESS | 1642 | 08:48:42 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 08:48:42 INFO - PROCESS | 1642 | RSS: Main: 272650240 08:48:42 INFO - PROCESS | 1642 | 08:48:42 INFO - PROCESS | 1642 | __start_tp_report 08:48:42 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 08:48:42 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 08:48:42 INFO - PROCESS | 1642 | |i|pagename|runs| 08:48:42 INFO - PROCESS | 1642 | |0;dojo.html;3460.775049621774;3472.114610774252;3492.790432574044;3497.9650203497968;3514.876979305723;5887.646741195531;5986.670733109677;5996.370381109986;6012.909806352908;6050.697465126748;5876.618019828708;5938.257717785277;5940.168376427293;5952.672603006833;6046.486048685856;5376.731163441832;5383.246345511631;5386.084365657847;5471.316085489314;5541.251930989325;5418.485243901823;5444.047291723952;5454.863628409282;5461.590380721462;5461.808836690717;148.79230869795438;4684.437867455893;5299.682019078868;5324.9733751331;5453.7818487260465;5325.227841962933;5333.959877823826;5336.59975501835;5364.168553874156;5403.621746477748;5314.893702125974;5318.308619879391;5337.359516857963;5353.304070470853;5366.865828354284;5519.724013799334;5702.714864256812;5849.970750146264;5922.703864806743;6066.757329706807;6217.875642487125;6300.59046161998;6303.684815759239;6326;6341.524385671047;6353.364663533656;6389.712462939179;6393;6395.648239346834;6421.93578064218;5853.414658534112;5891.793787217427;6083.452489275942;6085.847853803646;6115.4190351916495;6352.777652782131;6366.77716279928;6384.074309225136;6387.201599800025;6451.032345148265;6176.69116544171;6189.226346706661;6213.5339849511465;6227.408396202353;6229.4082062204925;5715.657060576379;5786.132080188006;5845.941540584539;6102.67103564696;6324.620522768649;6223.222097237845;6279;6288.685565721696;6294.464970477469;6295.307516173218;6269.059641053878;6275.843103922439;6287.937120628827;6291.653959032205;6293.244810622755;5013.774380152829;5071;5115.7953681852305;5127.359080114986;5185.170372740344;3501.9299614007577;3585.3546361655153;4472.485664148599;4527.777500075027;4592.173408786451;5103.770330335144;6151.9234134026365;6157.168782214433;6163.291221509494;6169.598976066542;5267.973660131752;6281.685915704288;6320.71556779946;6336.6831658416895;6339.524535659844;4933.45731969483;5982.581219314693;6124.081387791778;6129.325774164839;6167.691615419211;4901.901961960741;5591.27313449249;5667.546596272371;5667.631603945731;5706.743196556165;6609.834754131185;6635.6350400728425;6646.60120392778;6654.933450665625;6669.2997235289595;6122.825106970096;6177.135201071944;6310.842228944313;6332.778352757631;6400.135981642418;6475.838104047437;6540.149780528501;6588.7364505421165;6684.899726504009;6689.665516724242;6277.654728989949;6280.057991301342;6301.873962520633;6312.558120931674;6331.430171284606;6139.737467782926;6222.564420490522;6315.96842015787;6381.8723625528155;6414.102025716496;6124.6860923983895;6127.036821038878;6141.301904043499;6173.259208894961;6179.443850053517;6179.2584889811715;6236.126942228001;6243.562950593415;6244;6247.3440288769025;6023.186869772525;6083.6045657032155;6089.69551522431;6101.816945491642;6150.35421280777;6127.342242870382;6128.900367973482;6190.50475961931;6246.750129994749;6250.312465628868;6133.877322453615;6148.415900489446;6163.938360616516;6172.537059720449;6173.012318029094 08:48:42 INFO - PROCESS | 1642 | |1;ext.html;691.8512519808241;782.2255966593075;818.1450384348356;844.3076677124757;908.4413085952142;36068.45897311539;36700.44949325759;36736.265274694524;38319.425208621826;38379;36580.45129323056;36655.90032299029;36678.633213667854;36743.81628091856;36809.815950920274;7459.925400745991;7607.820787777903;10708.41103739294;10942.617008404708;11080.667579972614;10622.778380486227;10769.946150269237;10885.673429797118;10901.454927253606;10914.454277286144;9586.856197157049;10883.727906802314;10924.726881827939;10937.617183398583;10952.726181845477;10865.728356791144;10872.728181795439;10910.181736369725;10914.836277455845;10926.399048052353;10708.357498550035;10771.299865508796;10789.514471848786;10792.622258221003;10814.675559733221;38154;38260.234795303935;38348.65779697697;38433.61566384328;38451.423228651314;3707.9814600927093;3740.9438858417147;3757.7369584129124;3780.8676696315497;3923.136909879822;23565.05739770407;26024.479510409874;26898.731012690016;27404.451910961867;27951.161465156078;2651.9204423867313;3034.150437877398;3162.1936406216496;3255.0234929521284;3271.574695289597;348351;348857;349359;349626;352124.23937880405;33326.16684582905;42047.789761051;49848.25531106394;50232.74883625558;50983;46222.768886156024;51056.48943510592;51312.48687513077;53861;54403.72798136063;12525.56160534377;14154.504592339425;14774.482893098904;14914.955953083398;15037.624059398384;12152.817707734392;12178.695532611755;12183;12186.390680465942;12187.207831490925;13886.305684715724;13897.583072507841;13930.860691392956;13960.022798404218;14022.948278879125;4982.402111746614;5878.412158784173;5884.176215329857;5885.499732522783;5899.345294617354;1072.8444375565655;1109.8113320735495;1117.6400524079927;1130.4856290387854;1166.597523854269;26244.60633090505;26265.474690506082;26268.904288219528;26530.806113724928;26602.6009609856;990.5889056041661;1037.7976294622626;1085.46812062089;1102;1181.255808840425;12874.356282185854;13648.112872663245;13665.65191528082;13779.931100344435;13970;490533;503257;505828;506851;506875;45265.773671131436;54717.72641136769;55056;55368.446315537134;55536.44463555393;1146.6158836789602;1395.8673925977016;1401.1312985948573;1402.0816365280757;1407.7254935287726;24328.026878924644;24638.630420544065;25499.852506637617;25791.613125803502;25823.22530324093;45852.41475852388;45873.7245216791;45888.08223835572;46054;47374.763126184145;368797;456074;458055;458979;459628;33987.4901876477;36812.63187368199;37433.50265989439;37594.06014849541;37647.87056388313;44316.55683443124;45739.77130114328;47170;47201.29198061963;47584.7620761894;34608.48087278743;35554.644453555135;38357.80821095876;39121.804390977864;39354.40968385534;31934;37011.704590339206;37396;37601.811990939874;39027.80486097551;37527.81236093802;38407.42388864225;38647.8405647831;40625;40670.59329406668;29447;31635.683643163273;34073;34083.659163409044;34109.14727131841;19556.315528956417;22419;22773.202937897095;25487.87256063708;25593 08:48:42 INFO - PROCESS | 1642 | |2;jquery.html;379.63744623884196;384.8691444908731;385.97877116758565;392.67996582785025;427.7433539876073;16152.919235403806;20650.17399304028;21852.907354632243;21880.90595470228;21974.34076977691;21337.786622133775;21963.450913727163;21969.890150549225;22047.889760551174;22300.776992230112;6422.068800024005;6672.799816005528;6689.397954184123;6715.160604924385;6719.395254427101;6573.769918052869;6689.030090636855;7015.789526314219;7320.04839370881;7524.586147761871;7381.8892716609025;7392.482526223166;7469.738559150431;7472.514286571382;7483.438742094338;6051.0923361496125;7246.456515761312;7383.704651813948;7415.925840741632;7426.665800039012;6042.818715438544;6283.905741413882;6746.089277947463;6995.090638217;7064.964675176642;25507;26054.478910421687;26409.3397665058;26545.46909061808;26562.6015609766;3189.4259033373983;3276.3283526876935;3323.88366407177;3323.9501407478906;3419.1110311319235;15157.105361829772;16073.955192912423;16726.58183545409;16801.15994200285;17029.40397086096;1987.2448469581611;1990.3133418970433;2025.341763926715;2029.9492512687152;2035.3792093411503;146391.2680436591;147530.52469475276;147653;147669.78495322578;149276.50723492735;17142;17176.914115429343;18635.881847088946;20827.1669133236;21769.45576360572;25760.742392575834;32198.83900580482;33903;34476.31047379089;34765.82617086898;7921.683132674629;9389.483578403117;10408.531616077295;10558.472076396149;10657.946710266555;8461.830763384698;8560.443571167854;8593.613287401957;8760.912390876138;8761.38670293086;8501.95749021251;10359.741006474898;10407.635732749317;10454;10572.577096916037;3672.559292884871;4471.195184866755;4504.25679762842;4509.842155524607;4524.502304746476;1040.8542804007445;1041.4792603698152;1044.9634262800766;1048.940570024281;1095.3318475729798;18536.536586585444;19184.808151918303;20894.850783206766;22546;23205.76794232036;1006.7080546641538;1025.7127305231886;1074.7581794096423;1103.9558417663204;1106.983395249072;11311.547538098384;11834.112441566916;12090;12139.27164370123;12171.33057681836;383445;385589.07205464353;388913.055434721;724524;731073;126805;154175;157342.21328893283;161347.5797863033;161785;1149.1553708024596;1214.7631211913592;1225.4975460061332;1238.6531771104105;1241.069198101424;19745.901270493556;20031;20626.58746825085;21320.573588528452;21331.14675413028;39736.60263397329;59235.51911202254;60723.48191295253;61362.99999999822;61367.38632613617;79618.60190699241;85824.57087714522;86829;86848.56575717333;87107;11242.718932026437;11392.829107563228;11539;11778.52885884555;11927.463264153308;11710.1217408693;12062.698432538906;12371;12885.935570322088;13261.071724979545;9565.713028609152;10569.947150264199;10895.346279223271;11089.27919685218;11172.55309787632;9501.192398646193;11080.501377438195;11125.944370278097;11422.714432139262;11507.769844602893;11536.94231528837;11539.192256542297;11626.418679066182;11744.882551174378;11863.34751588671;9322.533873306444;11389.829152562885;11581.214222125058;11709.238899471848;11801.645950621494;11086.501107450345;11169.776604467703;11310.208285419942;11358.659240222807;11403 08:48:42 INFO - PROCESS | 1642 | |3;mootools.html;1514.6440586462186;1521.695660867826;1554.6735185611021;1568.1296880231473;1568.5529624057144;9450.574724137414;9664.951675241615;10557.366558006515;11237.943810280958;11283.774324513504;10596.470176491164;10916.617918372858;11184.832227516594;11194.10447164227;11198.608048718297;1859.5815941413175;2309.7459279479244;2314.6412306092584;2324.837261391703;2325.837191396603;2264.830137739668;2302.4128847143957;2305.4582173189287;2306.457982374149;2308.7575804540447;1832.825881541258;1900.695888657815;2261.5363850410627;2270.7048083749173;2314.4445333120016;2098.1502491490896;2105.547307328924;2112.3346145963974;2112.4718820294925;2117.8835164065895;2135.47680818199;2271.4775601611555;2276.0099356779856;2280.45269135408;2300.3903965449167;10898.400587967659;10956.56173753049;10963.945180274128;10987.670369888836;10987.945060274727;5556.638818476786;5715.942840571583;5759.913601295984;5762.769489220426;5774.971125144348;3259;3476.4959080933377;3616.3129005488872;3631.8728844490315;3674.2651469706166;1186.584695356627;1192.4455128365353;1203.2299328429724;1249.119370843553;1254.2537190371713;15067.321970511353;15091.169985650784;15165.772513412197;15189;15270;4062.431259623655;4494.662900282427;4759.4764575896625;4837.443693975168;4845.2732090186755;12594.992400607929;12611.495540178474;12673.04952128594;12693.619191424272;12694;5695.202671625976;5741.655500669973;5807.941920580824;5814.767409303665;5842.678652674143;5038.697678139323;5144.819931302386;5161.793528258828;5174.818881339135;5294.708791016531;3168.1604374840686;3714.9969508232625;3817.1983883384246;3839.6160383961937;3873.2253549290253;1015.7968406318765;1019.3527110284874;1030.948452577383;1032.0659802878256;1036.6838114375037;847.5126802088823;885.176785589408;905.8460061789511;921.7326975177124;954.4273435938381;5322.4411436799355;6440.194975628046;6622.337766223339;6725.6973436195485;6759.391654751096;744.7393412305757;873.1486800369715;878.0955615715823;887.6759982606302;896.7444278380631;3382.002309318757;3969.325214713506;4040.4949381327333;4085.9795701021303;4148.688848336386;13448.596542103556;13664.180149191081;13822.516211932736;13851.16892986424;13877.930610347086;10345.74135646615;12501.24992500453;13658.453661853415;13775.517856874962;13788.724225515434;9707.271954603433;9881.407115573089;10003.549840257207;10145.898541014496;10155.476678498431;5486.725663716718;6412.198475190601;6509.1863517060365;6583.30875258091;6599.901001485077;10740.462976851282;11115.055220306042;12003.459844307201;12116.939415303219;12302.569410070604;12227.78979552084;12392.955162398104;12639.988800895722;12718.10973231865;12760.4257808397;8330.291925186428;8499.83000340002;8508.74473765788;8512.914870851213;8518.74443766688;9878.851817222603;10254.128399086161;10317.27779055459;10430.895691043295;10431.634892778717;8844.867326990228;8983.191512763882;9087.727368178965;9100.77031127378;9170.403923744936;7536.718757811075;8501.277391421798;8674.30605551567;8923.973743019376;9022.323325750725;7842.29419352261;7936.365090792839;7998.360131189607;7999.84000320002;8008.639611217625;5713.800016999551;5765.250517432707;5799.217105690678;5909.468147866713;5982.222311099529;4949.30709700635;5306.230596563554;5831.446012628963;6002.039673652194;6067.605605635619 08:48:42 INFO - PROCESS | 1642 | |4;prototype.html;317.807726325573;347.9008482582463;356.07953440356675;368.5466875742836;380.21675348781514;4782.091402633498;4809.807607695692;5159.646229950525;5295.285136506571;5338.733063346836;4359.803808828602;4643.930341044878;4993;5086.211637196231;5170.9482905171035;3938.4289278054666;4481.843135490259;4488.753118578493;4521.638268938477;4547.158026233604;4063.878083657495;4218.557051509593;4292.742435453867;4481.7983190756495;4535.115652447774;3755.530558680165;4309.676774241929;4408.7956021989;4411.382406463098;4433.4901486329;3747.4191500317493;3952.8536724349906;4429.800658970338;4430.363933459618;4450.666200035011;3607.5310209672575;4261.2542805009;4264.936025959613;4300.247456695066;4351.26028575143;3909.7654140751647;4165.708400411972;4977.48186803025;5087.643864929457;5105.795768169269;4949.7030178189425;4982.82560110398;5185.55922746567;5201.635885488017;5260.421353651095;5057.645964782467;5199.66202196856;5203.817866374697;5240.580753539746;5565.413857050721;4016.8393264269394;4285.6142947134595;4379.299312110079;4405.779711014468;4438.490913089533;4714;5218.478152184789;5709.314882214119;5785.913211301834;6002.328963611195;3760.041189496661;4270.914581708348;4519.502854685982;4520.932186017212;4527.84152554659;4408.845690400821;4603.976980115078;4604.043281605304;4612.630989520831;4639.396878405853;4114.588541145921;4495.842645507457;4563.0873825235085;4798.23228283473;4923;4798.928016079762;5296.987144884919;5543.584231182677;5587.636803607753;5613.7193140342815;1352.594221733476;1379.4344318829233;1410.1891412437685;1491.784195880354;1566.9373225071097;1015.1675625986768;1044.8537204791335;1113.7080986056299;1175.4299164905185;1175.717827721358;4726.621870250372;4859.586935110555;4989.276554899519;5245.291885595472;5268.578513718893;4946.4064312282035;4949.430815456269;5736.6558006519745;5775;5775.335836378787;4448.5773851484055;4583.839565615188;4942.28336891156;5054.588152996713;5122.667026643257;2830.6320178376677;2993.925151871221;3038.875616022086;3040.4071206115036;3057.342671325676;5206.635535512478;5209.583233341324;5476.247600767796;5671.262735844396;5957.49361304294;4956.479569645207;5416.295881535375;5831.958814415006;5852;5974.820755377345;5103.030424219386;5646.774129034792;6045.0327947528185;6047.034713718059;6087.786927457518;4999.77501012448;5023.8492845214705;5057.609157481722;5228.481386645687;5635.718214089197;4877.609791216623;4949.7030178189425;4979.6428125390285;5027.396712394536;5406.324209473816;5147.716875571879;5387.515123638891;5834.912476312774;5925.89630681102;6012.745220542297;4461.665375096945;4467.687261891766;4478.59692627652;4482.238019536751;4504.932426013547;5067.746612669278;5456.699881506396;5720.971395142998;5768.769249229984;5840.503557197516;4815.638827087912;4863.294822250683;4873;5380.192971054216;5491.643043202179;4758.693382488853;5199.064168449564;5245.318108645852;5587.357453892854;5603.971980140073;4816.590589799766;4897.16748152822;4975.989526336659;4989.029333413354;5125.79496820123;4662.090892275974;4877.536843893811;5289.867753306198;5578.912112138257;5597.720113994365;4850.80596776125;4856.490068542894;5396.325459317585;5450.672959622435;5552.278203833475 08:48:42 INFO - PROCESS | 1642 | |5;yui.html;657.9407853293203;685.5578152091904;739.3604532079752;770.3452065744118;805.5005896344268;13031.934840325797;15003.924980375097;15696.21518924052;16381.754273685905;16458.341666333334;12643.05177111718;12778.913792327663;12795.744085118293;12796.104272700892;12815.807762883542;907.1880666803207;912.5938957164065;913.6528119314651;916.6287653500343;918.5407296351824;893.1604291965564;906.682661068624;911.1617312072875;913.7487191022456;931.2084727981196;960.9663661771839;967.908048735369;970.9271804614647;972.9902700973006;973.634886917406;954.5799848066863;957.4781743949565;959.0505399654362;959.203860795538;963.6675347005272;704.2429388407483;708.4544900426642;714.9571025738472;718.4575645387699;718.9137303523611;15719.607009824731;16229.918850405791;16278.83721162785;16960.3215871365;17154.485365438934;12970.870291297155;14629.534294313296;14631.70736585273;14739.189344586044;14926.776098358534;10064.849027264523;11761.529538818437;11860.762784744344;11910.880891191151;12056.156069075168;3637.4165655718343;3718.7025037997164;3746.269477451898;3757.380032294668;3879.18537107205;116100.03399795954;120038;121144;122162.3891880535;123270;11044.834327485094;11220.15848811326;11509.539618415338;11534.884651153381;11921.284722916653;24769.876150619493;24803.379915502257;24977.750222497907;25139.24582262535;25250.873745631154;7853.803654908559;7947.761567152995;7962.880556791652;7996.800079998046;8109.026916770025;4263.829446822155;4272.743635381887;4309.987153019038;4338.609525142752;4413.360062790877;3452.7755695879687;3549.8225088745976;3553.7157027437743;3560.590532088791;3574.3387473317;606.2845841906504;606.5753972219464;613.1691557939017;619.4301242856493;629.8393909553034;600.2766666167345;622.458461138803;639.5906619763355;641.1856941684035;666.2671061832043;8637.913620863837;8651;9289.34974551775;10024.047336333406;10318.27772055964;527.5726661404275;618.8205420428116;697.2574208467965;699.3146716218134;711.3578478191691;4124.896877578085;4681.6020638245445;4980.252962055648;4983.451820299764;4988.251762235621;96842;98321.50839245757;98890;100783.49608251912;103965;66049.66975165093;69214.65392673106;72006;74553.5089298211;82658.58670706728;53048.46951530435;65311;68220.3177968214;70870;79417.60291198507;10518.737031574272;10772.622958196425;10828.187885908746;10986.670399888017;11008.779824403628;44923;45749.31376029429;48535.271970921174;49528.00943981028;51073.48926510836;54397;62119.6894015527;64667.67666161827;68298.65850670914;68753.65623171852;13771.311434428038;13919;13941.58175254744;14104.224267665377;14232.573022809333;14339.667885618568;14420.530048154438;14889.106653600385;14976.925115374353;14985.925070375015;11248.381339026431;13518.59444216675;13664.93167534156;13957.3719182635;13959.371828267955;13552.525661601796;13676.589702308947;13736.862631373959;14170.167909795437;14241.21673307978;13229.213605549772;13287.270265427029;13356.398962046926;13543.932280338535;13826.861731382558;9344.486053267136;10944.671659850217;11026.779464410827;11161.44192790341;11205.60780372683;8560.871586926325;10199;11032.338059716443;11265.662030139109;11400.48697808584 08:48:42 INFO - PROCESS | 1642 | __end_tp_report 08:48:42 INFO - PROCESS | 1642 | __start_cc_report 08:48:42 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,443 08:48:42 INFO - PROCESS | 1642 | __end_cc_report 08:48:42 INFO - PROCESS | 1642 | __startTimestamp1489164522529__endTimestamp 08:48:42 INFO - PROCESS | 1642 | 08:48:42 INFO - PROCESS | 1642 | ------- Summary: start ------- 08:48:42 INFO - PROCESS | 1642 | Number of tests: 6 08:48:42 INFO - PROCESS | 1642 | 08:48:42 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5829.90 Median:6128.90 stddev:813.25 (13.3%) stddev-sans-first:794.22 08:48:42 INFO - PROCESS | 1642 | Values: 3460.8 3472.1 3492.8 3498.0 3514.9 5887.6 5986.7 5996.4 6012.9 6050.7 5876.6 5938.3 5940.2 5952.7 6046.5 5376.7 5383.2 5386.1 5471.3 5541.3 5418.5 5444.0 5454.9 5461.6 5461.8 148.8 4684.4 5299.7 5325.0 5453.8 5325.2 5334.0 5336.6 5364.2 5403.6 5314.9 5318.3 5337.4 5353.3 5366.9 5519.7 5702.7 5850.0 5922.7 6066.8 6217.9 6300.6 6303.7 6326.0 6341.5 6353.4 6389.7 6393.0 6395.6 6421.9 5853.4 5891.8 6083.5 6085.8 6115.4 6352.8 6366.8 6384.1 6387.2 6451.0 6176.7 6189.2 6213.5 6227.4 6229.4 5715.7 5786.1 5845.9 6102.7 6324.6 6223.2 6279.0 6288.7 6294.5 6295.3 6269.1 6275.8 6287.9 6291.7 6293.2 5013.8 5071.0 5115.8 5127.4 5185.2 3501.9 3585.4 4472.5 4527.8 4592.2 5103.8 6151.9 6157.2 6163.3 6169.6 5268.0 6281.7 6320.7 6336.7 6339.5 4933.5 5982.6 6124.1 6129.3 6167.7 4901.9 5591.3 5667.5 5667.6 5706.7 6609.8 6635.6 6646.6 6654.9 6669.3 6122.8 6177.1 6310.8 6332.8 6400.1 6475.8 6540.1 6588.7 6684.9 6689.7 6277.7 6280.1 6301.9 6312.6 6331.4 6139.7 6222.6 6316.0 6381.9 6414.1 6124.7 6127.0 6141.3 6173.3 6179.4 6179.3 6236.1 6243.6 6244.0 6247.3 6023.2 6083.6 6089.7 6101.8 6150.4 6127.3 6128.9 6190.5 6246.8 6250.3 6133.9 6148.4 6163.9 6172.5 6173.0 08:48:42 INFO - PROCESS | 1642 | 08:48:42 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:57073.51 Median:348604.00 stddev:115740.99 (33.2%) stddev-sans-first:115988.26 08:48:42 INFO - PROCESS | 1642 | Values: 691.9 782.2 818.1 844.3 908.4 36068.5 36700.4 36736.3 38319.4 38379.0 36580.5 36655.9 36678.6 36743.8 36809.8 7459.9 7607.8 10708.4 10942.6 11080.7 10622.8 10769.9 10885.7 10901.5 10914.5 9586.9 10883.7 10924.7 10937.6 10952.7 10865.7 10872.7 10910.2 10914.8 10926.4 10708.4 10771.3 10789.5 10792.6 10814.7 38154.0 38260.2 38348.7 38433.6 38451.4 3708.0 3740.9 3757.7 3780.9 3923.1 23565.1 26024.5 26898.7 27404.5 27951.2 2651.9 3034.2 3162.2 3255.0 3271.6 348351.0 348857.0 349359.0 349626.0 352124.2 33326.2 42047.8 49848.3 50232.7 50983.0 46222.8 51056.5 51312.5 53861.0 54403.7 12525.6 14154.5 14774.5 14915.0 15037.6 12152.8 12178.7 12183.0 12186.4 12187.2 13886.3 13897.6 13930.9 13960.0 14022.9 4982.4 5878.4 5884.2 5885.5 5899.3 1072.8 1109.8 1117.6 1130.5 1166.6 26244.6 26265.5 26268.9 26530.8 26602.6 990.6 1037.8 1085.5 1102.0 1181.3 12874.4 13648.1 13665.7 13779.9 13970.0 490533.0 503257.0 505828.0 506851.0 506875.0 45265.8 54717.7 55056.0 55368.4 55536.4 1146.6 1395.9 1401.1 1402.1 1407.7 24328.0 24638.6 25499.9 25791.6 25823.2 45852.4 45873.7 45888.1 46054.0 47374.8 368797.0 456074.0 458055.0 458979.0 459628.0 33987.5 36812.6 37433.5 37594.1 37647.9 44316.6 45739.8 47170.0 47201.3 47584.8 34608.5 35554.6 38357.8 39121.8 39354.4 31934.0 37011.7 37396.0 37601.8 39027.8 37527.8 38407.4 38647.8 40625.0 40670.6 29447.0 31635.7 34073.0 34083.7 34109.1 19556.3 22419.0 22773.2 25487.9 25593.0 08:48:42 INFO - PROCESS | 1642 | 08:48:42 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:36404.58 Median:21107.71 stddev:93807.01 (444.4%) stddev-sans-first:94031.16 08:48:42 INFO - PROCESS | 1642 | Values: 379.6 384.9 386.0 392.7 427.7 16152.9 20650.2 21852.9 21880.9 21974.3 21337.8 21963.5 21969.9 22047.9 22300.8 6422.1 6672.8 6689.4 6715.2 6719.4 6573.8 6689.0 7015.8 7320.0 7524.6 7381.9 7392.5 7469.7 7472.5 7483.4 6051.1 7246.5 7383.7 7415.9 7426.7 6042.8 6283.9 6746.1 6995.1 7065.0 25507.0 26054.5 26409.3 26545.5 26562.6 3189.4 3276.3 3323.9 3324.0 3419.1 15157.1 16074.0 16726.6 16801.2 17029.4 1987.2 1990.3 2025.3 2029.9 2035.4 146391.3 147530.5 147653.0 147669.8 149276.5 17142.0 17176.9 18635.9 20827.2 21769.5 25760.7 32198.8 33903.0 34476.3 34765.8 7921.7 9389.5 10408.5 10558.5 10657.9 8461.8 8560.4 8593.6 8760.9 8761.4 8502.0 10359.7 10407.6 10454.0 10572.6 3672.6 4471.2 4504.3 4509.8 4524.5 1040.9 1041.5 1045.0 1048.9 1095.3 18536.5 19184.8 20894.9 22546.0 23205.8 1006.7 1025.7 1074.8 1104.0 1107.0 11311.5 11834.1 12090.0 12139.3 12171.3 383445.0 385589.1 388913.1 724524.0 731073.0 126805.0 154175.0 157342.2 161347.6 161785.0 1149.2 1214.8 1225.5 1238.7 1241.1 19745.9 20031.0 20626.6 21320.6 21331.1 39736.6 59235.5 60723.5 61363.0 61367.4 79618.6 85824.6 86829.0 86848.6 87107.0 11242.7 11392.8 11539.0 11778.5 11927.5 11710.1 12062.7 12371.0 12885.9 13261.1 9565.7 10569.9 10895.3 11089.3 11172.6 9501.2 11080.5 11125.9 11422.7 11507.8 11536.9 11539.2 11626.4 11744.9 11863.3 9322.5 11389.8 11581.2 11709.2 11801.6 11086.5 11169.8 11310.2 11358.7 11403.0 08:48:42 INFO - PROCESS | 1642 | 08:48:42 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:6620.10 Median:3213.58 stddev:4227.24 (131.5%) stddev-sans-first:4221.69 08:48:42 INFO - PROCESS | 1642 | Values: 1514.6 1521.7 1554.7 1568.1 1568.6 9450.6 9665.0 10557.4 11237.9 11283.8 10596.5 10916.6 11184.8 11194.1 11198.6 1859.6 2309.7 2314.6 2324.8 2325.8 2264.8 2302.4 2305.5 2306.5 2308.8 1832.8 1900.7 2261.5 2270.7 2314.4 2098.2 2105.5 2112.3 2112.5 2117.9 2135.5 2271.5 2276.0 2280.5 2300.4 10898.4 10956.6 10963.9 10987.7 10987.9 5556.6 5715.9 5759.9 5762.8 5775.0 3259.0 3476.5 3616.3 3631.9 3674.3 1186.6 1192.4 1203.2 1249.1 1254.3 15067.3 15091.2 15165.8 15189.0 15270.0 4062.4 4494.7 4759.5 4837.4 4845.3 12595.0 12611.5 12673.0 12693.6 12694.0 5695.2 5741.7 5807.9 5814.8 5842.7 5038.7 5144.8 5161.8 5174.8 5294.7 3168.2 3715.0 3817.2 3839.6 3873.2 1015.8 1019.4 1030.9 1032.1 1036.7 847.5 885.2 905.8 921.7 954.4 5322.4 6440.2 6622.3 6725.7 6759.4 744.7 873.1 878.1 887.7 896.7 3382.0 3969.3 4040.5 4086.0 4148.7 13448.6 13664.2 13822.5 13851.2 13877.9 10345.7 12501.2 13658.5 13775.5 13788.7 9707.3 9881.4 10003.5 10145.9 10155.5 5486.7 6412.2 6509.2 6583.3 6599.9 10740.5 11115.1 12003.5 12116.9 12302.6 12227.8 12393.0 12640.0 12718.1 12760.4 8330.3 8499.8 8508.7 8512.9 8518.7 9878.9 10254.1 10317.3 10430.9 10431.6 8844.9 8983.2 9087.7 9100.8 9170.4 7536.7 8501.3 8674.3 8924.0 9022.3 7842.3 7936.4 7998.4 7999.8 8008.6 5713.8 5765.3 5799.2 5909.5 5982.2 4949.3 5306.2 5831.4 6002.0 6067.6 08:48:42 INFO - PROCESS | 1642 | 08:48:42 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4574.57 Median:4887.39 stddev:1260.81 (25.8%) stddev-sans-first:1223.19 08:48:42 INFO - PROCESS | 1642 | Values: 317.8 347.9 356.1 368.5 380.2 4782.1 4809.8 5159.6 5295.3 5338.7 4359.8 4643.9 4993.0 5086.2 5170.9 3938.4 4481.8 4488.8 4521.6 4547.2 4063.9 4218.6 4292.7 4481.8 4535.1 3755.5 4309.7 4408.8 4411.4 4433.5 3747.4 3952.9 4429.8 4430.4 4450.7 3607.5 4261.3 4264.9 4300.2 4351.3 3909.8 4165.7 4977.5 5087.6 5105.8 4949.7 4982.8 5185.6 5201.6 5260.4 5057.6 5199.7 5203.8 5240.6 5565.4 4016.8 4285.6 4379.3 4405.8 4438.5 4714.0 5218.5 5709.3 5785.9 6002.3 3760.0 4270.9 4519.5 4520.9 4527.8 4408.8 4604.0 4604.0 4612.6 4639.4 4114.6 4495.8 4563.1 4798.2 4923.0 4798.9 5297.0 5543.6 5587.6 5613.7 1352.6 1379.4 1410.2 1491.8 1566.9 1015.2 1044.9 1113.7 1175.4 1175.7 4726.6 4859.6 4989.3 5245.3 5268.6 4946.4 4949.4 5736.7 5775.0 5775.3 4448.6 4583.8 4942.3 5054.6 5122.7 2830.6 2993.9 3038.9 3040.4 3057.3 5206.6 5209.6 5476.2 5671.3 5957.5 4956.5 5416.3 5832.0 5852.0 5974.8 5103.0 5646.8 6045.0 6047.0 6087.8 4999.8 5023.8 5057.6 5228.5 5635.7 4877.6 4949.7 4979.6 5027.4 5406.3 5147.7 5387.5 5834.9 5925.9 6012.7 4461.7 4467.7 4478.6 4482.2 4504.9 5067.7 5456.7 5721.0 5768.8 5840.5 4815.6 4863.3 4873.0 5380.2 5491.6 4758.7 5199.1 5245.3 5587.4 5604.0 4816.6 4897.2 4976.0 4989.0 5125.8 4662.1 4877.5 5289.9 5578.9 5597.7 4850.8 4856.5 5396.3 5450.7 5552.3 08:48:42 INFO - PROCESS | 1642 | 08:48:42 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:20034.74 Median:3818.28 stddev:28796.80 (754.2%) stddev-sans-first:28840.82 08:48:42 INFO - PROCESS | 1642 | Values: 657.9 685.6 739.4 770.3 805.5 13031.9 15003.9 15696.2 16381.8 16458.3 12643.1 12778.9 12795.7 12796.1 12815.8 907.2 912.6 913.7 916.6 918.5 893.2 906.7 911.2 913.7 931.2 961.0 967.9 970.9 973.0 973.6 954.6 957.5 959.1 959.2 963.7 704.2 708.5 715.0 718.5 718.9 15719.6 16229.9 16278.8 16960.3 17154.5 12970.9 14629.5 14631.7 14739.2 14926.8 10064.8 11761.5 11860.8 11910.9 12056.2 3637.4 3718.7 3746.3 3757.4 3879.2 116100.0 120038.0 121144.0 122162.4 123270.0 11044.8 11220.2 11509.5 11534.9 11921.3 24769.9 24803.4 24977.8 25139.2 25250.9 7853.8 7947.8 7962.9 7996.8 8109.0 4263.8 4272.7 4310.0 4338.6 4413.4 3452.8 3549.8 3553.7 3560.6 3574.3 606.3 606.6 613.2 619.4 629.8 600.3 622.5 639.6 641.2 666.3 8637.9 8651.0 9289.3 10024.0 10318.3 527.6 618.8 697.3 699.3 711.4 4124.9 4681.6 4980.3 4983.5 4988.3 96842.0 98321.5 98890.0 100783.5 103965.0 66049.7 69214.7 72006.0 74553.5 82658.6 53048.5 65311.0 68220.3 70870.0 79417.6 10518.7 10772.6 10828.2 10986.7 11008.8 44923.0 45749.3 48535.3 49528.0 51073.5 54397.0 62119.7 64667.7 68298.7 68753.7 13771.3 13919.0 13941.6 14104.2 14232.6 14339.7 14420.5 14889.1 14976.9 14985.9 11248.4 13518.6 13664.9 13957.4 13959.4 13552.5 13676.6 13736.9 14170.2 14241.2 13229.2 13287.3 13356.4 13543.9 13826.9 9344.5 10944.7 11026.8 11161.4 11205.6 8560.9 10199.0 11032.3 11265.7 11400.5 08:48:42 INFO - PROCESS | 1642 | -------- Summary: end -------- 08:48:42 INFO - PROCESS | 1642 | 08:48:43 INFO - TEST-INFO | 1642: exit 0 08:48:43 INFO - TEST-OK | dromaeo_css | took 1117708ms 08:48:43 INFO - TEST-START | kraken 08:48:43 INFO - operating with platform_type : mac_ 08:48:43 INFO - Initialising browser for kraken test... 08:48:43 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/tmpRnBM0D/profile http://localhost:49240/getInfo.html) 08:48:46 INFO - PROCESS | 1653 | 2017-03-10 08:48:46.272 plugin-container[1655:10277] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 08:48:46 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:48:46 INFO - PROCESS | 1653 | 2017-03-10 08:48:46.272 plugin-container[1655:10277] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 08:48:46 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:48:46 INFO - PROCESS | 1653 | 2017-03-10 08:48:46.272 plugin-container[1655:10277] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:48:46 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 08:48:46 INFO - PROCESS | 1653 | colorDepth:24 08:48:46 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 08:48:46 INFO - PROCESS | 1653 | __metrics 08:48:46 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 08:48:47 INFO - TEST-INFO | 1653: exit 0 08:48:47 INFO - Browser initialized. 08:48:47 INFO - Running cycle 1/1 for kraken test... 08:48:47 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/tmpRnBM0D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:48:49 INFO - PROCESS | 1656 | 2017-03-10 08:48:49.588 plugin-container[1657:10411] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 08:48:49 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:48:49 INFO - PROCESS | 1656 | 2017-03-10 08:48:49.588 plugin-container[1657:10411] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.CFPasteboardClient' 08:48:49 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:48:49 INFO - PROCESS | 1656 | 2017-03-10 08:48:49.588 plugin-container[1657:10411] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:48:59 INFO - PROCESS | 1656 | RSS: Main: 227094528 08:48:59 INFO - PROCESS | 1656 | 08:49:02 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 08:49:03 INFO - PROCESS | 1656 | RSS: Main: 241328128 08:49:03 INFO - PROCESS | 1656 | 08:49:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 08:49:06 INFO - PROCESS | 1656 | RSS: Main: 249847808 08:49:06 INFO - PROCESS | 1656 | 08:49:10 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 08:49:11 INFO - PROCESS | 1656 | RSS: Main: 252538880 08:49:11 INFO - PROCESS | 1656 | 08:49:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 08:49:13 INFO - PROCESS | 1656 | RSS: Main: 254812160 08:49:13 INFO - PROCESS | 1656 | 08:49:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:49:16 INFO - PROCESS | 1656 | RSS: Main: 255332352 08:49:16 INFO - PROCESS | 1656 | 08:49:19 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 08:49:19 INFO - PROCESS | 1656 | RSS: Main: 260751360 08:49:19 INFO - PROCESS | 1656 | 08:49:23 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 08:49:23 INFO - PROCESS | 1656 | RSS: Main: 261746688 08:49:23 INFO - PROCESS | 1656 | 08:49:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 08:49:27 INFO - PROCESS | 1656 | RSS: Main: 261410816 08:49:27 INFO - PROCESS | 1656 | 08:49:29 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:49:29 INFO - PROCESS | 1656 | RSS: Main: 260825088 08:49:29 INFO - PROCESS | 1656 | 08:49:31 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:49:32 INFO - PROCESS | 1656 | RSS: Main: 261001216 08:49:32 INFO - PROCESS | 1656 | 08:49:34 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:49:35 INFO - PROCESS | 1656 | RSS: Main: 260861952 08:49:35 INFO - PROCESS | 1656 | 08:49:38 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:49:38 INFO - PROCESS | 1656 | RSS: Main: 261193728 08:49:38 INFO - PROCESS | 1656 | 08:49:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:49:42 INFO - PROCESS | 1656 | RSS: Main: 261316608 08:49:42 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 08:49:44 INFO - PROCESS | 1656 | RSS: Main: 264560640 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | __start_tp_report 08:49:44 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 08:49:44 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 08:49:44 INFO - PROCESS | 1656 | |i|pagename|runs| 08:49:44 INFO - PROCESS | 1656 | |0;ai-astar;108;129;159;120;121;120;120;121;123;147 08:49:44 INFO - PROCESS | 1656 | |1;audio-beat-detection;148;117;144;191;139;115;114;115;156;109 08:49:44 INFO - PROCESS | 1656 | |2;audio-dft;274;243;239;265;285;195;288;274;200;199 08:49:44 INFO - PROCESS | 1656 | |3;audio-fft;84;62;62;87;63;63;64;63;78;65 08:49:44 INFO - PROCESS | 1656 | |4;audio-oscillator;88;73;87;76;71;72;83;73;71;86 08:49:44 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;81;90;89;100;90;92;91;97;135;138 08:49:44 INFO - PROCESS | 1656 | |6;imaging-darkroom;98;99;98;96;129;97;116;97;97;154 08:49:44 INFO - PROCESS | 1656 | |7;imaging-desaturate;92;101;90;90;91;97;103;145;143;90 08:49:44 INFO - PROCESS | 1656 | |8;json-parse-financial;67;67;68;68;69;67;68;67;67;69 08:49:44 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;63;57;63;67;65;62;60;59;61;61 08:49:44 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;87;88;88;89;88;84;89;90;88;86 08:49:44 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;144;148;149;168;154;150;143;166;205;162 08:49:44 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;176;176;209;161;164;180;211;239;224;179 08:49:44 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;68;66;68;66;68;70;71;70;86;67 08:49:44 INFO - PROCESS | 1656 | __end_tp_report 08:49:44 INFO - PROCESS | 1656 | __start_cc_report 08:49:44 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1012 08:49:44 INFO - PROCESS | 1656 | __end_cc_report 08:49:44 INFO - PROCESS | 1656 | __startTimestamp1489164584745__endTimestamp 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | ------- Summary: start ------- 08:49:44 INFO - PROCESS | 1656 | Number of tests: 14 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:126.80 Median:122.00 stddev:14.99 (12.3%) stddev-sans-first:14.28 08:49:44 INFO - PROCESS | 1656 | Values: 108.0 129.0 159.0 120.0 121.0 120.0 120.0 121.0 123.0 147.0 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:141.50 stddev:26.00 (18.4%) stddev-sans-first:27.13 08:49:44 INFO - PROCESS | 1656 | Values: 148.0 117.0 144.0 191.0 139.0 115.0 114.0 115.0 156.0 109.0 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:246.20 Median:269.50 stddev:36.81 (13.7%) stddev-sans-first:37.65 08:49:44 INFO - PROCESS | 1656 | Values: 274.0 243.0 239.0 265.0 285.0 195.0 288.0 274.0 200.0 199.0 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:69.10 Median:64.50 stddev:9.87 (15.3%) stddev-sans-first:8.88 08:49:44 INFO - PROCESS | 1656 | Values: 84.0 62.0 62.0 87.0 63.0 63.0 64.0 63.0 78.0 65.0 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:79.50 stddev:7.13 (9.0%) stddev-sans-first:6.58 08:49:44 INFO - PROCESS | 1656 | Values: 88.0 73.0 87.0 76.0 71.0 72.0 83.0 73.0 71.0 86.0 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:100.30 Median:90.00 stddev:19.73 (21.9%) stddev-sans-first:19.65 08:49:44 INFO - PROCESS | 1656 | Values: 81.0 90.0 89.0 100.0 90.0 92.0 91.0 97.0 135.0 138.0 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:108.10 Median:97.00 stddev:19.46 (20.1%) stddev-sans-first:20.30 08:49:44 INFO - PROCESS | 1656 | Values: 98.0 99.0 98.0 96.0 129.0 97.0 116.0 97.0 97.0 154.0 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:104.20 Median:90.00 stddev:21.50 (23.9%) stddev-sans-first:22.35 08:49:44 INFO - PROCESS | 1656 | Values: 92.0 101.0 90.0 90.0 91.0 97.0 103.0 145.0 143.0 90.0 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:67.70 Median:68.00 stddev:0.82 (1.2%) stddev-sans-first:0.83 08:49:44 INFO - PROCESS | 1656 | Values: 67.0 67.0 68.0 68.0 69.0 67.0 68.0 67.0 67.0 69.0 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:61.80 Median:62.50 stddev:2.90 (4.6%) stddev-sans-first:3.04 08:49:44 INFO - PROCESS | 1656 | Values: 63.0 57.0 63.0 67.0 65.0 62.0 60.0 59.0 61.0 61.0 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:88.00 stddev:1.70 (1.9%) stddev-sans-first:1.79 08:49:44 INFO - PROCESS | 1656 | Values: 87.0 88.0 88.0 89.0 88.0 84.0 89.0 90.0 88.0 86.0 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:158.90 Median:158.00 stddev:18.45 (11.7%) stddev-sans-first:18.76 08:49:44 INFO - PROCESS | 1656 | Values: 144.0 148.0 149.0 168.0 154.0 150.0 143.0 166.0 205.0 162.0 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.90 Median:194.50 stddev:26.78 (13.8%) stddev-sans-first:27.78 08:49:44 INFO - PROCESS | 1656 | Values: 176.0 176.0 209.0 161.0 164.0 180.0 211.0 239.0 224.0 179.0 08:49:44 INFO - PROCESS | 1656 | 08:49:44 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:69.00 stddev:5.87 (8.5%) stddev-sans-first:6.18 08:49:44 INFO - PROCESS | 1656 | Values: 68.0 66.0 68.0 66.0 68.0 70.0 71.0 70.0 86.0 67.0 08:49:44 INFO - PROCESS | 1656 | -------- Summary: end -------- 08:49:44 INFO - PROCESS | 1656 | 08:49:45 INFO - TEST-INFO | 1656: exit 0 08:49:45 INFO - TEST-OK | kraken | took 62471ms 08:49:45 INFO - SUITE-END | took 1180s 08:49:45 INFO - Completed test suite (00:19:40) 08:49:45 INFO - javascript benchmark 08:49:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7646.328661299411, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3460.775049621774, 3472.114610774252, 3492.790432574044, 3497.9650203497968, 3514.876979305723, 5887.646741195531, 5986.670733109677, 5996.370381109986, 6012.909806352908, 6050.697465126748, 5876.618019828708, 5938.257717785277, 5940.168376427293, 5952.672603006833, 6046.486048685856, 5376.731163441832, 5383.246345511631, 5386.084365657847, 5471.316085489314, 5541.251930989325, 5418.485243901823, 5444.047291723952, 5454.863628409282, 5461.590380721462, 5461.808836690717, 148.79230869795438, 4684.437867455893, 5299.682019078868, 5324.9733751331, 5453.7818487260465, 5325.227841962933, 5333.959877823826, 5336.59975501835, 5364.168553874156, 5403.621746477748, 5314.893702125974, 5318.308619879391, 5337.359516857963, 5353.304070470853, 5366.865828354284, 5519.724013799334, 5702.714864256812, 5849.970750146264, 5922.703864806743, 6066.757329706807, 6217.875642487125, 6300.59046161998, 6303.684815759239, 6326.0, 6341.524385671047, 6353.364663533656, 6389.712462939179, 6393.0, 6395.648239346834, 6421.93578064218, 5853.414658534112, 5891.793787217427, 6083.452489275942, 6085.847853803646, 6115.4190351916495, 6352.777652782131, 6366.77716279928, 6384.074309225136, 6387.201599800025, 6451.032345148265, 6176.69116544171, 6189.226346706661, 6213.5339849511465, 6227.408396202353, 6229.4082062204925, 5715.657060576379, 5786.132080188006, 5845.941540584539, 6102.67103564696, 6324.620522768649, 6223.222097237845, 6279.0, 6288.685565721696, 6294.464970477469, 6295.307516173218, 6269.059641053878, 6275.843103922439, 6287.937120628827, 6291.653959032205, 6293.244810622755, 5013.774380152829, 5071.0, 5115.7953681852305, 5127.359080114986, 5185.170372740344, 3501.9299614007577, 3585.3546361655153, 4472.485664148599, 4527.777500075027, 4592.173408786451, 5103.770330335144, 6151.9234134026365, 6157.168782214433, 6163.291221509494, 6169.598976066542, 5267.973660131752, 6281.685915704288, 6320.71556779946, 6336.6831658416895, 6339.524535659844, 4933.45731969483, 5982.581219314693, 6124.081387791778, 6129.325774164839, 6167.691615419211, 4901.901961960741, 5591.27313449249, 5667.546596272371, 5667.631603945731, 5706.743196556165, 6609.834754131185, 6635.6350400728425, 6646.60120392778, 6654.933450665625, 6669.2997235289595, 6122.825106970096, 6177.135201071944, 6310.842228944313, 6332.778352757631, 6400.135981642418, 6475.838104047437, 6540.149780528501, 6588.7364505421165, 6684.899726504009, 6689.665516724242, 6277.654728989949, 6280.057991301342, 6301.873962520633, 6312.558120931674, 6331.430171284606, 6139.737467782926, 6222.564420490522, 6315.96842015787, 6381.8723625528155, 6414.102025716496, 6124.6860923983895, 6127.036821038878, 6141.301904043499, 6173.259208894961, 6179.443850053517, 6179.2584889811715, 6236.126942228001, 6243.562950593415, 6244.0, 6247.3440288769025, 6023.186869772525, 6083.6045657032155, 6089.69551522431, 6101.816945491642, 6150.35421280777, 6127.342242870382, 6128.900367973482, 6190.50475961931, 6246.750129994749, 6250.312465628868, 6133.877322453615, 6148.415900489446, 6163.938360616516, 6172.537059720449, 6173.012318029094], "value": 5778.553762767729, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.8512519808241, 782.2255966593075, 818.1450384348356, 844.3076677124757, 908.4413085952142, 36068.45897311539, 36700.44949325759, 36736.265274694524, 38319.425208621826, 38379.0, 36580.45129323056, 36655.90032299029, 36678.633213667854, 36743.81628091856, 36809.815950920274, 7459.925400745991, 7607.820787777903, 10708.41103739294, 10942.617008404708, 11080.667579972614, 10622.778380486227, 10769.946150269237, 10885.673429797118, 10901.454927253606, 10914.454277286144, 9586.856197157049, 10883.727906802314, 10924.726881827939, 10937.617183398583, 10952.726181845477, 10865.728356791144, 10872.728181795439, 10910.181736369725, 10914.836277455845, 10926.399048052353, 10708.357498550035, 10771.299865508796, 10789.514471848786, 10792.622258221003, 10814.675559733221, 38154.0, 38260.234795303935, 38348.65779697697, 38433.61566384328, 38451.423228651314, 3707.9814600927093, 3740.9438858417147, 3757.7369584129124, 3780.8676696315497, 3923.136909879822, 23565.05739770407, 26024.479510409874, 26898.731012690016, 27404.451910961867, 27951.161465156078, 2651.9204423867313, 3034.150437877398, 3162.1936406216496, 3255.0234929521284, 3271.574695289597, 348351.0, 348857.0, 349359.0, 349626.0, 352124.23937880405, 33326.16684582905, 42047.789761051, 49848.25531106394, 50232.74883625558, 50983.0, 46222.768886156024, 51056.48943510592, 51312.48687513077, 53861.0, 54403.72798136063, 12525.56160534377, 14154.504592339425, 14774.482893098904, 14914.955953083398, 15037.624059398384, 12152.817707734392, 12178.695532611755, 12183.0, 12186.390680465942, 12187.207831490925, 13886.305684715724, 13897.583072507841, 13930.860691392956, 13960.022798404218, 14022.948278879125, 4982.402111746614, 5878.412158784173, 5884.176215329857, 5885.499732522783, 5899.345294617354, 1072.8444375565655, 1109.8113320735495, 1117.6400524079927, 1130.4856290387854, 1166.597523854269, 26244.60633090505, 26265.474690506082, 26268.904288219528, 26530.806113724928, 26602.6009609856, 990.5889056041661, 1037.7976294622626, 1085.46812062089, 1102.0, 1181.255808840425, 12874.356282185854, 13648.112872663245, 13665.65191528082, 13779.931100344435, 13970.0, 490533.0, 503257.0, 505828.0, 506851.0, 506875.0, 45265.773671131436, 54717.72641136769, 55056.0, 55368.446315537134, 55536.44463555393, 1146.6158836789602, 1395.8673925977016, 1401.1312985948573, 1402.0816365280757, 1407.7254935287726, 24328.026878924644, 24638.630420544065, 25499.852506637617, 25791.613125803502, 25823.22530324093, 45852.41475852388, 45873.7245216791, 45888.08223835572, 46054.0, 47374.763126184145, 368797.0, 456074.0, 458055.0, 458979.0, 459628.0, 33987.4901876477, 36812.63187368199, 37433.50265989439, 37594.06014849541, 37647.87056388313, 44316.55683443124, 45739.77130114328, 47170.0, 47201.29198061963, 47584.7620761894, 34608.48087278743, 35554.644453555135, 38357.80821095876, 39121.804390977864, 39354.40968385534, 31934.0, 37011.704590339206, 37396.0, 37601.811990939874, 39027.80486097551, 37527.81236093802, 38407.42388864225, 38647.8405647831, 40625.0, 40670.59329406668, 29447.0, 31635.683643163273, 34073.0, 34083.659163409044, 34109.14727131841, 19556.315528956417, 22419.0, 22773.202937897095, 25487.87256063708, 25593.0], "value": 18984.889973029953, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.63744623884196, 384.8691444908731, 385.97877116758565, 392.67996582785025, 427.7433539876073, 16152.919235403806, 20650.17399304028, 21852.907354632243, 21880.90595470228, 21974.34076977691, 21337.786622133775, 21963.450913727163, 21969.890150549225, 22047.889760551174, 22300.776992230112, 6422.068800024005, 6672.799816005528, 6689.397954184123, 6715.160604924385, 6719.395254427101, 6573.769918052869, 6689.030090636855, 7015.789526314219, 7320.04839370881, 7524.586147761871, 7381.8892716609025, 7392.482526223166, 7469.738559150431, 7472.514286571382, 7483.438742094338, 6051.0923361496125, 7246.456515761312, 7383.704651813948, 7415.925840741632, 7426.665800039012, 6042.818715438544, 6283.905741413882, 6746.089277947463, 6995.090638217, 7064.964675176642, 25507.0, 26054.478910421687, 26409.3397665058, 26545.46909061808, 26562.6015609766, 3189.4259033373983, 3276.3283526876935, 3323.88366407177, 3323.9501407478906, 3419.1110311319235, 15157.105361829772, 16073.955192912423, 16726.58183545409, 16801.15994200285, 17029.40397086096, 1987.2448469581611, 1990.3133418970433, 2025.341763926715, 2029.9492512687152, 2035.3792093411503, 146391.2680436591, 147530.52469475276, 147653.0, 147669.78495322578, 149276.50723492735, 17142.0, 17176.914115429343, 18635.881847088946, 20827.1669133236, 21769.45576360572, 25760.742392575834, 32198.83900580482, 33903.0, 34476.31047379089, 34765.82617086898, 7921.683132674629, 9389.483578403117, 10408.531616077295, 10558.472076396149, 10657.946710266555, 8461.830763384698, 8560.443571167854, 8593.613287401957, 8760.912390876138, 8761.38670293086, 8501.95749021251, 10359.741006474898, 10407.635732749317, 10454.0, 10572.577096916037, 3672.559292884871, 4471.195184866755, 4504.25679762842, 4509.842155524607, 4524.502304746476, 1040.8542804007445, 1041.4792603698152, 1044.9634262800766, 1048.940570024281, 1095.3318475729798, 18536.536586585444, 19184.808151918303, 20894.850783206766, 22546.0, 23205.76794232036, 1006.7080546641538, 1025.7127305231886, 1074.7581794096423, 1103.9558417663204, 1106.983395249072, 11311.547538098384, 11834.112441566916, 12090.0, 12139.27164370123, 12171.33057681836, 383445.0, 385589.07205464353, 388913.055434721, 724524.0, 731073.0, 126805.0, 154175.0, 157342.21328893283, 161347.5797863033, 161785.0, 1149.1553708024596, 1214.7631211913592, 1225.4975460061332, 1238.6531771104105, 1241.069198101424, 19745.901270493556, 20031.0, 20626.58746825085, 21320.573588528452, 21331.14675413028, 39736.60263397329, 59235.51911202254, 60723.48191295253, 61362.99999999822, 61367.38632613617, 79618.60190699241, 85824.57087714522, 86829.0, 86848.56575717333, 87107.0, 11242.718932026437, 11392.829107563228, 11539.0, 11778.52885884555, 11927.463264153308, 11710.1217408693, 12062.698432538906, 12371.0, 12885.935570322088, 13261.071724979545, 9565.713028609152, 10569.947150264199, 10895.346279223271, 11089.27919685218, 11172.55309787632, 9501.192398646193, 11080.501377438195, 11125.944370278097, 11422.714432139262, 11507.769844602893, 11536.94231528837, 11539.192256542297, 11626.418679066182, 11744.882551174378, 11863.34751588671, 9322.533873306444, 11389.829152562885, 11581.214222125058, 11709.238899471848, 11801.645950621494, 11086.501107450345, 11169.776604467703, 11310.208285419942, 11358.659240222807, 11403.0], "value": 11557.70419405977, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1514.6440586462186, 1521.695660867826, 1554.6735185611021, 1568.1296880231473, 1568.5529624057144, 9450.574724137414, 9664.951675241615, 10557.366558006515, 11237.943810280958, 11283.774324513504, 10596.470176491164, 10916.617918372858, 11184.832227516594, 11194.10447164227, 11198.608048718297, 1859.5815941413175, 2309.7459279479244, 2314.6412306092584, 2324.837261391703, 2325.837191396603, 2264.830137739668, 2302.4128847143957, 2305.4582173189287, 2306.457982374149, 2308.7575804540447, 1832.825881541258, 1900.695888657815, 2261.5363850410627, 2270.7048083749173, 2314.4445333120016, 2098.1502491490896, 2105.547307328924, 2112.3346145963974, 2112.4718820294925, 2117.8835164065895, 2135.47680818199, 2271.4775601611555, 2276.0099356779856, 2280.45269135408, 2300.3903965449167, 10898.400587967659, 10956.56173753049, 10963.945180274128, 10987.670369888836, 10987.945060274727, 5556.638818476786, 5715.942840571583, 5759.913601295984, 5762.769489220426, 5774.971125144348, 3259.0, 3476.4959080933377, 3616.3129005488872, 3631.8728844490315, 3674.2651469706166, 1186.584695356627, 1192.4455128365353, 1203.2299328429724, 1249.119370843553, 1254.2537190371713, 15067.321970511353, 15091.169985650784, 15165.772513412197, 15189.0, 15270.0, 4062.431259623655, 4494.662900282427, 4759.4764575896625, 4837.443693975168, 4845.2732090186755, 12594.992400607929, 12611.495540178474, 12673.04952128594, 12693.619191424272, 12694.0, 5695.202671625976, 5741.655500669973, 5807.941920580824, 5814.767409303665, 5842.678652674143, 5038.697678139323, 5144.819931302386, 5161.793528258828, 5174.818881339135, 5294.708791016531, 3168.1604374840686, 3714.9969508232625, 3817.1983883384246, 3839.6160383961937, 3873.2253549290253, 1015.7968406318765, 1019.3527110284874, 1030.948452577383, 1032.0659802878256, 1036.6838114375037, 847.5126802088823, 885.176785589408, 905.8460061789511, 921.7326975177124, 954.4273435938381, 5322.4411436799355, 6440.194975628046, 6622.337766223339, 6725.6973436195485, 6759.391654751096, 744.7393412305757, 873.1486800369715, 878.0955615715823, 887.6759982606302, 896.7444278380631, 3382.002309318757, 3969.325214713506, 4040.4949381327333, 4085.9795701021303, 4148.688848336386, 13448.596542103556, 13664.180149191081, 13822.516211932736, 13851.16892986424, 13877.930610347086, 10345.74135646615, 12501.24992500453, 13658.453661853415, 13775.517856874962, 13788.724225515434, 9707.271954603433, 9881.407115573089, 10003.549840257207, 10145.898541014496, 10155.476678498431, 5486.725663716718, 6412.198475190601, 6509.1863517060365, 6583.30875258091, 6599.901001485077, 10740.462976851282, 11115.055220306042, 12003.459844307201, 12116.939415303219, 12302.569410070604, 12227.78979552084, 12392.955162398104, 12639.988800895722, 12718.10973231865, 12760.4257808397, 8330.291925186428, 8499.83000340002, 8508.74473765788, 8512.914870851213, 8518.74443766688, 9878.851817222603, 10254.128399086161, 10317.27779055459, 10430.895691043295, 10431.634892778717, 8844.867326990228, 8983.191512763882, 9087.727368178965, 9100.77031127378, 9170.403923744936, 7536.718757811075, 8501.277391421798, 8674.30605551567, 8923.973743019376, 9022.323325750725, 7842.29419352261, 7936.365090792839, 7998.360131189607, 7999.84000320002, 8008.639611217625, 5713.800016999551, 5765.250517432707, 5799.217105690678, 5909.468147866713, 5982.222311099529, 4949.30709700635, 5306.230596563554, 5831.446012628963, 6002.039673652194, 6067.605605635619], "value": 4989.889216170798, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.807726325573, 347.9008482582463, 356.07953440356675, 368.5466875742836, 380.21675348781514, 4782.091402633498, 4809.807607695692, 5159.646229950525, 5295.285136506571, 5338.733063346836, 4359.803808828602, 4643.930341044878, 4993.0, 5086.211637196231, 5170.9482905171035, 3938.4289278054666, 4481.843135490259, 4488.753118578493, 4521.638268938477, 4547.158026233604, 4063.878083657495, 4218.557051509593, 4292.742435453867, 4481.7983190756495, 4535.115652447774, 3755.530558680165, 4309.676774241929, 4408.7956021989, 4411.382406463098, 4433.4901486329, 3747.4191500317493, 3952.8536724349906, 4429.800658970338, 4430.363933459618, 4450.666200035011, 3607.5310209672575, 4261.2542805009, 4264.936025959613, 4300.247456695066, 4351.26028575143, 3909.7654140751647, 4165.708400411972, 4977.48186803025, 5087.643864929457, 5105.795768169269, 4949.7030178189425, 4982.82560110398, 5185.55922746567, 5201.635885488017, 5260.421353651095, 5057.645964782467, 5199.66202196856, 5203.817866374697, 5240.580753539746, 5565.413857050721, 4016.8393264269394, 4285.6142947134595, 4379.299312110079, 4405.779711014468, 4438.490913089533, 4714.0, 5218.478152184789, 5709.314882214119, 5785.913211301834, 6002.328963611195, 3760.041189496661, 4270.914581708348, 4519.502854685982, 4520.932186017212, 4527.84152554659, 4408.845690400821, 4603.976980115078, 4604.043281605304, 4612.630989520831, 4639.396878405853, 4114.588541145921, 4495.842645507457, 4563.0873825235085, 4798.23228283473, 4923.0, 4798.928016079762, 5296.987144884919, 5543.584231182677, 5587.636803607753, 5613.7193140342815, 1352.594221733476, 1379.4344318829233, 1410.1891412437685, 1491.784195880354, 1566.9373225071097, 1015.1675625986768, 1044.8537204791335, 1113.7080986056299, 1175.4299164905185, 1175.717827721358, 4726.621870250372, 4859.586935110555, 4989.276554899519, 5245.291885595472, 5268.578513718893, 4946.4064312282035, 4949.430815456269, 5736.6558006519745, 5775.0, 5775.335836378787, 4448.5773851484055, 4583.839565615188, 4942.28336891156, 5054.588152996713, 5122.667026643257, 2830.6320178376677, 2993.925151871221, 3038.875616022086, 3040.4071206115036, 3057.342671325676, 5206.635535512478, 5209.583233341324, 5476.247600767796, 5671.262735844396, 5957.49361304294, 4956.479569645207, 5416.295881535375, 5831.958814415006, 5852.0, 5974.820755377345, 5103.030424219386, 5646.774129034792, 6045.0327947528185, 6047.034713718059, 6087.786927457518, 4999.77501012448, 5023.8492845214705, 5057.609157481722, 5228.481386645687, 5635.718214089197, 4877.609791216623, 4949.7030178189425, 4979.6428125390285, 5027.396712394536, 5406.324209473816, 5147.716875571879, 5387.515123638891, 5834.912476312774, 5925.89630681102, 6012.745220542297, 4461.665375096945, 4467.687261891766, 4478.59692627652, 4482.238019536751, 4504.932426013547, 5067.746612669278, 5456.699881506396, 5720.971395142998, 5768.769249229984, 5840.503557197516, 4815.638827087912, 4863.294822250683, 4873.0, 5380.192971054216, 5491.643043202179, 4758.693382488853, 5199.064168449564, 5245.318108645852, 5587.357453892854, 5603.971980140073, 4816.590589799766, 4897.16748152822, 4975.989526336659, 4989.029333413354, 5125.79496820123, 4662.090892275974, 4877.536843893811, 5289.867753306198, 5578.912112138257, 5597.720113994365, 4850.80596776125, 4856.490068542894, 5396.325459317585, 5450.672959622435, 5552.278203833475], "value": 4196.613644844431, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.9407853293203, 685.5578152091904, 739.3604532079752, 770.3452065744118, 805.5005896344268, 13031.934840325797, 15003.924980375097, 15696.21518924052, 16381.754273685905, 16458.341666333334, 12643.05177111718, 12778.913792327663, 12795.744085118293, 12796.104272700892, 12815.807762883542, 907.1880666803207, 912.5938957164065, 913.6528119314651, 916.6287653500343, 918.5407296351824, 893.1604291965564, 906.682661068624, 911.1617312072875, 913.7487191022456, 931.2084727981196, 960.9663661771839, 967.908048735369, 970.9271804614647, 972.9902700973006, 973.634886917406, 954.5799848066863, 957.4781743949565, 959.0505399654362, 959.203860795538, 963.6675347005272, 704.2429388407483, 708.4544900426642, 714.9571025738472, 718.4575645387699, 718.9137303523611, 15719.607009824731, 16229.918850405791, 16278.83721162785, 16960.3215871365, 17154.485365438934, 12970.870291297155, 14629.534294313296, 14631.70736585273, 14739.189344586044, 14926.776098358534, 10064.849027264523, 11761.529538818437, 11860.762784744344, 11910.880891191151, 12056.156069075168, 3637.4165655718343, 3718.7025037997164, 3746.269477451898, 3757.380032294668, 3879.18537107205, 116100.03399795954, 120038.0, 121144.0, 122162.3891880535, 123270.0, 11044.834327485094, 11220.15848811326, 11509.539618415338, 11534.884651153381, 11921.284722916653, 24769.876150619493, 24803.379915502257, 24977.750222497907, 25139.24582262535, 25250.873745631154, 7853.803654908559, 7947.761567152995, 7962.880556791652, 7996.800079998046, 8109.026916770025, 4263.829446822155, 4272.743635381887, 4309.987153019038, 4338.609525142752, 4413.360062790877, 3452.7755695879687, 3549.8225088745976, 3553.7157027437743, 3560.590532088791, 3574.3387473317, 606.2845841906504, 606.5753972219464, 613.1691557939017, 619.4301242856493, 629.8393909553034, 600.2766666167345, 622.458461138803, 639.5906619763355, 641.1856941684035, 666.2671061832043, 8637.913620863837, 8651.0, 9289.34974551775, 10024.047336333406, 10318.27772055964, 527.5726661404275, 618.8205420428116, 697.2574208467965, 699.3146716218134, 711.3578478191691, 4124.896877578085, 4681.6020638245445, 4980.252962055648, 4983.451820299764, 4988.251762235621, 96842.0, 98321.50839245757, 98890.0, 100783.49608251912, 103965.0, 66049.66975165093, 69214.65392673106, 72006.0, 74553.5089298211, 82658.58670706728, 53048.46951530435, 65311.0, 68220.3177968214, 70870.0, 79417.60291198507, 10518.737031574272, 10772.622958196425, 10828.187885908746, 10986.670399888017, 11008.779824403628, 44923.0, 45749.31376029429, 48535.271970921174, 49528.00943981028, 51073.48926510836, 54397.0, 62119.6894015527, 64667.67666161827, 68298.65850670914, 68753.65623171852, 13771.311434428038, 13919.0, 13941.58175254744, 14104.224267665377, 14232.573022809333, 14339.667885618568, 14420.530048154438, 14889.106653600385, 14976.925115374353, 14985.925070375015, 11248.381339026431, 13518.59444216675, 13664.93167534156, 13957.3719182635, 13959.371828267955, 13552.525661601796, 13676.589702308947, 13736.862631373959, 14170.167909795437, 14241.21673307978, 13229.213605549772, 13287.270265427029, 13356.398962046926, 13543.932280338535, 13826.861731382558, 9344.486053267136, 10944.671659850217, 11026.779464410827, 11161.44192790341, 11205.60780372683, 8560.871586926325, 10199.0, 11032.338059716443, 11265.662030139109, 11400.48697808584], "value": 7526.377944720455, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1605.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 129.0, 159.0, 120.0, 121.0, 120.0, 120.0, 121.0, 123.0, 147.0], "value": 126.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 144.0, 191.0, 139.0, 115.0, 114.0, 115.0, 156.0, 109.0], "value": 134.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [274.0, 243.0, 239.0, 265.0, 285.0, 195.0, 288.0, 274.0, 200.0, 199.0], "value": 246.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 62.0, 62.0, 87.0, 63.0, 63.0, 64.0, 63.0, 78.0, 65.0], "value": 69.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 73.0, 87.0, 76.0, 71.0, 72.0, 83.0, 73.0, 71.0, 86.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 90.0, 89.0, 100.0, 90.0, 92.0, 91.0, 97.0, 135.0, 138.0], "value": 100.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 98.0, 96.0, 129.0, 97.0, 116.0, 97.0, 97.0, 154.0], "value": 108.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 101.0, 90.0, 90.0, 91.0, 97.0, 103.0, 145.0, 143.0, 90.0], "value": 104.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 68.0, 69.0, 67.0, 68.0, 67.0, 67.0, 69.0], "value": 67.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 57.0, 63.0, 67.0, 65.0, 62.0, 60.0, 59.0, 61.0, 61.0], "value": 61.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 88.0, 89.0, 88.0, 84.0, 89.0, 90.0, 88.0, 86.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 148.0, 149.0, 168.0, 154.0, 150.0, 143.0, 166.0, 205.0, 162.0], "value": 158.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 209.0, 161.0, 164.0, 180.0, 211.0, 239.0, 224.0, 179.0], "value": 191.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 68.0, 66.0, 68.0, 70.0, 71.0, 70.0, 86.0, 67.0], "value": 70.0, "unit": "score"}]}]} 08:49:45 INFO - Return code: 0 08:49:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:49:46 ERROR - # TBPL SUCCESS # 08:49:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:49:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:49:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:49:46 INFO - 2017-03-10 08:49:46.084 screenresolution[1661:10799] starting screenresolution argv=screenresolution get 08:49:46 INFO - 2017-03-10 08:49:46.087 screenresolution[1661:10799] Display 0: 1600x1200x32@60 08:49:46 INFO - 2017-03-10 08:49:46.093 screenresolution[1662:10802] starting screenresolution argv=screenresolution list 08:49:46 INFO - Available Modes on Display 0 08:49:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:49:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:49:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:49:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:49:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:49:46 INFO - Intel Iris: 08:49:46 INFO - Chipset Model: Intel Iris 08:49:46 INFO - Type: GPU 08:49:46 INFO - Bus: Built-In 08:49:46 INFO - VRAM (Dynamic, Max): 1536 MB 08:49:46 INFO - Vendor: Intel (0x8086) 08:49:46 INFO - Device ID: 0x0a2e 08:49:46 INFO - Revision ID: 0x0009 08:49:46 INFO - Displays: 08:49:46 INFO - Display: 08:49:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:49:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:49:46 INFO - Display Serial Number: 1600x1200 60 08:49:46 INFO - Main Display: Yes 08:49:46 INFO - Mirror: Off 08:49:46 INFO - Online: Yes 08:49:46 INFO - Rotation: Supported 08:49:46 INFO - Return code: 0 08:49:46 INFO - Running post-action listener: _resource_record_post_action 08:49:46 INFO - [mozharness: 2017-03-10 16:49:46.333899Z] Finished run-tests step (success) 08:49:46 INFO - Running post-run listener: _resource_record_post_run 08:49:46 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 38496256; Write bytes: 516099072; Read time: 395; Write time: 1766 08:49:46 INFO - TinderboxPrint: CPU usage
54.9% 08:49:46 INFO - TinderboxPrint: I/O read bytes / time
38,496,256 / 395 08:49:46 INFO - TinderboxPrint: I/O write bytes / time
516,099,072 / 1,766 08:49:46 INFO - TinderboxPrint: CPU idle
2,167.9 (45.1%) 08:49:46 INFO - TinderboxPrint: CPU system
970.6 (20.2%) 08:49:46 INFO - TinderboxPrint: CPU user
1,667.3 (34.7%) 08:49:46 INFO - TinderboxPrint: Swap in / out
248,430,592 / 0 08:49:46 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203340288; Write bytes: 200892416; Read time: 15636; Write time: 379 08:49:46 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 36677632; Write bytes: 302683136; Read time: 377; Write time: 1278 08:49:46 INFO - Running post-run listener: _upload_blobber_files 08:49:46 INFO - Blob upload gear active. 08:49:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:49:46 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:49:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:49:47 INFO - (blobuploader) - INFO - Open directory for files ... 08:49:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:49:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:49:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:48 INFO - (blobuploader) - INFO - Done attempting. 08:49:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:49:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:49:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:49 INFO - (blobuploader) - INFO - Done attempting. 08:49:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:49:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:49:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:50 INFO - (blobuploader) - INFO - Done attempting. 08:49:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:49:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:49:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:52 INFO - (blobuploader) - INFO - Done attempting. 08:49:52 INFO - (blobuploader) - INFO - Iteration through files over. 08:49:52 INFO - Return code: 0 08:49:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:49:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:49:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ec75b42a37b904461e1a4d5bb8b7fead236de481b4b66d3f3793eb2821aba316b55cdbff95fda0be12e825c1a7b1dc01965313099028f8891962028309e93c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc26542c507a947f7c235ebf9a8cda8b2e5f3dffc5df0c9c35c3d44c148edc68d63de78c3aee63ce0dadae73d91a2a42191fb4af04e7c2f51a9266008a8c6a9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/537906b88e767b31e571658843cae727ab5b318646e60c739530e71dfe74dfc28a7faedc286c709e5fbb7c01fecbdff2b34c3de9d193a24b7a66bc6865913ea4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28c497a05d5ab8a0079887e84a8bbf24d8a3f7789c0571a41efedfa41131ea6bcda471e1b0ed157c8b37fe7af87501d7235192ef699aa3b0fd011a8938f483ff"} 08:49:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:49:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:49:52 INFO - Contents: 08:49:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ec75b42a37b904461e1a4d5bb8b7fead236de481b4b66d3f3793eb2821aba316b55cdbff95fda0be12e825c1a7b1dc01965313099028f8891962028309e93c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc26542c507a947f7c235ebf9a8cda8b2e5f3dffc5df0c9c35c3d44c148edc68d63de78c3aee63ce0dadae73d91a2a42191fb4af04e7c2f51a9266008a8c6a9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/537906b88e767b31e571658843cae727ab5b318646e60c739530e71dfe74dfc28a7faedc286c709e5fbb7c01fecbdff2b34c3de9d193a24b7a66bc6865913ea4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28c497a05d5ab8a0079887e84a8bbf24d8a3f7789c0571a41efedfa41131ea6bcda471e1b0ed157c8b37fe7af87501d7235192ef699aa3b0fd011a8938f483ff"} 08:49:52 INFO - Running post-run listener: copy_logs_to_upload_dir 08:49:52 INFO - Copying logs to upload dir... 08:49:52 INFO - mkdir: /builds/slave/test/build/upload/logs 08:49:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.296227 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-10 08:49:52.760133) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-10 08:49:52.763435) ========= 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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners TMPDIR=/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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/1ec75b42a37b904461e1a4d5bb8b7fead236de481b4b66d3f3793eb2821aba316b55cdbff95fda0be12e825c1a7b1dc01965313099028f8891962028309e93c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc26542c507a947f7c235ebf9a8cda8b2e5f3dffc5df0c9c35c3d44c148edc68d63de78c3aee63ce0dadae73d91a2a42191fb4af04e7c2f51a9266008a8c6a9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/537906b88e767b31e571658843cae727ab5b318646e60c739530e71dfe74dfc28a7faedc286c709e5fbb7c01fecbdff2b34c3de9d193a24b7a66bc6865913ea4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28c497a05d5ab8a0079887e84a8bbf24d8a3f7789c0571a41efedfa41131ea6bcda471e1b0ed157c8b37fe7af87501d7235192ef699aa3b0fd011a8938f483ff"} build_url:https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011235 build_url: 'https://queue.taskcluster.net/v1/task/FiSactJITVirTqYN7bWjJw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ec75b42a37b904461e1a4d5bb8b7fead236de481b4b66d3f3793eb2821aba316b55cdbff95fda0be12e825c1a7b1dc01965313099028f8891962028309e93c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc26542c507a947f7c235ebf9a8cda8b2e5f3dffc5df0c9c35c3d44c148edc68d63de78c3aee63ce0dadae73d91a2a42191fb4af04e7c2f51a9266008a8c6a9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/537906b88e767b31e571658843cae727ab5b318646e60c739530e71dfe74dfc28a7faedc286c709e5fbb7c01fecbdff2b34c3de9d193a24b7a66bc6865913ea4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28c497a05d5ab8a0079887e84a8bbf24d8a3f7789c0571a41efedfa41131ea6bcda471e1b0ed157c8b37fe7af87501d7235192ef699aa3b0fd011a8938f483ff"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-10 08:49:52.803505) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 08:49:52.803857) ========= 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.F4gpdWtWfm/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.eqhMlIUWgS/Listeners TMPDIR=/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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.005054 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 08:49:52.852874) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-10 08:49:52.853181) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-10 08:49:52.853491) ========= ========= Total master_lag: 0.84 =========