builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0037 starttime: 1488993180.87 results: success (0) buildid: 20170308073826 builduid: 4026df89322444f9aa89106577b31b3d revision: 70790a08686d78e9f3bb83094c2c04bf1dad20f3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-08 09:13:00.873651) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-08 09:13:00.874094) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-08 09:13:00.874389) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-08 09:13:00.900145) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 09:13:00.900494) ========= 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.w5HxT27feu/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.f9VlqJCxB1/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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-08 09:13:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 105M=0s 2017-03-08 09:13:01 (105 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127270 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 09:13:01.049814) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 09:13:01.050150) ========= 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.w5HxT27feu/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.f9VlqJCxB1/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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.021601 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 09:13:01.098584) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-08 09:13:01.098952) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 70790a08686d78e9f3bb83094c2c04bf1dad20f3 --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 70790a08686d78e9f3bb83094c2c04bf1dad20f3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w5HxT27feu/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.f9VlqJCxB1/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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-08 09:13:01,184 truncating revision to first 12 chars 2017-03-08 09:13:01,185 Setting DEBUG logging. 2017-03-08 09:13:01,185 attempt 1/10 2017-03-08 09:13:01,185 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/70790a08686d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-08 09:13:02,319 unpacking tar archive at: mozilla-central-70790a08686d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.397243 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-08 09:13:02.519300) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-08 09:13:02.519637) ========= script_repo_revision: 70790a08686d78e9f3bb83094c2c04bf1dad20f3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-08 09:13:02.520032) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-08 09:13:02.520300) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-08 09:13:02.540216) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-08 09:13:02.540556) ========= /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.w5HxT27feu/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.f9VlqJCxB1/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:13:02 INFO - MultiFileLogger online at 20170308 09:13:02 in /builds/slave/test 09:13:02 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 09:13:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:13:02 INFO - {'append_to_log': False, 09:13:02 INFO - 'base_work_dir': '/builds/slave/test', 09:13:02 INFO - 'blob_upload_branch': 'Firefox', 09:13:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:13:02 INFO - 'branch': 'Firefox', 09:13:02 INFO - 'buildbot_json_path': 'buildprops.json', 09:13:02 INFO - 'config_files': ('talos/mac_config.py',), 09:13:02 INFO - 'default_actions': ('clobber', 09:13:02 INFO - 'read-buildbot-config', 09:13:02 INFO - 'download-and-extract', 09:13:02 INFO - 'populate-webroot', 09:13:02 INFO - 'create-virtualenv', 09:13:02 INFO - 'install', 09:13:02 INFO - 'run-tests'), 09:13:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:13:02 INFO - 'download_minidump_stackwalk': True, 09:13:02 INFO - 'download_symbols': 'ondemand', 09:13:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:13:02 INFO - 'tooltool.py': '/tools/tooltool.py', 09:13:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:13:02 INFO - '/tools/misc-python/virtualenv.py')}, 09:13:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:13:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:13:02 INFO - 'gecko_profile': False, 09:13:02 INFO - 'gecko_profile_interval': 0, 09:13:02 INFO - 'installer_path': 'installer.exe', 09:13:02 INFO - 'log_level': 'info', 09:13:02 INFO - 'log_name': 'talos', 09:13:02 INFO - 'log_to_console': True, 09:13:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:13:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:13:02 INFO - 'opt_config_files': (), 09:13:02 INFO - 'pip_index': False, 09:13:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:13:02 INFO - 'cmd': ('bash', 09:13:02 INFO - '-c', 09:13:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:13:02 INFO - 'enabled': True, 09:13:02 INFO - 'halt_on_failure': False, 09:13:02 INFO - 'name': 'check_screen_resolution'},), 09:13:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:13:02 INFO - 'cmd': ('bash', 09:13:02 INFO - '-c', 09:13:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:13:02 INFO - 'enabled': True, 09:13:02 INFO - 'halt_on_failure': False, 09:13:02 INFO - 'name': 'check_screen_resolution'},), 09:13:02 INFO - 'run_cmd_checks_enabled': True, 09:13:02 INFO - 'suite': 'dromaeojs-e10s', 09:13:02 INFO - 'system_bits': '32', 09:13:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:13:02 INFO - 'title': 't-yosemite-r7-0037', 09:13:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:13:02 INFO - 'use_talos_json': True, 09:13:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:13:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:13:02 INFO - 'work_dir': 'build'} 09:13:02 INFO - [mozharness: 2017-03-08 17:13:02.754440Z] Running clobber step. 09:13:02 INFO - Running pre-action listener: _resource_record_pre_action 09:13:02 INFO - Running main action method: clobber 09:13:02 INFO - rmtree: /builds/slave/test/build 09:13:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:13:03 INFO - Running post-action listener: _resource_record_post_action 09:13:03 INFO - [mozharness: 2017-03-08 17:13:03.569930Z] Finished clobber step (success) 09:13:03 INFO - [mozharness: 2017-03-08 17:13:03.570064Z] Running read-buildbot-config step. 09:13:03 INFO - Running pre-action listener: _resource_record_pre_action 09:13:03 INFO - Running main action method: read_buildbot_config 09:13:03 INFO - Using buildbot properties: 09:13:03 INFO - { 09:13:03 INFO - "project": "", 09:13:03 INFO - "product": "firefox", 09:13:03 INFO - "script_repo_revision": "production", 09:13:03 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:13:03 INFO - "repository": "", 09:13:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:13:03 INFO - "buildid": "20170308073826", 09:13:03 INFO - "pgo_build": "False", 09:13:03 INFO - "basedir": "/builds/slave/test", 09:13:03 INFO - "buildnumber": 167, 09:13:03 INFO - "slavename": "t-yosemite-r7-0037", 09:13:03 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:13:03 INFO - "platform": "yosemite_r7", 09:13:03 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:13:03 INFO - "branch": "mozilla-central", 09:13:03 INFO - "repo_path": "mozilla-central", 09:13:03 INFO - "revision": "70790a08686d78e9f3bb83094c2c04bf1dad20f3", 09:13:03 INFO - "stage_platform": "macosx64", 09:13:03 INFO - "builduid": "4026df89322444f9aa89106577b31b3d", 09:13:03 INFO - "slavebuilddir": "test" 09:13:03 INFO - } 09:13:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:13:03 INFO - Running post-action listener: _resource_record_post_action 09:13:03 INFO - [mozharness: 2017-03-08 17:13:03.573343Z] Finished read-buildbot-config step (success) 09:13:03 INFO - [mozharness: 2017-03-08 17:13:03.573479Z] Running download-and-extract step. 09:13:03 INFO - Running pre-action listener: _resource_record_pre_action 09:13:03 INFO - Running main action method: download_and_extract 09:13:03 INFO - mkdir: /builds/slave/test/build/tests 09:13:03 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')]} 09:13:03 INFO - trying https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:13:03 INFO - Downloading https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/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 09:13:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/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 09:13:04 INFO - Downloaded 1199 bytes. 09:13:04 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:13:04 INFO - Using the following test package requirements: 09:13:04 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:13:04 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:13:04 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:13:04 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:13:04 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:13:04 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:13:04 INFO - u'jsshell-mac.zip'], 09:13:04 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:13:04 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:13:04 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:13:04 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:13:04 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:13:04 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:13:04 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:13:04 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:13:04 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:13:04 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:13:04 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:13:04 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:13:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:13:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:13:04 INFO - Fetch https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:13:06 INFO - Expected file size: 51786402 09:13:06 INFO - Obtained file size: 51786402 09:13:22 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 09:13:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:13:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:13:22 INFO - Fetch https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:13:23 INFO - Expected file size: 51786402 09:13:23 INFO - Obtained file size: 51786402 09:13:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:13:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:13:38 INFO - Fetch https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:13:39 INFO - Expected file size: 14404424 09:13:39 INFO - Obtained file size: 14404424 09:13:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:40 INFO - trying https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:13:40 INFO - Downloading https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:13:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/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 09:13:42 INFO - Downloaded 74926928 bytes. 09:13:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:13:42 INFO - mkdir: /builds/slave/test/properties 09:13:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:13:42 INFO - Writing to file /builds/slave/test/properties/build_url 09:13:42 INFO - Contents: 09:13:42 INFO - build_url:https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:13:43 INFO - Running post-action listener: _resource_record_post_action 09:13:43 INFO - Running post-action listener: set_extra_try_arguments 09:13:43 INFO - [mozharness: 2017-03-08 17:13:43.041902Z] Finished download-and-extract step (success) 09:13:43 INFO - [mozharness: 2017-03-08 17:13:43.042042Z] Running populate-webroot step. 09:13:43 INFO - Running pre-action listener: _resource_record_pre_action 09:13:43 INFO - Running main action method: populate_webroot 09:13:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:13:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:13:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:13:43 INFO - '--tppagecycles', 09:13:43 INFO - '7'], 09:13:43 INFO - 'tests': ['tsvgm']}}, 09:13:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:13:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:13:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:13:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:13:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:13:43 INFO - 'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:13:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:13:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:13:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:13:43 INFO - 'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['damp', 'tps']}, 09:13:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:13:43 INFO - 'tests': ['damp', 'tps']}, 09:13:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['dromaeo_dom']}, 09:13:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:13:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['basic_compositor_video']}, 09:13:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:13:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['a11yr', 09:13:43 INFO - 'ts_paint', 09:13:43 INFO - 'tpaint', 09:13:43 INFO - 'sessionrestore', 09:13:43 INFO - 'sessionrestore_no_auto_restore', 09:13:43 INFO - 'tabpaint']}, 09:13:43 INFO - 'other-e10s': {'tests': ['a11yr', 09:13:43 INFO - 'ts_paint', 09:13:43 INFO - 'tpaint', 09:13:43 INFO - 'sessionrestore', 09:13:43 INFO - 'sessionrestore_no_auto_restore', 09:13:43 INFO - 'tabpaint']}, 09:13:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:13:43 INFO - 'ts_paint', 09:13:43 INFO - 'tpaint', 09:13:43 INFO - 'sessionrestore', 09:13:43 INFO - 'sessionrestore_no_auto_restore', 09:13:43 INFO - 'tabpaint']}, 09:13:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:13:43 INFO - 'ts_paint', 09:13:43 INFO - 'tpaint', 09:13:43 INFO - 'sessionrestore', 09:13:43 INFO - 'sessionrestore_no_auto_restore', 09:13:43 INFO - 'tabpaint']}, 09:13:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['a11yr', 09:13:43 INFO - 'ts_paint', 09:13:43 INFO - 'tpaint', 09:13:43 INFO - 'sessionrestore', 09:13:43 INFO - 'sessionrestore_no_auto_restore', 09:13:43 INFO - 'tabpaint']}, 09:13:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['a11yr', 09:13:43 INFO - 'ts_paint', 09:13:43 INFO - 'tpaint', 09:13:43 INFO - 'sessionrestore', 09:13:43 INFO - 'sessionrestore_no_auto_restore', 09:13:43 INFO - 'tabpaint']}, 09:13:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['tsvgx', 09:13:43 INFO - 'tsvgr_opacity', 09:13:43 INFO - 'tart', 09:13:43 INFO - 'tscrollx', 09:13:43 INFO - 'cart', 09:13:43 INFO - 'tsvg_static']}, 09:13:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:13:43 INFO - 'tsvgr_opacity', 09:13:43 INFO - 'tart', 09:13:43 INFO - 'tscrollx', 09:13:43 INFO - 'cart', 09:13:43 INFO - 'tsvg_static']}, 09:13:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:13:43 INFO - 'talos_options': ['--disable-e10s'], 09:13:43 INFO - 'tests': ['tp5o']}, 09:13:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:13:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:13:43 INFO - 'talos_options': ['--disable-e10s', 09:13:43 INFO - '--xperf_path', 09:13:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:13:43 INFO - 'tests': ['tp5n']}, 09:13:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:13:43 INFO - 'talos_options': ['--xperf_path', 09:13:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:13:43 INFO - 'tests': ['tp5n']}}, 09:13:43 INFO - 'talos.zip': {'path': '', 09:13:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:13:43 INFO - Running post-action listener: _resource_record_post_action 09:13:43 INFO - [mozharness: 2017-03-08 17:13:43.060426Z] Finished populate-webroot step (success) 09:13:43 INFO - [mozharness: 2017-03-08 17:13:43.060558Z] Running create-virtualenv step. 09:13:43 INFO - Running pre-action listener: _resource_record_pre_action 09:13:43 INFO - Running main action method: create_virtualenv 09:13:43 INFO - Creating virtualenv /builds/slave/test/build/venv 09:13:43 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 09:13:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:13:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:13:43 INFO - Using real prefix '/tools/python27' 09:13:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:13:43 INFO - Installing distribute.............................................................................................................................................................................................done. 09:13:46 INFO - Installing pip.................done. 09:13:46 INFO - Return code: 0 09:13:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:13:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:13:47 INFO - Reading from file tmpfile_stdout 09:13:47 INFO - Output received: 09:13:47 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) 09:13:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:47 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 0x10feafb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10feb2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe11d81330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8dd30>, '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 0x10fbb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc82be8>, '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.w5HxT27feu/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.f9VlqJCxB1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:13:47 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 09:13:47 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 09:13:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5HxT27feu/Render', 09:13:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:47 INFO - 'HOME': '/Users/cltbld', 09:13:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:47 INFO - 'LOGNAME': 'cltbld', 09:13:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:47 INFO - 'MOZ_NO_REMOTE': '1', 09:13:47 INFO - 'NO_EM_RESTART': '1', 09:13:47 INFO - 'PAGER': '/bin/cat', 09:13:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:47 INFO - 'PWD': '/builds/slave/test', 09:13:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:47 INFO - 'SHELL': '/bin/bash', 09:13:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f9VlqJCxB1/Listeners', 09:13:47 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:13:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:47 INFO - 'USER': 'cltbld', 09:13:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:47 INFO - 'XPC_FLAGS': '0x0', 09:13:47 INFO - 'XPC_SERVICE_NAME': '0', 09:13:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:47 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 09:13:47 INFO - Cleaning up... 09:13:47 INFO - Return code: 0 09:13:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:47 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 0x10feafb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10feb2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe11d81330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8dd30>, '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 0x10fbb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc82be8>, '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.w5HxT27feu/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.f9VlqJCxB1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:13:47 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 09:13:47 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 09:13:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5HxT27feu/Render', 09:13:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:47 INFO - 'HOME': '/Users/cltbld', 09:13:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:47 INFO - 'LOGNAME': 'cltbld', 09:13:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:47 INFO - 'MOZ_NO_REMOTE': '1', 09:13:47 INFO - 'NO_EM_RESTART': '1', 09:13:47 INFO - 'PAGER': '/bin/cat', 09:13:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:47 INFO - 'PWD': '/builds/slave/test', 09:13:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:47 INFO - 'SHELL': '/bin/bash', 09:13:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f9VlqJCxB1/Listeners', 09:13:47 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:13:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:47 INFO - 'USER': 'cltbld', 09:13:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:47 INFO - 'XPC_FLAGS': '0x0', 09:13:47 INFO - 'XPC_SERVICE_NAME': '0', 09:13:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:47 INFO - Downloading/unpacking psutil>=3.1.1 09:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:13:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:51 INFO - Installing collected packages: psutil 09:13:51 INFO - Running setup.py install for psutil 09:13:51 INFO - building 'psutil._psutil_osx' extension 09:13:51 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 09:13:51 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 09:13:51 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 09:13:51 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 09:13:51 INFO - building 'psutil._psutil_posix' extension 09:13:51 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 09:13:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:13:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:13:51 INFO - ^ 09:13:51 INFO - 1 warning generated. 09:13:51 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 09:13:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:51 INFO - Successfully installed psutil 09:13:51 INFO - Cleaning up... 09:13:52 INFO - Return code: 0 09:13:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:13:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10feafb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10feb2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe11d81330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8dd30>, '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 0x10fbb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc82be8>, '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.w5HxT27feu/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.f9VlqJCxB1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:13:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:13:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:13:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5HxT27feu/Render', 09:13:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:52 INFO - 'HOME': '/Users/cltbld', 09:13:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:52 INFO - 'LOGNAME': 'cltbld', 09:13:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:52 INFO - 'MOZ_NO_REMOTE': '1', 09:13:52 INFO - 'NO_EM_RESTART': '1', 09:13:52 INFO - 'PAGER': '/bin/cat', 09:13:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:52 INFO - 'PWD': '/builds/slave/test', 09:13:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:52 INFO - 'SHELL': '/bin/bash', 09:13:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f9VlqJCxB1/Listeners', 09:13:52 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:13:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:52 INFO - 'USER': 'cltbld', 09:13:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:52 INFO - 'XPC_FLAGS': '0x0', 09:13:52 INFO - 'XPC_SERVICE_NAME': '0', 09:13:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:13:52 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 09:13:52 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 09:13:52 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 09:13:52 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 09:13:52 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 09:13:52 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 09:13:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:13:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:13:54 INFO - Installing collected packages: mozsystemmonitor 09:13:54 INFO - Running setup.py install for mozsystemmonitor 09:13:55 INFO - Successfully installed mozsystemmonitor 09:13:55 INFO - Cleaning up... 09:13:55 INFO - Return code: 0 09:13:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:13:55 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')]} 09:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:55 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')]} 09:13:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:55 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 0x10feafb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10feb2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe11d81330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8dd30>, '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 0x10fbb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc82be8>, '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.w5HxT27feu/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.f9VlqJCxB1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:13:55 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 09:13:55 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 09:13:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5HxT27feu/Render', 09:13:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:55 INFO - 'HOME': '/Users/cltbld', 09:13:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:55 INFO - 'LOGNAME': 'cltbld', 09:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:55 INFO - 'MOZ_NO_REMOTE': '1', 09:13:55 INFO - 'NO_EM_RESTART': '1', 09:13:55 INFO - 'PAGER': '/bin/cat', 09:13:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:55 INFO - 'PWD': '/builds/slave/test', 09:13:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:55 INFO - 'SHELL': '/bin/bash', 09:13:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f9VlqJCxB1/Listeners', 09:13:55 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:13:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:55 INFO - 'USER': 'cltbld', 09:13:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:55 INFO - 'XPC_FLAGS': '0x0', 09:13:55 INFO - 'XPC_SERVICE_NAME': '0', 09:13:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:55 INFO - Downloading/unpacking jsonschema==2.5.1 09:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:13:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:13:58 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 09:13:58 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 09:13:58 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 09:13:58 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 09:13:58 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 09:13:58 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 09:13:58 INFO - Downloading functools32-3.2.3-2.tar.gz 09:13:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:13:58 INFO - warning: no files found matching '*.txt' 09:13:58 INFO - no previously-included directories found matching 'build' 09:13:58 INFO - no previously-included directories found matching 'dist' 09:13:58 INFO - no previously-included directories found matching '.git*' 09:13:58 INFO - Installing collected packages: jsonschema, functools32 09:13:58 INFO - Running setup.py install for functools32 09:13:58 INFO - warning: no files found matching '*.txt' 09:13:58 INFO - no previously-included directories found matching 'build' 09:13:58 INFO - no previously-included directories found matching 'dist' 09:13:58 INFO - no previously-included directories found matching '.git*' 09:13:58 INFO - Successfully installed jsonschema functools32 09:13:58 INFO - Cleaning up... 09:13:58 INFO - Return code: 0 09:13:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10feafb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10feb2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe11d81330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8dd30>, '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 0x10fbb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc82be8>, '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.w5HxT27feu/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.f9VlqJCxB1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:13:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:13:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:13:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5HxT27feu/Render', 09:13:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:59 INFO - 'HOME': '/Users/cltbld', 09:13:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:59 INFO - 'LOGNAME': 'cltbld', 09:13:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:59 INFO - 'MOZ_NO_REMOTE': '1', 09:13:59 INFO - 'NO_EM_RESTART': '1', 09:13:59 INFO - 'PAGER': '/bin/cat', 09:13:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:59 INFO - 'PWD': '/builds/slave/test', 09:13:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:59 INFO - 'SHELL': '/bin/bash', 09:13:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f9VlqJCxB1/Listeners', 09:13:59 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:13:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:59 INFO - 'USER': 'cltbld', 09:13:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:59 INFO - 'XPC_FLAGS': '0x0', 09:13:59 INFO - 'XPC_SERVICE_NAME': '0', 09:13:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:14:00 INFO - Cleaning up... 09:14:00 INFO - Return code: 0 09:14:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:14:00 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')]} 09:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:00 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')]} 09:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:00 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 0x10feafb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10feb2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe11d81330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8dd30>, '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 0x10fbb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc82be8>, '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.w5HxT27feu/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.f9VlqJCxB1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:14:00 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 09:14:00 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 09:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5HxT27feu/Render', 09:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:00 INFO - 'HOME': '/Users/cltbld', 09:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:00 INFO - 'LOGNAME': 'cltbld', 09:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:00 INFO - 'MOZ_NO_REMOTE': '1', 09:14:00 INFO - 'NO_EM_RESTART': '1', 09:14:00 INFO - 'PAGER': '/bin/cat', 09:14:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:00 INFO - 'PWD': '/builds/slave/test', 09:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:00 INFO - 'SHELL': '/bin/bash', 09:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f9VlqJCxB1/Listeners', 09:14:00 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:00 INFO - 'USER': 'cltbld', 09:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:00 INFO - 'XPC_FLAGS': '0x0', 09:14:00 INFO - 'XPC_SERVICE_NAME': '0', 09:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:00 INFO - Downloading/unpacking blobuploader==1.2.4 09:14:00 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 09:14:00 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 09:14:00 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 09:14:00 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 09:14:00 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 09:14:00 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 09:14:03 INFO - Downloading blobuploader-1.2.4.tar.gz 09:14:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:14:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:14:03 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 09:14:03 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 09:14:03 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 09:14:03 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 09:14:03 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 09:14:03 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 09:14:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:14:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:14:04 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 09:14:04 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 09:14:04 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 09:14:04 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 09:14:04 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 09:14:04 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 09:14:04 INFO - Downloading docopt-0.6.1.tar.gz 09:14:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:14:04 INFO - Installing collected packages: blobuploader, requests, docopt 09:14:04 INFO - Running setup.py install for blobuploader 09:14:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:14:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:14:04 INFO - Running setup.py install for requests 09:14:05 INFO - Running setup.py install for docopt 09:14:05 INFO - Successfully installed blobuploader requests docopt 09:14:05 INFO - Cleaning up... 09:14:05 INFO - Return code: 0 09:14:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14: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')]} 09:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14: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')]} 09:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:05 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 0x10feafb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10feb2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe11d81330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8dd30>, '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 0x10fbb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc82be8>, '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.w5HxT27feu/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.f9VlqJCxB1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:14:05 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 09:14:05 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 09:14:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5HxT27feu/Render', 09:14:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:05 INFO - 'HOME': '/Users/cltbld', 09:14:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:05 INFO - 'LOGNAME': 'cltbld', 09:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:05 INFO - 'MOZ_NO_REMOTE': '1', 09:14:05 INFO - 'NO_EM_RESTART': '1', 09:14:05 INFO - 'PAGER': '/bin/cat', 09:14:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:05 INFO - 'PWD': '/builds/slave/test', 09:14:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:05 INFO - 'SHELL': '/bin/bash', 09:14:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f9VlqJCxB1/Listeners', 09:14:05 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:14:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:05 INFO - 'USER': 'cltbld', 09:14:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:05 INFO - 'XPC_FLAGS': '0x0', 09:14:05 INFO - 'XPC_SERVICE_NAME': '0', 09:14:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:14:05 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-VIEtdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:14:05 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Km0q02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:14:06 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-qKr9Yr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:14:06 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-LdWgMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:14:06 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-OeyTRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:14:06 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-xEmt0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:14:06 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-5qDKF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:14:06 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-qBV3In-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:14:06 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-MIdyVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:14:06 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-M9jVF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:14:06 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-QHYXob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:14:06 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-6yt1Fk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:14:07 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-ICpOXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:14:07 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-IA0nh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:07 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-CqFIuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:14:07 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-rwDG5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:14:07 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-M4QzdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:14:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:14:07 INFO - Running setup.py install for manifestparser 09:14:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:14:07 INFO - Running setup.py install for mozcrash 09:14:07 INFO - Running setup.py install for mozdebug 09:14:08 INFO - Running setup.py install for mozdevice 09:14:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:14:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:14:08 INFO - Running setup.py install for mozfile 09:14:08 INFO - Running setup.py install for mozhttpd 09:14:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:14:08 INFO - Running setup.py install for mozinfo 09:14:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:14:08 INFO - Running setup.py install for mozInstall 09:14:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:14:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:14:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:14:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:14:08 INFO - Running setup.py install for mozleak 09:14:08 INFO - Running setup.py install for mozlog 09:14:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:14:09 INFO - Running setup.py install for moznetwork 09:14:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:14:09 INFO - Running setup.py install for mozprocess 09:14:09 INFO - Running setup.py install for mozprofile 09:14:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:14:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:14:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:14:09 INFO - Running setup.py install for mozrunner 09:14:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:14:09 INFO - Running setup.py install for mozscreenshot 09:14:09 INFO - Running setup.py install for moztest 09:14:10 INFO - Running setup.py install for mozversion 09:14:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:14:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:14:10 INFO - Cleaning up... 09:14:10 INFO - Return code: 0 09:14:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14:10 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')]} 09:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:10 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')]} 09:14:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:10 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 0x10feafb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10feb2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe11d81330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8dd30>, '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 0x10fbb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc82be8>, '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.w5HxT27feu/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.f9VlqJCxB1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:14:10 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 09:14:10 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 09:14:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5HxT27feu/Render', 09:14:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:10 INFO - 'HOME': '/Users/cltbld', 09:14:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:10 INFO - 'LOGNAME': 'cltbld', 09:14:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:10 INFO - 'MOZ_NO_REMOTE': '1', 09:14:10 INFO - 'NO_EM_RESTART': '1', 09:14:10 INFO - 'PAGER': '/bin/cat', 09:14:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:10 INFO - 'PWD': '/builds/slave/test', 09:14:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:10 INFO - 'SHELL': '/bin/bash', 09:14:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f9VlqJCxB1/Listeners', 09:14:10 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:14:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:10 INFO - 'USER': 'cltbld', 09:14:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:10 INFO - 'XPC_FLAGS': '0x0', 09:14:10 INFO - 'XPC_SERVICE_NAME': '0', 09:14:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:14:10 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-oqn2HZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:14:10 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)) 09:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:14:10 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-2RyC9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:14:10 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)) 09:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:14:10 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-qapVd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:14:10 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)) 09:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:14:10 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-WKMEHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:14:10 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)) 09:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:14:10 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-G8Mo48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:14:11 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)) 09:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:14:11 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-5xxKiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:14:11 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)) 09:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:14:11 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-0kw5Wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:14:11 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)) 09:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:14:11 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-boryFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:14:11 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)) 09:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:14:11 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-cs_qEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:14:11 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)) 09:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:14:11 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-kS9Tga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:14:11 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)) 09:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:14:11 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-W5kxyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:14:11 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)) 09:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:14:11 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-92fSzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:14:11 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)) 09:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:14:11 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-eEjrBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:14:11 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)) 09:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:14:11 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Fh7pNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:14:11 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)) 09:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:11 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-V4_fhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:12 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)) 09:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:14:12 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-AkCVIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:14:12 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)) 09:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:14:12 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-wChkqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:14:12 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)) 09:14:12 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)) 09:14:12 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)) 09:14:12 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)) 09:14:12 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)) 09:14:12 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)) 09:14:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:14:12 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 09:14:12 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 09:14:12 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 09:14:12 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 09:14:12 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 09:14:12 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 09:14:14 INFO - Downloading blessings-1.6.tar.gz 09:14:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:14:14 INFO - Installing collected packages: blessings 09:14:14 INFO - Running setup.py install for blessings 09:14:15 INFO - Successfully installed blessings 09:14:15 INFO - Cleaning up... 09:14:15 INFO - Return code: 0 09:14:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:14:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:14:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:14:15 INFO - Reading from file tmpfile_stdout 09:14:15 INFO - Current package versions: 09:14:15 INFO - blessings == 1.6 09:14:15 INFO - blobuploader == 1.2.4 09:14:15 INFO - docopt == 0.6.1 09:14:15 INFO - functools32 == 3.2.3-2 09:14:15 INFO - jsonschema == 2.5.1 09:14:15 INFO - manifestparser == 1.1 09:14:15 INFO - mozInstall == 1.12 09:14:15 INFO - mozcrash == 1.0 09:14:15 INFO - mozdebug == 0.1 09:14:15 INFO - mozdevice == 0.49 09:14:15 INFO - mozfile == 1.2 09:14:15 INFO - mozhttpd == 0.7 09:14:15 INFO - mozinfo == 0.9 09:14:15 INFO - mozleak == 0.1 09:14:15 INFO - mozlog == 3.4 09:14:15 INFO - moznetwork == 0.27 09:14:15 INFO - mozprocess == 0.25 09:14:15 INFO - mozprofile == 0.28 09:14:15 INFO - mozrunner == 6.13 09:14:15 INFO - mozscreenshot == 0.1 09:14:15 INFO - mozsystemmonitor == 0.3 09:14:15 INFO - moztest == 0.8 09:14:15 INFO - mozversion == 1.4 09:14:15 INFO - psutil == 3.1.1 09:14:15 INFO - requests == 1.2.3 09:14:15 INFO - wsgiref == 0.1.2 09:14:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14: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')]} 09:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14: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')]} 09:14:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:15 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 0x10feafb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10feb2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe11d81330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8dd30>, '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 0x10fbb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc82be8>, '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.w5HxT27feu/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.f9VlqJCxB1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:14:15 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 09:14:15 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 09:14:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5HxT27feu/Render', 09:14:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:15 INFO - 'HOME': '/Users/cltbld', 09:14:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:15 INFO - 'LOGNAME': 'cltbld', 09:14:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:15 INFO - 'MOZ_NO_REMOTE': '1', 09:14:15 INFO - 'NO_EM_RESTART': '1', 09:14:15 INFO - 'PAGER': '/bin/cat', 09:14:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:15 INFO - 'PWD': '/builds/slave/test', 09:14:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:15 INFO - 'SHELL': '/bin/bash', 09:14:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f9VlqJCxB1/Listeners', 09:14:15 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:14:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:15 INFO - 'USER': 'cltbld', 09:14:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:15 INFO - 'XPC_FLAGS': '0x0', 09:14:15 INFO - 'XPC_SERVICE_NAME': '0', 09:14:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:15 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)) 09:14:15 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)) 09:14:15 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)) 09:14:15 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)) 09:14:15 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)) 09:14:15 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)) 09:14:15 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)) 09:14:15 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)) 09:14:15 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)) 09:14:15 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)) 09:14:15 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)) 09:14:15 INFO - Cleaning up... 09:14:15 INFO - Return code: 0 09:14:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:14: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')]} 09:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14: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')]} 09:14:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10feafb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10feb2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe11d81330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8dd30>, '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 0x10fbb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc82be8>, '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.w5HxT27feu/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.f9VlqJCxB1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:14: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', 'jsonschema'] in /builds/slave/test/build 09:14: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 jsonschema 09:14:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5HxT27feu/Render', 09:14:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:15 INFO - 'HOME': '/Users/cltbld', 09:14:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:15 INFO - 'LOGNAME': 'cltbld', 09:14:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:15 INFO - 'MOZ_NO_REMOTE': '1', 09:14:15 INFO - 'NO_EM_RESTART': '1', 09:14:15 INFO - 'PAGER': '/bin/cat', 09:14:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:15 INFO - 'PWD': '/builds/slave/test', 09:14:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:15 INFO - 'SHELL': '/bin/bash', 09:14:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f9VlqJCxB1/Listeners', 09:14:15 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:14:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:15 INFO - 'USER': 'cltbld', 09:14:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:15 INFO - 'XPC_FLAGS': '0x0', 09:14:15 INFO - 'XPC_SERVICE_NAME': '0', 09:14:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:14:16 INFO - Cleaning up... 09:14:16 INFO - Return code: 0 09:14:16 INFO - Running post-action listener: _resource_record_post_action 09:14:16 INFO - Running post-action listener: _start_resource_monitoring 09:14:16 INFO - Starting resource monitoring. 09:14:16 INFO - [mozharness: 2017-03-08 17:14:16.118143Z] Finished create-virtualenv step (success) 09:14:16 INFO - [mozharness: 2017-03-08 17:14:16.119138Z] Running install step. 09:14:16 INFO - Running pre-action listener: _resource_record_pre_action 09:14:16 INFO - Running main action method: install 09:14:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:14:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:14:16 INFO - Reading from file tmpfile_stdout 09:14:16 INFO - Detecting whether we're running mozinstall >=1.0... 09:14:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:14:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:14:16 INFO - Reading from file tmpfile_stdout 09:14:16 INFO - Output received: 09:14:16 INFO - Usage: mozinstall [options] installer 09:14:16 INFO - Options: 09:14:16 INFO - -h, --help show this help message and exit 09:14:16 INFO - -d DEST, --destination=DEST 09:14:16 INFO - Directory to install application into. [default: 09:14:16 INFO - "/builds/slave/test"] 09:14:16 INFO - --app=APP Application being installed. [default: firefox] 09:14:16 INFO - mkdir: /builds/slave/test/build/application 09:14:16 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'] 09:14:16 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 09:14:36 INFO - Reading from file tmpfile_stdout 09:14:36 INFO - Output received: 09:14:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:14:36 INFO - Running post-action listener: _resource_record_post_action 09:14:36 INFO - [mozharness: 2017-03-08 17:14:36.850161Z] Finished install step (success) 09:14:36 INFO - [mozharness: 2017-03-08 17:14:36.850344Z] Running run-tests step. 09:14:36 INFO - Running pre-action listener: _resource_record_pre_action 09:14:36 INFO - Running main action method: run_tests 09:14:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:14:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:14:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:14:36 INFO - 2017-03-08 09:14:36.865 screenresolution[7084:136091] starting screenresolution argv=screenresolution get 09:14:36 INFO - 2017-03-08 09:14:36.867 screenresolution[7084:136091] Display 0: 1600x1200x32@60 09:14:36 INFO - 2017-03-08 09:14:36.873 screenresolution[7085:136094] starting screenresolution argv=screenresolution list 09:14:36 INFO - Available Modes on Display 0 09:14:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:14:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:14:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:14:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:14:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:14:37 INFO - Intel Iris: 09:14:37 INFO - Chipset Model: Intel Iris 09:14:37 INFO - Type: GPU 09:14:37 INFO - Bus: Built-In 09:14:37 INFO - VRAM (Dynamic, Max): 1536 MB 09:14:37 INFO - Vendor: Intel (0x8086) 09:14:37 INFO - Device ID: 0x0a2e 09:14:37 INFO - Revision ID: 0x0009 09:14:37 INFO - Displays: 09:14:37 INFO - Display: 09:14:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:14:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:14:37 INFO - Display Serial Number: 1600x1200 60 09:14:37 INFO - Main Display: Yes 09:14:37 INFO - Mirror: Off 09:14:37 INFO - Online: Yes 09:14:37 INFO - Rotation: Supported 09:14:37 INFO - Return code: 0 09:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:14:37 INFO - Python 2.7.3 09:14:37 INFO - Return code: 0 09:14:37 INFO - grabbing minidump binary from tooltool 09:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:37 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 0x10fc8dd30>, '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 0x10fbb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc82be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:14:37 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 09:14:37 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 09:14:37 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 09:14:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:14:37 INFO - Return code: 0 09:14:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:14:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:14:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:14:37 INFO - ENV: RUST_BACKTRACE is now 1 09:14:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:14:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0037', '--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 09:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0037 --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 09:14:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5HxT27feu/Render', 09:14:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:37 INFO - 'HOME': '/Users/cltbld', 09:14:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:37 INFO - 'LOGNAME': 'cltbld', 09:14:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:14:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:37 INFO - 'MOZ_NO_REMOTE': '1', 09:14:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:14:37 INFO - 'NO_EM_RESTART': '1', 09:14:37 INFO - 'PAGER': '/bin/cat', 09:14:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:37 INFO - 'PWD': '/builds/slave/test', 09:14:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:14:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:37 INFO - 'RUST_BACKTRACE': '1', 09:14:37 INFO - 'SHELL': '/bin/bash', 09:14:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f9VlqJCxB1/Listeners', 09:14:37 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:14:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:37 INFO - 'USER': 'cltbld', 09:14:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:37 INFO - 'XPC_FLAGS': '0x0', 09:14:37 INFO - 'XPC_SERVICE_NAME': '0', 09:14:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0037', '--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 09:14:37 INFO - mozversion application_buildid: 20170308073826 09:14:37 INFO - mozversion application_changeset: 70790a08686d78e9f3bb83094c2c04bf1dad20f3 09:14:37 INFO - mozversion application_display_name: Nightly 09:14:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:14:37 INFO - mozversion application_name: Firefox 09:14:37 INFO - mozversion application_remotingname: firefox 09:14:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:14:37 INFO - mozversion application_vendor: Mozilla 09:14:37 INFO - mozversion application_version: 55.0a1 09:14:37 INFO - mozversion platform_buildid: 20170308073826 09:14:37 INFO - mozversion platform_changeset: 70790a08686d78e9f3bb83094c2c04bf1dad20f3 09:14:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:14:37 INFO - mozversion platform_version: 55.0a1 09:14:37 INFO - using testdate: 1488993277 09:14:37 INFO - actual date: 1488993277 09:14:37 INFO - starting webserver on 'localhost:52063' 09:14:37 INFO - SUITE-START | Running 2 tests 09:14:37 INFO - TEST-START | dromaeo_css 09:14:37 INFO - operating with platform_type : mac_ 09:14:37 INFO - Initialising browser for dromaeo_css test... 09:14:37 INFO - TEST-INFO | started process 7095 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpdoGRmX/profile http://localhost:52063/getInfo.html) 09:14:40 INFO - PROCESS | 7095 | 2017-03-08 09:14:40.642 plugin-container[7097:136256] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e37, name = 'com.apple.tsm.portname' 09:14:40 INFO - PROCESS | 7095 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:14:40 INFO - PROCESS | 7095 | 2017-03-08 09:14:40.645 plugin-container[7097:136256] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x911f, name = 'com.apple.CFPasteboardClient' 09:14:40 INFO - PROCESS | 7095 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:14:40 INFO - PROCESS | 7095 | 2017-03-08 09:14:40.645 plugin-container[7097:136256] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:14:40 INFO - PROCESS | 7095 | __metrics Screen width/height:1600/1200 09:14:40 INFO - PROCESS | 7095 | colorDepth:24 09:14:40 INFO - PROCESS | 7095 | Browser inner width/height: 1024/689 09:14:40 INFO - PROCESS | 7095 | __metrics 09:14:40 ERROR - PROCESS | 7095 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:14:41 INFO - TEST-INFO | 7095: exit 0 09:14:41 INFO - Browser initialized. 09:14:41 INFO - Running cycle 1/1 for dromaeo_css test... 09:14:41 INFO - TEST-INFO | started process 7098 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpdoGRmX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:14:44 INFO - PROCESS | 7098 | 2017-03-08 09:14:44.281 plugin-container[7099:136385] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 09:14:44 INFO - PROCESS | 7098 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:14:44 INFO - PROCESS | 7098 | 2017-03-08 09:14:44.281 plugin-container[7099:136385] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 09:14:44 INFO - PROCESS | 7098 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:14:44 INFO - PROCESS | 7098 | 2017-03-08 09:14:44.281 plugin-container[7099:136385] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:14:53 INFO - PROCESS | 7098 | RSS: Main: 226553856 09:14:53 INFO - PROCESS | 7098 | 09:17:58 INFO - PROCESS | 7098 | Cycle 1(1): loaded http://localhost:52063/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52063/tests/dromaeo/cssquery-ext.html) 09:17:59 INFO - PROCESS | 7098 | RSS: Main: 250187776 09:17:59 INFO - PROCESS | 7098 | 09:19:42 INFO - PROCESS | 7098 | ************************* 09:19:42 INFO - PROCESS | 7098 | A coding exception was thrown and uncaught in a Task. 09:19:42 INFO - PROCESS | 7098 | 09:19:42 ERROR - PROCESS | 7098 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:19:42 INFO - PROCESS | 7098 | Full stack: 09:19:42 INFO - PROCESS | 7098 | ************************* 09:21:02 INFO - PROCESS | 7098 | Cycle 1(1): loaded http://localhost:52063/tests/dromaeo/cssquery-ext.html (next: http://localhost:52063/tests/dromaeo/cssquery-jquery.html) 09:21:02 INFO - PROCESS | 7098 | RSS: Main: 257835008 09:21:02 INFO - PROCESS | 7098 | 09:24:04 INFO - PROCESS | 7098 | Cycle 1(1): loaded http://localhost:52063/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52063/tests/dromaeo/cssquery-mootools.html) 09:24:05 INFO - PROCESS | 7098 | RSS: Main: 272220160 09:24:05 INFO - PROCESS | 7098 | 09:27:07 INFO - PROCESS | 7098 | Cycle 1(1): loaded http://localhost:52063/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52063/tests/dromaeo/cssquery-prototype.html) 09:27:07 INFO - PROCESS | 7098 | RSS: Main: 264523776 09:27:07 INFO - PROCESS | 7098 | 09:30:10 INFO - PROCESS | 7098 | Cycle 1(1): loaded http://localhost:52063/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52063/tests/dromaeo/cssquery-yui.html) 09:30:11 INFO - PROCESS | 7098 | RSS: Main: 274747392 09:30:11 INFO - PROCESS | 7098 | 09:33:14 INFO - PROCESS | 7098 | Cycle 1(1): loaded http://localhost:52063/tests/dromaeo/cssquery-yui.html (next: http://localhost:52063/tests/dromaeo/cssquery-dojo.html) 09:33:15 INFO - PROCESS | 7098 | RSS: Main: 268861440 09:33:15 INFO - PROCESS | 7098 | 09:33:15 INFO - PROCESS | 7098 | __start_tp_report 09:33:15 INFO - PROCESS | 7098 | _x_x_mozilla_page_load 09:33:15 INFO - PROCESS | 7098 | _x_x_mozilla_page_load_details 09:33:15 INFO - PROCESS | 7098 | |i|pagename|runs| 09:33:15 INFO - PROCESS | 7098 | |0;dojo.html;3321.4519604265297;3339.432296509593;3424.691777740003;3427.503012063249;3438.6217516073243;5278.973605131974;5340.691530575004;5403.78384864606;5760.7695692172265;5821.18503409523;5816.621919575225;5933.88132237355;5940.792072277472;6029.427204415573;6040.063790112528;4324.351347297899;4947.604191664658;5384.838454846361;5415.891682166354;5428.538574221196;5313.814016509404;5415.48552887477;5456.372517160537;5504.779808807642;5520.972395138018;4346.608805207515;5280.023195708806;5360.16917377807;5370.167624018264;5397.73011349431;5347.946520534783;5354.063038968204;5379.9731001345135;5403.7027963462;5436.673799571999;5321.068812957756;5327.493888080665;5364.865878353034;5365.704886231256;5394.973025134849;4531.731115287725;4911.68074075184;5138.58891288696;5646.57650676201;5659.462351076641;6209.223847019122;6269.843253918643;6279.686015699197;6313.589616674902;6335.904961425582;6403.807885763435;6409.327020662809;6412.93587064128;6413.134226879358;6430.1319321891415;5498.257735205736;5871.823845284648;5925.674087925119;6118.235220597469;6139.263288405331;6477.481801455872;6479.060536222316;6506.479481641457;6518.511111666549;6522.510811689142;6099.9390006099375;6138.846528836815;6207.441330280297;6259.624422534663;6275.811725648238;6244.063390491463;6299.496040316763;6342.175517182736;6356.110144579762;6356.745730170834;6314.273858506239;6323.336049714714;6326.5255105867245;6335.936640633535;6357.682115894187;6129.175413489998;6148.508119350441;6158.784442544489;6194.876102477925;6267.1539342189135;5160.354955630546;5177.534021938043;5184.8963020739375;5194.636375453758;5215.24378965048;4562.520935301812;4566.383538222298;4576.130535198302;4577.153226653079;4610.884727881763;5939.970300148558;5984.431479009487;6032.547558933097;6041.486473649701;6058.909116363258;6192.287886893065;6292.653904035231;6313.210848643919;6389.6805159741825;6397.840053998687;6068.908966365508;6132.110843927606;6145;6156.78451254204;6158.876822463615;5634.211210430543;5644.802431914945;5696.145578163226;5697.9430205698245;5698.458646428583;6312.242530896322;6396.93603063982;6524.771632992823;6535.836604084746;6648.4681225501845;5398;6343.90484142729;6356.841078973062;6388.169537960035;6396.872062558816;6474.870502590016;6498.772542960974;6557.573757705733;6567.9671601641685;6577.3422657733845;6230.6884655766125;6234.438900498977;6273.121762953281;6303.117563541016;6318.115463834974;6251.562390632794;6337.36626337381;6376.075469056868;6389.233292004989;6400.743970241138;6120.294761999531;6142.069075791408;6262.3111457738705;6290.402411771058;6293.96853015732;5400.351957765093;6235.844103897258;6253.624782513064;6284.497240220863;6335.3981371769605;5916.674582897982;5997.070454079625;6070.362611925861;6120.660465672032;6141.47797437205;4494.7752612368595;6278.843028924313;6293;6300.716467759054;6314.589551679126;5117.488251175002;6216.191895053614;6221.751129954932;6225.844353891189;6240.219972503437 09:33:15 INFO - PROCESS | 7098 | |1;ext.html;727.3054233207288;777.3392616276165;799.4403917257921;843.3379796859465;939.5959737312961;29291.56062659059;38387;38398.61601383992;38506.422403663964;38587.61412385879;36905.81547092261;37097.814510927405;37143.4428483573;37201.441978370276;37243.81378093112;7651.579163146025;7812.726554570591;10337.741556461073;10648.201384896125;10797.514111864946;10455.372677639329;10549.894501055007;10558.102561282261;10559.155267578613;10561.683149505527;8810.163034511712;10488.31825931312;10600.363978161335;10619.150467962583;10621.73445663857;10529.36823790575;10529.947350263237;10561.41912194829;10580.418077005723;10592.576296948113;10347.534360953776;10394.064534191883;10399.584016639325;10409.323393979366;10415.479226038744;32665;32759.50860737091;32936.6706332936;32967;33120.50319245213;3738.196287798109;3740.0519975390553;3761.492198553215;3775.754575952554;3907.7264591478606;20915.68626470604;24913.750862491324;24968.126115585867;25313.114041008477;25784.355391115372;3012.155704059167;3039.60485136933;3073.9538906916414;3115.8286294253808;3160.4469217886776;297109;298766;299316;299359;299669;42544;42552.361714573686;42684.7194584163;42687.786561067;42707;44263.114737705066;44432.77783611126;44465;44504;44518;12824.294663793395;12888.548900788428;12891.871081289068;12899.226046437056;12916.870831291755;10890.128789696806;11031.669049928514;11041.227114102096;11070.446477676083;11079.667609971713;9571.473568953774;11572.6528204154;11593.130515211393;11639.418029098511;11705;4853.247746599282;5694.487496125369;5709.571782116358;5727.971360143256;5739.684317362585;1005.3816902604846;1098.0501865885951;1104.3925840787535;1126.6507382711386;1172.9178957473066;22501.549969000527;25314.367140821258;25394.619080713805;26043.73956260451;26303.342416439744;1214.1986289049362;1220.9755804883853;1232.3961258983213;1268.1313300389263;1270.675977625718;13425;13463.057585968889;13611.455541778416;13627;13676.384562694702;368830;452051;459804;461053;462088;47324.504172103596;47457.762711186224;47697.52302476931;47870.28194577084;48008.27987580189;1116.7096554895786;1365.9521916733065;1397.9510717125056;1407.6269788506054;1412.9293535323193;21949.451263717896;23164;24634.876825615756;26934.057307994128;28213.858930706036;43036;45217.09565808731;45283.09433811371;45518;45741.771291143334;459745;461392;461430;463550;464737;23552.882235588713;29412.705872941853;31449.842750786098;31720.88976885798;31723.84138079295;29090;37122.07194820151;37691.246175075794;37949.241015180094;38093.4285985716;30575.847120764254;31219.37561248717;31874.36251274915;32075;32367.51448728318;31052.689473105882;31980.20049498781;32198.678013219567;32249.51625725663;32648.510272346408;31144.22139446532;31590.684093159693;31764.682353177097;31854.044378668677;31955.36089278248;26509.734902651497;29063.273418164714;29585.704142958293;29815.403691926473;30644.693553064182;22751.65872511947;23009;23079.19222827193;23515;23560.764392355857 09:33:15 INFO - PROCESS | 7098 | |2;jquery.html;398.6969902873817;401.48409294057143;412.8988397842529;429.9828006879725;444.2336968728943;19748.210071597136;20083.794972301654;20654.586908261863;20864;21034.684479732798;17708.911455442703;20439.386818395436;21113.577728445423;21487;21725.891370543166;5826.475617194462;6695.799126026227;6772.051912732221;6773.6613169341335;6778.254392016875;5207.765650545715;6524.760295543855;6545.410913017828;6550.770723024695;6560.639164845931;6297.779577714781;6322.146510221107;6565;6617.139771829656;6627.569208001489;6397.52018598607;6529.804105876831;6583.506237032194;6599.769008084694;6608.306127856602;6397.648129352884;6418.646974416405;6552.3120072392185;6908.516403851732;6957;24857.7571121445;24956;25108.87445562779;25166.49667006668;25297.87351063233;2406.6750988616486;2889.479893619148;2907.2731817045737;2927.970720292791;2952.3652414730936;15274.15992120433;15705.214739263105;15778.76331855023;15908.443204487787;16199.514014579581;1743.572824657967;1933.3040105561986;1944.572194117292;1963.342280336089;1984.28565716342;133410;158961;159736;162103.5684464734;162416;15648.66986306154;19127.808721912603;19990.400287991382;20247.570291565105;20631.587368252553;30241.848790755907;30284.09147725572;30363.089107326814;30408.239794005327;30425.69574304273;9516.23870090405;9782.5597848097;9861.605535778632;10102.898971010343;10142.137918276881;6866.866966950497;8032.1164671886045;8034.076081250614;8111.75664730059;8136;7408.777736667908;9157.908420915706;9267.76830579241;9408.529573521297;9427.622895084258;4084.5302790179508;4133.23535145999;4156.314208155681;4183.560726123713;4198.349255865284;826.8291103617573;833.3208435125354;928.3130483442204;1000.3347773730533;1070.8875568065396;15594.220288985505;17560.473185804447;18284.99432531197;20289.391318260477;20313.898430507754;1023.247912784103;1026.7022563456514;1054.1566746602687;1062.511244827388;1075.7203127186988;7019.929800701928;9642.855357169648;10084.344517606332;10132.848007279898;10566.947165264124;402547;546299;598840;614011;666061;83667.5816620913;118313.40843295728;152797;153974;159887;1231;1270.656922630903;1284.5825106840314;1292.9224246545236;1298.0718786068007;19133.13900874436;19227.61544769125;19320;19360.4191874244;19370.934598597214;48019.51980480151;50310.993780124925;60609.39390606037;62613;63194.68402658141;88804;90511.09488905026;90695;91430.54284728534;91611.25166245023;11494.367809770543;11612.954834064974;11804.881951180378;11810.468528916392;11826.645200643994;10879;11699.824502632637;12813.359332033548;13050.019743978179;13145.802812957621;9212.769680758034;9271.443713377219;10795.136389089012;10898.291611045257;11387.829182562102;10337.483125843528;10340.327878687862;10585.311954722918;10626.787464250827;10679.305845120043;11101.666949991513;11234.213605047567;11486.081113510732;11572;11659.591914282959;11293.265937714023;11389.487473063899;11644.6506604802;11678.88321116778;11755.471003804678;9161.358704890594;10759.838602421127;11262.887371126511;11318.60384886525;11319 09:33:15 INFO - PROCESS | 7098 | |3;mootools.html;1247.6880779805049;1543.660394713163;1548.790913226714;1554.308332791908;1589.952301430957;9092.363534552584;9336.159745622892;10165.034321739433;10723.571057157715;10762.7309317267;10255.487225638726;10551.736206594838;10771.138308935286;10792.78414431713;10837.67486975392;2309.0994512140237;2349.706286714161;2352.12971200656;2357.610994185957;2360.232924299601;2275.0217406515194;2293.3463962770616;2309.5727290451237;2314.4213946513373;2316.4324740438533;2030.35028790787;2264.8754318512474;2275.4425165834396;2288.771122887706;2293.1056887813766;2241.607718649246;2246.05665620439;2253.515494168745;2253.7408198057187;2258.6386178211574;2246.606843802328;2284.6344584866506;2309.307207837659;2309.3302942146756;2321.117975169425;10815.83776243357;10921.344719316787;10930.945345273301;10945.56217751289;10950.835737463944;5711.114777209539;5784.595078344518;5857.76568937242;5859.355470898197;5861.970690146564;3320.9833950830334;3393.253484233465;3571.499990001376;3642.9453558196647;3643.908902277438;1018.674024312213;1163.1625229834506;1208.7642909632625;1219.3171823778712;1229.5143418349792;15016.023958442665;15072.472463463835;15133;15147.242637868063;15173.696526069527;4732.290156476556;4851.708897466164;4857.951420485821;4936.185529387682;4985.725785081783;12554.623361299176;12601.936990314989;12629.684257893627;12632.17890837111;12655.11414200997;4832.275158726149;5813.593048486566;5880.294364676266;5890.293164820163;5891.05743081105;4330.350447432847;4751.714897106184;5017.8243761468175;5111.207762796698;5114.948850511521;3254.495553189259;4053.351463765783;4108.1783643271465;4134.834606615762;4148.647364974011;1137.8975892169744;1138.1107361779407;1144.227646338718;1148.4430051425052;1157.2824848593925;787.338635546144;800.7437619961556;811.3711873298123;819.2790344496805;824.1017291152673;6600.471962243009;6618.966905165444;6721.1598550181225;6775.424088952395;6800.65996700163;945.9858102128474;965.971020869375;970.7477354213128;981.087588542648;983.5475681186734;4040.454538637305;4104.91790164195;4112.568180341081;4160.1679664066705;4209.473815773028;12847.229166250056;13262.00534959882;13272.73454530904;13289;13290.269035202964;13090.672733181747;13103.27931963732;13113.409896554678;13115.147515411658;13138.540151094665;8116.553589552514;9673.516324183762;9843.852342214872;10020.498975051218;10038.498075096217;5062.949370506248;5461.9180712290145;5610.663360198401;6122.387761223842;6578.967105164444;11635.767284654428;11805.76388472243;11992.64022079339;12004.39978001114;12105.878941210474;11931.985781208694;11993.280403175837;12153.878461215629;12184;12202.450889710162;7829.7259595913865;8068.878966815385;8364.498130112213;8815.118488151391;8999.505027223566;9844.163246124159;9912.306138570231;9929.3545919515;9995.000499949947;10022.749431264278;7095.219525852049;8646.783830404038;8720.956395217983;8769.868451973098;8795.208431241219;8423.747287581382;8424.188799408157;8457.83084338322;8479.236868681848;8625.784355390915;7467.365273951775;7624.504407213513;7635.770926872203;7644.961775191088;7682.193369696325;5609.27079479665;5650.124230744241;5751.3673495916255;5847.239858818326;5874.118882167709;4985.277134815504;5575.494616453616;5769.423057694197;5999.460048595647;6041.093835924647 09:33:15 INFO - PROCESS | 7098 | |4;prototype.html;360.63575788453653;370.5516325246452;380.3742843023225;380.9580946095929;388.0415374026155;4170.540310891187;5202.32369791927;5218.504242096995;5236.895262094756;5329.89340213196;4995.400551933773;5019.372578427697;5082.720450375237;5127.589792816575;5174.404943431507;3457.7925324480484;3613.4579813027995;4197.796157017568;4300.849470268541;4317.244482215616;3886.300465916148;4265.829366825324;4271.850485233017;4288.828446862138;4294.549072347405;3225.58067451231;3394.8132852693093;3939.369700847865;4192.685548583853;4206.284931561642;3347.6150242722156;3500.9869325138147;4122.402251673506;4137.469136419529;4267.530571637118;3329.5516450344176;3598.676119149289;3912.9261491409598;3933.606639336072;3953.782541960191;4597.494275629678;4627.861164165047;4721.881952951169;4864.7324397158145;4875.073735990186;4020.8592699255387;4295.312749959991;4841.080194762983;4889.975550122226;4892.0705066037335;4746.131902499452;4977.203647416396;5179.663321884065;5438.301954670822;5489.286392768954;3532.823358832048;3641.635836416364;3805.934338385229;4215.636815895534;4273.807678654468;4954.355933728592;5461.9453805462235;5639.700775046603;5696.743646535916;5749.712514374264;3615.5480564929385;4390.463337831733;4404.713693609905;4414.293713005968;4428.6678499112695;4019.517657881073;4398.868033958986;4471.597556219956;4511.015153332523;4581.427321584802;3987.980060099681;4664.253719404879;4692.41344831896;4711.233287517181;4960.578350840219;4854.927176092361;5403.243545903577;5422.834090670524;5434.152388188035;5480.301106656949;1412.632715493979;1508.0725353907487;1529.936693997661;1530.051368151753;1533.6242620557812;1091.4924560079442;1098.5170020472458;1111.4609414434155;1144.8626164860104;1174.647605718281;4340.587644173862;4536.546345365503;5093.465186155475;5137.434882163034;5151.356080489939;4847.733374664427;4902.068606964665;5139;5658.74535645897;5693.971530142406;4369.431973843443;4760.64295177863;4855.6115510759055;4901.460839307673;4917.8361520930675;2433.9756602434104;2648.2187754612437;2847.302410909299;2937.3097322129283;2985.68629802886;5565.7217139143695;5631.549476041907;5677.473524281655;5827.417258274223;5882.970585147047;5051.787570982938;5143.868348963222;5325.4142044375085;5712.314522257356;5790.420957904176;4897.449349221857;4902.730349830794;5453.754581043862;5842.853928651818;5957.421283359913;4868.2210846264425;5286.76209570578;5668.574856885668;5679.659220446786;5770.480656740913;4537.8411755588395;5025.547700706954;5423.291663126102;5613.915791263053;5688.431156884279;5636.774529018958;5667.489925906523;5686.715664216856;5755.323774444903;5866.50134738552;3737.7944213067453;4096.32410652239;4186.35111557709;4268.59136484953;4439.778011099496;4830.2513110467935;4857.39705897046;5250.26496290512;5556.527695145958;5658.660480371191;4440.933385999278;4584.656150788771;4726.598239149574;4739.952600473951;5214.921776173436;5083.745812709423;5448.536874365724;5457.508824205682;5458.2610911525735;5470.234167216672;4328.018223234694;4825.565699087099;5012.974935125301;5185.144451165666;5338.2526446298325;4307.966088138886;5024.346834911438;5388.757505912322;5453.209284653783;5578.972105139448;4563.767782698586;4844.498205556148;5185.844424667266;5337.599680023936;5430.6198566101575 09:33:15 INFO - PROCESS | 7098 | |5;yui.html;644.5198327246196;682.1302838880426;770.718687678997;800.8998875140608;801.0947629179028;11366.545338186474;15566.45517406891;15697.52907412778;15995.84004159958;16134.435294764686;11318.151138664593;13423.798643020362;13740.725185496283;13968.930155349235;14025.929870350634;786.2098590916121;906.4425378392291;916.7937214126835;929.9767505812375;931.65063101337;887.4808237181256;936.2322895225917;944.2493217891758;945.6737425588161;949.1220620925616;1001.9248556358266;1007.3905287301187;1007.9697609071703;1008.3445760255819;1009.1926458832941;987.0770829274616;998.9600415983425;999.3554157568364;999.8350272205114;1000.0599436529639;728.1698863295846;736.5654263984221;742.7548908860064;743.7991742229568;746.6714645555992;15865.286062127232;16120.274587643584;16489.0930998795;16848.91575542127;17045.573860653458;12210.450529726184;13571.321433928362;13992.09051411655;14006.649833754136;14080.647983800385;10714.839277410845;11571.6528504145;11600.94199528997;11703.473343699554;12296.139270251086;3561.1453251219523;3615.114296997226;3630.80030598317;3758.1732018955786;3774.792386418746;108966;110848.44575777151;111056;111169;112204.99999999919;10471.633492827714;10675.199360047873;10753.301035432673;10850.348979061124;11174.050205732441;22187.445313867283;22258.332250032527;22341;22341.99461024258;22374.552508950055;7592.202818704067;7661.157272699998;7731.613419329011;7939.64271607779;7950.960245198737;4195.811188496525;4268.893277668083;4312.986448184674;4327.956720432755;4463.732176069446;3379.763416560817;3412.7611067225553;3421.8802341917913;3478.3912815257736;3489.0056333944704;533.9145736682112;537.301508039547;539.5710410223882;542.1731858915107;635.4916067146264;644.9742010319628;669.5547460938421;702.8348338140532;710.8720430322488;737.9594122323216;7221.53060051095;10144.847827282596;10261;10305;10316.474946772181;659.423004870738;689.917209934801;694.885343918258;697.6232834269539;703.5848849179009;4554.612857907049;4879.926801097987;4886.560209581084;4892.804287828518;4894.8042078317185;86609;89994.65008024884;104057.95942040483;105429.41855872168;106576.46711766547;82859.5857020723;83727;84496.57751711202;85578.57210713907;86253;75185.62407187928;79814;81322.59338703268;81828.18171818324;82186.58906705548;8716.302695784196;9489.66786162481;9507.429554226768;9524.523773811421;9563.904360956301;41396.58603414048;44851.59544839592;46713.59859204229;51388.74305628448;53269.46730532645;51269.743651281504;60002;64160.67919660372;67945.32054679588;68929.65535172292;10152.340097893613;12395.132340736061;12431.875681243071;12481.315396113603;13096.607101786962;14505.782413263598;14537.854621454073;14583.416663333348;14665.193414362313;14827.851721482648;11890.940545297564;13218.758207831243;13523.188608683511;13739.24434156131;13838.23889686077;13437.664058398619;13456.127397178248;13612.183269003892;13618.591442256748;13720.725585488433;13005.544805931748;13049.739005220032;13197.142185757895;13317.200967941955;14001.22993235382;10737.355758654505;10892.945535272273;11114.388708621103;11332.206745528063;11431.885681143081;11168.557398832118;11264.493097810455;11274.154438417316;11315.377654229096;11349.54601815918 09:33:15 INFO - PROCESS | 7098 | __end_tp_report 09:33:15 INFO - PROCESS | 7098 | __start_cc_report 09:33:15 INFO - PROCESS | 7098 | _x_x_mozilla_cycle_collect,429 09:33:15 INFO - PROCESS | 7098 | __end_cc_report 09:33:15 INFO - PROCESS | 7098 | __startTimestamp1488994395165__endTimestamp 09:33:15 INFO - PROCESS | 7098 | 09:33:15 INFO - PROCESS | 7098 | ------- Summary: start ------- 09:33:15 INFO - PROCESS | 7098 | Number of tests: 6 09:33:15 INFO - PROCESS | 7098 | 09:33:15 INFO - PROCESS | 7098 | [#0] dojo.html Cycles:165 Average:5837.60 Median:6138.85 stddev:691.83 (11.3%) stddev-sans-first:665.19 09:33:15 INFO - PROCESS | 7098 | Values: 3321.5 3339.4 3424.7 3427.5 3438.6 5279.0 5340.7 5403.8 5760.8 5821.2 5816.6 5933.9 5940.8 6029.4 6040.1 4324.4 4947.6 5384.8 5415.9 5428.5 5313.8 5415.5 5456.4 5504.8 5521.0 4346.6 5280.0 5360.2 5370.2 5397.7 5347.9 5354.1 5380.0 5403.7 5436.7 5321.1 5327.5 5364.9 5365.7 5395.0 4531.7 4911.7 5138.6 5646.6 5659.5 6209.2 6269.8 6279.7 6313.6 6335.9 6403.8 6409.3 6412.9 6413.1 6430.1 5498.3 5871.8 5925.7 6118.2 6139.3 6477.5 6479.1 6506.5 6518.5 6522.5 6099.9 6138.8 6207.4 6259.6 6275.8 6244.1 6299.5 6342.2 6356.1 6356.7 6314.3 6323.3 6326.5 6335.9 6357.7 6129.2 6148.5 6158.8 6194.9 6267.2 5160.4 5177.5 5184.9 5194.6 5215.2 4562.5 4566.4 4576.1 4577.2 4610.9 5940.0 5984.4 6032.5 6041.5 6058.9 6192.3 6292.7 6313.2 6389.7 6397.8 6068.9 6132.1 6145.0 6156.8 6158.9 5634.2 5644.8 5696.1 5697.9 5698.5 6312.2 6396.9 6524.8 6535.8 6648.5 5398.0 6343.9 6356.8 6388.2 6396.9 6474.9 6498.8 6557.6 6568.0 6577.3 6230.7 6234.4 6273.1 6303.1 6318.1 6251.6 6337.4 6376.1 6389.2 6400.7 6120.3 6142.1 6262.3 6290.4 6294.0 5400.4 6235.8 6253.6 6284.5 6335.4 5916.7 5997.1 6070.4 6120.7 6141.5 4494.8 6278.8 6293.0 6300.7 6314.6 5117.5 6216.2 6221.8 6225.8 6240.2 09:33:15 INFO - PROCESS | 7098 | 09:33:15 INFO - PROCESS | 7098 | [#1] ext.html Cycles:180 Average:52570.37 Median:16807.73 stddev:108385.42 (644.9%) stddev-sans-first:108619.57 09:33:15 INFO - PROCESS | 7098 | Values: 727.3 777.3 799.4 843.3 939.6 29291.6 38387.0 38398.6 38506.4 38587.6 36905.8 37097.8 37143.4 37201.4 37243.8 7651.6 7812.7 10337.7 10648.2 10797.5 10455.4 10549.9 10558.1 10559.2 10561.7 8810.2 10488.3 10600.4 10619.2 10621.7 10529.4 10529.9 10561.4 10580.4 10592.6 10347.5 10394.1 10399.6 10409.3 10415.5 32665.0 32759.5 32936.7 32967.0 33120.5 3738.2 3740.1 3761.5 3775.8 3907.7 20915.7 24913.8 24968.1 25313.1 25784.4 3012.2 3039.6 3074.0 3115.8 3160.4 297109.0 298766.0 299316.0 299359.0 299669.0 42544.0 42552.4 42684.7 42687.8 42707.0 44263.1 44432.8 44465.0 44504.0 44518.0 12824.3 12888.5 12891.9 12899.2 12916.9 10890.1 11031.7 11041.2 11070.4 11079.7 9571.5 11572.7 11593.1 11639.4 11705.0 4853.2 5694.5 5709.6 5728.0 5739.7 1005.4 1098.1 1104.4 1126.7 1172.9 22501.5 25314.4 25394.6 26043.7 26303.3 1214.2 1221.0 1232.4 1268.1 1270.7 13425.0 13463.1 13611.5 13627.0 13676.4 368830.0 452051.0 459804.0 461053.0 462088.0 47324.5 47457.8 47697.5 47870.3 48008.3 1116.7 1366.0 1398.0 1407.6 1412.9 21949.5 23164.0 24634.9 26934.1 28213.9 43036.0 45217.1 45283.1 45518.0 45741.8 459745.0 461392.0 461430.0 463550.0 464737.0 23552.9 29412.7 31449.8 31720.9 31723.8 29090.0 37122.1 37691.2 37949.2 38093.4 30575.8 31219.4 31874.4 32075.0 32367.5 31052.7 31980.2 32198.7 32249.5 32648.5 31144.2 31590.7 31764.7 31854.0 31955.4 26509.7 29063.3 29585.7 29815.4 30644.7 22751.7 23009.0 23079.2 23515.0 23560.8 09:33:15 INFO - PROCESS | 7098 | 09:33:15 INFO - PROCESS | 7098 | [#2] jquery.html Cycles:180 Average:36970.93 Median:21300.29 stddev:97223.55 (456.4%) stddev-sans-first:97457.50 09:33:15 INFO - PROCESS | 7098 | Values: 398.7 401.5 412.9 430.0 444.2 19748.2 20083.8 20654.6 20864.0 21034.7 17708.9 20439.4 21113.6 21487.0 21725.9 5826.5 6695.8 6772.1 6773.7 6778.3 5207.8 6524.8 6545.4 6550.8 6560.6 6297.8 6322.1 6565.0 6617.1 6627.6 6397.5 6529.8 6583.5 6599.8 6608.3 6397.6 6418.6 6552.3 6908.5 6957.0 24857.8 24956.0 25108.9 25166.5 25297.9 2406.7 2889.5 2907.3 2928.0 2952.4 15274.2 15705.2 15778.8 15908.4 16199.5 1743.6 1933.3 1944.6 1963.3 1984.3 133410.0 158961.0 159736.0 162103.6 162416.0 15648.7 19127.8 19990.4 20247.6 20631.6 30241.8 30284.1 30363.1 30408.2 30425.7 9516.2 9782.6 9861.6 10102.9 10142.1 6866.9 8032.1 8034.1 8111.8 8136.0 7408.8 9157.9 9267.8 9408.5 9427.6 4084.5 4133.2 4156.3 4183.6 4198.3 826.8 833.3 928.3 1000.3 1070.9 15594.2 17560.5 18285.0 20289.4 20313.9 1023.2 1026.7 1054.2 1062.5 1075.7 7019.9 9642.9 10084.3 10132.8 10566.9 402547.0 546299.0 598840.0 614011.0 666061.0 83667.6 118313.4 152797.0 153974.0 159887.0 1231.0 1270.7 1284.6 1292.9 1298.1 19133.1 19227.6 19320.0 19360.4 19370.9 48019.5 50311.0 60609.4 62613.0 63194.7 88804.0 90511.1 90695.0 91430.5 91611.3 11494.4 11613.0 11804.9 11810.5 11826.6 10879.0 11699.8 12813.4 13050.0 13145.8 9212.8 9271.4 10795.1 10898.3 11387.8 10337.5 10340.3 10585.3 10626.8 10679.3 11101.7 11234.2 11486.1 11572.0 11659.6 11293.3 11389.5 11644.7 11678.9 11755.5 9161.4 10759.8 11262.9 11318.6 11319.0 09:33:15 INFO - PROCESS | 7098 | 09:33:15 INFO - PROCESS | 7098 | [#3] mootools.html Cycles:180 Average:6546.96 Median:3643.43 stddev:4124.61 (113.2%) stddev-sans-first:4116.95 09:33:15 INFO - PROCESS | 7098 | Values: 1247.7 1543.7 1548.8 1554.3 1590.0 9092.4 9336.2 10165.0 10723.6 10762.7 10255.5 10551.7 10771.1 10792.8 10837.7 2309.1 2349.7 2352.1 2357.6 2360.2 2275.0 2293.3 2309.6 2314.4 2316.4 2030.4 2264.9 2275.4 2288.8 2293.1 2241.6 2246.1 2253.5 2253.7 2258.6 2246.6 2284.6 2309.3 2309.3 2321.1 10815.8 10921.3 10930.9 10945.6 10950.8 5711.1 5784.6 5857.8 5859.4 5862.0 3321.0 3393.3 3571.5 3642.9 3643.9 1018.7 1163.2 1208.8 1219.3 1229.5 15016.0 15072.5 15133.0 15147.2 15173.7 4732.3 4851.7 4858.0 4936.2 4985.7 12554.6 12601.9 12629.7 12632.2 12655.1 4832.3 5813.6 5880.3 5890.3 5891.1 4330.4 4751.7 5017.8 5111.2 5114.9 3254.5 4053.4 4108.2 4134.8 4148.6 1137.9 1138.1 1144.2 1148.4 1157.3 787.3 800.7 811.4 819.3 824.1 6600.5 6619.0 6721.2 6775.4 6800.7 946.0 966.0 970.7 981.1 983.5 4040.5 4104.9 4112.6 4160.2 4209.5 12847.2 13262.0 13272.7 13289.0 13290.3 13090.7 13103.3 13113.4 13115.1 13138.5 8116.6 9673.5 9843.9 10020.5 10038.5 5062.9 5461.9 5610.7 6122.4 6579.0 11635.8 11805.8 11992.6 12004.4 12105.9 11932.0 11993.3 12153.9 12184.0 12202.5 7829.7 8068.9 8364.5 8815.1 8999.5 9844.2 9912.3 9929.4 9995.0 10022.7 7095.2 8646.8 8721.0 8769.9 8795.2 8423.7 8424.2 8457.8 8479.2 8625.8 7467.4 7624.5 7635.8 7645.0 7682.2 5609.3 5650.1 5751.4 5847.2 5874.1 4985.3 5575.5 5769.4 5999.5 6041.1 09:33:15 INFO - PROCESS | 7098 | 09:33:15 INFO - PROCESS | 7098 | [#4] prototype.html Cycles:180 Average:4456.21 Median:4842.79 stddev:1270.01 (26.2%) stddev-sans-first:1235.81 09:33:15 INFO - PROCESS | 7098 | Values: 360.6 370.6 380.4 381.0 388.0 4170.5 5202.3 5218.5 5236.9 5329.9 4995.4 5019.4 5082.7 5127.6 5174.4 3457.8 3613.5 4197.8 4300.8 4317.2 3886.3 4265.8 4271.9 4288.8 4294.5 3225.6 3394.8 3939.4 4192.7 4206.3 3347.6 3501.0 4122.4 4137.5 4267.5 3329.6 3598.7 3912.9 3933.6 3953.8 4597.5 4627.9 4721.9 4864.7 4875.1 4020.9 4295.3 4841.1 4890.0 4892.1 4746.1 4977.2 5179.7 5438.3 5489.3 3532.8 3641.6 3805.9 4215.6 4273.8 4954.4 5461.9 5639.7 5696.7 5749.7 3615.5 4390.5 4404.7 4414.3 4428.7 4019.5 4398.9 4471.6 4511.0 4581.4 3988.0 4664.3 4692.4 4711.2 4960.6 4854.9 5403.2 5422.8 5434.2 5480.3 1412.6 1508.1 1529.9 1530.1 1533.6 1091.5 1098.5 1111.5 1144.9 1174.6 4340.6 4536.5 5093.5 5137.4 5151.4 4847.7 4902.1 5139.0 5658.7 5694.0 4369.4 4760.6 4855.6 4901.5 4917.8 2434.0 2648.2 2847.3 2937.3 2985.7 5565.7 5631.5 5677.5 5827.4 5883.0 5051.8 5143.9 5325.4 5712.3 5790.4 4897.4 4902.7 5453.8 5842.9 5957.4 4868.2 5286.8 5668.6 5679.7 5770.5 4537.8 5025.5 5423.3 5613.9 5688.4 5636.8 5667.5 5686.7 5755.3 5866.5 3737.8 4096.3 4186.4 4268.6 4439.8 4830.3 4857.4 5250.3 5556.5 5658.7 4440.9 4584.7 4726.6 4740.0 5214.9 5083.7 5448.5 5457.5 5458.3 5470.2 4328.0 4825.6 5013.0 5185.1 5338.3 4308.0 5024.3 5388.8 5453.2 5579.0 4563.8 4844.5 5185.8 5337.6 5430.6 09:33:15 INFO - PROCESS | 7098 | 09:33:15 INFO - PROCESS | 7098 | [#5] yui.html Cycles:180 Average:20180.21 Median:3450.14 stddev:29080.72 (842.9%) stddev-sans-first:29125.30 09:33:15 INFO - PROCESS | 7098 | Values: 644.5 682.1 770.7 800.9 801.1 11366.5 15566.5 15697.5 15995.8 16134.4 11318.2 13423.8 13740.7 13968.9 14025.9 786.2 906.4 916.8 930.0 931.7 887.5 936.2 944.2 945.7 949.1 1001.9 1007.4 1008.0 1008.3 1009.2 987.1 999.0 999.4 999.8 1000.1 728.2 736.6 742.8 743.8 746.7 15865.3 16120.3 16489.1 16848.9 17045.6 12210.5 13571.3 13992.1 14006.6 14080.6 10714.8 11571.7 11600.9 11703.5 12296.1 3561.1 3615.1 3630.8 3758.2 3774.8 108966.0 110848.4 111056.0 111169.0 112205.0 10471.6 10675.2 10753.3 10850.3 11174.1 22187.4 22258.3 22341.0 22342.0 22374.6 7592.2 7661.2 7731.6 7939.6 7951.0 4195.8 4268.9 4313.0 4328.0 4463.7 3379.8 3412.8 3421.9 3478.4 3489.0 533.9 537.3 539.6 542.2 635.5 645.0 669.6 702.8 710.9 738.0 7221.5 10144.8 10261.0 10305.0 10316.5 659.4 689.9 694.9 697.6 703.6 4554.6 4879.9 4886.6 4892.8 4894.8 86609.0 89994.7 104058.0 105429.4 106576.5 82859.6 83727.0 84496.6 85578.6 86253.0 75185.6 79814.0 81322.6 81828.2 82186.6 8716.3 9489.7 9507.4 9524.5 9563.9 41396.6 44851.6 46713.6 51388.7 53269.5 51269.7 60002.0 64160.7 67945.3 68929.7 10152.3 12395.1 12431.9 12481.3 13096.6 14505.8 14537.9 14583.4 14665.2 14827.9 11890.9 13218.8 13523.2 13739.2 13838.2 13437.7 13456.1 13612.2 13618.6 13720.7 13005.5 13049.7 13197.1 13317.2 14001.2 10737.4 10892.9 11114.4 11332.2 11431.9 11168.6 11264.5 11274.2 11315.4 11349.5 09:33:15 INFO - PROCESS | 7098 | -------- Summary: end -------- 09:33:15 INFO - PROCESS | 7098 | 09:33:15 INFO - TEST-INFO | 7098: exit 0 09:33:15 INFO - TEST-OK | dromaeo_css | took 1118375ms 09:33:15 INFO - TEST-START | kraken 09:33:15 INFO - operating with platform_type : mac_ 09:33:15 INFO - Initialising browser for kraken test... 09:33:15 INFO - TEST-INFO | started process 7103 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpasLL8C/profile http://localhost:52063/getInfo.html) 09:33:18 INFO - PROCESS | 7103 | 2017-03-08 09:33:18.779 plugin-container[7105:138568] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 09:33:18 INFO - PROCESS | 7103 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:33:18 INFO - PROCESS | 7103 | 2017-03-08 09:33:18.783 plugin-container[7105:138568] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 09:33:18 INFO - PROCESS | 7103 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:33:18 INFO - PROCESS | 7103 | 2017-03-08 09:33:18.783 plugin-container[7105:138568] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:33:18 INFO - PROCESS | 7103 | __metrics Screen width/height:1600/1200 09:33:18 INFO - PROCESS | 7103 | colorDepth:24 09:33:18 INFO - PROCESS | 7103 | Browser inner width/height: 1024/689 09:33:18 INFO - PROCESS | 7103 | __metrics 09:33:18 ERROR - PROCESS | 7103 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:33:19 INFO - TEST-INFO | 7103: exit 0 09:33:19 INFO - Browser initialized. 09:33:19 INFO - Running cycle 1/1 for kraken test... 09:33:19 INFO - TEST-INFO | started process 7106 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpasLL8C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:33:22 INFO - PROCESS | 7106 | 2017-03-08 09:33:22.392 plugin-container[7107:138694] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b47, name = 'com.apple.tsm.portname' 09:33:22 INFO - PROCESS | 7106 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:33:22 INFO - PROCESS | 7106 | 2017-03-08 09:33:22.392 plugin-container[7107:138694] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.CFPasteboardClient' 09:33:22 INFO - PROCESS | 7106 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:33:22 INFO - PROCESS | 7106 | 2017-03-08 09:33:22.392 plugin-container[7107:138694] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:33:32 INFO - PROCESS | 7106 | RSS: Main: 226713600 09:33:32 INFO - PROCESS | 7106 | 09:33:35 INFO - PROCESS | 7106 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52063/tests/kraken/driver.html?testName=audio-beat-detection) 09:33:35 INFO - PROCESS | 7106 | RSS: Main: 241291264 09:33:35 INFO - PROCESS | 7106 | 09:33:38 INFO - PROCESS | 7106 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52063/tests/kraken/driver.html?testName=audio-dft) 09:33:39 INFO - PROCESS | 7106 | RSS: Main: 249737216 09:33:39 INFO - PROCESS | 7106 | 09:33:43 INFO - PROCESS | 7106 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52063/tests/kraken/driver.html?testName=audio-fft) 09:33:43 INFO - PROCESS | 7106 | RSS: Main: 252411904 09:33:43 INFO - PROCESS | 7106 | 09:33:45 INFO - PROCESS | 7106 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52063/tests/kraken/driver.html?testName=audio-oscillator) 09:33:46 INFO - PROCESS | 7106 | RSS: Main: 253599744 09:33:46 INFO - PROCESS | 7106 | 09:33:48 INFO - PROCESS | 7106 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52063/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:33:48 INFO - PROCESS | 7106 | RSS: Main: 254017536 09:33:48 INFO - PROCESS | 7106 | 09:33:51 INFO - PROCESS | 7106 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52063/tests/kraken/driver.html?testName=imaging-darkroom) 09:33:52 INFO - PROCESS | 7106 | RSS: Main: 261066752 09:33:52 INFO - PROCESS | 7106 | 09:33:55 INFO - PROCESS | 7106 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52063/tests/kraken/driver.html?testName=imaging-desaturate) 09:33:56 INFO - PROCESS | 7106 | RSS: Main: 261378048 09:33:56 INFO - PROCESS | 7106 | 09:34:00 INFO - PROCESS | 7106 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52063/tests/kraken/driver.html?testName=json-parse-financial) 09:34:00 INFO - PROCESS | 7106 | RSS: Main: 258662400 09:34:00 INFO - PROCESS | 7106 | 09:34:02 INFO - PROCESS | 7106 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52063/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:34:03 INFO - PROCESS | 7106 | RSS: Main: 259915776 09:34:03 INFO - PROCESS | 7106 | 09:34:05 INFO - PROCESS | 7106 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:34:05 INFO - PROCESS | 7106 | RSS: Main: 260329472 09:34:05 INFO - PROCESS | 7106 | 09:34:08 INFO - PROCESS | 7106 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:34:08 INFO - PROCESS | 7106 | RSS: Main: 260608000 09:34:08 INFO - PROCESS | 7106 | 09:34:11 INFO - PROCESS | 7106 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:34:12 INFO - PROCESS | 7106 | RSS: Main: 260952064 09:34:12 INFO - PROCESS | 7106 | 09:34:16 INFO - PROCESS | 7106 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:34:16 INFO - PROCESS | 7106 | RSS: Main: 258912256 09:34:16 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | Cycle 1(1): loaded http://localhost:52063/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52063/tests/kraken/driver.html?testName=ai-astar) 09:34:18 INFO - PROCESS | 7106 | RSS: Main: 262418432 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | __start_tp_report 09:34:18 INFO - PROCESS | 7106 | _x_x_mozilla_page_load 09:34:18 INFO - PROCESS | 7106 | _x_x_mozilla_page_load_details 09:34:18 INFO - PROCESS | 7106 | |i|pagename|runs| 09:34:18 INFO - PROCESS | 7106 | |0;ai-astar;163;175;122;127;119;121;119;122;117;121 09:34:18 INFO - PROCESS | 7106 | |1;audio-beat-detection;153;145;112;185;146;130;109;114;130;112 09:34:18 INFO - PROCESS | 7106 | |2;audio-dft;225;267;264;279;280;202;195;198;207;211 09:34:18 INFO - PROCESS | 7106 | |3;audio-fft;85;59;62;87;61;60;58;60;74;62 09:34:18 INFO - PROCESS | 7106 | |4;audio-oscillator;91;71;86;73;71;71;84;70;72;86 09:34:18 INFO - PROCESS | 7106 | |5;imaging-gaussian-blur;89;88;102;88;88;88;88;89;89;85 09:34:18 INFO - PROCESS | 7106 | |6;imaging-darkroom;97;111;140;134;96;101;100;97;97;95 09:34:18 INFO - PROCESS | 7106 | |7;imaging-desaturate;153;98;134;131;97;112;92;107;151;150 09:34:18 INFO - PROCESS | 7106 | |8;json-parse-financial;65;66;67;69;68;67;67;68;69;82 09:34:18 INFO - PROCESS | 7106 | |9;json-stringify-tinderbox;59;76;96;89;76;57;58;73;86;87 09:34:18 INFO - PROCESS | 7106 | |10;stanford-crypto-aes;90;89;88;92;91;92;89;92;96;109 09:34:18 INFO - PROCESS | 7106 | |11;stanford-crypto-ccm;162;163;157;189;136;145;167;164;215;174 09:34:18 INFO - PROCESS | 7106 | |12;stanford-crypto-pbkdf2;215;234;175;209;241;225;233;185;176;205 09:34:18 INFO - PROCESS | 7106 | |13;stanford-crypto-sha256-iterative;66;66;69;67;67;67;73;86;87;69 09:34:18 INFO - PROCESS | 7106 | __end_tp_report 09:34:18 INFO - PROCESS | 7106 | __start_cc_report 09:34:18 INFO - PROCESS | 7106 | _x_x_mozilla_cycle_collect,1015 09:34:18 INFO - PROCESS | 7106 | __end_cc_report 09:34:18 INFO - PROCESS | 7106 | __startTimestamp1488994458765__endTimestamp 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | ------- Summary: start ------- 09:34:18 INFO - PROCESS | 7106 | Number of tests: 14 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | [#0] ai-astar Cycles:10 Average:130.60 Median:122.00 stddev:20.60 (16.9%) stddev-sans-first:18.21 09:34:18 INFO - PROCESS | 7106 | Values: 163.0 175.0 122.0 127.0 119.0 121.0 119.0 122.0 117.0 121.0 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | [#1] audio-beat-detection Cycles:10 Average:133.60 Median:137.50 stddev:24.15 (17.6%) stddev-sans-first:24.58 09:34:18 INFO - PROCESS | 7106 | Values: 153.0 145.0 112.0 185.0 146.0 130.0 109.0 114.0 130.0 112.0 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | [#2] audio-dft Cycles:10 Average:232.80 Median:244.50 stddev:35.43 (14.5%) stddev-sans-first:37.46 09:34:18 INFO - PROCESS | 7106 | Values: 225.0 267.0 264.0 279.0 280.0 202.0 195.0 198.0 207.0 211.0 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | [#3] audio-fft Cycles:10 Average:66.80 Median:62.00 stddev:11.06 (17.8%) stddev-sans-first:9.58 09:34:18 INFO - PROCESS | 7106 | Values: 85.0 59.0 62.0 87.0 61.0 60.0 58.0 60.0 74.0 62.0 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.50 stddev:8.18 (10.4%) stddev-sans-first:7.07 09:34:18 INFO - PROCESS | 7106 | Values: 91.0 71.0 86.0 73.0 71.0 71.0 84.0 70.0 72.0 86.0 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | [#5] imaging-gaussian-blur Cycles:10 Average:89.40 Median:88.00 stddev:4.58 (5.2%) stddev-sans-first:4.85 09:34:18 INFO - PROCESS | 7106 | Values: 89.0 88.0 102.0 88.0 88.0 88.0 88.0 89.0 89.0 85.0 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | [#6] imaging-darkroom Cycles:10 Average:106.80 Median:95.50 stddev:16.61 (17.4%) stddev-sans-first:17.24 09:34:18 INFO - PROCESS | 7106 | Values: 97.0 111.0 140.0 134.0 96.0 101.0 100.0 97.0 97.0 95.0 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | [#7] imaging-desaturate Cycles:10 Average:122.50 Median:152.00 stddev:24.12 (15.9%) stddev-sans-first:22.92 09:34:18 INFO - PROCESS | 7106 | Values: 153.0 98.0 134.0 131.0 97.0 112.0 92.0 107.0 151.0 150.0 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | [#8] json-parse-financial Cycles:10 Average:68.80 Median:68.00 stddev:4.80 (7.1%) stddev-sans-first:4.89 09:34:18 INFO - PROCESS | 7106 | Values: 65.0 66.0 67.0 69.0 68.0 67.0 67.0 68.0 69.0 82.0 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | [#9] json-stringify-tinderbox Cycles:10 Average:75.70 Median:81.00 stddev:14.03 (17.3%) stddev-sans-first:13.52 09:34:18 INFO - PROCESS | 7106 | Values: 59.0 76.0 96.0 89.0 76.0 57.0 58.0 73.0 86.0 87.0 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | [#10] stanford-crypto-aes Cycles:10 Average:92.80 Median:91.50 stddev:6.12 (6.7%) stddev-sans-first:6.41 09:34:18 INFO - PROCESS | 7106 | Values: 90.0 89.0 88.0 92.0 91.0 92.0 89.0 92.0 96.0 109.0 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | [#11] stanford-crypto-ccm Cycles:10 Average:167.20 Median:165.50 stddev:22.19 (13.4%) stddev-sans-first:23.46 09:34:18 INFO - PROCESS | 7106 | Values: 162.0 163.0 157.0 189.0 136.0 145.0 167.0 164.0 215.0 174.0 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:209.80 Median:220.00 stddev:24.38 (11.1%) stddev-sans-first:25.78 09:34:18 INFO - PROCESS | 7106 | Values: 215.0 234.0 175.0 209.0 241.0 225.0 233.0 185.0 176.0 205.0 09:34:18 INFO - PROCESS | 7106 | 09:34:18 INFO - PROCESS | 7106 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:69.00 stddev:8.07 (11.7%) stddev-sans-first:8.29 09:34:18 INFO - PROCESS | 7106 | Values: 66.0 66.0 69.0 67.0 67.0 67.0 73.0 86.0 87.0 69.0 09:34:18 INFO - PROCESS | 7106 | -------- Summary: end -------- 09:34:18 INFO - PROCESS | 7106 | 09:34:19 INFO - TEST-INFO | 7106: exit 0 09:34:19 INFO - TEST-OK | kraken | took 63797ms 09:34:19 INFO - SUITE-END | took 1182s 09:34:19 INFO - Completed test suite (00:19:42) 09:34:19 INFO - javascript benchmark 09:34:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7476.095404088704, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3321.4519604265297, 3339.432296509593, 3424.691777740003, 3427.503012063249, 3438.6217516073243, 5278.973605131974, 5340.691530575004, 5403.78384864606, 5760.7695692172265, 5821.18503409523, 5816.621919575225, 5933.88132237355, 5940.792072277472, 6029.427204415573, 6040.063790112528, 4324.351347297899, 4947.604191664658, 5384.838454846361, 5415.891682166354, 5428.538574221196, 5313.814016509404, 5415.48552887477, 5456.372517160537, 5504.779808807642, 5520.972395138018, 4346.608805207515, 5280.023195708806, 5360.16917377807, 5370.167624018264, 5397.73011349431, 5347.946520534783, 5354.063038968204, 5379.9731001345135, 5403.7027963462, 5436.673799571999, 5321.068812957756, 5327.493888080665, 5364.865878353034, 5365.704886231256, 5394.973025134849, 4531.731115287725, 4911.68074075184, 5138.58891288696, 5646.57650676201, 5659.462351076641, 6209.223847019122, 6269.843253918643, 6279.686015699197, 6313.589616674902, 6335.904961425582, 6403.807885763435, 6409.327020662809, 6412.93587064128, 6413.134226879358, 6430.1319321891415, 5498.257735205736, 5871.823845284648, 5925.674087925119, 6118.235220597469, 6139.263288405331, 6477.481801455872, 6479.060536222316, 6506.479481641457, 6518.511111666549, 6522.510811689142, 6099.9390006099375, 6138.846528836815, 6207.441330280297, 6259.624422534663, 6275.811725648238, 6244.063390491463, 6299.496040316763, 6342.175517182736, 6356.110144579762, 6356.745730170834, 6314.273858506239, 6323.336049714714, 6326.5255105867245, 6335.936640633535, 6357.682115894187, 6129.175413489998, 6148.508119350441, 6158.784442544489, 6194.876102477925, 6267.1539342189135, 5160.354955630546, 5177.534021938043, 5184.8963020739375, 5194.636375453758, 5215.24378965048, 4562.520935301812, 4566.383538222298, 4576.130535198302, 4577.153226653079, 4610.884727881763, 5939.970300148558, 5984.431479009487, 6032.547558933097, 6041.486473649701, 6058.909116363258, 6192.287886893065, 6292.653904035231, 6313.210848643919, 6389.6805159741825, 6397.840053998687, 6068.908966365508, 6132.110843927606, 6145.0, 6156.78451254204, 6158.876822463615, 5634.211210430543, 5644.802431914945, 5696.145578163226, 5697.9430205698245, 5698.458646428583, 6312.242530896322, 6396.93603063982, 6524.771632992823, 6535.836604084746, 6648.4681225501845, 5398.0, 6343.90484142729, 6356.841078973062, 6388.169537960035, 6396.872062558816, 6474.870502590016, 6498.772542960974, 6557.573757705733, 6567.9671601641685, 6577.3422657733845, 6230.6884655766125, 6234.438900498977, 6273.121762953281, 6303.117563541016, 6318.115463834974, 6251.562390632794, 6337.36626337381, 6376.075469056868, 6389.233292004989, 6400.743970241138, 6120.294761999531, 6142.069075791408, 6262.3111457738705, 6290.402411771058, 6293.96853015732, 5400.351957765093, 6235.844103897258, 6253.624782513064, 6284.497240220863, 6335.3981371769605, 5916.674582897982, 5997.070454079625, 6070.362611925861, 6120.660465672032, 6141.47797437205, 4494.7752612368595, 6278.843028924313, 6293.0, 6300.716467759054, 6314.589551679126, 5117.488251175002, 6216.191895053614, 6221.751129954932, 6225.844353891189, 6240.219972503437], "value": 5794.349760644862, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.3054233207288, 777.3392616276165, 799.4403917257921, 843.3379796859465, 939.5959737312961, 29291.56062659059, 38387.0, 38398.61601383992, 38506.422403663964, 38587.61412385879, 36905.81547092261, 37097.814510927405, 37143.4428483573, 37201.441978370276, 37243.81378093112, 7651.579163146025, 7812.726554570591, 10337.741556461073, 10648.201384896125, 10797.514111864946, 10455.372677639329, 10549.894501055007, 10558.102561282261, 10559.155267578613, 10561.683149505527, 8810.163034511712, 10488.31825931312, 10600.363978161335, 10619.150467962583, 10621.73445663857, 10529.36823790575, 10529.947350263237, 10561.41912194829, 10580.418077005723, 10592.576296948113, 10347.534360953776, 10394.064534191883, 10399.584016639325, 10409.323393979366, 10415.479226038744, 32665.0, 32759.50860737091, 32936.6706332936, 32967.0, 33120.50319245213, 3738.196287798109, 3740.0519975390553, 3761.492198553215, 3775.754575952554, 3907.7264591478606, 20915.68626470604, 24913.750862491324, 24968.126115585867, 25313.114041008477, 25784.355391115372, 3012.155704059167, 3039.60485136933, 3073.9538906916414, 3115.8286294253808, 3160.4469217886776, 297109.0, 298766.0, 299316.0, 299359.0, 299669.0, 42544.0, 42552.361714573686, 42684.7194584163, 42687.786561067, 42707.0, 44263.114737705066, 44432.77783611126, 44465.0, 44504.0, 44518.0, 12824.294663793395, 12888.548900788428, 12891.871081289068, 12899.226046437056, 12916.870831291755, 10890.128789696806, 11031.669049928514, 11041.227114102096, 11070.446477676083, 11079.667609971713, 9571.473568953774, 11572.6528204154, 11593.130515211393, 11639.418029098511, 11705.0, 4853.247746599282, 5694.487496125369, 5709.571782116358, 5727.971360143256, 5739.684317362585, 1005.3816902604846, 1098.0501865885951, 1104.3925840787535, 1126.6507382711386, 1172.9178957473066, 22501.549969000527, 25314.367140821258, 25394.619080713805, 26043.73956260451, 26303.342416439744, 1214.1986289049362, 1220.9755804883853, 1232.3961258983213, 1268.1313300389263, 1270.675977625718, 13425.0, 13463.057585968889, 13611.455541778416, 13627.0, 13676.384562694702, 368830.0, 452051.0, 459804.0, 461053.0, 462088.0, 47324.504172103596, 47457.762711186224, 47697.52302476931, 47870.28194577084, 48008.27987580189, 1116.7096554895786, 1365.9521916733065, 1397.9510717125056, 1407.6269788506054, 1412.9293535323193, 21949.451263717896, 23164.0, 24634.876825615756, 26934.057307994128, 28213.858930706036, 43036.0, 45217.09565808731, 45283.09433811371, 45518.0, 45741.771291143334, 459745.0, 461392.0, 461430.0, 463550.0, 464737.0, 23552.882235588713, 29412.705872941853, 31449.842750786098, 31720.88976885798, 31723.84138079295, 29090.0, 37122.07194820151, 37691.246175075794, 37949.241015180094, 38093.4285985716, 30575.847120764254, 31219.37561248717, 31874.36251274915, 32075.0, 32367.51448728318, 31052.689473105882, 31980.20049498781, 32198.678013219567, 32249.51625725663, 32648.510272346408, 31144.22139446532, 31590.684093159693, 31764.682353177097, 31854.044378668677, 31955.36089278248, 26509.734902651497, 29063.273418164714, 29585.704142958293, 29815.403691926473, 30644.693553064182, 22751.65872511947, 23009.0, 23079.19222827193, 23515.0, 23560.764392355857], "value": 17738.41110519774, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.6969902873817, 401.48409294057143, 412.8988397842529, 429.9828006879725, 444.2336968728943, 19748.210071597136, 20083.794972301654, 20654.586908261863, 20864.0, 21034.684479732798, 17708.911455442703, 20439.386818395436, 21113.577728445423, 21487.0, 21725.891370543166, 5826.475617194462, 6695.799126026227, 6772.051912732221, 6773.6613169341335, 6778.254392016875, 5207.765650545715, 6524.760295543855, 6545.410913017828, 6550.770723024695, 6560.639164845931, 6297.779577714781, 6322.146510221107, 6565.0, 6617.139771829656, 6627.569208001489, 6397.52018598607, 6529.804105876831, 6583.506237032194, 6599.769008084694, 6608.306127856602, 6397.648129352884, 6418.646974416405, 6552.3120072392185, 6908.516403851732, 6957.0, 24857.7571121445, 24956.0, 25108.87445562779, 25166.49667006668, 25297.87351063233, 2406.6750988616486, 2889.479893619148, 2907.2731817045737, 2927.970720292791, 2952.3652414730936, 15274.15992120433, 15705.214739263105, 15778.76331855023, 15908.443204487787, 16199.514014579581, 1743.572824657967, 1933.3040105561986, 1944.572194117292, 1963.342280336089, 1984.28565716342, 133410.0, 158961.0, 159736.0, 162103.5684464734, 162416.0, 15648.66986306154, 19127.808721912603, 19990.400287991382, 20247.570291565105, 20631.587368252553, 30241.848790755907, 30284.09147725572, 30363.089107326814, 30408.239794005327, 30425.69574304273, 9516.23870090405, 9782.5597848097, 9861.605535778632, 10102.898971010343, 10142.137918276881, 6866.866966950497, 8032.1164671886045, 8034.076081250614, 8111.75664730059, 8136.0, 7408.777736667908, 9157.908420915706, 9267.76830579241, 9408.529573521297, 9427.622895084258, 4084.5302790179508, 4133.23535145999, 4156.314208155681, 4183.560726123713, 4198.349255865284, 826.8291103617573, 833.3208435125354, 928.3130483442204, 1000.3347773730533, 1070.8875568065396, 15594.220288985505, 17560.473185804447, 18284.99432531197, 20289.391318260477, 20313.898430507754, 1023.247912784103, 1026.7022563456514, 1054.1566746602687, 1062.511244827388, 1075.7203127186988, 7019.929800701928, 9642.855357169648, 10084.344517606332, 10132.848007279898, 10566.947165264124, 402547.0, 546299.0, 598840.0, 614011.0, 666061.0, 83667.5816620913, 118313.40843295728, 152797.0, 153974.0, 159887.0, 1231.0, 1270.656922630903, 1284.5825106840314, 1292.9224246545236, 1298.0718786068007, 19133.13900874436, 19227.61544769125, 19320.0, 19360.4191874244, 19370.934598597214, 48019.51980480151, 50310.993780124925, 60609.39390606037, 62613.0, 63194.68402658141, 88804.0, 90511.09488905026, 90695.0, 91430.54284728534, 91611.25166245023, 11494.367809770543, 11612.954834064974, 11804.881951180378, 11810.468528916392, 11826.645200643994, 10879.0, 11699.824502632637, 12813.359332033548, 13050.019743978179, 13145.802812957621, 9212.769680758034, 9271.443713377219, 10795.136389089012, 10898.291611045257, 11387.829182562102, 10337.483125843528, 10340.327878687862, 10585.311954722918, 10626.787464250827, 10679.305845120043, 11101.666949991513, 11234.213605047567, 11486.081113510732, 11572.0, 11659.591914282959, 11293.265937714023, 11389.487473063899, 11644.6506604802, 11678.88321116778, 11755.471003804678, 9161.358704890594, 10759.838602421127, 11262.887371126511, 11318.60384886525, 11319.0], "value": 11087.624396109893, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1247.6880779805049, 1543.660394713163, 1548.790913226714, 1554.308332791908, 1589.952301430957, 9092.363534552584, 9336.159745622892, 10165.034321739433, 10723.571057157715, 10762.7309317267, 10255.487225638726, 10551.736206594838, 10771.138308935286, 10792.78414431713, 10837.67486975392, 2309.0994512140237, 2349.706286714161, 2352.12971200656, 2357.610994185957, 2360.232924299601, 2275.0217406515194, 2293.3463962770616, 2309.5727290451237, 2314.4213946513373, 2316.4324740438533, 2030.35028790787, 2264.8754318512474, 2275.4425165834396, 2288.771122887706, 2293.1056887813766, 2241.607718649246, 2246.05665620439, 2253.515494168745, 2253.7408198057187, 2258.6386178211574, 2246.606843802328, 2284.6344584866506, 2309.307207837659, 2309.3302942146756, 2321.117975169425, 10815.83776243357, 10921.344719316787, 10930.945345273301, 10945.56217751289, 10950.835737463944, 5711.114777209539, 5784.595078344518, 5857.76568937242, 5859.355470898197, 5861.970690146564, 3320.9833950830334, 3393.253484233465, 3571.499990001376, 3642.9453558196647, 3643.908902277438, 1018.674024312213, 1163.1625229834506, 1208.7642909632625, 1219.3171823778712, 1229.5143418349792, 15016.023958442665, 15072.472463463835, 15133.0, 15147.242637868063, 15173.696526069527, 4732.290156476556, 4851.708897466164, 4857.951420485821, 4936.185529387682, 4985.725785081783, 12554.623361299176, 12601.936990314989, 12629.684257893627, 12632.17890837111, 12655.11414200997, 4832.275158726149, 5813.593048486566, 5880.294364676266, 5890.293164820163, 5891.05743081105, 4330.350447432847, 4751.714897106184, 5017.8243761468175, 5111.207762796698, 5114.948850511521, 3254.495553189259, 4053.351463765783, 4108.1783643271465, 4134.834606615762, 4148.647364974011, 1137.8975892169744, 1138.1107361779407, 1144.227646338718, 1148.4430051425052, 1157.2824848593925, 787.338635546144, 800.7437619961556, 811.3711873298123, 819.2790344496805, 824.1017291152673, 6600.471962243009, 6618.966905165444, 6721.1598550181225, 6775.424088952395, 6800.65996700163, 945.9858102128474, 965.971020869375, 970.7477354213128, 981.087588542648, 983.5475681186734, 4040.454538637305, 4104.91790164195, 4112.568180341081, 4160.1679664066705, 4209.473815773028, 12847.229166250056, 13262.00534959882, 13272.73454530904, 13289.0, 13290.269035202964, 13090.672733181747, 13103.27931963732, 13113.409896554678, 13115.147515411658, 13138.540151094665, 8116.553589552514, 9673.516324183762, 9843.852342214872, 10020.498975051218, 10038.498075096217, 5062.949370506248, 5461.9180712290145, 5610.663360198401, 6122.387761223842, 6578.967105164444, 11635.767284654428, 11805.76388472243, 11992.64022079339, 12004.39978001114, 12105.878941210474, 11931.985781208694, 11993.280403175837, 12153.878461215629, 12184.0, 12202.450889710162, 7829.7259595913865, 8068.878966815385, 8364.498130112213, 8815.118488151391, 8999.505027223566, 9844.163246124159, 9912.306138570231, 9929.3545919515, 9995.000499949947, 10022.749431264278, 7095.219525852049, 8646.783830404038, 8720.956395217983, 8769.868451973098, 8795.208431241219, 8423.747287581382, 8424.188799408157, 8457.83084338322, 8479.236868681848, 8625.784355390915, 7467.365273951775, 7624.504407213513, 7635.770926872203, 7644.961775191088, 7682.193369696325, 5609.27079479665, 5650.124230744241, 5751.3673495916255, 5847.239858818326, 5874.118882167709, 4985.277134815504, 5575.494616453616, 5769.423057694197, 5999.460048595647, 6041.093835924647], "value": 4986.051246857503, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.63575788453653, 370.5516325246452, 380.3742843023225, 380.9580946095929, 388.0415374026155, 4170.540310891187, 5202.32369791927, 5218.504242096995, 5236.895262094756, 5329.89340213196, 4995.400551933773, 5019.372578427697, 5082.720450375237, 5127.589792816575, 5174.404943431507, 3457.7925324480484, 3613.4579813027995, 4197.796157017568, 4300.849470268541, 4317.244482215616, 3886.300465916148, 4265.829366825324, 4271.850485233017, 4288.828446862138, 4294.549072347405, 3225.58067451231, 3394.8132852693093, 3939.369700847865, 4192.685548583853, 4206.284931561642, 3347.6150242722156, 3500.9869325138147, 4122.402251673506, 4137.469136419529, 4267.530571637118, 3329.5516450344176, 3598.676119149289, 3912.9261491409598, 3933.606639336072, 3953.782541960191, 4597.494275629678, 4627.861164165047, 4721.881952951169, 4864.7324397158145, 4875.073735990186, 4020.8592699255387, 4295.312749959991, 4841.080194762983, 4889.975550122226, 4892.0705066037335, 4746.131902499452, 4977.203647416396, 5179.663321884065, 5438.301954670822, 5489.286392768954, 3532.823358832048, 3641.635836416364, 3805.934338385229, 4215.636815895534, 4273.807678654468, 4954.355933728592, 5461.9453805462235, 5639.700775046603, 5696.743646535916, 5749.712514374264, 3615.5480564929385, 4390.463337831733, 4404.713693609905, 4414.293713005968, 4428.6678499112695, 4019.517657881073, 4398.868033958986, 4471.597556219956, 4511.015153332523, 4581.427321584802, 3987.980060099681, 4664.253719404879, 4692.41344831896, 4711.233287517181, 4960.578350840219, 4854.927176092361, 5403.243545903577, 5422.834090670524, 5434.152388188035, 5480.301106656949, 1412.632715493979, 1508.0725353907487, 1529.936693997661, 1530.051368151753, 1533.6242620557812, 1091.4924560079442, 1098.5170020472458, 1111.4609414434155, 1144.8626164860104, 1174.647605718281, 4340.587644173862, 4536.546345365503, 5093.465186155475, 5137.434882163034, 5151.356080489939, 4847.733374664427, 4902.068606964665, 5139.0, 5658.74535645897, 5693.971530142406, 4369.431973843443, 4760.64295177863, 4855.6115510759055, 4901.460839307673, 4917.8361520930675, 2433.9756602434104, 2648.2187754612437, 2847.302410909299, 2937.3097322129283, 2985.68629802886, 5565.7217139143695, 5631.549476041907, 5677.473524281655, 5827.417258274223, 5882.970585147047, 5051.787570982938, 5143.868348963222, 5325.4142044375085, 5712.314522257356, 5790.420957904176, 4897.449349221857, 4902.730349830794, 5453.754581043862, 5842.853928651818, 5957.421283359913, 4868.2210846264425, 5286.76209570578, 5668.574856885668, 5679.659220446786, 5770.480656740913, 4537.8411755588395, 5025.547700706954, 5423.291663126102, 5613.915791263053, 5688.431156884279, 5636.774529018958, 5667.489925906523, 5686.715664216856, 5755.323774444903, 5866.50134738552, 3737.7944213067453, 4096.32410652239, 4186.35111557709, 4268.59136484953, 4439.778011099496, 4830.2513110467935, 4857.39705897046, 5250.26496290512, 5556.527695145958, 5658.660480371191, 4440.933385999278, 4584.656150788771, 4726.598239149574, 4739.952600473951, 5214.921776173436, 5083.745812709423, 5448.536874365724, 5457.508824205682, 5458.2610911525735, 5470.234167216672, 4328.018223234694, 4825.565699087099, 5012.974935125301, 5185.144451165666, 5338.2526446298325, 4307.966088138886, 5024.346834911438, 5388.757505912322, 5453.209284653783, 5578.972105139448, 4563.767782698586, 4844.498205556148, 5185.844424667266, 5337.599680023936, 5430.6198566101575], "value": 4093.477244220359, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.5198327246196, 682.1302838880426, 770.718687678997, 800.8998875140608, 801.0947629179028, 11366.545338186474, 15566.45517406891, 15697.52907412778, 15995.84004159958, 16134.435294764686, 11318.151138664593, 13423.798643020362, 13740.725185496283, 13968.930155349235, 14025.929870350634, 786.2098590916121, 906.4425378392291, 916.7937214126835, 929.9767505812375, 931.65063101337, 887.4808237181256, 936.2322895225917, 944.2493217891758, 945.6737425588161, 949.1220620925616, 1001.9248556358266, 1007.3905287301187, 1007.9697609071703, 1008.3445760255819, 1009.1926458832941, 987.0770829274616, 998.9600415983425, 999.3554157568364, 999.8350272205114, 1000.0599436529639, 728.1698863295846, 736.5654263984221, 742.7548908860064, 743.7991742229568, 746.6714645555992, 15865.286062127232, 16120.274587643584, 16489.0930998795, 16848.91575542127, 17045.573860653458, 12210.450529726184, 13571.321433928362, 13992.09051411655, 14006.649833754136, 14080.647983800385, 10714.839277410845, 11571.6528504145, 11600.94199528997, 11703.473343699554, 12296.139270251086, 3561.1453251219523, 3615.114296997226, 3630.80030598317, 3758.1732018955786, 3774.792386418746, 108966.0, 110848.44575777151, 111056.0, 111169.0, 112204.99999999919, 10471.633492827714, 10675.199360047873, 10753.301035432673, 10850.348979061124, 11174.050205732441, 22187.445313867283, 22258.332250032527, 22341.0, 22341.99461024258, 22374.552508950055, 7592.202818704067, 7661.157272699998, 7731.613419329011, 7939.64271607779, 7950.960245198737, 4195.811188496525, 4268.893277668083, 4312.986448184674, 4327.956720432755, 4463.732176069446, 3379.763416560817, 3412.7611067225553, 3421.8802341917913, 3478.3912815257736, 3489.0056333944704, 533.9145736682112, 537.301508039547, 539.5710410223882, 542.1731858915107, 635.4916067146264, 644.9742010319628, 669.5547460938421, 702.8348338140532, 710.8720430322488, 737.9594122323216, 7221.53060051095, 10144.847827282596, 10261.0, 10305.0, 10316.474946772181, 659.423004870738, 689.917209934801, 694.885343918258, 697.6232834269539, 703.5848849179009, 4554.612857907049, 4879.926801097987, 4886.560209581084, 4892.804287828518, 4894.8042078317185, 86609.0, 89994.65008024884, 104057.95942040483, 105429.41855872168, 106576.46711766547, 82859.5857020723, 83727.0, 84496.57751711202, 85578.57210713907, 86253.0, 75185.62407187928, 79814.0, 81322.59338703268, 81828.18171818324, 82186.58906705548, 8716.302695784196, 9489.66786162481, 9507.429554226768, 9524.523773811421, 9563.904360956301, 41396.58603414048, 44851.59544839592, 46713.59859204229, 51388.74305628448, 53269.46730532645, 51269.743651281504, 60002.0, 64160.67919660372, 67945.32054679588, 68929.65535172292, 10152.340097893613, 12395.132340736061, 12431.875681243071, 12481.315396113603, 13096.607101786962, 14505.782413263598, 14537.854621454073, 14583.416663333348, 14665.193414362313, 14827.851721482648, 11890.940545297564, 13218.758207831243, 13523.188608683511, 13739.24434156131, 13838.23889686077, 13437.664058398619, 13456.127397178248, 13612.183269003892, 13618.591442256748, 13720.725585488433, 13005.544805931748, 13049.739005220032, 13197.142185757895, 13317.200967941955, 14001.22993235382, 10737.355758654505, 10892.945535272273, 11114.388708621103, 11332.206745528063, 11431.885681143081, 11168.557398832118, 11264.493097810455, 11274.154438417316, 11315.377654229096, 11349.54601815918], "value": 7505.866915200044, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1646.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 175.0, 122.0, 127.0, 119.0, 121.0, 119.0, 122.0, 117.0, 121.0], "value": 130.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 145.0, 112.0, 185.0, 146.0, 130.0, 109.0, 114.0, 130.0, 112.0], "value": 133.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [225.0, 267.0, 264.0, 279.0, 280.0, 202.0, 195.0, 198.0, 207.0, 211.0], "value": 232.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 59.0, 62.0, 87.0, 61.0, 60.0, 58.0, 60.0, 74.0, 62.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 86.0, 73.0, 71.0, 71.0, 84.0, 70.0, 72.0, 86.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 102.0, 88.0, 88.0, 88.0, 88.0, 89.0, 89.0, 85.0], "value": 89.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 111.0, 140.0, 134.0, 96.0, 101.0, 100.0, 97.0, 97.0, 95.0], "value": 106.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 98.0, 134.0, 131.0, 97.0, 112.0, 92.0, 107.0, 151.0, 150.0], "value": 122.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 69.0, 68.0, 67.0, 67.0, 68.0, 69.0, 82.0], "value": 68.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 76.0, 96.0, 89.0, 76.0, 57.0, 58.0, 73.0, 86.0, 87.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 88.0, 92.0, 91.0, 92.0, 89.0, 92.0, 96.0, 109.0], "value": 92.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 157.0, 189.0, 136.0, 145.0, 167.0, 164.0, 215.0, 174.0], "value": 167.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [215.0, 234.0, 175.0, 209.0, 241.0, 225.0, 233.0, 185.0, 176.0, 205.0], "value": 209.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 69.0, 67.0, 67.0, 67.0, 73.0, 86.0, 87.0, 69.0], "value": 71.7, "unit": "score"}]}]} 09:34:20 INFO - Return code: 0 09:34:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:34:20 ERROR - # TBPL SUCCESS # 09:34:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:34:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:34:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:34:20 INFO - 2017-03-08 09:34:20.119 screenresolution[7111:139073] starting screenresolution argv=screenresolution get 09:34:20 INFO - 2017-03-08 09:34:20.122 screenresolution[7111:139073] Display 0: 1600x1200x32@60 09:34:20 INFO - 2017-03-08 09:34:20.128 screenresolution[7112:139076] starting screenresolution argv=screenresolution list 09:34:20 INFO - Available Modes on Display 0 09:34:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:34:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:34:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:34:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:34:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:34:20 INFO - Intel Iris: 09:34:20 INFO - Chipset Model: Intel Iris 09:34:20 INFO - Type: GPU 09:34:20 INFO - Bus: Built-In 09:34:20 INFO - VRAM (Dynamic, Max): 1536 MB 09:34:20 INFO - Vendor: Intel (0x8086) 09:34:20 INFO - Device ID: 0x0a2e 09:34:20 INFO - Revision ID: 0x0009 09:34:20 INFO - Displays: 09:34:20 INFO - Display: 09:34:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:34:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:34:20 INFO - Display Serial Number: 1600x1200 60 09:34:20 INFO - Main Display: Yes 09:34:20 INFO - Mirror: Off 09:34:20 INFO - Online: Yes 09:34:20 INFO - Rotation: Supported 09:34:20 INFO - Return code: 0 09:34:20 INFO - Running post-action listener: _resource_record_post_action 09:34:20 INFO - [mozharness: 2017-03-08 17:34:20.363725Z] Finished run-tests step (success) 09:34:20 INFO - Running post-run listener: _resource_record_post_run 09:34:20 INFO - Total resource usage - Wall time: 1203s; CPU: 55.0%; Read bytes: 552960; Write bytes: 515868160; Read time: 5; Write time: 1690 09:34:20 INFO - TinderboxPrint: CPU usage
54.6% 09:34:20 INFO - TinderboxPrint: I/O read bytes / time
552,960 / 5 09:34:20 INFO - TinderboxPrint: I/O write bytes / time
515,868,160 / 1,690 09:34:20 INFO - TinderboxPrint: CPU idle
2,186.8 (45.4%) 09:34:20 INFO - TinderboxPrint: CPU system
969.8 (20.1%) 09:34:20 INFO - TinderboxPrint: CPU user
1,657.1 (34.4%) 09:34:20 INFO - TinderboxPrint: Swap in / out
210,296,832 / 0 09:34:20 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203418112; Write bytes: 204457984; Read time: 16055; Write time: 503 09:34:20 INFO - run-tests - Wall time: 1184s; CPU: 55.0%; Read bytes: 552960; Write bytes: 301506048; Read time: 5; Write time: 1158 09:34:20 INFO - Running post-run listener: _upload_blobber_files 09:34:20 INFO - Blob upload gear active. 09:34:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:34:20 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 09:34:20 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'] 09:34:20 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 09:34:21 INFO - (blobuploader) - INFO - Open directory for files ... 09:34:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:34:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:34:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:22 INFO - (blobuploader) - INFO - Done attempting. 09:34:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:34:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:34:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:23 INFO - (blobuploader) - INFO - Done attempting. 09:34:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:34:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:34:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:24 INFO - (blobuploader) - INFO - Done attempting. 09:34:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:34:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:34:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:26 INFO - (blobuploader) - INFO - Done attempting. 09:34:26 INFO - (blobuploader) - INFO - Iteration through files over. 09:34:26 INFO - Return code: 0 09:34:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:34:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:34:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d77762c421329532ec3e8a130572f336f430285c5bc0bf4e026f9b21a45c45a68aed1080b681256d8b562fa2d9b87b5112996727a76ab2b79164ef935fb1c21f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81c77a58d5f3da8e7a6670b28e613cdfb8d2b84f7a36d3f2f71ea80bde828c2a4f9fe7f0808a9d2226fa2ba6f684c7a0fc6e912b16835327d6c25ec5d198128c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc5986f5902de07762099d46819a7f1d553fb41ff94a2eb30386286f138b6edc1513b38c775abf2f8d4d0a20d64cc292f561b21aa787129fd4f89353e097ccb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b66786b8ceb299dc53d87c572a3b7de579f329f1e5f9627b42eedfda48e58830bb2db2fe463efba5b8a06273905b2494b2a73cf86e2a1d1418d8f7e7705f36e"} 09:34:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:34:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:34:26 INFO - Contents: 09:34:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d77762c421329532ec3e8a130572f336f430285c5bc0bf4e026f9b21a45c45a68aed1080b681256d8b562fa2d9b87b5112996727a76ab2b79164ef935fb1c21f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81c77a58d5f3da8e7a6670b28e613cdfb8d2b84f7a36d3f2f71ea80bde828c2a4f9fe7f0808a9d2226fa2ba6f684c7a0fc6e912b16835327d6c25ec5d198128c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc5986f5902de07762099d46819a7f1d553fb41ff94a2eb30386286f138b6edc1513b38c775abf2f8d4d0a20d64cc292f561b21aa787129fd4f89353e097ccb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b66786b8ceb299dc53d87c572a3b7de579f329f1e5f9627b42eedfda48e58830bb2db2fe463efba5b8a06273905b2494b2a73cf86e2a1d1418d8f7e7705f36e"} 09:34:26 INFO - Running post-run listener: copy_logs_to_upload_dir 09:34:26 INFO - Copying logs to upload dir... 09:34:26 INFO - mkdir: /builds/slave/test/build/upload/logs 09:34:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.646420 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-08 09:34:26.236358) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-08 09:34:26.240193) ========= 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.w5HxT27feu/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.f9VlqJCxB1/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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/d77762c421329532ec3e8a130572f336f430285c5bc0bf4e026f9b21a45c45a68aed1080b681256d8b562fa2d9b87b5112996727a76ab2b79164ef935fb1c21f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81c77a58d5f3da8e7a6670b28e613cdfb8d2b84f7a36d3f2f71ea80bde828c2a4f9fe7f0808a9d2226fa2ba6f684c7a0fc6e912b16835327d6c25ec5d198128c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc5986f5902de07762099d46819a7f1d553fb41ff94a2eb30386286f138b6edc1513b38c775abf2f8d4d0a20d64cc292f561b21aa787129fd4f89353e097ccb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b66786b8ceb299dc53d87c572a3b7de579f329f1e5f9627b42eedfda48e58830bb2db2fe463efba5b8a06273905b2494b2a73cf86e2a1d1418d8f7e7705f36e"} build_url:https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011383 build_url: 'https://queue.taskcluster.net/v1/task/W814j9yQSNy3dAL9zQLoiA/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/d77762c421329532ec3e8a130572f336f430285c5bc0bf4e026f9b21a45c45a68aed1080b681256d8b562fa2d9b87b5112996727a76ab2b79164ef935fb1c21f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81c77a58d5f3da8e7a6670b28e613cdfb8d2b84f7a36d3f2f71ea80bde828c2a4f9fe7f0808a9d2226fa2ba6f684c7a0fc6e912b16835327d6c25ec5d198128c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc5986f5902de07762099d46819a7f1d553fb41ff94a2eb30386286f138b6edc1513b38c775abf2f8d4d0a20d64cc292f561b21aa787129fd4f89353e097ccb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b66786b8ceb299dc53d87c572a3b7de579f329f1e5f9627b42eedfda48e58830bb2db2fe463efba5b8a06273905b2494b2a73cf86e2a1d1418d8f7e7705f36e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-08 09:34:26.279722) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 09:34:26.280503) ========= 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.w5HxT27feu/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.f9VlqJCxB1/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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.005472 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 09:34:26.331900) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-08 09:34:26.332551) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-08 09:34:26.333215) ========= ========= Total master_lag: 0.20 =========