builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0266 starttime: 1491972518.54 results: success (0) buildid: 20170411201809 builduid: 0d8be032e19847518a63d8bbbbdcfe58 revision: f40e24f40b4c4556944c762d4764eace261297f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 21:48:38.537978) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 21:48:38.538408) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 21:48:38.538696) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 21:48:38.565465) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 21:48:38.566032) ========= 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.IJAXQbEFYD/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.RA15kkdZUc/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-11 21:48:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.9M=0.001s 2017-04-11 21:48:38 (16.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129748 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 21:48:38.720663) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 21:48:38.721011) ========= 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.IJAXQbEFYD/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.RA15kkdZUc/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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.026694 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 21:48:38.771361) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 21:48:38.771908) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f40e24f40b4c4556944c762d4764eace261297f5 --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 f40e24f40b4c4556944c762d4764eace261297f5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IJAXQbEFYD/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.RA15kkdZUc/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-11 21:48:39,053 truncating revision to first 12 chars 2017-04-11 21:48:39,053 Setting DEBUG logging. 2017-04-11 21:48:39,053 attempt 1/10 2017-04-11 21:48:39,053 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f40e24f40b4c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-11 21:48:39,665 unpacking tar archive at: mozilla-central-f40e24f40b4c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.901327 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 21:48:39.699662) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 21:48:39.700191) ========= script_repo_revision: f40e24f40b4c4556944c762d4764eace261297f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 21:48:39.700872) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 21:48:39.701323) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 21:48:39.722850) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-11 21:48:39.723451) ========= /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.IJAXQbEFYD/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.RA15kkdZUc/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 21:48:40 INFO - MultiFileLogger online at 20170411 21:48:40 in /builds/slave/test 21:48:40 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 21:48:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:48:40 INFO - {'append_to_log': False, 21:48:40 INFO - 'base_work_dir': '/builds/slave/test', 21:48:40 INFO - 'blob_upload_branch': 'Firefox', 21:48:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:48:40 INFO - 'branch': 'Firefox', 21:48:40 INFO - 'buildbot_json_path': 'buildprops.json', 21:48:40 INFO - 'config_files': ('talos/mac_config.py',), 21:48:40 INFO - 'default_actions': ('clobber', 21:48:40 INFO - 'read-buildbot-config', 21:48:40 INFO - 'download-and-extract', 21:48:40 INFO - 'populate-webroot', 21:48:40 INFO - 'create-virtualenv', 21:48:40 INFO - 'install', 21:48:40 INFO - 'run-tests'), 21:48:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:48:40 INFO - 'download_minidump_stackwalk': True, 21:48:40 INFO - 'download_symbols': 'ondemand', 21:48:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:48:40 INFO - 'tooltool.py': '/tools/tooltool.py', 21:48:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:48:40 INFO - '/tools/misc-python/virtualenv.py')}, 21:48:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:48:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:48:40 INFO - 'gecko_profile': False, 21:48:40 INFO - 'gecko_profile_interval': 0, 21:48:40 INFO - 'installer_path': 'installer.exe', 21:48:40 INFO - 'log_level': 'info', 21:48:40 INFO - 'log_name': 'talos', 21:48:40 INFO - 'log_to_console': True, 21:48:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:48:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:48:40 INFO - 'opt_config_files': (), 21:48:40 INFO - 'pip_index': False, 21:48:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:48:40 INFO - 'cmd': ('bash', 21:48:40 INFO - '-c', 21:48:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:48:40 INFO - 'enabled': True, 21:48:40 INFO - 'halt_on_failure': False, 21:48:40 INFO - 'name': 'check_screen_resolution'},), 21:48:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:48:40 INFO - 'cmd': ('bash', 21:48:40 INFO - '-c', 21:48:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:48:40 INFO - 'enabled': True, 21:48:40 INFO - 'halt_on_failure': False, 21:48:40 INFO - 'name': 'check_screen_resolution'},), 21:48:40 INFO - 'run_cmd_checks_enabled': True, 21:48:40 INFO - 'suite': 'dromaeojs-e10s', 21:48:40 INFO - 'system_bits': '32', 21:48:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:48:40 INFO - 'title': 't-yosemite-r7-0266', 21:48:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:48:40 INFO - 'use_talos_json': True, 21:48:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:48:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:48:40 INFO - 'work_dir': 'build'} 21:48:40 INFO - [mozharness: 2017-04-12 04:48:40.108766Z] Running clobber step. 21:48:40 INFO - Running pre-action listener: _resource_record_pre_action 21:48:40 INFO - Running main action method: clobber 21:48:40 INFO - rmtree: /builds/slave/test/build 21:48:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:48:42 INFO - Running post-action listener: _resource_record_post_action 21:48:42 INFO - [mozharness: 2017-04-12 04:48:42.426538Z] Finished clobber step (success) 21:48:42 INFO - [mozharness: 2017-04-12 04:48:42.426738Z] Running read-buildbot-config step. 21:48:42 INFO - Running pre-action listener: _resource_record_pre_action 21:48:42 INFO - Running main action method: read_buildbot_config 21:48:42 INFO - Using buildbot properties: 21:48:42 INFO - { 21:48:42 INFO - "project": "", 21:48:42 INFO - "product": "firefox", 21:48:42 INFO - "script_repo_revision": "production", 21:48:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 21:48:42 INFO - "repository": "", 21:48:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 21:48:42 INFO - "buildid": "20170411201809", 21:48:42 INFO - "pgo_build": "False", 21:48:42 INFO - "basedir": "/builds/slave/test", 21:48:42 INFO - "buildnumber": 9, 21:48:42 INFO - "slavename": "t-yosemite-r7-0266", 21:48:42 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 21:48:42 INFO - "platform": "yosemite_r7", 21:48:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:48:42 INFO - "branch": "mozilla-central", 21:48:42 INFO - "repo_path": "mozilla-central", 21:48:42 INFO - "revision": "f40e24f40b4c4556944c762d4764eace261297f5", 21:48:42 INFO - "stage_platform": "macosx64", 21:48:42 INFO - "builduid": "0d8be032e19847518a63d8bbbbdcfe58", 21:48:42 INFO - "slavebuilddir": "test" 21:48:42 INFO - } 21:48:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 21:48:42 INFO - Running post-action listener: _resource_record_post_action 21:48:42 INFO - [mozharness: 2017-04-12 04:48:42.430019Z] Finished read-buildbot-config step (success) 21:48:42 INFO - [mozharness: 2017-04-12 04:48:42.430146Z] Running download-and-extract step. 21:48:42 INFO - Running pre-action listener: _resource_record_pre_action 21:48:42 INFO - Running main action method: download_and_extract 21:48:42 INFO - mkdir: /builds/slave/test/build/tests 21:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:42 INFO - trying https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 21:48:42 INFO - Downloading https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/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 21:48:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/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 21:48:43 INFO - Downloaded 1325 bytes. 21:48:43 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 21:48:43 INFO - Using the following test package requirements: 21:48:43 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:48:43 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 21:48:43 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 21:48:43 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:48:43 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 21:48:43 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:48:43 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 21:48:43 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:48:43 INFO - u'jsshell-mac.zip'], 21:48:43 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:48:43 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 21:48:43 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 21:48:43 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:48:43 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 21:48:43 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:48:43 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 21:48:43 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:48:43 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 21:48:43 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:48:43 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 21:48:43 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:48:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 21:48:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:48:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 21:48:45 INFO - Expected file size: 52596071 21:48:45 INFO - Obtained file size: 52596071 21:49:01 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 21:49:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 21:49:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:49:01 INFO - Fetch https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 21:49:03 INFO - Expected file size: 52596071 21:49:03 INFO - Obtained file size: 52596071 21:49:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 21:49:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:49:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 21:49:20 INFO - Expected file size: 14405596 21:49:20 INFO - Obtained file size: 14405596 21:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:20 INFO - trying https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:49:20 INFO - Downloading https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 21:49:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/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 21:49:22 INFO - Downloaded 70964126 bytes. 21:49:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:49:22 INFO - mkdir: /builds/slave/test/properties 21:49:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:49:22 INFO - Writing to file /builds/slave/test/properties/build_url 21:49:22 INFO - Contents: 21:49:22 INFO - build_url:https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:49:23 INFO - Running post-action listener: _resource_record_post_action 21:49:23 INFO - Running post-action listener: set_extra_try_arguments 21:49:23 INFO - [mozharness: 2017-04-12 04:49:23.160556Z] Finished download-and-extract step (success) 21:49:23 INFO - [mozharness: 2017-04-12 04:49:23.160660Z] Running populate-webroot step. 21:49:23 INFO - Running pre-action listener: _resource_record_pre_action 21:49:23 INFO - Running main action method: populate_webroot 21:49:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:49:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:49:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:49:23 INFO - '--tppagecycles', 21:49:23 INFO - '7'], 21:49:23 INFO - 'tests': ['tsvgm']}}, 21:49:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:49:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:49:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:49:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:49:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:49:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:49:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:49:23 INFO - 'talos_options': ['--disable-e10s'], 21:49:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:49:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:49:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:49:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:49:23 INFO - 'talos_options': ['--disable-e10s'], 21:49:23 INFO - 'tests': ['damp', 'tps']}, 21:49:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:49:23 INFO - 'tests': ['damp', 'tps']}, 21:49:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:49:23 INFO - 'tests': ['dromaeo_dom']}, 21:49:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:49:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:49:23 INFO - 'tests': ['basic_compositor_video']}, 21:49:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:49:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:49:23 INFO - 'tests': ['a11yr', 21:49:23 INFO - 'ts_paint', 21:49:23 INFO - 'tpaint', 21:49:23 INFO - 'sessionrestore', 21:49:23 INFO - 'sessionrestore_no_auto_restore', 21:49:23 INFO - 'tabpaint']}, 21:49:23 INFO - 'other-e10s': {'tests': ['a11yr', 21:49:23 INFO - 'ts_paint', 21:49:23 INFO - 'tpaint', 21:49:23 INFO - 'sessionrestore', 21:49:23 INFO - 'sessionrestore_no_auto_restore', 21:49:23 INFO - 'tabpaint']}, 21:49:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:49:23 INFO - 'ts_paint', 21:49:23 INFO - 'tpaint', 21:49:23 INFO - 'sessionrestore', 21:49:23 INFO - 'sessionrestore_no_auto_restore', 21:49:23 INFO - 'tabpaint']}, 21:49:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:49:23 INFO - 'ts_paint', 21:49:23 INFO - 'tpaint', 21:49:23 INFO - 'sessionrestore', 21:49:23 INFO - 'sessionrestore_no_auto_restore', 21:49:23 INFO - 'tabpaint']}, 21:49:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:49:23 INFO - 'tests': ['a11yr', 21:49:23 INFO - 'ts_paint', 21:49:23 INFO - 'tpaint', 21:49:23 INFO - 'sessionrestore', 21:49:23 INFO - 'sessionrestore_no_auto_restore', 21:49:23 INFO - 'tabpaint']}, 21:49:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:49:23 INFO - 'tests': ['a11yr', 21:49:23 INFO - 'ts_paint', 21:49:23 INFO - 'tpaint', 21:49:23 INFO - 'sessionrestore', 21:49:23 INFO - 'sessionrestore_no_auto_restore', 21:49:23 INFO - 'tabpaint']}, 21:49:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:49:23 INFO - 'tests': ['tsvgx', 21:49:23 INFO - 'tsvgr_opacity', 21:49:23 INFO - 'tart', 21:49:23 INFO - 'tscrollx', 21:49:23 INFO - 'cart', 21:49:23 INFO - 'tsvg_static']}, 21:49:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:49:23 INFO - 'tsvgr_opacity', 21:49:23 INFO - 'tart', 21:49:23 INFO - 'tscrollx', 21:49:23 INFO - 'cart', 21:49:23 INFO - 'tsvg_static']}, 21:49:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:49:23 INFO - 'talos_options': ['--disable-e10s'], 21:49:23 INFO - 'tests': ['tp5o']}, 21:49:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:49:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:49:23 INFO - 'talos_options': ['--disable-e10s', 21:49:23 INFO - '--xperf_path', 21:49:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:49:23 INFO - 'tests': ['tp5n']}, 21:49:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:49:23 INFO - 'talos_options': ['--xperf_path', 21:49:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:49:23 INFO - 'tests': ['tp5n']}}, 21:49:23 INFO - 'talos.zip': {'path': '', 21:49:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:49:23 INFO - Running post-action listener: _resource_record_post_action 21:49:23 INFO - [mozharness: 2017-04-12 04:49:23.179193Z] Finished populate-webroot step (success) 21:49:23 INFO - [mozharness: 2017-04-12 04:49:23.179327Z] Running create-virtualenv step. 21:49:23 INFO - Running pre-action listener: _resource_record_pre_action 21:49:23 INFO - Running main action method: create_virtualenv 21:49:23 INFO - Creating virtualenv /builds/slave/test/build/venv 21:49:23 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 21:49:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:49:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:49:23 INFO - Using real prefix '/tools/python27' 21:49:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:49:24 INFO - Installing distribute.............................................................................................................................................................................................done. 21:49:26 INFO - Installing pip.................done. 21:49:26 INFO - Return code: 0 21:49:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:49:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:49:27 INFO - Reading from file tmpfile_stdout 21:49:27 INFO - Output received: 21:49:27 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) 21:49:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:49:27 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')]} 21:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:27 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')]} 21:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:27 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 0x108aabb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bfb470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889d30>, '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 0x1087b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10887ebe8>, '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.IJAXQbEFYD/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.RA15kkdZUc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 21:49:27 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 21:49:27 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 21:49:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IJAXQbEFYD/Render', 21:49:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:27 INFO - 'HOME': '/Users/cltbld', 21:49:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:27 INFO - 'LOGNAME': 'cltbld', 21:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:27 INFO - 'MOZ_NO_REMOTE': '1', 21:49:27 INFO - 'NO_EM_RESTART': '1', 21:49:27 INFO - 'PAGER': '/bin/cat', 21:49:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:27 INFO - 'PWD': '/builds/slave/test', 21:49:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:27 INFO - 'SHELL': '/bin/bash', 21:49:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RA15kkdZUc/Listeners', 21:49:27 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 21:49:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:27 INFO - 'USER': 'cltbld', 21:49:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:27 INFO - 'XPC_FLAGS': '0x0', 21:49:27 INFO - 'XPC_SERVICE_NAME': '0', 21:49:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:27 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 21:49:27 INFO - Cleaning up... 21:49:27 INFO - Return code: 0 21:49:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:49:27 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')]} 21:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:27 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')]} 21:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:27 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 0x108aabb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bfb470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889d30>, '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 0x1087b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10887ebe8>, '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.IJAXQbEFYD/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.RA15kkdZUc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 21:49:27 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 21:49:27 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 21:49:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IJAXQbEFYD/Render', 21:49:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:27 INFO - 'HOME': '/Users/cltbld', 21:49:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:27 INFO - 'LOGNAME': 'cltbld', 21:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:27 INFO - 'MOZ_NO_REMOTE': '1', 21:49:27 INFO - 'NO_EM_RESTART': '1', 21:49:27 INFO - 'PAGER': '/bin/cat', 21:49:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:27 INFO - 'PWD': '/builds/slave/test', 21:49:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:27 INFO - 'SHELL': '/bin/bash', 21:49:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RA15kkdZUc/Listeners', 21:49:27 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 21:49:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:27 INFO - 'USER': 'cltbld', 21:49:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:27 INFO - 'XPC_FLAGS': '0x0', 21:49:27 INFO - 'XPC_SERVICE_NAME': '0', 21:49:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:27 INFO - Downloading/unpacking psutil>=3.1.1 21:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:49:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:49:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:49:31 INFO - Installing collected packages: psutil 21:49:31 INFO - Running setup.py install for psutil 21:49:31 INFO - building 'psutil._psutil_osx' extension 21:49:31 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 21:49:31 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 21:49:31 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 21:49:31 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 21:49:32 INFO - building 'psutil._psutil_posix' extension 21:49:32 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 21:49:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:49:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:49:32 INFO - ^ 21:49:32 INFO - 1 warning generated. 21:49:32 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 21:49:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:49:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:49:32 INFO - Successfully installed psutil 21:49:32 INFO - Cleaning up... 21:49:32 INFO - Return code: 0 21:49:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:49:32 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')]} 21:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:32 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')]} 21:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:32 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 0x108aabb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bfb470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889d30>, '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 0x1087b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10887ebe8>, '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.IJAXQbEFYD/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.RA15kkdZUc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 21:49:32 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 21:49:32 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 21:49:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IJAXQbEFYD/Render', 21:49:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:32 INFO - 'HOME': '/Users/cltbld', 21:49:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:32 INFO - 'LOGNAME': 'cltbld', 21:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:32 INFO - 'MOZ_NO_REMOTE': '1', 21:49:32 INFO - 'NO_EM_RESTART': '1', 21:49:32 INFO - 'PAGER': '/bin/cat', 21:49:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:32 INFO - 'PWD': '/builds/slave/test', 21:49:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:32 INFO - 'SHELL': '/bin/bash', 21:49:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RA15kkdZUc/Listeners', 21:49:32 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 21:49:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:32 INFO - 'USER': 'cltbld', 21:49:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:32 INFO - 'XPC_FLAGS': '0x0', 21:49:32 INFO - 'XPC_SERVICE_NAME': '0', 21:49:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:49:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:49:35 INFO - Installing collected packages: mozsystemmonitor 21:49:35 INFO - Running setup.py install for mozsystemmonitor 21:49:35 INFO - Successfully installed mozsystemmonitor 21:49:35 INFO - Cleaning up... 21:49:35 INFO - Return code: 0 21:49:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108aabb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bfb470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889d30>, '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 0x1087b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10887ebe8>, '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.IJAXQbEFYD/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.RA15kkdZUc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 21:49:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:49:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:49:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IJAXQbEFYD/Render', 21:49:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:36 INFO - 'HOME': '/Users/cltbld', 21:49:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:36 INFO - 'LOGNAME': 'cltbld', 21:49:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:36 INFO - 'MOZ_NO_REMOTE': '1', 21:49:36 INFO - 'NO_EM_RESTART': '1', 21:49:36 INFO - 'PAGER': '/bin/cat', 21:49:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:36 INFO - 'PWD': '/builds/slave/test', 21:49:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:36 INFO - 'SHELL': '/bin/bash', 21:49:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RA15kkdZUc/Listeners', 21:49:36 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 21:49:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:36 INFO - 'USER': 'cltbld', 21:49:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:36 INFO - 'XPC_FLAGS': '0x0', 21:49:36 INFO - 'XPC_SERVICE_NAME': '0', 21:49:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:36 INFO - Downloading/unpacking jsonschema==2.5.1 21:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:49:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:49:39 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 21:49:39 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 21:49:39 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 21:49:39 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 21:49:39 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 21:49:39 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 21:49:40 INFO - Downloading functools32-3.2.3-2.tar.gz 21:49:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:49:40 INFO - warning: no files found matching '*.txt' 21:49:40 INFO - no previously-included directories found matching 'build' 21:49:40 INFO - no previously-included directories found matching 'dist' 21:49:40 INFO - no previously-included directories found matching '.git*' 21:49:40 INFO - Installing collected packages: jsonschema, functools32 21:49:40 INFO - Running setup.py install for functools32 21:49:40 INFO - warning: no files found matching '*.txt' 21:49:40 INFO - no previously-included directories found matching 'build' 21:49:40 INFO - no previously-included directories found matching 'dist' 21:49:40 INFO - no previously-included directories found matching '.git*' 21:49:40 INFO - Successfully installed jsonschema functools32 21:49:40 INFO - Cleaning up... 21:49:40 INFO - Return code: 0 21:49:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:49: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')]} 21:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49: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')]} 21:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:40 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 0x108aabb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bfb470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889d30>, '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 0x1087b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10887ebe8>, '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.IJAXQbEFYD/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.RA15kkdZUc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 21:49:40 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 21:49:40 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 21:49:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IJAXQbEFYD/Render', 21:49:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:40 INFO - 'HOME': '/Users/cltbld', 21:49:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:40 INFO - 'LOGNAME': 'cltbld', 21:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:40 INFO - 'MOZ_NO_REMOTE': '1', 21:49:40 INFO - 'NO_EM_RESTART': '1', 21:49:40 INFO - 'PAGER': '/bin/cat', 21:49:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:40 INFO - 'PWD': '/builds/slave/test', 21:49:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:40 INFO - 'SHELL': '/bin/bash', 21:49:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RA15kkdZUc/Listeners', 21:49:40 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 21:49:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:40 INFO - 'USER': 'cltbld', 21:49:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:40 INFO - 'XPC_FLAGS': '0x0', 21:49:40 INFO - 'XPC_SERVICE_NAME': '0', 21:49:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:49:40 INFO - Cleaning up... 21:49:40 INFO - Return code: 0 21:49:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:49: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')]} 21:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49: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')]} 21:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:40 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 0x108aabb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bfb470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889d30>, '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 0x1087b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10887ebe8>, '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.IJAXQbEFYD/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.RA15kkdZUc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 21:49:40 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 21:49:40 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 21:49:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IJAXQbEFYD/Render', 21:49:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:40 INFO - 'HOME': '/Users/cltbld', 21:49:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:40 INFO - 'LOGNAME': 'cltbld', 21:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:40 INFO - 'MOZ_NO_REMOTE': '1', 21:49:40 INFO - 'NO_EM_RESTART': '1', 21:49:40 INFO - 'PAGER': '/bin/cat', 21:49:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:40 INFO - 'PWD': '/builds/slave/test', 21:49:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:40 INFO - 'SHELL': '/bin/bash', 21:49:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RA15kkdZUc/Listeners', 21:49:40 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 21:49:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:40 INFO - 'USER': 'cltbld', 21:49:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:40 INFO - 'XPC_FLAGS': '0x0', 21:49:40 INFO - 'XPC_SERVICE_NAME': '0', 21:49:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:40 INFO - Downloading/unpacking blobuploader==1.2.4 21:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:43 INFO - Downloading blobuploader-1.2.4.tar.gz 21:49:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:49:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:49:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:49:44 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 21:49:44 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 21:49:44 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 21:49:44 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 21:49:44 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 21:49:44 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 21:49:44 INFO - Downloading docopt-0.6.1.tar.gz 21:49:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:49:44 INFO - Installing collected packages: blobuploader, requests, docopt 21:49:44 INFO - Running setup.py install for blobuploader 21:49:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:49:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:49:45 INFO - Running setup.py install for requests 21:49:45 INFO - Running setup.py install for docopt 21:49:45 INFO - Successfully installed blobuploader requests docopt 21:49:45 INFO - Cleaning up... 21:49:45 INFO - Return code: 0 21:49:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:49:45 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')]} 21:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:45 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')]} 21:49:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:45 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 0x108aabb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bfb470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889d30>, '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 0x1087b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10887ebe8>, '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.IJAXQbEFYD/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.RA15kkdZUc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 21:49:45 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 21:49:45 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 21:49:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IJAXQbEFYD/Render', 21:49:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:45 INFO - 'HOME': '/Users/cltbld', 21:49:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:45 INFO - 'LOGNAME': 'cltbld', 21:49:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:45 INFO - 'MOZ_NO_REMOTE': '1', 21:49:45 INFO - 'NO_EM_RESTART': '1', 21:49:45 INFO - 'PAGER': '/bin/cat', 21:49:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:45 INFO - 'PWD': '/builds/slave/test', 21:49:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:45 INFO - 'SHELL': '/bin/bash', 21:49:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RA15kkdZUc/Listeners', 21:49:45 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 21:49:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:45 INFO - 'USER': 'cltbld', 21:49:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:45 INFO - 'XPC_FLAGS': '0x0', 21:49:45 INFO - 'XPC_SERVICE_NAME': '0', 21:49:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:49:46 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-HLL2iK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:49:46 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-hiAFQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:49:46 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-U1GkjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:49:46 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-8CDP4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:49:46 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-WQWIHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:49:46 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-TxI9c8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:49:46 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-f_PMEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:49:46 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-neZwWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:49:46 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-TC4DJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:49:46 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-3qIkLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:49:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-I_dvD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:49:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-LuNHd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:49:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-nyEfWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:49:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-IR0SY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:49:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-jY8JC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:49:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-1aDtQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:49:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-CUnK2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:49:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:49:47 INFO - Running setup.py install for manifestparser 21:49:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:49:47 INFO - Running setup.py install for mozcrash 21:49:48 INFO - Running setup.py install for mozdebug 21:49:48 INFO - Running setup.py install for mozdevice 21:49:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:49:48 INFO - Running setup.py install for mozfile 21:49:48 INFO - Running setup.py install for mozhttpd 21:49:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:49:48 INFO - Running setup.py install for mozinfo 21:49:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:49:48 INFO - Running setup.py install for mozInstall 21:49:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:49:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:49:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:49:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:49:48 INFO - Running setup.py install for mozleak 21:49:49 INFO - Running setup.py install for mozlog 21:49:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:49:49 INFO - Running setup.py install for moznetwork 21:49:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:49:49 INFO - Running setup.py install for mozprocess 21:49:49 INFO - Running setup.py install for mozprofile 21:49:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:49:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:49:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:49:49 INFO - Running setup.py install for mozrunner 21:49:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:49:49 INFO - Running setup.py install for mozscreenshot 21:49:50 INFO - Running setup.py install for moztest 21:49:50 INFO - Running setup.py install for mozversion 21:49:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:49:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:49:50 INFO - Cleaning up... 21:49:50 INFO - Return code: 0 21:49:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:49:50 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')]} 21:49:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:50 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')]} 21:49:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:50 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 0x108aabb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bfb470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889d30>, '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 0x1087b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10887ebe8>, '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.IJAXQbEFYD/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.RA15kkdZUc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 21:49:50 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 21:49:50 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 21:49:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IJAXQbEFYD/Render', 21:49:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:50 INFO - 'HOME': '/Users/cltbld', 21:49:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:50 INFO - 'LOGNAME': 'cltbld', 21:49:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:50 INFO - 'MOZ_NO_REMOTE': '1', 21:49:50 INFO - 'NO_EM_RESTART': '1', 21:49:50 INFO - 'PAGER': '/bin/cat', 21:49:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:50 INFO - 'PWD': '/builds/slave/test', 21:49:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:50 INFO - 'SHELL': '/bin/bash', 21:49:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RA15kkdZUc/Listeners', 21:49:50 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 21:49:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:50 INFO - 'USER': 'cltbld', 21:49:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:50 INFO - 'XPC_FLAGS': '0x0', 21:49:50 INFO - 'XPC_SERVICE_NAME': '0', 21:49:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:49:50 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-B0QI_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:49:50 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)) 21:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:49:50 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-JhvKNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:49:50 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)) 21:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:49:50 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-MfrK5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:49:50 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)) 21:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:49:50 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-TmFR6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:49:51 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-IDXk6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:49:51 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)) 21:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:49:51 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-yV0l0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:49:51 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)) 21:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:49:51 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-WDOtLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:49:51 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)) 21:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:49:51 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-jI3D9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:49:51 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)) 21:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:49:51 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-2cNtRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:49:51 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)) 21:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:49:51 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-CeJSnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:49:51 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)) 21:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:49:51 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-P6bDMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:49:51 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)) 21:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:49:51 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-KU7qbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:49:51 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)) 21:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:49:51 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-E53PfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:49:51 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)) 21:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:49:52 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-Xk668M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:49:52 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)) 21:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:49:52 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-pIEmvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:49:52 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)) 21:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:49:52 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-5zFajx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:49:52 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)) 21:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:49:52 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-pITa7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:49:52 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)) 21:49:52 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)) 21:49:52 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)) 21:49:52 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)) 21:49:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:49:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:49:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:49: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 21:49: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 21:49: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 21:49: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 21:49: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 21:49: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 21:49:55 INFO - Downloading blessings-1.6.tar.gz 21:49:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:49:55 INFO - Installing collected packages: blessings 21:49:55 INFO - Running setup.py install for blessings 21:49:55 INFO - Successfully installed blessings 21:49:55 INFO - Cleaning up... 21:49:55 INFO - Return code: 0 21:49:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:49:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:49:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:49:55 INFO - Reading from file tmpfile_stdout 21:49:55 INFO - Current package versions: 21:49:55 INFO - blessings == 1.6 21:49:55 INFO - blobuploader == 1.2.4 21:49:55 INFO - docopt == 0.6.1 21:49:55 INFO - functools32 == 3.2.3-2 21:49:55 INFO - jsonschema == 2.5.1 21:49:55 INFO - manifestparser == 1.1 21:49:55 INFO - mozInstall == 1.12 21:49:55 INFO - mozcrash == 1.0 21:49:55 INFO - mozdebug == 0.1 21:49:55 INFO - mozdevice == 0.50 21:49:55 INFO - mozfile == 1.2 21:49:55 INFO - mozhttpd == 0.7 21:49:55 INFO - mozinfo == 0.9 21:49:55 INFO - mozleak == 0.1 21:49:55 INFO - mozlog == 3.4 21:49:55 INFO - moznetwork == 0.27 21:49:55 INFO - mozprocess == 0.25 21:49:55 INFO - mozprofile == 0.28 21:49:55 INFO - mozrunner == 6.13 21:49:55 INFO - mozscreenshot == 0.1 21:49:55 INFO - mozsystemmonitor == 0.3 21:49:55 INFO - moztest == 0.8 21:49:55 INFO - mozversion == 1.4 21:49:55 INFO - psutil == 3.1.1 21:49:55 INFO - requests == 1.2.3 21:49:55 INFO - wsgiref == 0.1.2 21:49:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:49: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')]} 21:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49: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')]} 21:49:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:55 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 0x108aabb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bfb470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889d30>, '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 0x1087b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10887ebe8>, '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.IJAXQbEFYD/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.RA15kkdZUc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 21:49:55 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 21:49:55 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 21:49:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IJAXQbEFYD/Render', 21:49:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:55 INFO - 'HOME': '/Users/cltbld', 21:49:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:55 INFO - 'LOGNAME': 'cltbld', 21:49:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:55 INFO - 'MOZ_NO_REMOTE': '1', 21:49:55 INFO - 'NO_EM_RESTART': '1', 21:49:55 INFO - 'PAGER': '/bin/cat', 21:49:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:55 INFO - 'PWD': '/builds/slave/test', 21:49:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:55 INFO - 'SHELL': '/bin/bash', 21:49:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RA15kkdZUc/Listeners', 21:49:55 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 21:49:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:55 INFO - 'USER': 'cltbld', 21:49:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:55 INFO - 'XPC_FLAGS': '0x0', 21:49:55 INFO - 'XPC_SERVICE_NAME': '0', 21:49:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:55 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)) 21:49:55 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)) 21:49:55 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)) 21:49:55 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)) 21:49:55 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)) 21:49:55 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)) 21:49:55 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)) 21:49:55 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)) 21:49:55 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)) 21:49:55 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)) 21:49:55 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)) 21:49:55 INFO - Cleaning up... 21:49:55 INFO - Return code: 0 21:49:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:49: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')]} 21:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49: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')]} 21:49:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aabb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108aae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bfb470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108889d30>, '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 0x1087b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10887ebe8>, '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.IJAXQbEFYD/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.RA15kkdZUc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 21:49: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'] in /builds/slave/test/build 21:49: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 21:49:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IJAXQbEFYD/Render', 21:49:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:56 INFO - 'HOME': '/Users/cltbld', 21:49:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:56 INFO - 'LOGNAME': 'cltbld', 21:49:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:56 INFO - 'MOZ_NO_REMOTE': '1', 21:49:56 INFO - 'NO_EM_RESTART': '1', 21:49:56 INFO - 'PAGER': '/bin/cat', 21:49:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:56 INFO - 'PWD': '/builds/slave/test', 21:49:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:56 INFO - 'SHELL': '/bin/bash', 21:49:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RA15kkdZUc/Listeners', 21:49:56 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 21:49:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:56 INFO - 'USER': 'cltbld', 21:49:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:56 INFO - 'XPC_FLAGS': '0x0', 21:49:56 INFO - 'XPC_SERVICE_NAME': '0', 21:49:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:49:56 INFO - Cleaning up... 21:49:56 INFO - Return code: 0 21:49:56 INFO - Running post-action listener: _resource_record_post_action 21:49:56 INFO - Running post-action listener: _start_resource_monitoring 21:49:56 INFO - Starting resource monitoring. 21:49:56 INFO - [mozharness: 2017-04-12 04:49:56.288059Z] Finished create-virtualenv step (success) 21:49:56 INFO - [mozharness: 2017-04-12 04:49:56.289008Z] Running install step. 21:49:56 INFO - Running pre-action listener: _resource_record_pre_action 21:49:56 INFO - Running main action method: install 21:49:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:49:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:49:56 INFO - Reading from file tmpfile_stdout 21:49:56 INFO - Detecting whether we're running mozinstall >=1.0... 21:49:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:49:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:49:56 INFO - Reading from file tmpfile_stdout 21:49:56 INFO - Output received: 21:49:56 INFO - Usage: mozinstall [options] installer 21:49:56 INFO - Options: 21:49:56 INFO - -h, --help show this help message and exit 21:49:56 INFO - -d DEST, --destination=DEST 21:49:56 INFO - Directory to install application into. [default: 21:49:56 INFO - "/builds/slave/test"] 21:49:56 INFO - --app=APP Application being installed. [default: firefox] 21:49:56 INFO - mkdir: /builds/slave/test/build/application 21:49:56 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'] 21:49:56 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 21:50:16 INFO - Reading from file tmpfile_stdout 21:50:16 INFO - Output received: 21:50:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:50:16 INFO - Running post-action listener: _resource_record_post_action 21:50:16 INFO - [mozharness: 2017-04-12 04:50:16.948027Z] Finished install step (success) 21:50:16 INFO - [mozharness: 2017-04-12 04:50:16.948163Z] Running run-tests step. 21:50:16 INFO - Running pre-action listener: _resource_record_pre_action 21:50:16 INFO - Running main action method: run_tests 21:50:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:50:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:50:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:50:16 INFO - 2017-04-11 21:50:16.962 screenresolution[1629:7368] starting screenresolution argv=screenresolution get 21:50:16 INFO - 2017-04-11 21:50:16.965 screenresolution[1629:7368] Display 0: 1600x1200x32@60 21:50:16 INFO - 2017-04-11 21:50:16.971 screenresolution[1630:7370] starting screenresolution argv=screenresolution list 21:50:16 INFO - Available Modes on Display 0 21:50:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:50:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:50:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:50:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:50:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:50:17 INFO - Intel Iris: 21:50:17 INFO - Chipset Model: Intel Iris 21:50:17 INFO - Type: GPU 21:50:17 INFO - Bus: Built-In 21:50:17 INFO - VRAM (Dynamic, Max): 1536 MB 21:50:17 INFO - Vendor: Intel (0x8086) 21:50:17 INFO - Device ID: 0x0a2e 21:50:17 INFO - Revision ID: 0x0009 21:50:17 INFO - Displays: 21:50:17 INFO - Display: 21:50:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:50:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:50:17 INFO - Display Serial Number: 1600x1200 60 21:50:17 INFO - Main Display: Yes 21:50:17 INFO - Mirror: Off 21:50:17 INFO - Online: Yes 21:50:17 INFO - Rotation: Supported 21:50:17 INFO - Return code: 0 21:50:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:50:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:50:17 INFO - Python 2.7.3 21:50:17 INFO - Return code: 0 21:50:17 INFO - grabbing minidump binary from tooltool 21:50:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:50:17 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 0x108889d30>, '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 0x1087b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10887ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:50:17 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 21:50:17 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 21:50:17 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 21:50:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:50:17 INFO - Return code: 0 21:50:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:50:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:50:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:50:17 INFO - ENV: RUST_BACKTRACE is now 1 21:50:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:50:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:50:17 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/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0266', '--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 21:50:17 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/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0266 --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 21:50:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IJAXQbEFYD/Render', 21:50:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:50:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:50:17 INFO - 'HOME': '/Users/cltbld', 21:50:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:50:17 INFO - 'LOGNAME': 'cltbld', 21:50:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:50:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:50:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:50:17 INFO - 'MOZ_NO_REMOTE': '1', 21:50:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:50:17 INFO - 'NO_EM_RESTART': '1', 21:50:17 INFO - 'PAGER': '/bin/cat', 21:50:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:50:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:50:17 INFO - 'PWD': '/builds/slave/test', 21:50:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:50:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:50:17 INFO - 'RUST_BACKTRACE': '1', 21:50:17 INFO - 'SHELL': '/bin/bash', 21:50:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RA15kkdZUc/Listeners', 21:50:17 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 21:50:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:50:17 INFO - 'USER': 'cltbld', 21:50:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:50:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:50:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:50:17 INFO - 'XPC_FLAGS': '0x0', 21:50:17 INFO - 'XPC_SERVICE_NAME': '0', 21:50:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:50:17 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/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0266', '--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 21:50:17 INFO - mozversion application_buildid: 20170411201809 21:50:17 INFO - mozversion application_changeset: f40e24f40b4c4556944c762d4764eace261297f5 21:50:17 INFO - mozversion application_display_name: Nightly 21:50:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:50:17 INFO - mozversion application_name: Firefox 21:50:17 INFO - mozversion application_remotingname: firefox 21:50:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:50:17 INFO - mozversion application_vendor: Mozilla 21:50:17 INFO - mozversion application_version: 55.0a1 21:50:17 INFO - mozversion platform_buildid: 20170411201809 21:50:17 INFO - mozversion platform_changeset: f40e24f40b4c4556944c762d4764eace261297f5 21:50:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:50:17 INFO - mozversion platform_version: 55.0a1 21:50:17 INFO - using testdate: 1491972617 21:50:17 INFO - actual date: 1491972617 21:50:17 INFO - starting webserver on 'localhost:49238' 21:50:17 INFO - SUITE-START | Running 2 tests 21:50:17 INFO - TEST-START | dromaeo_css 21:50:17 INFO - operating with platform_type : mac_ 21:50:17 INFO - Initialising browser for dromaeo_css test... 21:50:17 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpYPGV4A/profile http://localhost:49238/getInfo.html) 21:50:20 INFO - PID 1640 | 2017-04-11 21:50:20.514 plugin-container[1642:7545] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 21:50:20 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:50:20 INFO - PID 1640 | 2017-04-11 21:50:20.516 plugin-container[1642:7545] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 21:50:20 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:50:20 INFO - PID 1640 | 2017-04-11 21:50:20.516 plugin-container[1642:7545] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:50:20 INFO - PID 1640 | __metrics Screen width/height:1600/1200 21:50:20 INFO - PID 1640 | colorDepth:24 21:50:20 INFO - PID 1640 | Browser inner width/height: 1024/689 21:50:20 INFO - PID 1640 | __metrics 21:50:20 ERROR - PID 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 21:50:21 INFO - TEST-INFO | 1640: exit 0 21:50:21 INFO - Browser initialized. 21:50:21 INFO - Running cycle 1/1 for dromaeo_css test... 21:50:21 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpYPGV4A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:50:24 INFO - PID 1643 | 2017-04-11 21:50:24.374 plugin-container[1644:7673] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 21:50:24 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:50:24 INFO - PID 1643 | 2017-04-11 21:50:24.377 plugin-container[1644:7673] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 21:50:24 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:50:24 INFO - PID 1643 | 2017-04-11 21:50:24.377 plugin-container[1644:7673] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:50:34 INFO - PID 1643 | RSS: Main: 234926080 21:50:34 INFO - PID 1643 | 21:53:37 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49238/tests/dromaeo/cssquery-ext.html) 21:53:39 INFO - PID 1643 | RSS: Main: 255172608 21:53:39 INFO - PID 1643 | 21:55:22 INFO - PID 1643 | ************************* 21:55:22 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 21:55:22 INFO - PID 1643 | 21:55:22 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 21:55:22 INFO - PID 1643 | Full stack: 21:55:22 INFO - PID 1643 | ************************* 21:55:22 INFO - PID 1643 | ************************* 21:55:22 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 21:55:22 INFO - PID 1643 | 21:55:22 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 21:55:22 INFO - PID 1643 | Full stack: 21:55:22 INFO - PID 1643 | ************************* 21:55:22 INFO - PID 1643 | ************************* 21:55:22 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 21:55:22 INFO - PID 1643 | 21:55:22 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 21:55:22 INFO - PID 1643 | Full stack: 21:55:22 INFO - PID 1643 | ************************* 21:55:22 INFO - PID 1643 | ************************* 21:55:22 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 21:55:22 INFO - PID 1643 | 21:55:22 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 21:55:22 INFO - PID 1643 | Full stack: 21:55:22 INFO - PID 1643 | ************************* 21:56:41 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-ext.html (next: http://localhost:49238/tests/dromaeo/cssquery-jquery.html) 21:56:42 INFO - PID 1643 | RSS: Main: 266297344 21:56:42 INFO - PID 1643 | 21:59:43 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49238/tests/dromaeo/cssquery-mootools.html) 21:59:44 INFO - PID 1643 | RSS: Main: 277389312 21:59:44 INFO - PID 1643 | 22:02:46 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49238/tests/dromaeo/cssquery-prototype.html) 22:02:46 INFO - PID 1643 | RSS: Main: 269762560 22:02:46 INFO - PID 1643 | 22:05:49 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49238/tests/dromaeo/cssquery-yui.html) 22:05:50 INFO - PID 1643 | RSS: Main: 279937024 22:05:50 INFO - PID 1643 | 22:08:52 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-yui.html (next: http://localhost:49238/tests/dromaeo/cssquery-dojo.html) 22:08:52 INFO - PID 1643 | RSS: Main: 273580032 22:08:52 INFO - PID 1643 | 22:08:52 INFO - PID 1643 | __start_tp_report 22:08:52 INFO - PID 1643 | _x_x_mozilla_page_load 22:08:52 INFO - PID 1643 | _x_x_mozilla_page_load_details 22:08:52 INFO - PID 1643 | |i|pagename|runs| 22:08:52 INFO - PID 1643 | |0;dojo.html;3030.2727345437097;3556.164301389175;3571.6071232164463;3613.2412193439377;3633.5094762207095;6070.453659170674;6182.938170618292;6244.46922011629;6272.717727702253;6351.428371446569;6096.375503402309;6098.008671753297;6111.236095488064;6212.565120441571;6241.375862413756;5323.547498462635;5416.945830541703;5430.456954304557;5490.6431081979745;5514.80698175564;5379.946200538003;5437.510624043835;5514.310711161105;5523.696196709179;5523.861903452405;5452.236686863842;5456.699881506535;5495.587830912657;5501.380310222237;5518.779248830042;5268.92096618551;5341.03861304965;5342.171963345688;5349.090654588729;5366.114591092465;4670.8365207217585;5339.492748188916;5376.623636345457;5401.000814849265;5403.162509810985;5274.55166310864;6181.134641150197;6189.9381006189815;6292.842678933018;6304.621722696653;5706.144078388275;6352.523560732963;6438.034294855763;6676.632785196813;6680;6662.20053593567;6754.527183097185;6759.7296108155615;6763.188417389944;6782.457403407716;5257.947420525746;6303.338149494283;6352.396522330371;6353.205849268841;6367.108604795332;6567.573107747981;6768.932310676831;6899.517033807686;6906.930930690729;6914.308569143146;6289.119523266746;6376.936230637727;6455.225372955276;6506.056621789814;6509.088727578142;5637.9718101409235;6481.222253329631;6578.309277525791;6709.429698475684;6736.865262694719;5588.497035266845;6515.022746587955;6590.275069742324;6611.702473388709;6652.600843949282;6338.492920566344;6407.775727849503;6627.370399812106;6653.135092437952;6672.332766723289;5257.553107985787;5263.973680131651;5272.156454967187;5281.234221037928;5289.365276166807;4682.41469816273;4725.763711814396;4736.550027747358;4738.691985020962;4761.4524329701835;6152.815415537541;6343.936560634427;6347.301796802348;6348.269948955929;6358.904616430757;6407.295197528269;6669.599824010575;6688.966555167193;6718.764843230463;6767.627780472121;6254.437100660963;6270.341614130451;6311.558190926683;6355.968220158869;6359.332270111571;5914.201582786355;5915.142304365929;5916.083007133901;5921.911171332434;5924.792632257937;6778.8644227116165;6825.761098361533;6861.348171923659;6866;6913.3777959983845;6421.614703117828;6530.542861999805;6623.867522649616;6707.698153583003;6725.327467253235;6845.247022827385;6872.965635171792;6877.415419689382;6894.586324820527;6900.6204658742245;6590.406863382319;6620.635865027277;6635.568688035262;6640.701168447528;6657.866842663217;6004.309504407049;6351.523635727246;6612.966935165294;6682;6698.631575263407;6494.935050649433;6514.869702605826;6520.152380190545;6533;6543.1821022372205;6249.875002500015;6270;6492.285848556749;6498.902516462163;6515.6742162892615;5424.864378390572;6343.619382837044;6363.077353783768;6369.9681501592195;6388.105665206967;5045.722485263199;5407.513323800877;6537.8692426152165;6544.44372228366;6565.409113179646;5482.917756233739;6320.209973753281;6383.7127329271225;6384.904226436514;6389.904151437825 22:08:52 INFO - PID 1643 | |1;ext.html;744.9702011919524;795.8885755994162;826.5949684654519;1176.9819106472903;1249.0507214516977;33778.99999999997;35768.82115589421;38745.22509549807;38764.80617596912;38768.22463550727;38106.8094659527;38301.61698383015;38491.42262866059;38500;38588.26352814123;7740.916271721963;8134.186581341848;8277.420580559363;8575.828483430327;8849.867251991225;8686.609102590366;8812.8678069829;8815.471071735685;8817;8825.940887093559;8765.430247033953;8804.64781408743;8820.382573219877;8850.73447796564;8859.069797671249;8760.561971901412;8790.64837406503;8793.472391656522;8798.384113112084;8824.382293239443;8565.700200493015;8602.225799678059;8608.655653773842;8631.956840215758;8651.95674021626;29631.962881299267;37170;37366.43950340747;37433.62566374329;37443;3648.1791596890753;3685.4840322354894;3688.520492336006;3691.2063906260014;3695.9260814783825;30724.078277651704;30920;30929.536056959165;30942.53586196209;30981.070567882998;3144.9056528304186;3198.088544764731;3205.871765129392;3213.469777486712;3229.3056992746674;219262;224324.8783756087;255458;275294.6235268811;277003;47778.04443911102;47841.282380764314;48158.518414816106;48175;48199;49524.75237623789;49664.0067198654;49730.0053998918;49958.50041499611;50062.499375005784;14136;14233.359498822578;14366.281685915663;14366.85633143676;14372.712545749026;11677.416129193507;11759.176857619885;11867.821982670266;11871.228370155912;11901.642950711492;13305.467781288833;13337.666558335924;13355.53255636048;13377.531786387624;13463.730725385438;5907.202527658797;5920.911186332209;5929.466348028698;5942.643441393444;6019.337872833985;943.2265542255285;1042.2287507244737;1045.2004216774174;1074.8065348237235;1095.9506822192861;20944.162233510488;24099.638505422434;24237.394065148514;24399;24453.144139955017;1021.7088129883097;1078.7518870659792;1145.6448500964727;1174.2308787744041;1182.6274723462082;12209.389530523438;12361.925031099625;12402.317872517098;12437.440315185657;12483.93886519656;332204;332510;333519;334339;334433;53111.73444132755;53280;53546;53569;53638;1529.8852586055868;1564.1944398634712;1567.4748959098602;1571.4028669105896;1581.1777875504672;20869.895650521652;23030.42423939415;23417.648735269326;23746.287611371685;23859.284221473383;31660.5250921241;33046.66953330436;37782.2443551133;38172.85481435561;38180.427293590066;306002;306663;307135;307315;313407;33857.66142338545;34281.31437371189;34882.47676284807;35008.649913500536;35070.298594028485;44600.43898463538;45261;45368.77315613401;45444.545554544035;45880;30259;30279.394412112077;31083.378332433676;31107.844460777553;31244.375112498077;30179.396412072074;30241.69758302477;30522.236944076576;31022.379552408373;31204.531932020585;36946.63053369432;37015.074623134635;37068.25863482661;37110;37171.44242836306;24973.625395618717;30008.79964801471;30275.243118922204;31033.758649653762;31221.53167702441;24520.93488313961;24591.508169836146;24651.50696986086;24785.876070620252;25049.749502504743 22:08:52 INFO - PID 1643 | |2;jquery.html;422.8773655639865;436.45007290813567;438.38018521562816;448.49320268097046;448.96183824374924;19885.304014359517;21328.253511127095;21897.233596824113;21914;22088.570257271687;21694.457638559044;23091.538169236606;23190.3042908713;23248.302550923458;23509.647355289686;6219.471344935686;6282.434580887731;6494.415502604765;6531.314212007742;6545.738170473175;6233.438990490855;6358.078078678589;6409.487241020731;6431.581947173442;6453.322401147882;4890.828820991266;5517.2551705519645;5853.414658534134;6318.652474113922;6406.583572067824;5431.8098866539485;6342.4926005919415;6414.422701956823;6418.582792118497;6421.550491465599;5615.0454422748235;5624.128260119688;5703.173039909231;5704.087346024658;5704.743286552115;26864;26915.86542067277;26947.461050778875;27044.053458129067;27167.864160679266;2229.8996545155505;2295.3228797504607;2296.368498662881;2300.0454811253308;2307.180950762472;17888.195031223495;17942.551436214068;18013.099345032697;18439.815601843944;18593;2167.783221677835;2194.5830292244423;2238.563480121377;2244.730632324115;2245.6182448983714;129682;134243.32878335644;135215.999999999;136055.31972340174;136076.27847442994;10847.75250846147;12325.198862073936;12673;12693.555725549746;12741.55404560836;16029.679406411806;16333.836661633231;16472.4234651789;16522.173891305385;16572.005679659258;7094.787156385317;8833.69082082124;9051.185393314634;9054.094590541024;9173.954130229306;7037.4018208451835;7090.958153852254;8727.167294868148;8744.69393571222;8751.343649226334;7875.960620196863;7993.9607850979;8055.597220139087;8064.935324576741;8069.256452806074;2163.5889181055863;2166.1118941234017;2171.8045375916245;2178.7385513738454;2190.539986602799;893.446063440671;1002.7057138309737;1018.9949050254701;1025.646152077532;1070.1545778834789;19314.903425482782;20586.558940874296;20922;25484.362890927874;27256;1012.5443550402348;1019.2966852871494;1021.7088129882948;1045.4406892312577;1047.7328281288223;9010.189082982562;10808.62169824068;11212.439378031066;11226.438678066064;11369.488373023234;685556;694925;707943;724046;724844;151735.24132379267;153175;156416;157526;159163;1202.9939850300693;1204.903607711381;1260.9936950315373;1264.393091316155;1270.5870592057618;17334.82665173332;20729.68905466389;21154.894225528773;21672.458188545414;22027.559448810614;57574.7121264391;58506;58741.82516349564;59164.112538312824;59633.701831490565;81014;81377.59311203405;81421;83196.58401708194;83583.16416835754;7665.540067595962;8698.869516957124;8718.084601117045;8820.911790882266;9025.548722563713;10253.436061016715;11095.445227738743;11427.942860285977;14265.64335891611;14310.427582896567;11653.53385864556;11731.765364692488;11836.46735896904;12093.637190884288;12134.211276267015;9663.661771837951;9705.757356066155;10863.130949524177;11112.444377781241;11334.716632083933;11430.314181149157;11772.823407649063;12115.757684846078;12253.203541769757;12254.693632659255;8741.956290218763;8746.431481953652;8788.956055219684;9025.232855207381;9135.132162444557;11638;11787.528498859949;12013.219140756173;12115.454804533994;12163.817542737042 22:08:52 INFO - PID 1643 | |3;mootools.html;1374.2785037855124;1460.2260801775062;1468.1044562816687;1471.587955372496;1486.191098823224;11083.833742493858;11575.073994080474;11602.419879006047;11819.40902954851;12264.93867530661;11734;11947.940260298708;11982.221155624879;11987.460564274606;11998.640040798768;2493.164789795421;2534.4550921551863;2540.479201763634;2543.898244070235;2544.0459827564664;2400.987995060022;2420.165043060141;2425.4542727886173;2427.1141033522727;2441.694788151481;2454.2514533067406;2461.520003599299;2478.4423504711385;2492.314613481289;2494.950100997979;2423.9757602423924;2424.4544977380037;2443.2548072837776;2457.0663148003823;2476.2447453526665;1915.8850468971907;2431.8540887546806;2454.828162028659;2455.9877200613882;2496.737842526527;10843.295185812896;11034.668959931134;11830.822537661941;11938.343391113485;11951.581694640732;6488.416042556192;6685.465162787014;6740.12378390806;6740.1237839081095;6755.831104222385;4015.3976903464422;4250.617444430016;4324.135172965388;4338.826446942118;4375.5405682403525;1163.6567212672282;1174.8531433570804;1203.1758245601704;1205.2226314027491;1209.842720446345;13058.934705326508;14914.925425372912;15810.13044282564;15974.201289935456;16127.193640318055;5467.371252305956;5573.108302671553;5703.486686198179;5788.131780232915;5812.041013232853;10294.588216471408;12262.938685306695;12353.135280530276;12365.443555039865;12373.93813030929;6173.536984726074;6478.125453063871;6538.0192971053775;6538.574992625464;6544.934550654528;6026.12117071511;6051.939480605226;6175.413335733188;6227.470664993526;6257.186565746424;4310.030243195319;4419.1382680377665;4457.442819647544;4475.910481790411;4506;1141.2809929744208;1257.3838818978634;1281.564268148834;1296.0817989375776;1297.1892567145535;742.7140550887839;783.8158032862273;791.4855344026429;825.5542007316102;826.0954255090666;5428.728563571805;6134.662593557308;6234.657093859791;6283.59156654816;6359.682015899187;588.3351812451901;588.6850534963773;594.180031556458;608.6256951974592;615.3354377272535;3704.592494825567;4160.54234034256;4294.720843145248;4407.184670835905;4422.845200418034;14668.339924703414;14880.776788348183;14948;15329.770053449207;15366.231688415535;12506.936910362721;15159.848401516065;15309.617259568377;15322.080675159303;15387.538373848802;10889.292196007234;10930.836037459445;10958;10970.670879873616;11079.058280046125;5780.306363236438;6576.309487503934;6694.765683201064;6803.7618683345845;6859.416949559343;13253.602391928258;13280.867191327963;13296.534621288209;13341.799873001719;13571.457141714222;11840.75672054422;13722.382492788049;13858.376373063034;13982.650433739238;14021.29893505341;8678.566071696263;8732.039475657799;8775.385722999328;8779.385443018928;8799.868001979847;10509.579616815241;10607.4165920872;10717.892821072;10726.570937162425;10762.46187690628;7611.733589324347;8006.439549231498;9011;9110.772230694285;9181.540922953693;8846.690365837165;8861.822763544822;8982.101789820965;8994.775130621787;9012.729618111467;6512.5766825156215;8086.070101938352;8113.553754543556;8209.917900820916;8256.504609723435;5821.388754180919;6007.12896629995;6018.337982821974;6122.632642041492;6190.845228869314;6074.210352654127;6128.111423843607;6203.0075187969705;6291.339409361951;6322.36776322364 22:08:52 INFO - PID 1643 | |4;prototype.html;392.8899908025753;402.86392373506703;403.9050823056579;405.61871840469956;410.97739624320667;6078.513718902488;6221.351341894401;6239.469645080162;6256.781012664557;6258.968705156473;4781.904361912769;4951.900961980758;5830.766769329232;6088.634681919088;6116.44951954324;4044.9483134384977;4107.404426358176;4279.679024073192;4313.340346084237;4689.57793798558;3939.1333906540517;4076.7769669099152;4551.431071116111;4642.767861606923;4748.596369308613;4567.246404343296;4587.151416634872;4591.471980722226;4671.976640116812;4679.415073115861;4209.0529630833125;4517.435320584927;4626.560476754703;4628.953710462886;4661.184292748772;3610.422332426799;4139.606737359946;4140.399642051916;4171.457710497646;4200.096979149467;5099.903520743021;5457.372402173761;5474.591179880839;5514.042514907191;5829.941700582982;4585.518520555327;4616.976915115437;5529.198266251411;5532.640378375393;5541.476094074122;6230.470410015154;6450.419462248373;6494.512911531607;6498;6535.091884111573;3952.9209415811524;4539.364488971547;4663.580277774982;4683.672142949995;4732.574068333866;5429.321334833146;6146.231721034871;6356.103627564098;6794.218664853555;6849.520533562652;4439.001224724476;4665.650076244296;4770.833020844254;4823.421189457287;4865.854024379274;4203.76879271643;4926.743680361485;4950.975245123824;4955.653104282737;4955.752212389366;4899.3385892904;5314.548263397654;5484.561235101182;5544.889102217933;5581.6930068845795;5363.356397232357;6231.940570103094;6234.812955611339;6501.024846273097;6593.406593406617;1425.2374979386186;1636.35364031207;1676.8574671152844;1710.7938903073552;1714.9657006859793;997.7954519323521;1149.8332741752567;1177.1465687376583;1179.151011271883;1181.7931861924128;5305.840824775263;5458.533831017253;5540.445955404427;6220.688965551704;6246.219222597176;5423.864403389947;6202.255729312421;6772.255051944282;6786.117804685359;6814.454843612499;6116.235470566179;6173.69131543421;6221.2845522764555;6252.718627661675;6261.96869015652;2832.937648381857;3529.3823580873245;3549.432090865449;3549.6450354964295;3553.271579326232;5659.349174844946;6037.034074548052;6533.901991470132;7043.225245223021;7056.400205982549;6037.63774173551;6127.325994140641;7055.506114572034;7064.293570642894;7084.610346430893;6151.738893526726;6421.839454013687;6474.6762661866715;6776.76281330161;7008.579485230801;5371.328583927009;6123.755049797958;6126.509879209563;6730.562513436611;6776.833081895452;5537.169424586184;6391.616503009835;6611.570247934062;6644.60132392058;6651.567648102858;5182.274481572658;6260.280067792262;6536.379043990813;6794.966025169842;6936.098307220029;4418.873187337208;4425.82296708141;4429.468463784237;4452.30989196675;4466.285394336891;6606.273309935999;6653.767118150841;6711.46308295345;6759.831004224934;6771.6614169292325;5606.635568688022;6104.69476526163;6458.580192287486;6581;6587.571807832475;5913.704314784158;6533.705983230861;6534.281229064894;6558.606483610999;6814.250432452525;5361.35663720356;6340.461060809883;6409;6430;6464.773732919325;6588.769393071219;6598.538102332791;6599.373059559334;6600.471962243105;6609.339066093352;5567.526760225426;5960.109346966804;6137.539684523589;6140.263168419819;6416.197975253093 22:08:52 INFO - PID 1643 | |5;yui.html;781.3046388714043;822.404535201709;834.1783343406747;862.693743720979;875.6234819027818;14007.299635018258;14592.197429141394;16517.256723447445;16903.577410564743;17124.65750684987;16869.819112662117;17342.132893355345;17504.949703017795;17632.64734705305;17841;976.4092723902044;982.6855406269997;984.6110786239467;985.7042887133867;988.298308201178;874.7725591346264;966.9806603867919;997.1922742578498;1001.8346972749524;1004.4324956399647;1034.3897100710578;1037.2946396450411;1037.4605205293242;1037.9931466477506;1038.3458421194637;1025.220832167558;1029.8455231715227;1030.4023666273542;1032.8450732390127;1040.7294103533097;757.6249756370631;759.4531937005403;760.2701406649624;761.7372006657695;767.6545554500497;16276.26756795947;16887.915560422243;17030.914845425817;17126.657466850593;17137.314507419687;15784.447544336008;16228.675426491522;16238.431654892021;16409.097499637515;16477.67044659112;13089.803652945213;13409.798853017115;13551.322433878366;13551.39018744149;13566.186028838203;3346.0965539304493;3371.2920286739813;3384.5769278840144;3446.190145315849;3546.85812567497;81032;97704;106293.46853265761;108715.45642271738;109387;13094.476220951055;13489.527866524624;13492.93253533726;13505.392257348443;13563.525276615272;24138.39654008664;24549.754502455104;25020.624690629655;25038.874805625856;25047.248582542554;7308.990027742413;7585.355244804254;8592;8915;8988.280937525113;4821.132196204647;4928.6303527235605;4930.6795058321095;4934.309196712463;4941.332920055818;4374.737515749065;4829.444613869379;4837.467878533358;4870.4155501340065;4888.046830868017;946.9337146399823;974.5273542331963;979.480875136179;994.9154321882721;995.4425521707722;637.6492928889093;648.5492582655104;649.5972497051858;652.8106849013733;667.2693400726197;10311.381317120848;11210.327380357205;11817.350045747395;11942.283462992249;11994.220375675553;622.8069298517474;644.3105876711874;656.8456412743002;663.3234101216732;665.3944910131756;5498.945010549924;5517.972410137923;5633.267675202197;5649.6327738696855;5737.397573254748;88462;97523.51238243764;98980.51529227068;107125.4643726792;107369;63382.09853704396;78453;79601.80597291046;79874.60062699765;82357;58853;63213.419664507834;63275.6836215816;63944.36055639477;64392.03411948824;11598.826017609912;12115.697107572028;12155.270683759003;12175.452104655134;12290.754184916073;37554.436683450316;37618.623813761515;37807;37883.67407140737;38394.42408363933;48426.75786621044;48825.75587122042;48873.75563122162;48906;49529.50470495249;15689.607759806097;15759.448419305269;16106.27521761547;16686.16335222991;16886.324547017983;17636.294548217928;18171.273149073888;18225;18599.44201673898;18609.976451295308;16194.109323986822;16882.24029918632;17047.659046818746;17126.571835704206;17193.742093868852;16293.022418654919;16588.170591470138;16629.83370166283;16840.831591683927;16899.66200675955;16133.75799362987;16213.756793647868;16266.755998659793;16451;16598.502044938672;11696.70758231017;11941.044716422839;11971.341576213392;12073;12116.818247726467;11279.266847654877;12740.299283539494;12753;12857;13064.21614703083 22:08:52 INFO - PID 1643 | __end_tp_report 22:08:52 INFO - PID 1643 | __start_cc_report 22:08:52 INFO - PID 1643 | _x_x_mozilla_cycle_collect,435 22:08:52 INFO - PID 1643 | __end_cc_report 22:08:52 INFO - PID 1643 | __startTimestamp1491973732404__endTimestamp 22:08:52 INFO - PID 1643 | 22:08:52 INFO - PID 1643 | ------- Summary: start ------- 22:08:52 INFO - PID 1643 | Number of tests: 6 22:08:52 INFO - PID 1643 | 22:08:52 INFO - PID 1643 | [#0] dojo.html Cycles:165 Average:6080.33 Median:6351.43 stddev:737.40 (11.6%) stddev-sans-first:699.77 22:08:52 INFO - PID 1643 | Values: 3030.3 3556.2 3571.6 3613.2 3633.5 6070.5 6182.9 6244.5 6272.7 6351.4 6096.4 6098.0 6111.2 6212.6 6241.4 5323.5 5416.9 5430.5 5490.6 5514.8 5379.9 5437.5 5514.3 5523.7 5523.9 5452.2 5456.7 5495.6 5501.4 5518.8 5268.9 5341.0 5342.2 5349.1 5366.1 4670.8 5339.5 5376.6 5401.0 5403.2 5274.6 6181.1 6189.9 6292.8 6304.6 5706.1 6352.5 6438.0 6676.6 6680.0 6662.2 6754.5 6759.7 6763.2 6782.5 5257.9 6303.3 6352.4 6353.2 6367.1 6567.6 6768.9 6899.5 6906.9 6914.3 6289.1 6376.9 6455.2 6506.1 6509.1 5638.0 6481.2 6578.3 6709.4 6736.9 5588.5 6515.0 6590.3 6611.7 6652.6 6338.5 6407.8 6627.4 6653.1 6672.3 5257.6 5264.0 5272.2 5281.2 5289.4 4682.4 4725.8 4736.6 4738.7 4761.5 6152.8 6343.9 6347.3 6348.3 6358.9 6407.3 6669.6 6689.0 6718.8 6767.6 6254.4 6270.3 6311.6 6356.0 6359.3 5914.2 5915.1 5916.1 5921.9 5924.8 6778.9 6825.8 6861.3 6866.0 6913.4 6421.6 6530.5 6623.9 6707.7 6725.3 6845.2 6873.0 6877.4 6894.6 6900.6 6590.4 6620.6 6635.6 6640.7 6657.9 6004.3 6351.5 6613.0 6682.0 6698.6 6494.9 6514.9 6520.2 6533.0 6543.2 6249.9 6270.0 6492.3 6498.9 6515.7 5424.9 6343.6 6363.1 6370.0 6388.1 5045.7 5407.5 6537.9 6544.4 6565.4 5482.9 6320.2 6383.7 6384.9 6389.9 22:08:52 INFO - PID 1643 | 22:08:52 INFO - PID 1643 | [#1] ext.html Cycles:180 Average:44805.94 Median:333014.50 stddev:78622.04 (23.6%) stddev-sans-first:78773.00 22:08:52 INFO - PID 1643 | Values: 745.0 795.9 826.6 1177.0 1249.1 33779.0 35768.8 38745.2 38764.8 38768.2 38106.8 38301.6 38491.4 38500.0 38588.3 7740.9 8134.2 8277.4 8575.8 8849.9 8686.6 8812.9 8815.5 8817.0 8825.9 8765.4 8804.6 8820.4 8850.7 8859.1 8760.6 8790.6 8793.5 8798.4 8824.4 8565.7 8602.2 8608.7 8632.0 8652.0 29632.0 37170.0 37366.4 37433.6 37443.0 3648.2 3685.5 3688.5 3691.2 3695.9 30724.1 30920.0 30929.5 30942.5 30981.1 3144.9 3198.1 3205.9 3213.5 3229.3 219262.0 224324.9 255458.0 275294.6 277003.0 47778.0 47841.3 48158.5 48175.0 48199.0 49524.8 49664.0 49730.0 49958.5 50062.5 14136.0 14233.4 14366.3 14366.9 14372.7 11677.4 11759.2 11867.8 11871.2 11901.6 13305.5 13337.7 13355.5 13377.5 13463.7 5907.2 5920.9 5929.5 5942.6 6019.3 943.2 1042.2 1045.2 1074.8 1096.0 20944.2 24099.6 24237.4 24399.0 24453.1 1021.7 1078.8 1145.6 1174.2 1182.6 12209.4 12361.9 12402.3 12437.4 12483.9 332204.0 332510.0 333519.0 334339.0 334433.0 53111.7 53280.0 53546.0 53569.0 53638.0 1529.9 1564.2 1567.5 1571.4 1581.2 20869.9 23030.4 23417.6 23746.3 23859.3 31660.5 33046.7 37782.2 38172.9 38180.4 306002.0 306663.0 307135.0 307315.0 313407.0 33857.7 34281.3 34882.5 35008.6 35070.3 44600.4 45261.0 45368.8 45444.5 45880.0 30259.0 30279.4 31083.4 31107.8 31244.4 30179.4 30241.7 30522.2 31022.4 31204.5 36946.6 37015.1 37068.3 37110.0 37171.4 24973.6 30008.8 30275.2 31033.8 31221.5 24520.9 24591.5 24651.5 24785.9 25049.7 22:08:52 INFO - PID 1643 | 22:08:52 INFO - PID 1643 | [#2] jquery.html Cycles:180 Average:40333.81 Median:2270.47 stddev:118110.43 (5202.0%) stddev-sans-first:118403.74 22:08:52 INFO - PID 1643 | Values: 422.9 436.5 438.4 448.5 449.0 19885.3 21328.3 21897.2 21914.0 22088.6 21694.5 23091.5 23190.3 23248.3 23509.6 6219.5 6282.4 6494.4 6531.3 6545.7 6233.4 6358.1 6409.5 6431.6 6453.3 4890.8 5517.3 5853.4 6318.7 6406.6 5431.8 6342.5 6414.4 6418.6 6421.6 5615.0 5624.1 5703.2 5704.1 5704.7 26864.0 26915.9 26947.5 27044.1 27167.9 2229.9 2295.3 2296.4 2300.0 2307.2 17888.2 17942.6 18013.1 18439.8 18593.0 2167.8 2194.6 2238.6 2244.7 2245.6 129682.0 134243.3 135216.0 136055.3 136076.3 10847.8 12325.2 12673.0 12693.6 12741.6 16029.7 16333.8 16472.4 16522.2 16572.0 7094.8 8833.7 9051.2 9054.1 9174.0 7037.4 7091.0 8727.2 8744.7 8751.3 7876.0 7994.0 8055.6 8064.9 8069.3 2163.6 2166.1 2171.8 2178.7 2190.5 893.4 1002.7 1019.0 1025.6 1070.2 19314.9 20586.6 20922.0 25484.4 27256.0 1012.5 1019.3 1021.7 1045.4 1047.7 9010.2 10808.6 11212.4 11226.4 11369.5 685556.0 694925.0 707943.0 724046.0 724844.0 151735.2 153175.0 156416.0 157526.0 159163.0 1203.0 1204.9 1261.0 1264.4 1270.6 17334.8 20729.7 21154.9 21672.5 22027.6 57574.7 58506.0 58741.8 59164.1 59633.7 81014.0 81377.6 81421.0 83196.6 83583.2 7665.5 8698.9 8718.1 8820.9 9025.5 10253.4 11095.4 11427.9 14265.6 14310.4 11653.5 11731.8 11836.5 12093.6 12134.2 9663.7 9705.8 10863.1 11112.4 11334.7 11430.3 11772.8 12115.8 12253.2 12254.7 8742.0 8746.4 8789.0 9025.2 9135.1 11638.0 11787.5 12013.2 12115.5 12163.8 22:08:52 INFO - PID 1643 | 22:08:52 INFO - PID 1643 | [#3] mootools.html Cycles:180 Average:7078.04 Median:2515.60 stddev:4476.80 (178.0%) stddev-sans-first:4468.84 22:08:52 INFO - PID 1643 | Values: 1374.3 1460.2 1468.1 1471.6 1486.2 11083.8 11575.1 11602.4 11819.4 12264.9 11734.0 11947.9 11982.2 11987.5 11998.6 2493.2 2534.5 2540.5 2543.9 2544.0 2401.0 2420.2 2425.5 2427.1 2441.7 2454.3 2461.5 2478.4 2492.3 2495.0 2424.0 2424.5 2443.3 2457.1 2476.2 1915.9 2431.9 2454.8 2456.0 2496.7 10843.3 11034.7 11830.8 11938.3 11951.6 6488.4 6685.5 6740.1 6740.1 6755.8 4015.4 4250.6 4324.1 4338.8 4375.5 1163.7 1174.9 1203.2 1205.2 1209.8 13058.9 14914.9 15810.1 15974.2 16127.2 5467.4 5573.1 5703.5 5788.1 5812.0 10294.6 12262.9 12353.1 12365.4 12373.9 6173.5 6478.1 6538.0 6538.6 6544.9 6026.1 6051.9 6175.4 6227.5 6257.2 4310.0 4419.1 4457.4 4475.9 4506.0 1141.3 1257.4 1281.6 1296.1 1297.2 742.7 783.8 791.5 825.6 826.1 5428.7 6134.7 6234.7 6283.6 6359.7 588.3 588.7 594.2 608.6 615.3 3704.6 4160.5 4294.7 4407.2 4422.8 14668.3 14880.8 14948.0 15329.8 15366.2 12506.9 15159.8 15309.6 15322.1 15387.5 10889.3 10930.8 10958.0 10970.7 11079.1 5780.3 6576.3 6694.8 6803.8 6859.4 13253.6 13280.9 13296.5 13341.8 13571.5 11840.8 13722.4 13858.4 13982.7 14021.3 8678.6 8732.0 8775.4 8779.4 8799.9 10509.6 10607.4 10717.9 10726.6 10762.5 7611.7 8006.4 9011.0 9110.8 9181.5 8846.7 8861.8 8982.1 8994.8 9012.7 6512.6 8086.1 8113.6 8209.9 8256.5 5821.4 6007.1 6018.3 6122.6 6190.8 6074.2 6128.1 6203.0 6291.3 6322.4 22:08:52 INFO - PID 1643 | 22:08:52 INFO - PID 1643 | [#4] prototype.html Cycles:180 Average:5247.58 Median:5594.16 stddev:1588.07 (28.4%) stddev-sans-first:1550.16 22:08:52 INFO - PID 1643 | Values: 392.9 402.9 403.9 405.6 411.0 6078.5 6221.4 6239.5 6256.8 6259.0 4781.9 4951.9 5830.8 6088.6 6116.4 4044.9 4107.4 4279.7 4313.3 4689.6 3939.1 4076.8 4551.4 4642.8 4748.6 4567.2 4587.2 4591.5 4672.0 4679.4 4209.1 4517.4 4626.6 4629.0 4661.2 3610.4 4139.6 4140.4 4171.5 4200.1 5099.9 5457.4 5474.6 5514.0 5829.9 4585.5 4617.0 5529.2 5532.6 5541.5 6230.5 6450.4 6494.5 6498.0 6535.1 3952.9 4539.4 4663.6 4683.7 4732.6 5429.3 6146.2 6356.1 6794.2 6849.5 4439.0 4665.7 4770.8 4823.4 4865.9 4203.8 4926.7 4951.0 4955.7 4955.8 4899.3 5314.5 5484.6 5544.9 5581.7 5363.4 6231.9 6234.8 6501.0 6593.4 1425.2 1636.4 1676.9 1710.8 1715.0 997.8 1149.8 1177.1 1179.2 1181.8 5305.8 5458.5 5540.4 6220.7 6246.2 5423.9 6202.3 6772.3 6786.1 6814.5 6116.2 6173.7 6221.3 6252.7 6262.0 2832.9 3529.4 3549.4 3549.6 3553.3 5659.3 6037.0 6533.9 7043.2 7056.4 6037.6 6127.3 7055.5 7064.3 7084.6 6151.7 6421.8 6474.7 6776.8 7008.6 5371.3 6123.8 6126.5 6730.6 6776.8 5537.2 6391.6 6611.6 6644.6 6651.6 5182.3 6260.3 6536.4 6795.0 6936.1 4418.9 4425.8 4429.5 4452.3 4466.3 6606.3 6653.8 6711.5 6759.8 6771.7 5606.6 6104.7 6458.6 6581.0 6587.6 5913.7 6533.7 6534.3 6558.6 6814.3 5361.4 6340.5 6409.0 6430.0 6464.8 6588.8 6598.5 6599.4 6600.5 6609.3 5567.5 5960.1 6137.5 6140.3 6416.2 22:08:52 INFO - PID 1643 | 22:08:52 INFO - PID 1643 | [#5] yui.html Cycles:180 Average:19777.80 Median:18604.71 stddev:25963.80 (139.6%) stddev-sans-first:25997.45 22:08:52 INFO - PID 1643 | Values: 781.3 822.4 834.2 862.7 875.6 14007.3 14592.2 16517.3 16903.6 17124.7 16869.8 17342.1 17504.9 17632.6 17841.0 976.4 982.7 984.6 985.7 988.3 874.8 967.0 997.2 1001.8 1004.4 1034.4 1037.3 1037.5 1038.0 1038.3 1025.2 1029.8 1030.4 1032.8 1040.7 757.6 759.5 760.3 761.7 767.7 16276.3 16887.9 17030.9 17126.7 17137.3 15784.4 16228.7 16238.4 16409.1 16477.7 13089.8 13409.8 13551.3 13551.4 13566.2 3346.1 3371.3 3384.6 3446.2 3546.9 81032.0 97704.0 106293.5 108715.5 109387.0 13094.5 13489.5 13492.9 13505.4 13563.5 24138.4 24549.8 25020.6 25038.9 25047.2 7309.0 7585.4 8592.0 8915.0 8988.3 4821.1 4928.6 4930.7 4934.3 4941.3 4374.7 4829.4 4837.5 4870.4 4888.0 946.9 974.5 979.5 994.9 995.4 637.6 648.5 649.6 652.8 667.3 10311.4 11210.3 11817.4 11942.3 11994.2 622.8 644.3 656.8 663.3 665.4 5498.9 5518.0 5633.3 5649.6 5737.4 88462.0 97523.5 98980.5 107125.5 107369.0 63382.1 78453.0 79601.8 79874.6 82357.0 58853.0 63213.4 63275.7 63944.4 64392.0 11598.8 12115.7 12155.3 12175.5 12290.8 37554.4 37618.6 37807.0 37883.7 38394.4 48426.8 48825.8 48873.8 48906.0 49529.5 15689.6 15759.4 16106.3 16686.2 16886.3 17636.3 18171.3 18225.0 18599.4 18610.0 16194.1 16882.2 17047.7 17126.6 17193.7 16293.0 16588.2 16629.8 16840.8 16899.7 16133.8 16213.8 16266.8 16451.0 16598.5 11696.7 11941.0 11971.3 12073.0 12116.8 11279.3 12740.3 12753.0 12857.0 13064.2 22:08:52 INFO - PID 1643 | -------- Summary: end -------- 22:08:52 INFO - PID 1643 | 22:08:53 INFO - TEST-INFO | 1643: exit 0 22:08:53 INFO - TEST-OK | dromaeo_css | took 1115489ms 22:08:53 INFO - TEST-START | kraken 22:08:53 INFO - operating with platform_type : mac_ 22:08:53 INFO - Initialising browser for kraken test... 22:08:53 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpm980WA/profile http://localhost:49238/getInfo.html) 22:08:56 INFO - PID 1654 | 2017-04-11 22:08:56.200 plugin-container[1655:10077] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8833, name = 'com.apple.tsm.portname' 22:08:56 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:08:56 INFO - PID 1654 | 2017-04-11 22:08:56.204 plugin-container[1655:10077] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0f, name = 'com.apple.CFPasteboardClient' 22:08:56 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:08:56 INFO - PID 1654 | 2017-04-11 22:08:56.204 plugin-container[1655:10077] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:08:56 INFO - PID 1654 | __metrics Screen width/height:1600/1200 22:08:56 INFO - PID 1654 | colorDepth:24 22:08:56 INFO - PID 1654 | Browser inner width/height: 1024/689 22:08:56 INFO - PID 1654 | __metrics 22:08:56 ERROR - PID 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 22:08:57 INFO - TEST-INFO | 1654: exit 0 22:08:57 INFO - Browser initialized. 22:08:57 INFO - Running cycle 1/1 for kraken test... 22:08:57 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpm980WA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:08:59 INFO - PID 1656 | 2017-04-11 22:08:59.963 plugin-container[1657:10209] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4f, name = 'com.apple.tsm.portname' 22:08:59 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:08:59 INFO - PID 1656 | 2017-04-11 22:08:59.964 plugin-container[1657:10209] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 22:08:59 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:08:59 INFO - PID 1656 | 2017-04-11 22:08:59.964 plugin-container[1657:10209] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:09:09 INFO - PID 1656 | RSS: Main: 235261952 22:09:09 INFO - PID 1656 | 22:09:12 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection) 22:09:13 INFO - PID 1656 | RSS: Main: 250068992 22:09:13 INFO - PID 1656 | 22:09:16 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-dft) 22:09:16 INFO - PID 1656 | RSS: Main: 258633728 22:09:16 INFO - PID 1656 | 22:09:20 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-fft) 22:09:21 INFO - PID 1656 | RSS: Main: 260714496 22:09:21 INFO - PID 1656 | 22:09:23 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator) 22:09:23 INFO - PID 1656 | RSS: Main: 262897664 22:09:23 INFO - PID 1656 | 22:09:26 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:09:26 INFO - PID 1656 | RSS: Main: 263229440 22:09:26 INFO - PID 1656 | 22:09:29 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom) 22:09:30 INFO - PID 1656 | RSS: Main: 269000704 22:09:30 INFO - PID 1656 | 22:09:33 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate) 22:09:33 INFO - PID 1656 | RSS: Main: 269152256 22:09:33 INFO - PID 1656 | 22:09:37 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial) 22:09:38 INFO - PID 1656 | RSS: Main: 269422592 22:09:38 INFO - PID 1656 | 22:09:39 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:09:40 INFO - PID 1656 | RSS: Main: 269021184 22:09:40 INFO - PID 1656 | 22:09:42 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:09:42 INFO - PID 1656 | RSS: Main: 269004800 22:09:42 INFO - PID 1656 | 22:09:44 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:09:45 INFO - PID 1656 | RSS: Main: 268988416 22:09:45 INFO - PID 1656 | 22:09:48 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:09:49 INFO - PID 1656 | RSS: Main: 269324288 22:09:49 INFO - PID 1656 | 22:09:52 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:09:53 INFO - PID 1656 | RSS: Main: 266706944 22:09:53 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49238/tests/kraken/driver.html?testName=ai-astar) 22:09:55 INFO - PID 1656 | RSS: Main: 270077952 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | __start_tp_report 22:09:55 INFO - PID 1656 | _x_x_mozilla_page_load 22:09:55 INFO - PID 1656 | _x_x_mozilla_page_load_details 22:09:55 INFO - PID 1656 | |i|pagename|runs| 22:09:55 INFO - PID 1656 | |0;ai-astar;111;124;113;121;120;124;122;145;125;127 22:09:55 INFO - PID 1656 | |1;audio-beat-detection;150;123;143;163;115;116;117;120;133;111 22:09:55 INFO - PID 1656 | |2;audio-dft;220;226;226;228;222;242;301;271;226;292 22:09:55 INFO - PID 1656 | |3;audio-fft;80;67;88;120;84;68;61;63;75;64 22:09:55 INFO - PID 1656 | |4;audio-oscillator;91;74;87;82;110;105;86;73;74;86 22:09:55 INFO - PID 1656 | |5;imaging-gaussian-blur;94;92;94;94;94;93;94;92;93;94 22:09:55 INFO - PID 1656 | |6;imaging-darkroom;102;101;101;100;100;110;102;100;101;99 22:09:55 INFO - PID 1656 | |7;imaging-desaturate;146;129;104;103;102;99;161;128;101;157 22:09:55 INFO - PID 1656 | |8;json-parse-financial;52;49;51;52;52;54;51;52;52;52 22:09:55 INFO - PID 1656 | |9;json-stringify-tinderbox;66;85;91;92;67;61;60;60;62;60 22:09:55 INFO - PID 1656 | |10;stanford-crypto-aes;87;88;88;89;88;87;100;88;77;76 22:09:55 INFO - PID 1656 | |11;stanford-crypto-ccm;147;146;160;156;165;168;170;154;282;168 22:09:55 INFO - PID 1656 | |12;stanford-crypto-pbkdf2;215;249;208;219;246;204;196;197;193;194 22:09:55 INFO - PID 1656 | |13;stanford-crypto-sha256-iterative;72;71;73;72;68;101;84;70;69;72 22:09:55 INFO - PID 1656 | __end_tp_report 22:09:55 INFO - PID 1656 | __start_cc_report 22:09:55 INFO - PID 1656 | _x_x_mozilla_cycle_collect,1018 22:09:55 INFO - PID 1656 | __end_cc_report 22:09:55 INFO - PID 1656 | __startTimestamp1491973795698__endTimestamp 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | ------- Summary: start ------- 22:09:55 INFO - PID 1656 | Number of tests: 14 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | [#0] ai-astar Cycles:10 Average:123.20 Median:124.00 stddev:9.21 (7.4%) stddev-sans-first:8.65 22:09:55 INFO - PID 1656 | Values: 111.0 124.0 113.0 121.0 120.0 124.0 122.0 145.0 125.0 127.0 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:128.00 stddev:17.51 (13.7%) stddev-sans-first:16.86 22:09:55 INFO - PID 1656 | Values: 150.0 123.0 143.0 163.0 115.0 116.0 117.0 120.0 133.0 111.0 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | [#2] audio-dft Cycles:10 Average:245.40 Median:235.00 stddev:30.83 (13.1%) stddev-sans-first:31.30 22:09:55 INFO - PID 1656 | Values: 220.0 226.0 226.0 228.0 222.0 242.0 301.0 271.0 226.0 292.0 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | [#3] audio-fft Cycles:10 Average:77.00 Median:71.50 stddev:17.75 (24.8%) stddev-sans-first:18.79 22:09:55 INFO - PID 1656 | Values: 80.0 67.0 88.0 120.0 84.0 68.0 61.0 63.0 75.0 64.0 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | [#4] audio-oscillator Cycles:10 Average:86.80 Median:84.00 stddev:12.60 (15.0%) stddev-sans-first:13.28 22:09:55 INFO - PID 1656 | Values: 91.0 74.0 87.0 82.0 110.0 105.0 86.0 73.0 74.0 86.0 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:93.40 Median:94.00 stddev:0.84 (0.9%) stddev-sans-first:0.87 22:09:55 INFO - PID 1656 | Values: 94.0 92.0 94.0 94.0 94.0 93.0 94.0 92.0 93.0 94.0 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:101.50 stddev:3.10 (3.1%) stddev-sans-first:3.28 22:09:55 INFO - PID 1656 | Values: 102.0 101.0 101.0 100.0 100.0 110.0 102.0 100.0 101.0 99.0 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | [#7] imaging-desaturate Cycles:10 Average:123.00 Median:137.50 stddev:24.61 (17.9%) stddev-sans-first:24.66 22:09:55 INFO - PID 1656 | Values: 146.0 129.0 104.0 103.0 102.0 99.0 161.0 128.0 101.0 157.0 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:1.25 (2.4%) stddev-sans-first:1.32 22:09:55 INFO - PID 1656 | Values: 52.0 49.0 51.0 52.0 52.0 54.0 51.0 52.0 52.0 52.0 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:66.50 stddev:13.41 (20.2%) stddev-sans-first:14.13 22:09:55 INFO - PID 1656 | Values: 66.0 85.0 91.0 92.0 67.0 61.0 60.0 60.0 62.0 60.0 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:88.00 stddev:6.65 (7.6%) stddev-sans-first:7.05 22:09:55 INFO - PID 1656 | Values: 87.0 88.0 88.0 89.0 88.0 87.0 100.0 88.0 77.0 76.0 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:171.60 Median:166.50 stddev:39.73 (23.9%) stddev-sans-first:41.13 22:09:55 INFO - PID 1656 | Values: 147.0 146.0 160.0 156.0 165.0 168.0 170.0 154.0 282.0 168.0 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:212.10 Median:211.50 stddev:20.63 (9.8%) stddev-sans-first:21.85 22:09:55 INFO - PID 1656 | Values: 215.0 249.0 208.0 219.0 246.0 204.0 196.0 197.0 193.0 194.0 22:09:55 INFO - PID 1656 | 22:09:55 INFO - PID 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:72.00 stddev:10.08 (14.0%) stddev-sans-first:10.62 22:09:55 INFO - PID 1656 | Values: 72.0 71.0 73.0 72.0 68.0 101.0 84.0 70.0 69.0 72.0 22:09:55 INFO - PID 1656 | -------- Summary: end -------- 22:09:55 INFO - PID 1656 | 22:09:56 INFO - TEST-INFO | 1656: exit 0 22:09:56 INFO - TEST-OK | kraken | took 63713ms 22:09:56 INFO - SUITE-END | took 1179s 22:09:56 INFO - Completed test suite (00:19:39) 22:09:56 INFO - javascript benchmark 22:09:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7848.3351165125905, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3030.2727345437097, 3556.164301389175, 3571.6071232164463, 3613.2412193439377, 3633.5094762207095, 6070.453659170674, 6182.938170618292, 6244.46922011629, 6272.717727702253, 6351.428371446569, 6096.375503402309, 6098.008671753297, 6111.236095488064, 6212.565120441571, 6241.375862413756, 5323.547498462635, 5416.945830541703, 5430.456954304557, 5490.6431081979745, 5514.80698175564, 5379.946200538003, 5437.510624043835, 5514.310711161105, 5523.696196709179, 5523.861903452405, 5452.236686863842, 5456.699881506535, 5495.587830912657, 5501.380310222237, 5518.779248830042, 5268.92096618551, 5341.03861304965, 5342.171963345688, 5349.090654588729, 5366.114591092465, 4670.8365207217585, 5339.492748188916, 5376.623636345457, 5401.000814849265, 5403.162509810985, 5274.55166310864, 6181.134641150197, 6189.9381006189815, 6292.842678933018, 6304.621722696653, 5706.144078388275, 6352.523560732963, 6438.034294855763, 6676.632785196813, 6680.0, 6662.20053593567, 6754.527183097185, 6759.7296108155615, 6763.188417389944, 6782.457403407716, 5257.947420525746, 6303.338149494283, 6352.396522330371, 6353.205849268841, 6367.108604795332, 6567.573107747981, 6768.932310676831, 6899.517033807686, 6906.930930690729, 6914.308569143146, 6289.119523266746, 6376.936230637727, 6455.225372955276, 6506.056621789814, 6509.088727578142, 5637.9718101409235, 6481.222253329631, 6578.309277525791, 6709.429698475684, 6736.865262694719, 5588.497035266845, 6515.022746587955, 6590.275069742324, 6611.702473388709, 6652.600843949282, 6338.492920566344, 6407.775727849503, 6627.370399812106, 6653.135092437952, 6672.332766723289, 5257.553107985787, 5263.973680131651, 5272.156454967187, 5281.234221037928, 5289.365276166807, 4682.41469816273, 4725.763711814396, 4736.550027747358, 4738.691985020962, 4761.4524329701835, 6152.815415537541, 6343.936560634427, 6347.301796802348, 6348.269948955929, 6358.904616430757, 6407.295197528269, 6669.599824010575, 6688.966555167193, 6718.764843230463, 6767.627780472121, 6254.437100660963, 6270.341614130451, 6311.558190926683, 6355.968220158869, 6359.332270111571, 5914.201582786355, 5915.142304365929, 5916.083007133901, 5921.911171332434, 5924.792632257937, 6778.8644227116165, 6825.761098361533, 6861.348171923659, 6866.0, 6913.3777959983845, 6421.614703117828, 6530.542861999805, 6623.867522649616, 6707.698153583003, 6725.327467253235, 6845.247022827385, 6872.965635171792, 6877.415419689382, 6894.586324820527, 6900.6204658742245, 6590.406863382319, 6620.635865027277, 6635.568688035262, 6640.701168447528, 6657.866842663217, 6004.309504407049, 6351.523635727246, 6612.966935165294, 6682.0, 6698.631575263407, 6494.935050649433, 6514.869702605826, 6520.152380190545, 6533.0, 6543.1821022372205, 6249.875002500015, 6270.0, 6492.285848556749, 6498.902516462163, 6515.6742162892615, 5424.864378390572, 6343.619382837044, 6363.077353783768, 6369.9681501592195, 6388.105665206967, 5045.722485263199, 5407.513323800877, 6537.8692426152165, 6544.44372228366, 6565.409113179646, 5482.917756233739, 6320.209973753281, 6383.7127329271225, 6384.904226436514, 6389.904151437825], "value": 6032.630683024069, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.9702011919524, 795.8885755994162, 826.5949684654519, 1176.9819106472903, 1249.0507214516977, 33778.99999999997, 35768.82115589421, 38745.22509549807, 38764.80617596912, 38768.22463550727, 38106.8094659527, 38301.61698383015, 38491.42262866059, 38500.0, 38588.26352814123, 7740.916271721963, 8134.186581341848, 8277.420580559363, 8575.828483430327, 8849.867251991225, 8686.609102590366, 8812.8678069829, 8815.471071735685, 8817.0, 8825.940887093559, 8765.430247033953, 8804.64781408743, 8820.382573219877, 8850.73447796564, 8859.069797671249, 8760.561971901412, 8790.64837406503, 8793.472391656522, 8798.384113112084, 8824.382293239443, 8565.700200493015, 8602.225799678059, 8608.655653773842, 8631.956840215758, 8651.95674021626, 29631.962881299267, 37170.0, 37366.43950340747, 37433.62566374329, 37443.0, 3648.1791596890753, 3685.4840322354894, 3688.520492336006, 3691.2063906260014, 3695.9260814783825, 30724.078277651704, 30920.0, 30929.536056959165, 30942.53586196209, 30981.070567882998, 3144.9056528304186, 3198.088544764731, 3205.871765129392, 3213.469777486712, 3229.3056992746674, 219262.0, 224324.8783756087, 255458.0, 275294.6235268811, 277003.0, 47778.04443911102, 47841.282380764314, 48158.518414816106, 48175.0, 48199.0, 49524.75237623789, 49664.0067198654, 49730.0053998918, 49958.50041499611, 50062.499375005784, 14136.0, 14233.359498822578, 14366.281685915663, 14366.85633143676, 14372.712545749026, 11677.416129193507, 11759.176857619885, 11867.821982670266, 11871.228370155912, 11901.642950711492, 13305.467781288833, 13337.666558335924, 13355.53255636048, 13377.531786387624, 13463.730725385438, 5907.202527658797, 5920.911186332209, 5929.466348028698, 5942.643441393444, 6019.337872833985, 943.2265542255285, 1042.2287507244737, 1045.2004216774174, 1074.8065348237235, 1095.9506822192861, 20944.162233510488, 24099.638505422434, 24237.394065148514, 24399.0, 24453.144139955017, 1021.7088129883097, 1078.7518870659792, 1145.6448500964727, 1174.2308787744041, 1182.6274723462082, 12209.389530523438, 12361.925031099625, 12402.317872517098, 12437.440315185657, 12483.93886519656, 332204.0, 332510.0, 333519.0, 334339.0, 334433.0, 53111.73444132755, 53280.0, 53546.0, 53569.0, 53638.0, 1529.8852586055868, 1564.1944398634712, 1567.4748959098602, 1571.4028669105896, 1581.1777875504672, 20869.895650521652, 23030.42423939415, 23417.648735269326, 23746.287611371685, 23859.284221473383, 31660.5250921241, 33046.66953330436, 37782.2443551133, 38172.85481435561, 38180.427293590066, 306002.0, 306663.0, 307135.0, 307315.0, 313407.0, 33857.66142338545, 34281.31437371189, 34882.47676284807, 35008.649913500536, 35070.298594028485, 44600.43898463538, 45261.0, 45368.77315613401, 45444.545554544035, 45880.0, 30259.0, 30279.394412112077, 31083.378332433676, 31107.844460777553, 31244.375112498077, 30179.396412072074, 30241.69758302477, 30522.236944076576, 31022.379552408373, 31204.531932020585, 36946.63053369432, 37015.074623134635, 37068.25863482661, 37110.0, 37171.44242836306, 24973.625395618717, 30008.79964801471, 30275.243118922204, 31033.758649653762, 31221.53167702441, 24520.93488313961, 24591.508169836146, 24651.50696986086, 24785.876070620252, 25049.749502504743], "value": 17589.577916483995, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.8773655639865, 436.45007290813567, 438.38018521562816, 448.49320268097046, 448.96183824374924, 19885.304014359517, 21328.253511127095, 21897.233596824113, 21914.0, 22088.570257271687, 21694.457638559044, 23091.538169236606, 23190.3042908713, 23248.302550923458, 23509.647355289686, 6219.471344935686, 6282.434580887731, 6494.415502604765, 6531.314212007742, 6545.738170473175, 6233.438990490855, 6358.078078678589, 6409.487241020731, 6431.581947173442, 6453.322401147882, 4890.828820991266, 5517.2551705519645, 5853.414658534134, 6318.652474113922, 6406.583572067824, 5431.8098866539485, 6342.4926005919415, 6414.422701956823, 6418.582792118497, 6421.550491465599, 5615.0454422748235, 5624.128260119688, 5703.173039909231, 5704.087346024658, 5704.743286552115, 26864.0, 26915.86542067277, 26947.461050778875, 27044.053458129067, 27167.864160679266, 2229.8996545155505, 2295.3228797504607, 2296.368498662881, 2300.0454811253308, 2307.180950762472, 17888.195031223495, 17942.551436214068, 18013.099345032697, 18439.815601843944, 18593.0, 2167.783221677835, 2194.5830292244423, 2238.563480121377, 2244.730632324115, 2245.6182448983714, 129682.0, 134243.32878335644, 135215.999999999, 136055.31972340174, 136076.27847442994, 10847.75250846147, 12325.198862073936, 12673.0, 12693.555725549746, 12741.55404560836, 16029.679406411806, 16333.836661633231, 16472.4234651789, 16522.173891305385, 16572.005679659258, 7094.787156385317, 8833.69082082124, 9051.185393314634, 9054.094590541024, 9173.954130229306, 7037.4018208451835, 7090.958153852254, 8727.167294868148, 8744.69393571222, 8751.343649226334, 7875.960620196863, 7993.9607850979, 8055.597220139087, 8064.935324576741, 8069.256452806074, 2163.5889181055863, 2166.1118941234017, 2171.8045375916245, 2178.7385513738454, 2190.539986602799, 893.446063440671, 1002.7057138309737, 1018.9949050254701, 1025.646152077532, 1070.1545778834789, 19314.903425482782, 20586.558940874296, 20922.0, 25484.362890927874, 27256.0, 1012.5443550402348, 1019.2966852871494, 1021.7088129882948, 1045.4406892312577, 1047.7328281288223, 9010.189082982562, 10808.62169824068, 11212.439378031066, 11226.438678066064, 11369.488373023234, 685556.0, 694925.0, 707943.0, 724046.0, 724844.0, 151735.24132379267, 153175.0, 156416.0, 157526.0, 159163.0, 1202.9939850300693, 1204.903607711381, 1260.9936950315373, 1264.393091316155, 1270.5870592057618, 17334.82665173332, 20729.68905466389, 21154.894225528773, 21672.458188545414, 22027.559448810614, 57574.7121264391, 58506.0, 58741.82516349564, 59164.112538312824, 59633.701831490565, 81014.0, 81377.59311203405, 81421.0, 83196.58401708194, 83583.16416835754, 7665.540067595962, 8698.869516957124, 8718.084601117045, 8820.911790882266, 9025.548722563713, 10253.436061016715, 11095.445227738743, 11427.942860285977, 14265.64335891611, 14310.427582896567, 11653.53385864556, 11731.765364692488, 11836.46735896904, 12093.637190884288, 12134.211276267015, 9663.661771837951, 9705.757356066155, 10863.130949524177, 11112.444377781241, 11334.716632083933, 11430.314181149157, 11772.823407649063, 12115.757684846078, 12253.203541769757, 12254.693632659255, 8741.956290218763, 8746.431481953652, 8788.956055219684, 9025.232855207381, 9135.132162444557, 11638.0, 11787.528498859949, 12013.219140756173, 12115.454804533994, 12163.817542737042], "value": 10609.873525813913, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1374.2785037855124, 1460.2260801775062, 1468.1044562816687, 1471.587955372496, 1486.191098823224, 11083.833742493858, 11575.073994080474, 11602.419879006047, 11819.40902954851, 12264.93867530661, 11734.0, 11947.940260298708, 11982.221155624879, 11987.460564274606, 11998.640040798768, 2493.164789795421, 2534.4550921551863, 2540.479201763634, 2543.898244070235, 2544.0459827564664, 2400.987995060022, 2420.165043060141, 2425.4542727886173, 2427.1141033522727, 2441.694788151481, 2454.2514533067406, 2461.520003599299, 2478.4423504711385, 2492.314613481289, 2494.950100997979, 2423.9757602423924, 2424.4544977380037, 2443.2548072837776, 2457.0663148003823, 2476.2447453526665, 1915.8850468971907, 2431.8540887546806, 2454.828162028659, 2455.9877200613882, 2496.737842526527, 10843.295185812896, 11034.668959931134, 11830.822537661941, 11938.343391113485, 11951.581694640732, 6488.416042556192, 6685.465162787014, 6740.12378390806, 6740.1237839081095, 6755.831104222385, 4015.3976903464422, 4250.617444430016, 4324.135172965388, 4338.826446942118, 4375.5405682403525, 1163.6567212672282, 1174.8531433570804, 1203.1758245601704, 1205.2226314027491, 1209.842720446345, 13058.934705326508, 14914.925425372912, 15810.13044282564, 15974.201289935456, 16127.193640318055, 5467.371252305956, 5573.108302671553, 5703.486686198179, 5788.131780232915, 5812.041013232853, 10294.588216471408, 12262.938685306695, 12353.135280530276, 12365.443555039865, 12373.93813030929, 6173.536984726074, 6478.125453063871, 6538.0192971053775, 6538.574992625464, 6544.934550654528, 6026.12117071511, 6051.939480605226, 6175.413335733188, 6227.470664993526, 6257.186565746424, 4310.030243195319, 4419.1382680377665, 4457.442819647544, 4475.910481790411, 4506.0, 1141.2809929744208, 1257.3838818978634, 1281.564268148834, 1296.0817989375776, 1297.1892567145535, 742.7140550887839, 783.8158032862273, 791.4855344026429, 825.5542007316102, 826.0954255090666, 5428.728563571805, 6134.662593557308, 6234.657093859791, 6283.59156654816, 6359.682015899187, 588.3351812451901, 588.6850534963773, 594.180031556458, 608.6256951974592, 615.3354377272535, 3704.592494825567, 4160.54234034256, 4294.720843145248, 4407.184670835905, 4422.845200418034, 14668.339924703414, 14880.776788348183, 14948.0, 15329.770053449207, 15366.231688415535, 12506.936910362721, 15159.848401516065, 15309.617259568377, 15322.080675159303, 15387.538373848802, 10889.292196007234, 10930.836037459445, 10958.0, 10970.670879873616, 11079.058280046125, 5780.306363236438, 6576.309487503934, 6694.765683201064, 6803.7618683345845, 6859.416949559343, 13253.602391928258, 13280.867191327963, 13296.534621288209, 13341.799873001719, 13571.457141714222, 11840.75672054422, 13722.382492788049, 13858.376373063034, 13982.650433739238, 14021.29893505341, 8678.566071696263, 8732.039475657799, 8775.385722999328, 8779.385443018928, 8799.868001979847, 10509.579616815241, 10607.4165920872, 10717.892821072, 10726.570937162425, 10762.46187690628, 7611.733589324347, 8006.439549231498, 9011.0, 9110.772230694285, 9181.540922953693, 8846.690365837165, 8861.822763544822, 8982.101789820965, 8994.775130621787, 9012.729618111467, 6512.5766825156215, 8086.070101938352, 8113.553754543556, 8209.917900820916, 8256.504609723435, 5821.388754180919, 6007.12896629995, 6018.337982821974, 6122.632642041492, 6190.845228869314, 6074.210352654127, 6128.111423843607, 6203.0075187969705, 6291.339409361951, 6322.36776322364], "value": 5295.043363446079, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.8899908025753, 402.86392373506703, 403.9050823056579, 405.61871840469956, 410.97739624320667, 6078.513718902488, 6221.351341894401, 6239.469645080162, 6256.781012664557, 6258.968705156473, 4781.904361912769, 4951.900961980758, 5830.766769329232, 6088.634681919088, 6116.44951954324, 4044.9483134384977, 4107.404426358176, 4279.679024073192, 4313.340346084237, 4689.57793798558, 3939.1333906540517, 4076.7769669099152, 4551.431071116111, 4642.767861606923, 4748.596369308613, 4567.246404343296, 4587.151416634872, 4591.471980722226, 4671.976640116812, 4679.415073115861, 4209.0529630833125, 4517.435320584927, 4626.560476754703, 4628.953710462886, 4661.184292748772, 3610.422332426799, 4139.606737359946, 4140.399642051916, 4171.457710497646, 4200.096979149467, 5099.903520743021, 5457.372402173761, 5474.591179880839, 5514.042514907191, 5829.941700582982, 4585.518520555327, 4616.976915115437, 5529.198266251411, 5532.640378375393, 5541.476094074122, 6230.470410015154, 6450.419462248373, 6494.512911531607, 6498.0, 6535.091884111573, 3952.9209415811524, 4539.364488971547, 4663.580277774982, 4683.672142949995, 4732.574068333866, 5429.321334833146, 6146.231721034871, 6356.103627564098, 6794.218664853555, 6849.520533562652, 4439.001224724476, 4665.650076244296, 4770.833020844254, 4823.421189457287, 4865.854024379274, 4203.76879271643, 4926.743680361485, 4950.975245123824, 4955.653104282737, 4955.752212389366, 4899.3385892904, 5314.548263397654, 5484.561235101182, 5544.889102217933, 5581.6930068845795, 5363.356397232357, 6231.940570103094, 6234.812955611339, 6501.024846273097, 6593.406593406617, 1425.2374979386186, 1636.35364031207, 1676.8574671152844, 1710.7938903073552, 1714.9657006859793, 997.7954519323521, 1149.8332741752567, 1177.1465687376583, 1179.151011271883, 1181.7931861924128, 5305.840824775263, 5458.533831017253, 5540.445955404427, 6220.688965551704, 6246.219222597176, 5423.864403389947, 6202.255729312421, 6772.255051944282, 6786.117804685359, 6814.454843612499, 6116.235470566179, 6173.69131543421, 6221.2845522764555, 6252.718627661675, 6261.96869015652, 2832.937648381857, 3529.3823580873245, 3549.432090865449, 3549.6450354964295, 3553.271579326232, 5659.349174844946, 6037.034074548052, 6533.901991470132, 7043.225245223021, 7056.400205982549, 6037.63774173551, 6127.325994140641, 7055.506114572034, 7064.293570642894, 7084.610346430893, 6151.738893526726, 6421.839454013687, 6474.6762661866715, 6776.76281330161, 7008.579485230801, 5371.328583927009, 6123.755049797958, 6126.509879209563, 6730.562513436611, 6776.833081895452, 5537.169424586184, 6391.616503009835, 6611.570247934062, 6644.60132392058, 6651.567648102858, 5182.274481572658, 6260.280067792262, 6536.379043990813, 6794.966025169842, 6936.098307220029, 4418.873187337208, 4425.82296708141, 4429.468463784237, 4452.30989196675, 4466.285394336891, 6606.273309935999, 6653.767118150841, 6711.46308295345, 6759.831004224934, 6771.6614169292325, 5606.635568688022, 6104.69476526163, 6458.580192287486, 6581.0, 6587.571807832475, 5913.704314784158, 6533.705983230861, 6534.281229064894, 6558.606483610999, 6814.250432452525, 5361.35663720356, 6340.461060809883, 6409.0, 6430.0, 6464.773732919325, 6588.769393071219, 6598.538102332791, 6599.373059559334, 6600.471962243105, 6609.339066093352, 5567.526760225426, 5960.109346966804, 6137.539684523589, 6140.263168419819, 6416.197975253093], "value": 4769.467851976018, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [781.3046388714043, 822.404535201709, 834.1783343406747, 862.693743720979, 875.6234819027818, 14007.299635018258, 14592.197429141394, 16517.256723447445, 16903.577410564743, 17124.65750684987, 16869.819112662117, 17342.132893355345, 17504.949703017795, 17632.64734705305, 17841.0, 976.4092723902044, 982.6855406269997, 984.6110786239467, 985.7042887133867, 988.298308201178, 874.7725591346264, 966.9806603867919, 997.1922742578498, 1001.8346972749524, 1004.4324956399647, 1034.3897100710578, 1037.2946396450411, 1037.4605205293242, 1037.9931466477506, 1038.3458421194637, 1025.220832167558, 1029.8455231715227, 1030.4023666273542, 1032.8450732390127, 1040.7294103533097, 757.6249756370631, 759.4531937005403, 760.2701406649624, 761.7372006657695, 767.6545554500497, 16276.26756795947, 16887.915560422243, 17030.914845425817, 17126.657466850593, 17137.314507419687, 15784.447544336008, 16228.675426491522, 16238.431654892021, 16409.097499637515, 16477.67044659112, 13089.803652945213, 13409.798853017115, 13551.322433878366, 13551.39018744149, 13566.186028838203, 3346.0965539304493, 3371.2920286739813, 3384.5769278840144, 3446.190145315849, 3546.85812567497, 81032.0, 97704.0, 106293.46853265761, 108715.45642271738, 109387.0, 13094.476220951055, 13489.527866524624, 13492.93253533726, 13505.392257348443, 13563.525276615272, 24138.39654008664, 24549.754502455104, 25020.624690629655, 25038.874805625856, 25047.248582542554, 7308.990027742413, 7585.355244804254, 8592.0, 8915.0, 8988.280937525113, 4821.132196204647, 4928.6303527235605, 4930.6795058321095, 4934.309196712463, 4941.332920055818, 4374.737515749065, 4829.444613869379, 4837.467878533358, 4870.4155501340065, 4888.046830868017, 946.9337146399823, 974.5273542331963, 979.480875136179, 994.9154321882721, 995.4425521707722, 637.6492928889093, 648.5492582655104, 649.5972497051858, 652.8106849013733, 667.2693400726197, 10311.381317120848, 11210.327380357205, 11817.350045747395, 11942.283462992249, 11994.220375675553, 622.8069298517474, 644.3105876711874, 656.8456412743002, 663.3234101216732, 665.3944910131756, 5498.945010549924, 5517.972410137923, 5633.267675202197, 5649.6327738696855, 5737.397573254748, 88462.0, 97523.51238243764, 98980.51529227068, 107125.4643726792, 107369.0, 63382.09853704396, 78453.0, 79601.80597291046, 79874.60062699765, 82357.0, 58853.0, 63213.419664507834, 63275.6836215816, 63944.36055639477, 64392.03411948824, 11598.826017609912, 12115.697107572028, 12155.270683759003, 12175.452104655134, 12290.754184916073, 37554.436683450316, 37618.623813761515, 37807.0, 37883.67407140737, 38394.42408363933, 48426.75786621044, 48825.75587122042, 48873.75563122162, 48906.0, 49529.50470495249, 15689.607759806097, 15759.448419305269, 16106.27521761547, 16686.16335222991, 16886.324547017983, 17636.294548217928, 18171.273149073888, 18225.0, 18599.44201673898, 18609.976451295308, 16194.109323986822, 16882.24029918632, 17047.659046818746, 17126.571835704206, 17193.742093868852, 16293.022418654919, 16588.170591470138, 16629.83370166283, 16840.831591683927, 16899.66200675955, 16133.75799362987, 16213.756793647868, 16266.755998659793, 16451.0, 16598.502044938672, 11696.70758231017, 11941.044716422839, 11971.341576213392, 12073.0, 12116.818247726467, 11279.266847654877, 12740.299283539494, 12753.0, 12857.0, 13064.21614703083], "value": 8219.055522784252, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1647.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 124.0, 113.0, 121.0, 120.0, 124.0, 122.0, 145.0, 125.0, 127.0], "value": 123.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 123.0, 143.0, 163.0, 115.0, 116.0, 117.0, 120.0, 133.0, 111.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 226.0, 226.0, 228.0, 222.0, 242.0, 301.0, 271.0, 226.0, 292.0], "value": 245.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 67.0, 88.0, 120.0, 84.0, 68.0, 61.0, 63.0, 75.0, 64.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 74.0, 87.0, 82.0, 110.0, 105.0, 86.0, 73.0, 74.0, 86.0], "value": 86.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 92.0, 94.0, 94.0, 94.0, 93.0, 94.0, 92.0, 93.0, 94.0], "value": 93.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 101.0, 101.0, 100.0, 100.0, 110.0, 102.0, 100.0, 101.0, 99.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 129.0, 104.0, 103.0, 102.0, 99.0, 161.0, 128.0, 101.0, 157.0], "value": 123.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 51.0, 52.0, 52.0, 54.0, 51.0, 52.0, 52.0, 52.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 85.0, 91.0, 92.0, 67.0, 61.0, 60.0, 60.0, 62.0, 60.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 88.0, 89.0, 88.0, 87.0, 100.0, 88.0, 77.0, 76.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 160.0, 156.0, 165.0, 168.0, 170.0, 154.0, 282.0, 168.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [215.0, 249.0, 208.0, 219.0, 246.0, 204.0, 196.0, 197.0, 193.0, 194.0], "value": 212.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 73.0, 72.0, 68.0, 101.0, 84.0, 70.0, 69.0, 72.0], "value": 75.2, "unit": "score"}]}]} 22:09:56 INFO - Return code: 0 22:09:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:09:57 ERROR - # TBPL SUCCESS # 22:09:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:09:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:09:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:09:57 INFO - 2017-04-11 22:09:57.019 screenresolution[1661:10597] starting screenresolution argv=screenresolution get 22:09:57 INFO - 2017-04-11 22:09:57.023 screenresolution[1661:10597] Display 0: 1600x1200x32@60 22:09:57 INFO - 2017-04-11 22:09:57.029 screenresolution[1662:10600] starting screenresolution argv=screenresolution list 22:09:57 INFO - Available Modes on Display 0 22:09:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:09:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:09:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:09:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:09:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:09:57 INFO - Intel Iris: 22:09:57 INFO - Chipset Model: Intel Iris 22:09:57 INFO - Type: GPU 22:09:57 INFO - Bus: Built-In 22:09:57 INFO - VRAM (Dynamic, Max): 1536 MB 22:09:57 INFO - Vendor: Intel (0x8086) 22:09:57 INFO - Device ID: 0x0a2e 22:09:57 INFO - Revision ID: 0x0009 22:09:57 INFO - Displays: 22:09:57 INFO - Display: 22:09:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:09:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:09:57 INFO - Display Serial Number: 1600x1200 60 22:09:57 INFO - Main Display: Yes 22:09:57 INFO - Mirror: Off 22:09:57 INFO - Online: Yes 22:09:57 INFO - Rotation: Supported 22:09:57 INFO - Return code: 0 22:09:57 INFO - Running post-action listener: _resource_record_post_action 22:09:57 INFO - [mozharness: 2017-04-12 05:09:57.274869Z] Finished run-tests step (success) 22:09:57 INFO - Running post-run listener: _resource_record_post_run 22:09:57 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 39053824; Write bytes: 521432576; Read time: 249; Write time: 1643 22:09:57 INFO - TinderboxPrint: CPU usage
54.2% 22:09:57 INFO - TinderboxPrint: I/O read bytes / time
39,053,824 / 249 22:09:57 INFO - TinderboxPrint: I/O write bytes / time
521,432,576 / 1,643 22:09:57 INFO - TinderboxPrint: CPU idle
2,200.1 (45.8%) 22:09:57 INFO - TinderboxPrint: CPU system
963.3 (20.1%) 22:09:57 INFO - TinderboxPrint: CPU user
1,638.2 (34.1%) 22:09:57 INFO - TinderboxPrint: Swap in / out
247,148,544 / 0 22:09:57 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 200688128; Write bytes: 194459136; Read time: 15552; Write time: 411 22:09:57 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 37235200; Write bytes: 310319104; Read time: 232; Write time: 1182 22:09:57 INFO - Running post-run listener: _upload_blobber_files 22:09:57 INFO - Blob upload gear active. 22:09:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:09:57 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 22:09:57 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'] 22:09:57 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 22:09:58 INFO - (blobuploader) - INFO - Open directory for files ... 22:09:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:09:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:09:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:59 INFO - (blobuploader) - INFO - Done attempting. 22:09:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:09:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:10:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:10:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:10:00 INFO - (blobuploader) - INFO - Done attempting. 22:10:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:10:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:10:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:10:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:10:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:10:01 INFO - (blobuploader) - INFO - Done attempting. 22:10:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:10:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:10:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:10:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:10:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:10:03 INFO - (blobuploader) - INFO - Done attempting. 22:10:03 INFO - (blobuploader) - INFO - Iteration through files over. 22:10:03 INFO - Return code: 0 22:10:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:10:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:10:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/090b6b50dc58bcc9e2ce48e2c6bff881c56adb9c70a3ec7bd97f148c4fd61be117ac47f28d420d0e119e4e59841eec55922027a92a93e68903c7567836a946e2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5044349a263e6c2a7039e892258541221d6df986db79d8410f742d98844bf03fe197b81a74c87d2921474db951d1cd8e94213884a057b30eff40538daf82cb3c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b80fae02209c03e376e5eabc567810e9cb22000c25318d6490ea550fffd73df4b848640c766a22f24b06ef8345afd00c36a70aab8f873b63cd427bca537b4d68", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02935d5a812fbdf08c10ebb1f8c674ed8daa9a70a1dbb677fd83e124c8f3657e79051396e4aee1c793027fddfca4f9b8154aa4fa6f3598a732f82543de12a8fb"} 22:10:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:10:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:10:03 INFO - Contents: 22:10:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/090b6b50dc58bcc9e2ce48e2c6bff881c56adb9c70a3ec7bd97f148c4fd61be117ac47f28d420d0e119e4e59841eec55922027a92a93e68903c7567836a946e2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5044349a263e6c2a7039e892258541221d6df986db79d8410f742d98844bf03fe197b81a74c87d2921474db951d1cd8e94213884a057b30eff40538daf82cb3c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b80fae02209c03e376e5eabc567810e9cb22000c25318d6490ea550fffd73df4b848640c766a22f24b06ef8345afd00c36a70aab8f873b63cd427bca537b4d68", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02935d5a812fbdf08c10ebb1f8c674ed8daa9a70a1dbb677fd83e124c8f3657e79051396e4aee1c793027fddfca4f9b8154aa4fa6f3598a732f82543de12a8fb"} 22:10:03 INFO - Running post-run listener: copy_logs_to_upload_dir 22:10:03 INFO - Copying logs to upload dir... 22:10:03 INFO - mkdir: /builds/slave/test/build/upload/logs 22:10:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.771960 ========= master_lag: 0.22 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-11 22:10:03.712808) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 22:10:03.716902) ========= 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.IJAXQbEFYD/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.RA15kkdZUc/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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/090b6b50dc58bcc9e2ce48e2c6bff881c56adb9c70a3ec7bd97f148c4fd61be117ac47f28d420d0e119e4e59841eec55922027a92a93e68903c7567836a946e2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5044349a263e6c2a7039e892258541221d6df986db79d8410f742d98844bf03fe197b81a74c87d2921474db951d1cd8e94213884a057b30eff40538daf82cb3c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b80fae02209c03e376e5eabc567810e9cb22000c25318d6490ea550fffd73df4b848640c766a22f24b06ef8345afd00c36a70aab8f873b63cd427bca537b4d68", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02935d5a812fbdf08c10ebb1f8c674ed8daa9a70a1dbb677fd83e124c8f3657e79051396e4aee1c793027fddfca4f9b8154aa4fa6f3598a732f82543de12a8fb"} build_url:https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011200 build_url: 'https://queue.taskcluster.net/v1/task/Y50WiuXNTXOAF6_FMsGpQw/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/090b6b50dc58bcc9e2ce48e2c6bff881c56adb9c70a3ec7bd97f148c4fd61be117ac47f28d420d0e119e4e59841eec55922027a92a93e68903c7567836a946e2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5044349a263e6c2a7039e892258541221d6df986db79d8410f742d98844bf03fe197b81a74c87d2921474db951d1cd8e94213884a057b30eff40538daf82cb3c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b80fae02209c03e376e5eabc567810e9cb22000c25318d6490ea550fffd73df4b848640c766a22f24b06ef8345afd00c36a70aab8f873b63cd427bca537b4d68", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02935d5a812fbdf08c10ebb1f8c674ed8daa9a70a1dbb677fd83e124c8f3657e79051396e4aee1c793027fddfca4f9b8154aa4fa6f3598a732f82543de12a8fb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 22:10:03.752690) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 22:10:03.753064) ========= 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.IJAXQbEFYD/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.RA15kkdZUc/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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.005156 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 22:10:03.804693) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 22:10:03.805198) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 22:10:03.805788) ========= ========= Total master_lag: 0.36 =========