builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0381 starttime: 1491279081.43 results: success (0) buildid: 20170403194227 builduid: 054368ba47b24d358a0639f5f3914aba revision: 47919d9e58bff32e107bd06d2fd42d759dcae7f1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-03 21:11:21.428646) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-03 21:11:21.429074) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-03 21:11:21.429352) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-03 21:11:21.456113) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:11:21.456772) ========= 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.XGQRih8fqD/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.JoFmfO8YwA/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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-03 21:11:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.5M=0.001s 2017-04-03 21:11:21 (18.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101062 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:11:21.581644) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:11:21.581969) ========= 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.XGQRih8fqD/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.JoFmfO8YwA/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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.026997 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:11:21.630512) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-03 21:11:21.630858) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 47919d9e58bff32e107bd06d2fd42d759dcae7f1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 47919d9e58bff32e107bd06d2fd42d759dcae7f1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XGQRih8fqD/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.JoFmfO8YwA/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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-03 21:11:21,741 truncating revision to first 12 chars 2017-04-03 21:11:21,741 Setting DEBUG logging. 2017-04-03 21:11:21,741 attempt 1/10 2017-04-03 21:11:21,741 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/47919d9e58bf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-03 21:11:23,072 unpacking tar archive at: mozilla-beta-47919d9e58bf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.600323 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-03 21:11:23.258320) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-03 21:11:23.259124) ========= script_repo_revision: 47919d9e58bff32e107bd06d2fd42d759dcae7f1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-03 21:11:23.260069) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-03 21:11:23.260759) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-03 21:11:23.282406) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-04-03 21:11:23.283121) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XGQRih8fqD/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.JoFmfO8YwA/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:11:23 INFO - MultiFileLogger online at 20170403 21:11:23 in /builds/slave/test 21:11:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 21:11:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:11:23 INFO - {'append_to_log': False, 21:11:23 INFO - 'base_work_dir': '/builds/slave/test', 21:11:23 INFO - 'blob_upload_branch': 'Mozilla-Beta', 21:11:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:11:23 INFO - 'branch': 'Mozilla-Beta', 21:11:23 INFO - 'buildbot_json_path': 'buildprops.json', 21:11:23 INFO - 'config_files': ('talos/mac_config.py',), 21:11:23 INFO - 'default_actions': ('clobber', 21:11:23 INFO - 'read-buildbot-config', 21:11:23 INFO - 'download-and-extract', 21:11:23 INFO - 'populate-webroot', 21:11:23 INFO - 'create-virtualenv', 21:11:23 INFO - 'install', 21:11:23 INFO - 'run-tests'), 21:11:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:11:23 INFO - 'download_minidump_stackwalk': True, 21:11:23 INFO - 'download_symbols': 'ondemand', 21:11:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:11:23 INFO - 'tooltool.py': '/tools/tooltool.py', 21:11:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:11:23 INFO - '/tools/misc-python/virtualenv.py')}, 21:11:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:11:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:11:23 INFO - 'installer_path': 'installer.exe', 21:11:23 INFO - 'log_level': 'info', 21:11:23 INFO - 'log_name': 'talos', 21:11:23 INFO - 'log_to_console': True, 21:11:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:11:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:11:23 INFO - 'opt_config_files': (), 21:11:23 INFO - 'pip_index': False, 21:11:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:11:23 INFO - 'cmd': ('bash', 21:11:23 INFO - '-c', 21:11:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:11:23 INFO - 'enabled': True, 21:11:23 INFO - 'halt_on_failure': False, 21:11:23 INFO - 'name': 'check_screen_resolution'},), 21:11:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:11:23 INFO - 'cmd': ('bash', 21:11:23 INFO - '-c', 21:11:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:11:23 INFO - 'enabled': True, 21:11:23 INFO - 'halt_on_failure': False, 21:11:23 INFO - 'name': 'check_screen_resolution'},), 21:11:23 INFO - 'run_cmd_checks_enabled': True, 21:11:23 INFO - 'sps_profile': False, 21:11:23 INFO - 'sps_profile_interval': 0, 21:11:23 INFO - 'suite': 'dromaeojs-e10s', 21:11:23 INFO - 'system_bits': '32', 21:11:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:11:23 INFO - 'title': 't-yosemite-r7-0381', 21:11:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:11:23 INFO - 'use_talos_json': True, 21:11:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:11:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:11:23 INFO - 'work_dir': 'build'} 21:11:23 INFO - [mozharness: 2017-04-04 04:11:23.502733Z] Running clobber step. 21:11:23 INFO - Running pre-action listener: _resource_record_pre_action 21:11:23 INFO - Running main action method: clobber 21:11:23 INFO - rmtree: /builds/slave/test/build 21:11:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:11:25 INFO - Running post-action listener: _resource_record_post_action 21:11:25 INFO - [mozharness: 2017-04-04 04:11:25.892368Z] Finished clobber step (success) 21:11:25 INFO - [mozharness: 2017-04-04 04:11:25.892497Z] Running read-buildbot-config step. 21:11:25 INFO - Running pre-action listener: _resource_record_pre_action 21:11:25 INFO - Running main action method: read_buildbot_config 21:11:25 INFO - Using buildbot properties: 21:11:25 INFO - { 21:11:25 INFO - "project": "", 21:11:25 INFO - "product": "firefox", 21:11:25 INFO - "script_repo_revision": "production", 21:11:25 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 21:11:25 INFO - "repository": "", 21:11:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 21:11:25 INFO - "buildid": "20170403194227", 21:11:25 INFO - "pgo_build": "False", 21:11:25 INFO - "basedir": "/builds/slave/test", 21:11:25 INFO - "buildnumber": 11, 21:11:25 INFO - "slavename": "t-yosemite-r7-0381", 21:11:25 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 21:11:25 INFO - "platform": "yosemite_r7", 21:11:25 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 21:11:25 INFO - "branch": "mozilla-beta", 21:11:25 INFO - "repo_path": "releases/mozilla-beta", 21:11:25 INFO - "revision": "47919d9e58bff32e107bd06d2fd42d759dcae7f1", 21:11:25 INFO - "stage_platform": "macosx64", 21:11:25 INFO - "builduid": "054368ba47b24d358a0639f5f3914aba", 21:11:25 INFO - "slavebuilddir": "test" 21:11:25 INFO - } 21:11:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 21:11:25 INFO - Running post-action listener: _resource_record_post_action 21:11:25 INFO - [mozharness: 2017-04-04 04:11:25.895755Z] Finished read-buildbot-config step (success) 21:11:25 INFO - [mozharness: 2017-04-04 04:11:25.895873Z] Running download-and-extract step. 21:11:25 INFO - Running pre-action listener: _resource_record_pre_action 21:11:25 INFO - Running main action method: download_and_extract 21:11:25 INFO - mkdir: /builds/slave/test/build/tests 21:11:25 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:11:25 INFO - trying https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 21:11:25 INFO - Downloading https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 21:11:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 21:11:27 INFO - Downloaded 1165 bytes. 21:11:27 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 21:11:27 INFO - Using the following test package requirements: 21:11:27 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 21:11:27 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 21:11:27 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 21:11:27 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 21:11:27 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 21:11:27 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 21:11:27 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 21:11:27 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 21:11:27 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 21:11:27 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 21:11:27 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 21:11:27 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 21:11:27 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 21:11:27 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 21:11:27 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 21:11:27 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 21:11:27 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 21:11:27 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 21:11:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 21:11:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 21:11:27 INFO - Fetch https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 21:11:28 INFO - Expected file size: 16116182 21:11:28 INFO - Obtained file size: 16116182 21:11:34 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 21:11:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 21:11:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 21:11:34 INFO - Fetch https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 21:11:35 INFO - Expected file size: 16116182 21:11:35 INFO - Obtained file size: 16116182 21:11:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 21:11:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 21:11:41 INFO - Fetch https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 21:11:42 INFO - Expected file size: 14403663 21:11:42 INFO - Obtained file size: 14403663 21:11: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:11:42 INFO - trying https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.dmg 21:11:42 INFO - Downloading https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 21:11:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 21:11:45 INFO - Downloaded 58741713 bytes. 21:11:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.dmg 21:11:45 INFO - mkdir: /builds/slave/test/properties 21:11:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:11:45 INFO - Writing to file /builds/slave/test/properties/build_url 21:11:45 INFO - Contents: 21:11:45 INFO - build_url:https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.dmg 21:11:45 INFO - Running post-action listener: _resource_record_post_action 21:11:45 INFO - Running post-action listener: set_extra_try_arguments 21:11:45 INFO - [mozharness: 2017-04-04 04:11:45.703334Z] Finished download-and-extract step (success) 21:11:45 INFO - [mozharness: 2017-04-04 04:11:45.703475Z] Running populate-webroot step. 21:11:45 INFO - Running pre-action listener: _resource_record_pre_action 21:11:45 INFO - Running main action method: populate_webroot 21:11:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:11:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:11:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:11:45 INFO - '--tppagecycles', 21:11:45 INFO - '7'], 21:11:45 INFO - 'tests': ['tsvgm']}}, 21:11:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:11:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:11:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:11:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:11:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:11:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:11:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:11:45 INFO - 'talos_options': ['--disable-e10s'], 21:11:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:11:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:11:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:11:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:11:45 INFO - 'talos_options': ['--disable-e10s'], 21:11:45 INFO - 'tests': ['damp', 'tps']}, 21:11:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:11:45 INFO - 'tests': ['damp', 'tps']}, 21:11:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:11:45 INFO - 'tests': ['dromaeo_dom']}, 21:11:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:11:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:11:45 INFO - 'tests': ['basic_compositor_video']}, 21:11:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:11:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:11:45 INFO - 'tests': ['a11yr', 21:11:45 INFO - 'ts_paint', 21:11:45 INFO - 'tpaint', 21:11:45 INFO - 'sessionrestore', 21:11:45 INFO - 'sessionrestore_no_auto_restore', 21:11:45 INFO - 'tabpaint']}, 21:11:45 INFO - 'other-e10s': {'tests': ['a11yr', 21:11:45 INFO - 'ts_paint', 21:11:45 INFO - 'tpaint', 21:11:45 INFO - 'sessionrestore', 21:11:45 INFO - 'sessionrestore_no_auto_restore', 21:11:45 INFO - 'tabpaint']}, 21:11:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:11:45 INFO - 'ts_paint', 21:11:45 INFO - 'tpaint', 21:11:45 INFO - 'sessionrestore', 21:11:45 INFO - 'sessionrestore_no_auto_restore', 21:11:45 INFO - 'tabpaint']}, 21:11:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:11:45 INFO - 'ts_paint', 21:11:45 INFO - 'tpaint', 21:11:45 INFO - 'sessionrestore', 21:11:45 INFO - 'sessionrestore_no_auto_restore', 21:11:45 INFO - 'tabpaint']}, 21:11:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:11:45 INFO - 'tests': ['a11yr', 21:11:45 INFO - 'ts_paint', 21:11:45 INFO - 'tpaint', 21:11:45 INFO - 'sessionrestore', 21:11:45 INFO - 'sessionrestore_no_auto_restore', 21:11:45 INFO - 'tabpaint']}, 21:11:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:11:45 INFO - 'tests': ['a11yr', 21:11:45 INFO - 'ts_paint', 21:11:45 INFO - 'tpaint', 21:11:45 INFO - 'sessionrestore', 21:11:45 INFO - 'sessionrestore_no_auto_restore', 21:11:45 INFO - 'tabpaint']}, 21:11:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:11:45 INFO - 'tests': ['tsvgx', 21:11:45 INFO - 'tsvgr_opacity', 21:11:45 INFO - 'tart', 21:11:45 INFO - 'tscrollx', 21:11:45 INFO - 'cart', 21:11:45 INFO - 'tsvg_static']}, 21:11:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:11:45 INFO - 'tsvgr_opacity', 21:11:45 INFO - 'tart', 21:11:45 INFO - 'tscrollx', 21:11:45 INFO - 'cart', 21:11:45 INFO - 'tsvg_static']}, 21:11:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:11:45 INFO - 'talos_options': ['--disable-e10s'], 21:11:45 INFO - 'tests': ['tp5o']}, 21:11:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:11:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:11:45 INFO - 'talos_options': ['--disable-e10s', 21:11:45 INFO - '--xperf_path', 21:11:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:11:45 INFO - 'tests': ['tp5n']}, 21:11:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:11:45 INFO - 'talos_options': ['--xperf_path', 21:11:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:11:45 INFO - 'tests': ['tp5n']}}, 21:11:45 INFO - 'talos.zip': {'path': '', 21:11:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:11:45 INFO - Running post-action listener: _resource_record_post_action 21:11:45 INFO - [mozharness: 2017-04-04 04:11:45.721374Z] Finished populate-webroot step (success) 21:11:45 INFO - [mozharness: 2017-04-04 04:11:45.721494Z] Running create-virtualenv step. 21:11:45 INFO - Running pre-action listener: _resource_record_pre_action 21:11:45 INFO - Running main action method: create_virtualenv 21:11:45 INFO - Creating virtualenv /builds/slave/test/build/venv 21:11:45 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:11:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:11:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:11:45 INFO - Using real prefix '/tools/python27' 21:11:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:11:46 INFO - Installing distribute.............................................................................................................................................................................................done. 21:11:49 INFO - Installing pip.................done. 21:11:49 INFO - Return code: 0 21:11:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:11:49 INFO - Reading from file tmpfile_stdout 21:11:49 INFO - Output received: 21:11:49 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:11:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:11:49 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 0x10e456b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e459768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1bd90e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e234d30>, '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 0x10e15bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e229be8>, '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.XGQRih8fqD/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.JoFmfO8YwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:11:49 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:11:49 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:11:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGQRih8fqD/Render', 21:11:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:11:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:11:49 INFO - 'HOME': '/Users/cltbld', 21:11:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:11:49 INFO - 'LOGNAME': 'cltbld', 21:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:49 INFO - 'MOZ_NO_REMOTE': '1', 21:11:49 INFO - 'NO_EM_RESTART': '1', 21:11:49 INFO - 'PAGER': '/bin/cat', 21:11:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:49 INFO - 'PWD': '/builds/slave/test', 21:11:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:11:49 INFO - 'SHELL': '/bin/bash', 21:11:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JoFmfO8YwA/Listeners', 21:11:49 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:11:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:11:49 INFO - 'USER': 'cltbld', 21:11:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:11:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:49 INFO - 'XPC_FLAGS': '0x0', 21:11:49 INFO - 'XPC_SERVICE_NAME': '0', 21:11:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:49 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:11:49 INFO - Cleaning up... 21:11:49 INFO - Return code: 0 21:11:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:11:49 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 0x10e456b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e459768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1bd90e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e234d30>, '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 0x10e15bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e229be8>, '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.XGQRih8fqD/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.JoFmfO8YwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:11:49 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:11:49 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:11:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGQRih8fqD/Render', 21:11:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:11:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:11:49 INFO - 'HOME': '/Users/cltbld', 21:11:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:11:49 INFO - 'LOGNAME': 'cltbld', 21:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:49 INFO - 'MOZ_NO_REMOTE': '1', 21:11:49 INFO - 'NO_EM_RESTART': '1', 21:11:49 INFO - 'PAGER': '/bin/cat', 21:11:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:49 INFO - 'PWD': '/builds/slave/test', 21:11:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:11:49 INFO - 'SHELL': '/bin/bash', 21:11:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JoFmfO8YwA/Listeners', 21:11:49 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:11:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:11:49 INFO - 'USER': 'cltbld', 21:11:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:11:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:49 INFO - 'XPC_FLAGS': '0x0', 21:11:49 INFO - 'XPC_SERVICE_NAME': '0', 21:11:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:50 INFO - Downloading/unpacking psutil>=3.1.1 21:11:50 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:11:50 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:11:50 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:11:50 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:11:50 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:11:50 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:11:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:11:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:11:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:11:53 INFO - Installing collected packages: psutil 21:11:53 INFO - Running setup.py install for psutil 21:11:53 INFO - building 'psutil._psutil_osx' extension 21:11:53 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:11:54 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:11:54 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:11:54 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:11:54 INFO - building 'psutil._psutil_posix' extension 21:11:54 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:11:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:11:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:11:54 INFO - ^ 21:11:54 INFO - 1 warning generated. 21:11:54 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:11:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:11:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:11:54 INFO - Successfully installed psutil 21:11:54 INFO - Cleaning up... 21:11:54 INFO - Return code: 0 21:11:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:11:54 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:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:11:54 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:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:11:54 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 0x10e456b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e459768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1bd90e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e234d30>, '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 0x10e15bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e229be8>, '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.XGQRih8fqD/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.JoFmfO8YwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:11:54 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:11:54 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:11:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGQRih8fqD/Render', 21:11:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:11:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:11:55 INFO - 'HOME': '/Users/cltbld', 21:11:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:11:55 INFO - 'LOGNAME': 'cltbld', 21:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:55 INFO - 'MOZ_NO_REMOTE': '1', 21:11:55 INFO - 'NO_EM_RESTART': '1', 21:11:55 INFO - 'PAGER': '/bin/cat', 21:11:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:55 INFO - 'PWD': '/builds/slave/test', 21:11:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:11:55 INFO - 'SHELL': '/bin/bash', 21:11:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JoFmfO8YwA/Listeners', 21:11:55 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:11:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:11:55 INFO - 'USER': 'cltbld', 21:11:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:11:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:55 INFO - 'XPC_FLAGS': '0x0', 21:11:55 INFO - 'XPC_SERVICE_NAME': '0', 21:11:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:11:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:11:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:11:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:11:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:11:57 INFO - Installing collected packages: mozsystemmonitor 21:11:57 INFO - Running setup.py install for mozsystemmonitor 21:11:58 INFO - Successfully installed mozsystemmonitor 21:11:58 INFO - Cleaning up... 21:11:58 INFO - Return code: 0 21:11:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:11:58 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 0x10e456b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e459768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1bd90e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e234d30>, '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 0x10e15bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e229be8>, '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.XGQRih8fqD/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.JoFmfO8YwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:11:58 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:11:58 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:11:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGQRih8fqD/Render', 21:11:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:11:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:11:58 INFO - 'HOME': '/Users/cltbld', 21:11:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:11:58 INFO - 'LOGNAME': 'cltbld', 21:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:58 INFO - 'MOZ_NO_REMOTE': '1', 21:11:58 INFO - 'NO_EM_RESTART': '1', 21:11:58 INFO - 'PAGER': '/bin/cat', 21:11:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:58 INFO - 'PWD': '/builds/slave/test', 21:11:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:11:58 INFO - 'SHELL': '/bin/bash', 21:11:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JoFmfO8YwA/Listeners', 21:11:58 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:11:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:11:58 INFO - 'USER': 'cltbld', 21:11:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:11:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:58 INFO - 'XPC_FLAGS': '0x0', 21:11:58 INFO - 'XPC_SERVICE_NAME': '0', 21:11:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:11:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:58 INFO - Downloading/unpacking jsonschema==2.5.1 21:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:11:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:11:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:11:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:11:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:12:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:12:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:12:01 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:12:01 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:12:01 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:12:01 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:12:01 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:12:01 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:12:01 INFO - Downloading functools32-3.2.3-2.tar.gz 21:12:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:12:01 INFO - warning: no files found matching '*.txt' 21:12:01 INFO - no previously-included directories found matching 'build' 21:12:01 INFO - no previously-included directories found matching 'dist' 21:12:01 INFO - no previously-included directories found matching '.git*' 21:12:01 INFO - Installing collected packages: jsonschema, functools32 21:12:01 INFO - Running setup.py install for functools32 21:12:01 INFO - warning: no files found matching '*.txt' 21:12:01 INFO - no previously-included directories found matching 'build' 21:12:01 INFO - no previously-included directories found matching 'dist' 21:12:01 INFO - no previously-included directories found matching '.git*' 21:12:01 INFO - Successfully installed jsonschema functools32 21:12:01 INFO - Cleaning up... 21:12:01 INFO - Return code: 0 21:12:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:12:01 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:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:01 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:12:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:01 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 0x10e456b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e459768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1bd90e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e234d30>, '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 0x10e15bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e229be8>, '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.XGQRih8fqD/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.JoFmfO8YwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:12:01 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:12:01 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:12:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGQRih8fqD/Render', 21:12:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:12:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:12:01 INFO - 'HOME': '/Users/cltbld', 21:12:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:12:01 INFO - 'LOGNAME': 'cltbld', 21:12:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:01 INFO - 'MOZ_NO_REMOTE': '1', 21:12:01 INFO - 'NO_EM_RESTART': '1', 21:12:01 INFO - 'PAGER': '/bin/cat', 21:12:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:12:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:01 INFO - 'PWD': '/builds/slave/test', 21:12:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:12:01 INFO - 'SHELL': '/bin/bash', 21:12:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JoFmfO8YwA/Listeners', 21:12:01 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:12:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:12:01 INFO - 'USER': 'cltbld', 21:12:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:12:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:12:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:01 INFO - 'XPC_FLAGS': '0x0', 21:12:01 INFO - 'XPC_SERVICE_NAME': '0', 21:12:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:12:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:12:02 INFO - Cleaning up... 21:12:02 INFO - Return code: 0 21:12:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:12:02 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:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:02 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:12:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:02 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 0x10e456b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e459768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1bd90e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e234d30>, '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 0x10e15bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e229be8>, '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.XGQRih8fqD/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.JoFmfO8YwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:12:02 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:12:02 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:12:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGQRih8fqD/Render', 21:12:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:12:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:12:02 INFO - 'HOME': '/Users/cltbld', 21:12:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:12:02 INFO - 'LOGNAME': 'cltbld', 21:12:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:02 INFO - 'MOZ_NO_REMOTE': '1', 21:12:02 INFO - 'NO_EM_RESTART': '1', 21:12:02 INFO - 'PAGER': '/bin/cat', 21:12:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:12:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:02 INFO - 'PWD': '/builds/slave/test', 21:12:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:12:02 INFO - 'SHELL': '/bin/bash', 21:12:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JoFmfO8YwA/Listeners', 21:12:02 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:12:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:12:02 INFO - 'USER': 'cltbld', 21:12:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:12:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:12:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:02 INFO - 'XPC_FLAGS': '0x0', 21:12:02 INFO - 'XPC_SERVICE_NAME': '0', 21:12:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:12:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:02 INFO - Downloading/unpacking blobuploader==1.2.4 21:12:02 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:12:02 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:12:02 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:12:02 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:12:02 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:12:02 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:12:05 INFO - Downloading blobuploader-1.2.4.tar.gz 21:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:12:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:12:05 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:12:05 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:12:05 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:12:05 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:12:05 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:12:05 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:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:12:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:12:05 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:12:05 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:12:05 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:12:05 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:12:05 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:12:05 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:12:06 INFO - Downloading docopt-0.6.1.tar.gz 21:12:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:12:06 INFO - Installing collected packages: blobuploader, requests, docopt 21:12:06 INFO - Running setup.py install for blobuploader 21:12:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:12:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:12:06 INFO - Running setup.py install for requests 21:12:07 INFO - Running setup.py install for docopt 21:12:07 INFO - Successfully installed blobuploader requests docopt 21:12:07 INFO - Cleaning up... 21:12:07 INFO - Return code: 0 21:12:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:07 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 0x10e456b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e459768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1bd90e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e234d30>, '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 0x10e15bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e229be8>, '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.XGQRih8fqD/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.JoFmfO8YwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:12:07 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:12:07 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:12:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGQRih8fqD/Render', 21:12:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:12:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:12:07 INFO - 'HOME': '/Users/cltbld', 21:12:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:12:07 INFO - 'LOGNAME': 'cltbld', 21:12:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:07 INFO - 'MOZ_NO_REMOTE': '1', 21:12:07 INFO - 'NO_EM_RESTART': '1', 21:12:07 INFO - 'PAGER': '/bin/cat', 21:12:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:12:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:07 INFO - 'PWD': '/builds/slave/test', 21:12:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:12:07 INFO - 'SHELL': '/bin/bash', 21:12:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JoFmfO8YwA/Listeners', 21:12:07 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:12:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:12:07 INFO - 'USER': 'cltbld', 21:12:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:12:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:12:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:07 INFO - 'XPC_FLAGS': '0x0', 21:12:07 INFO - 'XPC_SERVICE_NAME': '0', 21:12:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:12:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:12:07 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-rWRMEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:12:07 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ZyQV31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:12:07 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-sFPLZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:12:07 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-gW2Bzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:12:07 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-eZfZJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:12:08 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-wyrJTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:12:08 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-KV09sD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:12:08 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-mFyhJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:12:08 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-t8RnVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:12:08 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-gvIfGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:12:08 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-TYLfwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:12:08 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Ww4R4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:12:08 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-1YXQ56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:12:08 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-pm6Vrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:12:08 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-zvmcIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:12:09 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ZMgJiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:12:09 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ySTtaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:12:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:12:09 INFO - Running setup.py install for manifestparser 21:12:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:12:09 INFO - Running setup.py install for mozcrash 21:12:09 INFO - Running setup.py install for mozdebug 21:12:09 INFO - Running setup.py install for mozdevice 21:12:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:12:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:12:09 INFO - Running setup.py install for mozfile 21:12:10 INFO - Running setup.py install for mozhttpd 21:12:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:12:10 INFO - Running setup.py install for mozinfo 21:12:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:12:10 INFO - Running setup.py install for mozInstall 21:12:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:12:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:12:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:12:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:12:10 INFO - Running setup.py install for mozleak 21:12:10 INFO - Running setup.py install for mozlog 21:12:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:12:10 INFO - Running setup.py install for moznetwork 21:12:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:12:10 INFO - Running setup.py install for mozprocess 21:12:11 INFO - Running setup.py install for mozprofile 21:12:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:12:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:12:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:12:11 INFO - Running setup.py install for mozrunner 21:12:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:12:11 INFO - Running setup.py install for mozscreenshot 21:12:11 INFO - Running setup.py install for moztest 21:12:11 INFO - Running setup.py install for mozversion 21:12:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:12:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:12:11 INFO - Cleaning up... 21:12:11 INFO - Return code: 0 21:12:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:12:11 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:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:11 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:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:11 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 0x10e456b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e459768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1bd90e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e234d30>, '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 0x10e15bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e229be8>, '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.XGQRih8fqD/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.JoFmfO8YwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:12:11 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:12:11 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:12:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGQRih8fqD/Render', 21:12:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:12:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:12:11 INFO - 'HOME': '/Users/cltbld', 21:12:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:12:11 INFO - 'LOGNAME': 'cltbld', 21:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:11 INFO - 'MOZ_NO_REMOTE': '1', 21:12:11 INFO - 'NO_EM_RESTART': '1', 21:12:11 INFO - 'PAGER': '/bin/cat', 21:12:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:11 INFO - 'PWD': '/builds/slave/test', 21:12:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:12:11 INFO - 'SHELL': '/bin/bash', 21:12:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JoFmfO8YwA/Listeners', 21:12:11 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:12:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:12:11 INFO - 'USER': 'cltbld', 21:12:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:12:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:11 INFO - 'XPC_FLAGS': '0x0', 21:12:11 INFO - 'XPC_SERVICE_NAME': '0', 21:12:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:12:12 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-gJDfOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:12:12 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:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:12:12 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-NgkWhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:12:12 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:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:12:12 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-wNVTCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:12:12 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:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:12:12 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-bQceYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:12:12 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-xInC0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:12:12 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:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:12:12 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-0tTu5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:12:12 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:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:12:12 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-U_l4cX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:12:12 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:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:12:12 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-yrIySB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:12:12 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:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:12:12 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-_iFllo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:12:13 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:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:12:13 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-oGz5KY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:12:13 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:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:12:13 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Ct9fvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:12:13 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:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:12:13 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-EDtFcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:12:13 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-3dk038-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:12:13 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:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:12:13 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-CaXqBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:12:13 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:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:12:13 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Ngp78F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:12:13 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:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:12:13 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Vm3xyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:12:13 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:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:12:13 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-r4QYwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:12:13 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:12:13 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:12:13 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:12:13 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:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:12:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:12:13 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:12:13 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:12:13 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:12:13 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:12:13 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:12:13 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:12:16 INFO - Downloading blessings-1.6.tar.gz 21:12:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:12:16 INFO - Installing collected packages: blessings 21:12:16 INFO - Running setup.py install for blessings 21:12:16 INFO - Successfully installed blessings 21:12:16 INFO - Cleaning up... 21:12:16 INFO - Return code: 0 21:12:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:12:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:12:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:12:17 INFO - Reading from file tmpfile_stdout 21:12:17 INFO - Current package versions: 21:12:17 INFO - blessings == 1.6 21:12:17 INFO - blobuploader == 1.2.4 21:12:17 INFO - docopt == 0.6.1 21:12:17 INFO - functools32 == 3.2.3-2 21:12:17 INFO - jsonschema == 2.5.1 21:12:17 INFO - manifestparser == 1.1 21:12:17 INFO - mozInstall == 1.12 21:12:17 INFO - mozcrash == 1.0 21:12:17 INFO - mozdebug == 0.1 21:12:17 INFO - mozdevice == 0.49 21:12:17 INFO - mozfile == 1.2 21:12:17 INFO - mozhttpd == 0.7 21:12:17 INFO - mozinfo == 0.9 21:12:17 INFO - mozleak == 0.1 21:12:17 INFO - mozlog == 3.4 21:12:17 INFO - moznetwork == 0.27 21:12:17 INFO - mozprocess == 0.24 21:12:17 INFO - mozprofile == 0.28 21:12:17 INFO - mozrunner == 6.13 21:12:17 INFO - mozscreenshot == 0.1 21:12:17 INFO - mozsystemmonitor == 0.3 21:12:17 INFO - moztest == 0.8 21:12:17 INFO - mozversion == 1.4 21:12:17 INFO - psutil == 3.1.1 21:12:17 INFO - requests == 1.2.3 21:12:17 INFO - wsgiref == 0.1.2 21:12:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:12: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:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12: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:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:18 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 0x10e456b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e459768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1bd90e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e234d30>, '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 0x10e15bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e229be8>, '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.XGQRih8fqD/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.JoFmfO8YwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:12:18 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:12:18 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:12:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGQRih8fqD/Render', 21:12:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:12:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:12:18 INFO - 'HOME': '/Users/cltbld', 21:12:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:12:18 INFO - 'LOGNAME': 'cltbld', 21:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:18 INFO - 'MOZ_NO_REMOTE': '1', 21:12:18 INFO - 'NO_EM_RESTART': '1', 21:12:18 INFO - 'PAGER': '/bin/cat', 21:12:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:18 INFO - 'PWD': '/builds/slave/test', 21:12:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:12:18 INFO - 'SHELL': '/bin/bash', 21:12:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JoFmfO8YwA/Listeners', 21:12:18 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:12:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:12:18 INFO - 'USER': 'cltbld', 21:12:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:12:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:18 INFO - 'XPC_FLAGS': '0x0', 21:12:18 INFO - 'XPC_SERVICE_NAME': '0', 21:12:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:12:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:18 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:12:18 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:12:18 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:12:18 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:12:18 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:12:18 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:12:18 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:12:18 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:12:18 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:12:18 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:12:18 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:12:18 INFO - Cleaning up... 21:12:18 INFO - Return code: 0 21:12:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:12:18 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:12:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:18 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:12:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:18 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 0x10e456b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e459768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1bd90e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e234d30>, '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 0x10e15bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e229be8>, '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.XGQRih8fqD/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.JoFmfO8YwA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:12:18 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:12:18 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:12:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGQRih8fqD/Render', 21:12:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:12:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:12:18 INFO - 'HOME': '/Users/cltbld', 21:12:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:12:18 INFO - 'LOGNAME': 'cltbld', 21:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:18 INFO - 'MOZ_NO_REMOTE': '1', 21:12:18 INFO - 'NO_EM_RESTART': '1', 21:12:18 INFO - 'PAGER': '/bin/cat', 21:12:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:18 INFO - 'PWD': '/builds/slave/test', 21:12:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:12:18 INFO - 'SHELL': '/bin/bash', 21:12:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JoFmfO8YwA/Listeners', 21:12:18 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:12:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:12:18 INFO - 'USER': 'cltbld', 21:12:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:12:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:18 INFO - 'XPC_FLAGS': '0x0', 21:12:18 INFO - 'XPC_SERVICE_NAME': '0', 21:12:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:12:19 INFO - Cleaning up... 21:12:19 INFO - Return code: 0 21:12:19 INFO - Running post-action listener: _resource_record_post_action 21:12:19 INFO - Running post-action listener: _start_resource_monitoring 21:12:19 INFO - Starting resource monitoring. 21:12:19 INFO - [mozharness: 2017-04-04 04:12:19.123606Z] Finished create-virtualenv step (success) 21:12:19 INFO - [mozharness: 2017-04-04 04:12:19.124370Z] Running install step. 21:12:19 INFO - Running pre-action listener: _resource_record_pre_action 21:12:19 INFO - Running main action method: install 21:12:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:12:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:12:19 INFO - Reading from file tmpfile_stdout 21:12:19 INFO - Detecting whether we're running mozinstall >=1.0... 21:12:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:12:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:12:19 INFO - Reading from file tmpfile_stdout 21:12:19 INFO - Output received: 21:12:19 INFO - Usage: mozinstall [options] installer 21:12:19 INFO - Options: 21:12:19 INFO - -h, --help show this help message and exit 21:12:19 INFO - -d DEST, --destination=DEST 21:12:19 INFO - Directory to install application into. [default: 21:12:19 INFO - "/builds/slave/test"] 21:12:19 INFO - --app=APP Application being installed. [default: firefox] 21:12:19 INFO - mkdir: /builds/slave/test/build/application 21:12:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:12:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 21:12:34 INFO - Reading from file tmpfile_stdout 21:12:34 INFO - Output received: 21:12:34 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 21:12:34 INFO - Running post-action listener: _resource_record_post_action 21:12:34 INFO - [mozharness: 2017-04-04 04:12:34.024123Z] Finished install step (success) 21:12:34 INFO - [mozharness: 2017-04-04 04:12:34.024257Z] Running run-tests step. 21:12:34 INFO - Running pre-action listener: _resource_record_pre_action 21:12:34 INFO - Running main action method: run_tests 21:12:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:12:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:12:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:12:34 INFO - 2017-04-03 21:12:34.038 screenresolution[1632:7260] starting screenresolution argv=screenresolution get 21:12:34 INFO - 2017-04-03 21:12:34.041 screenresolution[1632:7260] Display 0: 1600x1200x32@60 21:12:34 INFO - 2017-04-03 21:12:34.047 screenresolution[1633:7262] starting screenresolution argv=screenresolution list 21:12:34 INFO - Available Modes on Display 0 21:12:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:12:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:12:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:12:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:12:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:12:34 INFO - Intel Iris: 21:12:34 INFO - Chipset Model: Intel Iris 21:12:34 INFO - Type: GPU 21:12:34 INFO - Bus: Built-In 21:12:34 INFO - VRAM (Dynamic, Max): 1536 MB 21:12:34 INFO - Vendor: Intel (0x8086) 21:12:34 INFO - Device ID: 0x0a2e 21:12:34 INFO - Revision ID: 0x0009 21:12:34 INFO - Displays: 21:12:34 INFO - Display: 21:12:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:12:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:12:34 INFO - Display Serial Number: 1600x1200 60 21:12:34 INFO - Main Display: Yes 21:12:34 INFO - Mirror: Off 21:12:34 INFO - Online: Yes 21:12:34 INFO - Rotation: Supported 21:12:34 INFO - Return code: 0 21:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:12:34 INFO - Python 2.7.3 21:12:34 INFO - Return code: 0 21:12:34 INFO - grabbing minidump binary from tooltool 21:12:34 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:12:34 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 0x10e234d30>, '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 0x10e15bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e229be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:12:34 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:12:34 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:12:34 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:12:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:12:34 INFO - Return code: 0 21:12:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:12:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:12:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:12:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:12:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0381', '--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:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0381 --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:12:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGQRih8fqD/Render', 21:12:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:12:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:12:34 INFO - 'HOME': '/Users/cltbld', 21:12:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:12:34 INFO - 'LOGNAME': 'cltbld', 21:12:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:12:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:34 INFO - 'MOZ_NO_REMOTE': '1', 21:12:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:12:34 INFO - 'NO_EM_RESTART': '1', 21:12:34 INFO - 'PAGER': '/bin/cat', 21:12:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:34 INFO - 'PWD': '/builds/slave/test', 21:12:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:12:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:12:34 INFO - 'SHELL': '/bin/bash', 21:12:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JoFmfO8YwA/Listeners', 21:12:34 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:12:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:12:34 INFO - 'USER': 'cltbld', 21:12:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:12:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:34 INFO - 'XPC_FLAGS': '0x0', 21:12:34 INFO - 'XPC_SERVICE_NAME': '0', 21:12:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:12:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0381', '--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:12:34 INFO - mozversion application_buildid: 20170403194227 21:12:34 INFO - mozversion application_changeset: 47919d9e58bff32e107bd06d2fd42d759dcae7f1 21:12:34 INFO - mozversion application_display_name: Firefox 21:12:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:12:34 INFO - mozversion application_name: Firefox 21:12:34 INFO - mozversion application_remotingname: firefox 21:12:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 21:12:34 INFO - mozversion application_vendor: Mozilla 21:12:34 INFO - mozversion application_version: 53.0 21:12:34 INFO - mozversion platform_buildid: 20170403194227 21:12:34 INFO - mozversion platform_changeset: 47919d9e58bff32e107bd06d2fd42d759dcae7f1 21:12:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 21:12:34 INFO - mozversion platform_version: 53.0 21:12:34 INFO - using testdate: 1491279154 21:12:34 INFO - actual date: 1491279154 21:12:34 INFO - starting webserver on 'localhost:49253' 21:12:34 INFO - SUITE-START | Running 2 tests 21:12:34 INFO - TEST-START | dromaeo_css 21:12:34 INFO - operating with platform_type : mac_ 21:12:34 INFO - Initialising browser for dromaeo_css test... 21:12:34 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmpZLuIWc/profile http://localhost:49253/getInfo.html) 21:12:37 INFO - PROCESS | 1643 | 2017-04-03 21:12:37.439 plugin-container[1646:7426] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4b, name = 'com.apple.tsm.portname' 21:12:37 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:12:37 INFO - PROCESS | 1643 | 2017-04-03 21:12:37.440 plugin-container[1646:7426] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 21:12:37 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:12:37 INFO - PROCESS | 1643 | 2017-04-03 21:12:37.440 plugin-container[1646:7426] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:12:37 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 21:12:37 INFO - PROCESS | 1643 | colorDepth:24 21:12:37 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 21:12:37 INFO - PROCESS | 1643 | __metrics 21:12:37 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 21:12:38 INFO - TEST-INFO | 1643: exit 0 21:12:38 INFO - Browser initialized. 21:12:38 INFO - Running cycle 1/1 for dromaeo_css test... 21:12:38 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmpZLuIWc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:12:40 INFO - PROCESS | 1647 | 2017-04-03 21:12:40.456 plugin-container[1648:7566] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x832f, name = 'com.apple.tsm.portname' 21:12:40 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:12:40 INFO - PROCESS | 1647 | 2017-04-03 21:12:40.457 plugin-container[1648:7566] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b03, name = 'com.apple.CFPasteboardClient' 21:12:40 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:12:40 INFO - PROCESS | 1647 | 2017-04-03 21:12:40.457 plugin-container[1648:7566] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:12:50 INFO - PROCESS | 1647 | RSS: Main: 173436928 21:12:50 INFO - PROCESS | 1647 | 21:15:52 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49253/tests/dromaeo/cssquery-ext.html) 21:15:54 INFO - PROCESS | 1647 | RSS: Main: 189206528 21:15:54 INFO - PROCESS | 1647 | 21:18:56 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-ext.html (next: http://localhost:49253/tests/dromaeo/cssquery-jquery.html) 21:18:57 INFO - PROCESS | 1647 | RSS: Main: 200118272 21:18:57 INFO - PROCESS | 1647 | 21:21:58 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49253/tests/dromaeo/cssquery-mootools.html) 21:21:59 INFO - PROCESS | 1647 | RSS: Main: 202690560 21:21:59 INFO - PROCESS | 1647 | 21:25:01 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49253/tests/dromaeo/cssquery-prototype.html) 21:25:02 INFO - PROCESS | 1647 | RSS: Main: 206036992 21:25:02 INFO - PROCESS | 1647 | 21:28:04 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49253/tests/dromaeo/cssquery-yui.html) 21:28:05 INFO - PROCESS | 1647 | RSS: Main: 208195584 21:28:05 INFO - PROCESS | 1647 | 21:31:08 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49253/tests/dromaeo/cssquery-yui.html (next: http://localhost:49253/tests/dromaeo/cssquery-dojo.html) 21:31:08 INFO - PROCESS | 1647 | RSS: Main: 208269312 21:31:08 INFO - PROCESS | 1647 | 21:31:08 INFO - PROCESS | 1647 | __start_tp_report 21:31:08 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 21:31:08 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 21:31:08 INFO - PROCESS | 1647 | |i|pagename|runs| 21:31:08 INFO - PROCESS | 1647 | |0;dojo.html;3094.0717784664594;3167.9841600791992;3493.4235851084572;3519.2081781599145;3525.5240542526753;4593.908121837561;5855.970720146399;5866.9999999999945;5906.734196961136;5934.525237980962;5944.70276486175;5999.220101386813;6006.219191505098;6046.002409602422;6059.454649081582;5086.720230387327;5306.000893433264;5371.5165635092835;5402.459754024605;5408.134698448249;4581.885452863672;5435.619506634537;5440.619156659035;5446.509814116729;5469.972650136764;4636.049609829994;5261.763220655061;5436.809711660093;5444.564434845203;5468.890622187554;5290.153575427932;5334.653247538898;5336.546393556552;5405.162199859028;5449.891002179974;5242.266082748418;5335.573154147659;5356.839294821161;5358.866028349284;5407.297051383295;5780.421957804228;5858.502027327682;5931.1613399845755;6047.818565443044;6061.272647282311;6146.846328841814;6167.475764559973;6187.978983467722;6213.5961162524745;6265.0915617235705;5858.916100521417;5893.675847828367;6297.685115744241;6365.968170159166;6384.3934826191435;6124.448799608057;6129.969350153221;6132.601380910227;6138.69306534676;6161.815145545641;5343.919841202385;6184.381561843779;6190.876182476325;6292.244930608356;6357.459615932605;6163.630182189083;6210.968945155336;6213.720382582795;6219.40915613016;6232.376762323822;6292.811215663537;6312.52656050798;6320.494360451153;6325.557210995186;6330.936690633127;6115.057807494094;6115.879294474695;6141.30190404341;6178.567500275028;6275.623462592259;6178.320384757673;6225.28409232944;6255;6278.560500764903;6283.6858157091965;5121.28302037715;5129.512696293846;5130.30740849988;5131.692098474104;5145.228215767665;4456.598906098467;4498.437695288089;4588.655850811202;4603.976980115078;4606.355110284563;5970.313413957364;5976.432238937294;6018.368071352533;6028.246469191351;6143.078538219213;6231.071813972874;6240.12638230639;6283.497320214372;6290.779822706183;6298.212723409574;5867.559933004956;5896.469317761422;5963.403659634002;5989.161517387569;5997.0404735242155;5584.246126772915;5587.44125587438;5598.412166722517;5621.943780562224;5664.065429204135;6448.742050317935;6451.000094985378;6469.514786390945;6478.870422591427;6496.155499784998;6144.617461071223;6178.351273116438;6188.628682279078;6196.566240363311;6279.403456671609;6412.134361861089;6413;6414.647194404166;6423.454006409507;6447.0651755494255;6259.029850373199;6260.248770147429;6266.968665156645;6270.6551139685935;6272.749090036347;6163.691815409122;6213.440790328892;6224.502039836892;6309.968450157719;6325.683715814283;6048.697565121638;6243.906341404973;6251.124842522141;6257.342978987323;6266.091416744638;6200;6247.5001999840815;6270.717817698123;6273.121762953281;6286.74853005893;4397.516273210008;5990.161377407254;6039.698015099315;6041.426064523863;6160.537959652955;5080.593552515864;5120.743962801919;6112.685862051747;6167.105769663463;6167.198264225622;6144.201253836973;6175.814725558241;6186.628802271878;6194.132821405097;6210.3168651449205 21:31:08 INFO - PROCESS | 1647 | |1;ext.html;457.67962426301597;471.8254245929007;490.64183146303196;502.9195328747401;543.18522216675;31881.84059079704;42628.57371426285;42649;42685.146297074076;42699.78650106753;36249.45625815615;45174.77412612941;45262.54737452624;45263.86840328993;45264.773676131575;9490.90509094907;9701.70894873155;9725.51372431379;11528.711782205428;11937;11846.703832404173;11906.642800715992;11962.2822630642;11963.58127465539;11977.820332695017;11970.401479926013;12007.759844803099;12014.819777703342;12022.759544809142;12026.579069732561;11851.822222666668;11869.584564540286;11890.10824188185;11958.760824783543;11966.700832479215;11983.64049078529;12033.157678962476;12036.338001409918;12040.39798010096;12056.939715301454;37603.247935041145;37651.24697506062;37711.43432848509;37938;38003.429948550795;4059.9594004059877;4065.288574499451;4065.4105154752397;4152.750834949882;4182.330827067685;27302.907883684627;27390;27564.173074807786;28075.157745267676;28139.859300703367;3328.6671332866517;3388.695017448417;3426.143374596964;3469.8438570264148;3585.748997570171;466793;466831.665841672;466923;469203;470682;50130.49869501331;60569;60782;60851;61451.385486144565;51062.48937510652;63193.36806631875;63379;63660;63785;16753.1623418831;16957.576060598338;16965.491035268962;16974;17010.404635837935;11077;11527.481263343001;12797.552085676956;13050.15174013686;13105.60683179487;17626;17646.647067058842;17705.38031168903;17732.822671773116;17899.910500447673;7966.402519811038;8006.879896801553;8024.919750802417;8033.477823941425;8049.4365394423;874.4927941793744;883.9292856571459;894.7271082319955;895.713371721043;915.4919019943995;31398.372032559677;31539.742134811506;31816.204594885312;31948.680513195035;32857.68569257251;891.4250308551078;1083.9078678312273;1090.574675876409;1106;1106.336198281041;13269.07116501855;14904.850951490347;15504.844951550565;15860.682786344209;16683.416080437128;609240;611154;614443;615101;616745;65517.344826551125;65534.67232663902;65942.67028664827;66135.34661633498;66280.00579991304;1253.3858409379338;1314.4347930389833;1322.9680848937844;1503.1882783296896;1504.3230546254229;27704.722952771022;29287.631922328354;30006;30167.944121955625;30592.84703576468;52695;53201.46798531965;53316.200256996955;53387.733061334446;53454;508145;511640;512073;513240;513651;24156.51686966286;24798.504029919663;28176.85911570511;28500.287492812844;28742.42515149727;49832;50497.99004019972;50964;52248.47751522436;53776;32888;32997.010089697345;33192.1701957453;33269.16827079342;34253.14367140841;34172.82913585416;34266.82866585651;34760.65239347574;34905.30189396249;35178.47232291569;38690;39469.40795888007;39539.40690889581;39580.80209598933;39655;28361.858190708914;34088.48867266943;34467.827660861534;34896.82551587226;35241.29517409586;22699.659505107105;23249.535009300056;26042.479150417264;26286.342841429116;26443.206703798918 21:31:08 INFO - PROCESS | 1647 | |2;jquery.html;400.89576710055377;407.26099554900594;417.55321805667927;418.32649434410604;434.1555674213654;18696.15867285972;18970.90514547427;19380.321688740896;20586.691199632016;21098.050587723552;21644.8917755411;21860.8906955465;21863.453413664665;22398.776012239872;22754.31737047887;5857;5979.880402391971;6056.182415373934;6069.362716914727;6070.6661133637635;5052.595792336622;5772.191893134965;5841.941580584183;5896.498797602209;6122;5797.420257974211;5814.7092645367775;5849.649021058729;5861;5868.589198756089;5359.9732001339935;5599.580031497654;5868.17845501628;5980.162777211194;6010.429009244115;5296.523312901857;5311.973440132813;5313.38896026958;5326.493983071641;5338.572914166857;26038.95844166231;26443.73556264432;26618.20145395641;26679.733202668114;26966.325841853915;2850.2874281429645;2856.657201135877;2856.8000239983207;2869.1249169003445;2883.855807209652;15642.374305027784;16237.188140592923;16237.188140593042;16272.83727162725;16583.419580314752;1885.9151338189815;1901.6006638605909;1908.9331873384504;1948.5907959328558;1959;119055.21417178749;121579.78420215772;122375;128690.42619147565;129297;10627.681169564925;11825.586104486301;11846.348450835114;12162.635120946386;12448.75102497945;16271.51185464438;16302.184890755414;16306.266218020217;16321.102339371448;16345.509634710977;7762.61186940662;8985.910140898639;9058.864117038249;9237.030111838294;9297.302702297355;9012.54937253148;9029.683961061332;9031.187193152648;9174.67888623895;9192.724218273463;4945.629077819178;5009.398872135294;5012.122878496249;5023.497650234947;5077.517635824591;2091.5921395327764;2129.371835308588;2134.146341463396;2140.56118495708;2147.2591955775547;1056.2975621211808;1060.1306928318704;1066.2536224642627;1091.6015654286127;1123.775244951013;17036.48890533286;21096.367108986757;21660.675089873665;21864.89067554652;22121.778782212295;1079.3685693869018;1096.1066730614523;1110.938898360598;1114.5541783286587;1131.6944425005202;10849.077828384676;11361.318320900773;11588.36264005471;11596.420178991017;11610.6516804496;631568;682771;720708;740973;750138;151181.24409377883;157084;158267;161073;161903;1076.1498416251075;1080.7406222506543;1101.3997371432588;1103.674416047268;1115.5036008975928;18677;19633;19647.901760491106;19678.803211967697;19874.50313742168;48602.99999999859;57153.71423143024;58994.70502647631;59145.11282330855;59747.103793444;88643;88923;90633;91394;91903;6101.60339577926;7207;8487.702930397407;8663.350248731243;8875.289976801969;11523.884761152281;13072.542461013818;13608.251546165056;13643.590692279247;13932.93033534826;10450.320729152581;11150.606174228222;11456.885431145914;11812;12234.632961011184;11029.283096598696;11045.226834121535;11071.83392249133;11184.94407527957;11223.88776112261;10943.233973621771;11876.584319548774;11895.643130705746;12033.398330083635;12058;7760.30157285847;8262.958685206535;8702.51899240309;8848.778780530281;8968.506732129534;10143.239257055602;11430.885691142983;11723.41382930867;12091.697707557381;12114.21257618252 21:31:08 INFO - PROCESS | 1647 | |3;mootools.html;1359.1505309181762;1483.399223314558;1503.5188739603325;1503.8420965798591;1511.1310996177194;7027.332403421679;7961.044674639052;8331.791705207374;8769.079246679103;8815.118488151189;8091.3931455140955;8452.492850428978;8455.196756308156;8616.655333786657;8616.870746938801;2384.880755962199;2398.040783686522;2411.2766170148884;2415.7463466336044;2420;2334.124703236286;2358.7759162879584;2368.159303447277;2368.988155059222;2373.074500944633;2320.3966968588206;2329.860208387502;2354.1878052072007;2354.682117914077;2359.0327965534134;2355.8468699534646;2368.976310236893;2376.251480783565;2387.7612238776155;2393.521295740859;1901.5436295289169;2406.9518609627708;2411;2433.720122185936;2440.2435245074084;7193.8201544961275;8621.05168431472;8644.178803013703;8661.610227539775;8720.43317184381;5174.09453345663;5328.520433160996;5357.946420535823;5364.544013758835;5366.195070739382;3018.8528359223355;3565.447355659877;3590.9640903591153;3597.1726502904216;3673.5040769496045;1266.936653167338;1275.489804078367;1311.2722439046327;1323.1862404621186;1323.913945593543;10893.727656808564;11011.779764404748;11038.17213708975;11084.445777711162;11099.944500277446;4205.264078786261;4552.658550608651;4603.700759450692;4787.234042553175;4817.205161148441;8828.161324674145;8853.158949899878;8916.331275154389;8925.196732294125;8927.419717718327;5211.2443695663915;5277.762500687479;5282.260483532308;5282.471752824764;5283.815066472655;4400.889977750518;4471.686981911235;4836.733979631154;4880.438749543777;4893.706377617284;3432.3650124726505;3736.93497353253;3773.3019391412677;3836.0985168485386;3925.4111883217174;1025.820481415764;1189.1854079955258;1201.1711918776016;1201.645514573203;1206.2702065250573;812.2162113560324;843.5782108945527;900.7703035725848;915.2997956563235;923.3813345058827;5661.858453538612;5669.603127781016;5801.245838041112;5815.854603634858;6045.244344456943;985.8324084905584;1023.1763430438419;1044.7179261599326;1049.2812423489934;1086.1853609792656;3298.274379636476;3752.793596352227;3975.522937247491;4050.716449848482;4058;9439.386439881524;10193.643222487177;10214.336068155546;10225.539850706737;10262.384256944608;9925.255605829447;9943.204543636492;10177.982201779763;10185.847212291674;10188.694339169837;7917.920820791834;8111.9594402027615;8134;8159.388045896581;8162.755117346489;4535.523770004234;5576.525995290446;5693.003724348306;5776.277965254343;5800.100984347433;9256.444613323221;9279.67521136784;9344.392614480037;9400.294977876823;9507.144357007903;9482.004389538999;9508.952455237957;9522.761930951781;9575.994520575518;9603.03969603034;5625.874825035091;6315.747370105328;6718.22740384862;6876.553024053421;6905.930940690529;8048.557329346942;8177;8295.875561866698;8301.211384918422;8320.625571849403;7024.121984751906;7165.928340716526;7208.35124838746;7208.35124838746;7253.238409967089;6997.265287144776;7042.154941406859;7057.823554411181;7162;7164.605946672983;6432.807015789534;6434.034894765822;6516.771912983023;6522.119513865568;6532.477401807939;4932.556069953722;5051;5051.924221136613;5068.265101560327;5114.2840002400435;5044.646874718735;5169.043726910533;5182.507661772125;5218.034663587248;5231.372235331784 21:31:08 INFO - PROCESS | 1647 | |4;prototype.html;370.2391585292225;374.6590602551678;381.95989421110784;386.2834442109884;387.99315775562405;4815.69976106451;5212.1660534314515;5620.510564700353;5652.491275785179;5681.431856814316;4601.217792975194;5219.582433405328;5444.876498165719;5453.7818487260465;5473.644213126144;4303.225419424502;4332.870013899572;4343.891402714926;4354.651627869779;4374.12517496502;3826.770393776382;4210.97894510527;4245.384419259206;4246.543191998283;4406.4271644686305;3277.3936821687944;3555.8933232003083;3714.275716235335;4053.43251944728;4259.552746961571;3231.418344697953;3753.436984452313;4105.445764821756;4266.530681625019;4304.031592891605;3800.467934489174;3814.7901865397394;3826.253880493305;3828.1578052828613;3839.3665045267776;4420.4253447052;5168.405633352175;5218.769626461701;5247.606429517801;5318.22885681574;4108.2129273766;4605.861824145281;4938.259261110827;4952.579030782388;4997.55071029401;5814.534837213013;5823.67969761663;5830.30036395631;5851.824445266649;5876.764929402819;3715.5541335039698;3952.6679259236903;3996.680265578747;3998.5001874765653;4002.3596224604216;5140.717260550706;5314.874050379879;5839.678817665027;5977.176961026885;6048.516118710492;3400.795952242873;3552.946705799415;3869.5936926622862;4021.5174179098112;4029.6977726670616;3959.7922633596454;4047.716659833842;4088.345864661699;4091.529474110456;4106.973256685828;4056.269871423172;4762.9523704762505;4771.355866957916;4786.712797232177;4794.5924596408995;5804.477597016289;5862.237909071793;5880.970595146997;5902;5908.350081491032;1155.8497395338552;1265.6962329040964;1314.6581888709004;1331.3343328335832;1340.3298350824587;869.7564681889081;896.8161526886972;904.8371293167293;911.8723378726984;914.7895983923602;5203.895922081537;5221.973890130601;5229.790808367623;5241.50205730455;5247.422783493813;5489.807856724996;5507.834764957058;5566.693831839207;5723.971380143073;5747.603083641018;4103.651189648914;5063;5125.668315594893;5212.661177023481;5281.547574417066;2622.632831403605;2622.6493355921716;2859.7845915485836;2986.163874115208;2990.6560745514107;4775.880602984953;5142.382914050263;5403.621746477709;5463.350275116565;5805.10020946754;5643.181738647873;5814.738336774855;5852.180694702745;5869;5876.589618491531;5307.894881494215;5603.35561410443;5608.046632072476;5966.373530779292;5974.2830860297045;4747.347599352584;5429.809956651657;5464.972675136599;5643.71781410936;5671.631343962629;4855.93169502723;4875.414950205998;5145.691258524501;5191.948080519146;5406.161904952178;5313.734313284398;5428.782848686008;5700.657960522382;5719.428057194414;5897.734601943009;3292.489664102068;3744.6629803317833;3890.8133019428215;3899.3760998240145;3905.906346223062;4903.656744027884;5386.165144402624;5451.100568406168;5523.6409633373705;5933.465988060922;5661.518770904355;5745.569082318759;5852.97572924745;5883.382244864399;5906.379830117948;4913.852584422473;5575.609707320448;5788.671513879452;5835.416458354301;5879.441453061952;5443.972780136232;5697.857553561194;5718.79984200551;5798.884022319446;5801.738921748447;5779.450952159538;5782.884342313214;5815;5830.008898487352;5884.293884733859;4501.819927202875;4853.757312134308;4958.752062396794;5570.2201691762375;5718.51392631631 21:31:08 INFO - PROCESS | 1647 | |5;yui.html;737.97786066418;742.3467348733114;784.1961988961314;847.5380917400017;873.1792115411511;15438.305276262567;15873.84126158738;16439.671206575862;16523.256453459595;16572.337106515755;14934.701305973875;14967.25163741814;15203.92398038011;15246.695066098699;15299.770503442458;874.2699845628907;895.9552022398854;896.66375109334;899;900.5092224737534;727.538013361514;751.3801114080906;879.1077056787366;898.5192921786845;900.621738869673;946.9526523673824;954.3558098283665;961.5192403798101;961.5769061612868;961.7211008807437;942.1708896171359;951.4101257220499;952.9857052144223;953.1136043479561;960.3229723045237;701.0115736811072;704.7075463682565;713.6895450479058;715.1561157833754;715.4920006795181;16035.957662751884;16263.024218546925;16284;16285;16800.15999200035;15033.473828415952;15115.848841511553;15118.773218401622;15186.316615752206;15314.540563783105;11818.527258909633;11947.880521194764;12071.155019148664;12084.214526055866;12202.633920982385;3824.2351529694174;3888.0668639526593;3956.8219430125714;4056;4085.162541678949;99600;101967.49016254945;115033;117479;118585.40707296494;11358.545658173745;11603.129765267639;11673.591424300279;11723.062155027577;12041.123345045271;23400.414989625056;23664.7633523666;24143.879280603483;24182.91176897044;24272.514549708907;8160.143184965612;8207.548584827773;8229;8291.129431409616;8453.323734101257;4402.207602631493;4447.844325448594;4495.865124046219;4540.160070386989;4558.27067669178;3722.571904231048;3784.6026167252594;3812.4662547243406;3813.656770890633;3827.5789663137034;691.7717153339487;692.4564217089561;692.757534862804;705.5837056136801;711.6299524247362;620.8447888027993;626.5676683088654;628.233738736279;656.5830697507022;663.5024226243526;9060.776795132573;9260.861087083698;9605.327627066039;10227.488625568692;10825.350478971286;726.8546290741767;730.5689643110591;733.4315905173534;739.7706710919632;740.3817812126935;4484.170428470678;4852.611791056707;4876.21980483121;4878.170710979142;4888.804447822118;85138;93735;101379;101622;102012;86991;87280.56359718287;89040.10959890448;90645;90690;75943.62028189935;76073.23926760661;78080;78862.6056869712;79263.81104283441;9202.171804537624;9434.764130896783;9479.715608531755;9556.713298601053;9568.330216884751;48488;50307.24539131989;50872;52348.738256309996;53002.734986324824;51271;69020.54897255218;70961.58076838538;72168.63915680388;73063;13344.733105338033;13402.262875541548;13768.655783605089;13849.446022159404;13997.230152341312;15157.469488567847;15257.313420896307;15266;15599.220038998232;16239.756403654192;14016.18860823803;14029.438822446988;14310.141391516543;14521.27393630293;14585.978981471195;14021.859781402056;14192.006559540732;14271.57185284443;14349.713005740035;14379;13803.723925521634;13938;14231.928840355731;14251.643708906946;14693.265336732908;10101.393916365041;10125;10452.68641940743;10541.894581054397;11263.549458021587;9459.668911588062;9542.570584323861;11320.305288962474;11596.652100437;11731.35477548743 21:31:08 INFO - PROCESS | 1647 | __end_tp_report 21:31:08 INFO - PROCESS | 1647 | __start_cc_report 21:31:08 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,363 21:31:08 INFO - PROCESS | 1647 | __end_cc_report 21:31:08 INFO - PROCESS | 1647 | __startTimestamp1491280268386__endTimestamp 21:31:08 INFO - PROCESS | 1647 | 21:31:08 INFO - PROCESS | 1647 | ------- Summary: start ------- 21:31:08 INFO - PROCESS | 1647 | Number of tests: 6 21:31:08 INFO - PROCESS | 1647 | 21:31:08 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5824.97 Median:6124.45 stddev:659.20 (10.8%) stddev-sans-first:625.44 21:31:08 INFO - PROCESS | 1647 | Values: 3094.1 3168.0 3493.4 3519.2 3525.5 4593.9 5856.0 5867.0 5906.7 5934.5 5944.7 5999.2 6006.2 6046.0 6059.5 5086.7 5306.0 5371.5 5402.5 5408.1 4581.9 5435.6 5440.6 5446.5 5470.0 4636.0 5261.8 5436.8 5444.6 5468.9 5290.2 5334.7 5336.5 5405.2 5449.9 5242.3 5335.6 5356.8 5358.9 5407.3 5780.4 5858.5 5931.2 6047.8 6061.3 6146.8 6167.5 6188.0 6213.6 6265.1 5858.9 5893.7 6297.7 6366.0 6384.4 6124.4 6130.0 6132.6 6138.7 6161.8 5343.9 6184.4 6190.9 6292.2 6357.5 6163.6 6211.0 6213.7 6219.4 6232.4 6292.8 6312.5 6320.5 6325.6 6330.9 6115.1 6115.9 6141.3 6178.6 6275.6 6178.3 6225.3 6255.0 6278.6 6283.7 5121.3 5129.5 5130.3 5131.7 5145.2 4456.6 4498.4 4588.7 4604.0 4606.4 5970.3 5976.4 6018.4 6028.2 6143.1 6231.1 6240.1 6283.5 6290.8 6298.2 5867.6 5896.5 5963.4 5989.2 5997.0 5584.2 5587.4 5598.4 5621.9 5664.1 6448.7 6451.0 6469.5 6478.9 6496.2 6144.6 6178.4 6188.6 6196.6 6279.4 6412.1 6413.0 6414.6 6423.5 6447.1 6259.0 6260.2 6267.0 6270.7 6272.7 6163.7 6213.4 6224.5 6310.0 6325.7 6048.7 6243.9 6251.1 6257.3 6266.1 6200.0 6247.5 6270.7 6273.1 6286.7 4397.5 5990.2 6039.7 6041.4 6160.5 5080.6 5120.7 6112.7 6167.1 6167.2 6144.2 6175.8 6186.6 6194.1 6210.3 21:31:08 INFO - PROCESS | 1647 | 21:31:08 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:67414.87 Median:19115.25 stddev:142441.82 (745.2%) stddev-sans-first:142752.70 21:31:08 INFO - PROCESS | 1647 | Values: 457.7 471.8 490.6 502.9 543.2 31881.8 42628.6 42649.0 42685.1 42699.8 36249.5 45174.8 45262.5 45263.9 45264.8 9490.9 9701.7 9725.5 11528.7 11937.0 11846.7 11906.6 11962.3 11963.6 11977.8 11970.4 12007.8 12014.8 12022.8 12026.6 11851.8 11869.6 11890.1 11958.8 11966.7 11983.6 12033.2 12036.3 12040.4 12056.9 37603.2 37651.2 37711.4 37938.0 38003.4 4060.0 4065.3 4065.4 4152.8 4182.3 27302.9 27390.0 27564.2 28075.2 28139.9 3328.7 3388.7 3426.1 3469.8 3585.7 466793.0 466831.7 466923.0 469203.0 470682.0 50130.5 60569.0 60782.0 60851.0 61451.4 51062.5 63193.4 63379.0 63660.0 63785.0 16753.2 16957.6 16965.5 16974.0 17010.4 11077.0 11527.5 12797.6 13050.2 13105.6 17626.0 17646.6 17705.4 17732.8 17899.9 7966.4 8006.9 8024.9 8033.5 8049.4 874.5 883.9 894.7 895.7 915.5 31398.4 31539.7 31816.2 31948.7 32857.7 891.4 1083.9 1090.6 1106.0 1106.3 13269.1 14904.9 15504.8 15860.7 16683.4 609240.0 611154.0 614443.0 615101.0 616745.0 65517.3 65534.7 65942.7 66135.3 66280.0 1253.4 1314.4 1323.0 1503.2 1504.3 27704.7 29287.6 30006.0 30167.9 30592.8 52695.0 53201.5 53316.2 53387.7 53454.0 508145.0 511640.0 512073.0 513240.0 513651.0 24156.5 24798.5 28176.9 28500.3 28742.4 49832.0 50498.0 50964.0 52248.5 53776.0 32888.0 32997.0 33192.2 33269.2 34253.1 34172.8 34266.8 34760.7 34905.3 35178.5 38690.0 39469.4 39539.4 39580.8 39655.0 28361.9 34088.5 34467.8 34896.8 35241.3 22699.7 23249.5 26042.5 26286.3 26443.2 21:31:08 INFO - PROCESS | 1647 | 21:31:08 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:39896.54 Median:2143.91 stddev:117958.22 (5502.0%) stddev-sans-first:118251.84 21:31:08 INFO - PROCESS | 1647 | Values: 400.9 407.3 417.6 418.3 434.2 18696.2 18970.9 19380.3 20586.7 21098.1 21644.9 21860.9 21863.5 22398.8 22754.3 5857.0 5979.9 6056.2 6069.4 6070.7 5052.6 5772.2 5841.9 5896.5 6122.0 5797.4 5814.7 5849.6 5861.0 5868.6 5360.0 5599.6 5868.2 5980.2 6010.4 5296.5 5312.0 5313.4 5326.5 5338.6 26039.0 26443.7 26618.2 26679.7 26966.3 2850.3 2856.7 2856.8 2869.1 2883.9 15642.4 16237.2 16237.2 16272.8 16583.4 1885.9 1901.6 1908.9 1948.6 1959.0 119055.2 121579.8 122375.0 128690.4 129297.0 10627.7 11825.6 11846.3 12162.6 12448.8 16271.5 16302.2 16306.3 16321.1 16345.5 7762.6 8985.9 9058.9 9237.0 9297.3 9012.5 9029.7 9031.2 9174.7 9192.7 4945.6 5009.4 5012.1 5023.5 5077.5 2091.6 2129.4 2134.1 2140.6 2147.3 1056.3 1060.1 1066.3 1091.6 1123.8 17036.5 21096.4 21660.7 21864.9 22121.8 1079.4 1096.1 1110.9 1114.6 1131.7 10849.1 11361.3 11588.4 11596.4 11610.7 631568.0 682771.0 720708.0 740973.0 750138.0 151181.2 157084.0 158267.0 161073.0 161903.0 1076.1 1080.7 1101.4 1103.7 1115.5 18677.0 19633.0 19647.9 19678.8 19874.5 48603.0 57153.7 58994.7 59145.1 59747.1 88643.0 88923.0 90633.0 91394.0 91903.0 6101.6 7207.0 8487.7 8663.4 8875.3 11523.9 13072.5 13608.3 13643.6 13932.9 10450.3 11150.6 11456.9 11812.0 12234.6 11029.3 11045.2 11071.8 11184.9 11223.9 10943.2 11876.6 11895.6 12033.4 12058.0 7760.3 8263.0 8702.5 8848.8 8968.5 10143.2 11430.9 11723.4 12091.7 12114.2 21:31:08 INFO - PROCESS | 1647 | 21:31:08 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5488.82 Median:5196.88 stddev:2978.04 (57.3%) stddev-sans-first:2970.22 21:31:08 INFO - PROCESS | 1647 | Values: 1359.2 1483.4 1503.5 1503.8 1511.1 7027.3 7961.0 8331.8 8769.1 8815.1 8091.4 8452.5 8455.2 8616.7 8616.9 2384.9 2398.0 2411.3 2415.7 2420.0 2334.1 2358.8 2368.2 2369.0 2373.1 2320.4 2329.9 2354.2 2354.7 2359.0 2355.8 2369.0 2376.3 2387.8 2393.5 1901.5 2407.0 2411.0 2433.7 2440.2 7193.8 8621.1 8644.2 8661.6 8720.4 5174.1 5328.5 5357.9 5364.5 5366.2 3018.9 3565.4 3591.0 3597.2 3673.5 1266.9 1275.5 1311.3 1323.2 1323.9 10893.7 11011.8 11038.2 11084.4 11099.9 4205.3 4552.7 4603.7 4787.2 4817.2 8828.2 8853.2 8916.3 8925.2 8927.4 5211.2 5277.8 5282.3 5282.5 5283.8 4400.9 4471.7 4836.7 4880.4 4893.7 3432.4 3736.9 3773.3 3836.1 3925.4 1025.8 1189.2 1201.2 1201.6 1206.3 812.2 843.6 900.8 915.3 923.4 5661.9 5669.6 5801.2 5815.9 6045.2 985.8 1023.2 1044.7 1049.3 1086.2 3298.3 3752.8 3975.5 4050.7 4058.0 9439.4 10193.6 10214.3 10225.5 10262.4 9925.3 9943.2 10178.0 10185.8 10188.7 7917.9 8112.0 8134.0 8159.4 8162.8 4535.5 5576.5 5693.0 5776.3 5800.1 9256.4 9279.7 9344.4 9400.3 9507.1 9482.0 9509.0 9522.8 9576.0 9603.0 5625.9 6315.7 6718.2 6876.6 6905.9 8048.6 8177.0 8295.9 8301.2 8320.6 7024.1 7165.9 7208.4 7208.4 7253.2 6997.3 7042.2 7057.8 7162.0 7164.6 6432.8 6434.0 6516.8 6522.1 6532.5 4932.6 5051.0 5051.9 5068.3 5114.3 5044.6 5169.0 5182.5 5218.0 5231.4 21:31:08 INFO - PROCESS | 1647 | 21:31:08 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4599.41 Median:5208.03 stddev:1401.51 (26.9%) stddev-sans-first:1369.02 21:31:08 INFO - PROCESS | 1647 | Values: 370.2 374.7 382.0 386.3 388.0 4815.7 5212.2 5620.5 5652.5 5681.4 4601.2 5219.6 5444.9 5453.8 5473.6 4303.2 4332.9 4343.9 4354.7 4374.1 3826.8 4211.0 4245.4 4246.5 4406.4 3277.4 3555.9 3714.3 4053.4 4259.6 3231.4 3753.4 4105.4 4266.5 4304.0 3800.5 3814.8 3826.3 3828.2 3839.4 4420.4 5168.4 5218.8 5247.6 5318.2 4108.2 4605.9 4938.3 4952.6 4997.6 5814.5 5823.7 5830.3 5851.8 5876.8 3715.6 3952.7 3996.7 3998.5 4002.4 5140.7 5314.9 5839.7 5977.2 6048.5 3400.8 3552.9 3869.6 4021.5 4029.7 3959.8 4047.7 4088.3 4091.5 4107.0 4056.3 4763.0 4771.4 4786.7 4794.6 5804.5 5862.2 5881.0 5902.0 5908.4 1155.8 1265.7 1314.7 1331.3 1340.3 869.8 896.8 904.8 911.9 914.8 5203.9 5222.0 5229.8 5241.5 5247.4 5489.8 5507.8 5566.7 5724.0 5747.6 4103.7 5063.0 5125.7 5212.7 5281.5 2622.6 2622.6 2859.8 2986.2 2990.7 4775.9 5142.4 5403.6 5463.4 5805.1 5643.2 5814.7 5852.2 5869.0 5876.6 5307.9 5603.4 5608.0 5966.4 5974.3 4747.3 5429.8 5465.0 5643.7 5671.6 4855.9 4875.4 5145.7 5191.9 5406.2 5313.7 5428.8 5700.7 5719.4 5897.7 3292.5 3744.7 3890.8 3899.4 3905.9 4903.7 5386.2 5451.1 5523.6 5933.5 5661.5 5745.6 5853.0 5883.4 5906.4 4913.9 5575.6 5788.7 5835.4 5879.4 5444.0 5697.9 5718.8 5798.9 5801.7 5779.5 5782.9 5815.0 5830.0 5884.3 4501.8 4853.8 4958.8 5570.2 5718.5 21:31:08 INFO - PROCESS | 1647 | 21:31:08 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20763.81 Median:4518.01 stddev:29306.84 (648.7%) stddev-sans-first:29350.48 21:31:08 INFO - PROCESS | 1647 | Values: 738.0 742.3 784.2 847.5 873.2 15438.3 15873.8 16439.7 16523.3 16572.3 14934.7 14967.3 15203.9 15246.7 15299.8 874.3 896.0 896.7 899.0 900.5 727.5 751.4 879.1 898.5 900.6 947.0 954.4 961.5 961.6 961.7 942.2 951.4 953.0 953.1 960.3 701.0 704.7 713.7 715.2 715.5 16036.0 16263.0 16284.0 16285.0 16800.2 15033.5 15115.8 15118.8 15186.3 15314.5 11818.5 11947.9 12071.2 12084.2 12202.6 3824.2 3888.1 3956.8 4056.0 4085.2 99600.0 101967.5 115033.0 117479.0 118585.4 11358.5 11603.1 11673.6 11723.1 12041.1 23400.4 23664.8 24143.9 24182.9 24272.5 8160.1 8207.5 8229.0 8291.1 8453.3 4402.2 4447.8 4495.9 4540.2 4558.3 3722.6 3784.6 3812.5 3813.7 3827.6 691.8 692.5 692.8 705.6 711.6 620.8 626.6 628.2 656.6 663.5 9060.8 9260.9 9605.3 10227.5 10825.4 726.9 730.6 733.4 739.8 740.4 4484.2 4852.6 4876.2 4878.2 4888.8 85138.0 93735.0 101379.0 101622.0 102012.0 86991.0 87280.6 89040.1 90645.0 90690.0 75943.6 76073.2 78080.0 78862.6 79263.8 9202.2 9434.8 9479.7 9556.7 9568.3 48488.0 50307.2 50872.0 52348.7 53002.7 51271.0 69020.5 70961.6 72168.6 73063.0 13344.7 13402.3 13768.7 13849.4 13997.2 15157.5 15257.3 15266.0 15599.2 16239.8 14016.2 14029.4 14310.1 14521.3 14586.0 14021.9 14192.0 14271.6 14349.7 14379.0 13803.7 13938.0 14231.9 14251.6 14693.3 10101.4 10125.0 10452.7 10541.9 11263.5 9459.7 9542.6 11320.3 11596.7 11731.4 21:31:08 INFO - PROCESS | 1647 | -------- Summary: end -------- 21:31:08 INFO - PROCESS | 1647 | 21:31:08 INFO - TEST-INFO | 1647: exit 0 21:31:08 INFO - TEST-OK | dromaeo_css | took 1114295ms 21:31:08 INFO - TEST-START | kraken 21:31:08 INFO - operating with platform_type : mac_ 21:31:08 INFO - Initialising browser for kraken test... 21:31:08 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmpE2SPzW/profile http://localhost:49253/getInfo.html) 21:31:11 INFO - PROCESS | 1660 | 2017-04-03 21:31:11.541 plugin-container[1662:9834] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 21:31:11 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:31:11 INFO - PROCESS | 1660 | 2017-04-03 21:31:11.541 plugin-container[1662:9834] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 21:31:11 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:31:11 INFO - PROCESS | 1660 | 2017-04-03 21:31:11.541 plugin-container[1662:9834] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:31:11 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 21:31:11 INFO - PROCESS | 1660 | colorDepth:24 21:31:11 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 21:31:11 INFO - PROCESS | 1660 | __metrics 21:31:11 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 21:31:12 INFO - TEST-INFO | 1660: exit 0 21:31:12 INFO - Browser initialized. 21:31:12 INFO - Running cycle 1/1 for kraken test... 21:31:12 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmpE2SPzW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:31:14 INFO - PROCESS | 1663 | 2017-04-03 21:31:14.723 plugin-container[1664:9960] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 21:31:14 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:31:14 INFO - PROCESS | 1663 | 2017-04-03 21:31:14.724 plugin-container[1664:9960] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 21:31:14 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:31:14 INFO - PROCESS | 1663 | 2017-04-03 21:31:14.724 plugin-container[1664:9960] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:31:24 INFO - PROCESS | 1663 | RSS: Main: 173436928 21:31:24 INFO - PROCESS | 1663 | 21:31:27 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49253/tests/kraken/driver.html?testName=audio-beat-detection) 21:31:28 INFO - PROCESS | 1663 | RSS: Main: 186355712 21:31:28 INFO - PROCESS | 1663 | 21:31:30 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49253/tests/kraken/driver.html?testName=audio-dft) 21:31:31 INFO - PROCESS | 1663 | RSS: Main: 194240512 21:31:31 INFO - PROCESS | 1663 | 21:31:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49253/tests/kraken/driver.html?testName=audio-fft) 21:31:35 INFO - PROCESS | 1663 | RSS: Main: 197869568 21:31:35 INFO - PROCESS | 1663 | 21:31:37 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49253/tests/kraken/driver.html?testName=audio-oscillator) 21:31:38 INFO - PROCESS | 1663 | RSS: Main: 199241728 21:31:38 INFO - PROCESS | 1663 | 21:31:39 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49253/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:31:40 INFO - PROCESS | 1663 | RSS: Main: 199262208 21:31:40 INFO - PROCESS | 1663 | 21:31:43 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49253/tests/kraken/driver.html?testName=imaging-darkroom) 21:31:44 INFO - PROCESS | 1663 | RSS: Main: 203657216 21:31:44 INFO - PROCESS | 1663 | 21:31:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49253/tests/kraken/driver.html?testName=imaging-desaturate) 21:31:47 INFO - PROCESS | 1663 | RSS: Main: 204021760 21:31:47 INFO - PROCESS | 1663 | 21:31:50 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49253/tests/kraken/driver.html?testName=json-parse-financial) 21:31:51 INFO - PROCESS | 1663 | RSS: Main: 203288576 21:31:51 INFO - PROCESS | 1663 | 21:31:52 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49253/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:31:53 INFO - PROCESS | 1663 | RSS: Main: 202141696 21:31:53 INFO - PROCESS | 1663 | 21:31:55 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:31:56 INFO - PROCESS | 1663 | RSS: Main: 202305536 21:31:56 INFO - PROCESS | 1663 | 21:31:58 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:31:58 INFO - PROCESS | 1663 | RSS: Main: 202248192 21:31:58 INFO - PROCESS | 1663 | 21:32:01 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:32:02 INFO - PROCESS | 1663 | RSS: Main: 202215424 21:32:02 INFO - PROCESS | 1663 | 21:32:05 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:32:06 INFO - PROCESS | 1663 | RSS: Main: 202637312 21:32:06 INFO - PROCESS | 1663 | 21:32:07 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49253/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49253/tests/kraken/driver.html?testName=ai-astar) 21:32:08 INFO - PROCESS | 1663 | RSS: Main: 204324864 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | __start_tp_report 21:32:08 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 21:32:08 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 21:32:08 INFO - PROCESS | 1663 | |i|pagename|runs| 21:32:08 INFO - PROCESS | 1663 | |0;ai-astar;105;115;119;117;117;136;151;117;117;117 21:32:08 INFO - PROCESS | 1663 | |1;audio-beat-detection;138;110;121;144;110;99;108;131;111;130 21:32:08 INFO - PROCESS | 1663 | |2;audio-dft;195;198;194;200;194;192;200;193;196;194 21:32:08 INFO - PROCESS | 1663 | |3;audio-fft;77;75;84;108;50;63;85;84;92;51 21:32:08 INFO - PROCESS | 1663 | |4;audio-oscillator;90;70;83;73;71;82;104;77;71;82 21:32:08 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;89;109;120;85;119;74;128;85;89;86 21:32:08 INFO - PROCESS | 1663 | |6;imaging-darkroom;95;95;96;95;100;96;119;97;139;150 21:32:08 INFO - PROCESS | 1663 | |7;imaging-desaturate;114;148;93;91;113;123;97;92;92;93 21:32:08 INFO - PROCESS | 1663 | |8;json-parse-financial;56;59;58;60;61;62;61;61;60;60 21:32:08 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;66;74;71;75;68;67;67;68;67;69 21:32:08 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;83;87;83;83;92;103;81;82;84;82 21:32:08 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;136;137;127;133;149;148;147;151;204;163 21:32:08 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;165;165;166;202;164;223;225;168;165;166 21:32:08 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;67;65;66;62;65;81;87;85;89;70 21:32:08 INFO - PROCESS | 1663 | __end_tp_report 21:32:08 INFO - PROCESS | 1663 | __start_cc_report 21:32:08 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,921 21:32:08 INFO - PROCESS | 1663 | __end_cc_report 21:32:08 INFO - PROCESS | 1663 | __startTimestamp1491280328117__endTimestamp 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | ------- Summary: start ------- 21:32:08 INFO - PROCESS | 1663 | Number of tests: 14 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:121.10 Median:117.00 stddev:12.91 (11.0%) stddev-sans-first:12.31 21:32:08 INFO - PROCESS | 1663 | Values: 105.0 115.0 119.0 117.0 117.0 136.0 151.0 117.0 117.0 117.0 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:120.20 Median:130.50 stddev:14.86 (11.4%) stddev-sans-first:14.30 21:32:08 INFO - PROCESS | 1663 | Values: 138.0 110.0 121.0 144.0 110.0 99.0 108.0 131.0 111.0 130.0 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:195.60 Median:195.50 stddev:2.84 (1.5%) stddev-sans-first:3.00 21:32:08 INFO - PROCESS | 1663 | Values: 195.0 198.0 194.0 200.0 194.0 192.0 200.0 193.0 196.0 194.0 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:76.90 Median:80.50 stddev:18.11 (22.5%) stddev-sans-first:19.21 21:32:08 INFO - PROCESS | 1663 | Values: 77.0 75.0 84.0 108.0 50.0 63.0 85.0 84.0 92.0 51.0 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:80.30 Median:79.50 stddev:10.60 (13.3%) stddev-sans-first:10.65 21:32:08 INFO - PROCESS | 1663 | Values: 90.0 70.0 83.0 73.0 71.0 82.0 104.0 77.0 71.0 82.0 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:98.40 Median:85.00 stddev:18.75 (22.1%) stddev-sans-first:19.58 21:32:08 INFO - PROCESS | 1663 | Values: 89.0 109.0 120.0 85.0 119.0 74.0 128.0 85.0 89.0 86.0 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:108.20 Median:95.00 stddev:20.62 (21.7%) stddev-sans-first:21.31 21:32:08 INFO - PROCESS | 1663 | Values: 95.0 95.0 96.0 95.0 100.0 96.0 119.0 97.0 139.0 150.0 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:105.60 Median:92.00 stddev:18.86 (20.5%) stddev-sans-first:19.75 21:32:08 INFO - PROCESS | 1663 | Values: 114.0 148.0 93.0 91.0 113.0 123.0 97.0 92.0 92.0 93.0 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:60.50 stddev:1.75 (2.9%) stddev-sans-first:1.20 21:32:08 INFO - PROCESS | 1663 | Values: 56.0 59.0 58.0 60.0 61.0 62.0 61.0 61.0 60.0 60.0 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:68.50 stddev:3.12 (4.6%) stddev-sans-first:3.09 21:32:08 INFO - PROCESS | 1663 | Values: 66.0 74.0 71.0 75.0 68.0 67.0 67.0 68.0 67.0 69.0 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:83.00 stddev:6.78 (8.2%) stddev-sans-first:7.11 21:32:08 INFO - PROCESS | 1663 | Values: 83.0 87.0 83.0 83.0 92.0 103.0 81.0 82.0 84.0 82.0 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:148.50 stddev:21.81 (14.7%) stddev-sans-first:22.58 21:32:08 INFO - PROCESS | 1663 | Values: 136.0 137.0 127.0 133.0 149.0 148.0 147.0 151.0 204.0 163.0 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.90 Median:167.00 stddev:25.42 (15.2%) stddev-sans-first:26.31 21:32:08 INFO - PROCESS | 1663 | Values: 165.0 165.0 166.0 202.0 164.0 223.0 225.0 168.0 165.0 166.0 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:75.50 stddev:10.53 (13.9%) stddev-sans-first:10.89 21:32:08 INFO - PROCESS | 1663 | Values: 67.0 65.0 66.0 62.0 65.0 81.0 87.0 85.0 89.0 70.0 21:32:08 INFO - PROCESS | 1663 | -------- Summary: end -------- 21:32:08 INFO - PROCESS | 1663 | 21:32:08 INFO - TEST-INFO | 1663: exit 0 21:32:08 INFO - TEST-OK | kraken | took 60025ms 21:32:08 INFO - SUITE-END | took 1174s 21:32:09 INFO - Completed test suite (00:19:34) 21:32:09 INFO - javascript benchmark 21:32:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7488.691405212139, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3094.0717784664594, 3167.9841600791992, 3493.4235851084572, 3519.2081781599145, 3525.5240542526753, 4593.908121837561, 5855.970720146399, 5866.9999999999945, 5906.734196961136, 5934.525237980962, 5944.70276486175, 5999.220101386813, 6006.219191505098, 6046.002409602422, 6059.454649081582, 5086.720230387327, 5306.000893433264, 5371.5165635092835, 5402.459754024605, 5408.134698448249, 4581.885452863672, 5435.619506634537, 5440.619156659035, 5446.509814116729, 5469.972650136764, 4636.049609829994, 5261.763220655061, 5436.809711660093, 5444.564434845203, 5468.890622187554, 5290.153575427932, 5334.653247538898, 5336.546393556552, 5405.162199859028, 5449.891002179974, 5242.266082748418, 5335.573154147659, 5356.839294821161, 5358.866028349284, 5407.297051383295, 5780.421957804228, 5858.502027327682, 5931.1613399845755, 6047.818565443044, 6061.272647282311, 6146.846328841814, 6167.475764559973, 6187.978983467722, 6213.5961162524745, 6265.0915617235705, 5858.916100521417, 5893.675847828367, 6297.685115744241, 6365.968170159166, 6384.3934826191435, 6124.448799608057, 6129.969350153221, 6132.601380910227, 6138.69306534676, 6161.815145545641, 5343.919841202385, 6184.381561843779, 6190.876182476325, 6292.244930608356, 6357.459615932605, 6163.630182189083, 6210.968945155336, 6213.720382582795, 6219.40915613016, 6232.376762323822, 6292.811215663537, 6312.52656050798, 6320.494360451153, 6325.557210995186, 6330.936690633127, 6115.057807494094, 6115.879294474695, 6141.30190404341, 6178.567500275028, 6275.623462592259, 6178.320384757673, 6225.28409232944, 6255.0, 6278.560500764903, 6283.6858157091965, 5121.28302037715, 5129.512696293846, 5130.30740849988, 5131.692098474104, 5145.228215767665, 4456.598906098467, 4498.437695288089, 4588.655850811202, 4603.976980115078, 4606.355110284563, 5970.313413957364, 5976.432238937294, 6018.368071352533, 6028.246469191351, 6143.078538219213, 6231.071813972874, 6240.12638230639, 6283.497320214372, 6290.779822706183, 6298.212723409574, 5867.559933004956, 5896.469317761422, 5963.403659634002, 5989.161517387569, 5997.0404735242155, 5584.246126772915, 5587.44125587438, 5598.412166722517, 5621.943780562224, 5664.065429204135, 6448.742050317935, 6451.000094985378, 6469.514786390945, 6478.870422591427, 6496.155499784998, 6144.617461071223, 6178.351273116438, 6188.628682279078, 6196.566240363311, 6279.403456671609, 6412.134361861089, 6413.0, 6414.647194404166, 6423.454006409507, 6447.0651755494255, 6259.029850373199, 6260.248770147429, 6266.968665156645, 6270.6551139685935, 6272.749090036347, 6163.691815409122, 6213.440790328892, 6224.502039836892, 6309.968450157719, 6325.683715814283, 6048.697565121638, 6243.906341404973, 6251.124842522141, 6257.342978987323, 6266.091416744638, 6200.0, 6247.5001999840815, 6270.717817698123, 6273.121762953281, 6286.74853005893, 4397.516273210008, 5990.161377407254, 6039.698015099315, 6041.426064523863, 6160.537959652955, 5080.593552515864, 5120.743962801919, 6112.685862051747, 6167.105769663463, 6167.198264225622, 6144.201253836973, 6175.814725558241, 6186.628802271878, 6194.132821405097, 6210.3168651449205], "value": 5784.632137876828, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.67962426301597, 471.8254245929007, 490.64183146303196, 502.9195328747401, 543.18522216675, 31881.84059079704, 42628.57371426285, 42649.0, 42685.146297074076, 42699.78650106753, 36249.45625815615, 45174.77412612941, 45262.54737452624, 45263.86840328993, 45264.773676131575, 9490.90509094907, 9701.70894873155, 9725.51372431379, 11528.711782205428, 11937.0, 11846.703832404173, 11906.642800715992, 11962.2822630642, 11963.58127465539, 11977.820332695017, 11970.401479926013, 12007.759844803099, 12014.819777703342, 12022.759544809142, 12026.579069732561, 11851.822222666668, 11869.584564540286, 11890.10824188185, 11958.760824783543, 11966.700832479215, 11983.64049078529, 12033.157678962476, 12036.338001409918, 12040.39798010096, 12056.939715301454, 37603.247935041145, 37651.24697506062, 37711.43432848509, 37938.0, 38003.429948550795, 4059.9594004059877, 4065.288574499451, 4065.4105154752397, 4152.750834949882, 4182.330827067685, 27302.907883684627, 27390.0, 27564.173074807786, 28075.157745267676, 28139.859300703367, 3328.6671332866517, 3388.695017448417, 3426.143374596964, 3469.8438570264148, 3585.748997570171, 466793.0, 466831.665841672, 466923.0, 469203.0, 470682.0, 50130.49869501331, 60569.0, 60782.0, 60851.0, 61451.385486144565, 51062.48937510652, 63193.36806631875, 63379.0, 63660.0, 63785.0, 16753.1623418831, 16957.576060598338, 16965.491035268962, 16974.0, 17010.404635837935, 11077.0, 11527.481263343001, 12797.552085676956, 13050.15174013686, 13105.60683179487, 17626.0, 17646.647067058842, 17705.38031168903, 17732.822671773116, 17899.910500447673, 7966.402519811038, 8006.879896801553, 8024.919750802417, 8033.477823941425, 8049.4365394423, 874.4927941793744, 883.9292856571459, 894.7271082319955, 895.713371721043, 915.4919019943995, 31398.372032559677, 31539.742134811506, 31816.204594885312, 31948.680513195035, 32857.68569257251, 891.4250308551078, 1083.9078678312273, 1090.574675876409, 1106.0, 1106.336198281041, 13269.07116501855, 14904.850951490347, 15504.844951550565, 15860.682786344209, 16683.416080437128, 609240.0, 611154.0, 614443.0, 615101.0, 616745.0, 65517.344826551125, 65534.67232663902, 65942.67028664827, 66135.34661633498, 66280.00579991304, 1253.3858409379338, 1314.4347930389833, 1322.9680848937844, 1503.1882783296896, 1504.3230546254229, 27704.722952771022, 29287.631922328354, 30006.0, 30167.944121955625, 30592.84703576468, 52695.0, 53201.46798531965, 53316.200256996955, 53387.733061334446, 53454.0, 508145.0, 511640.0, 512073.0, 513240.0, 513651.0, 24156.51686966286, 24798.504029919663, 28176.85911570511, 28500.287492812844, 28742.42515149727, 49832.0, 50497.99004019972, 50964.0, 52248.47751522436, 53776.0, 32888.0, 32997.010089697345, 33192.1701957453, 33269.16827079342, 34253.14367140841, 34172.82913585416, 34266.82866585651, 34760.65239347574, 34905.30189396249, 35178.47232291569, 38690.0, 39469.40795888007, 39539.40690889581, 39580.80209598933, 39655.0, 28361.858190708914, 34088.48867266943, 34467.827660861534, 34896.82551587226, 35241.29517409586, 22699.659505107105, 23249.535009300056, 26042.479150417264, 26286.342841429116, 26443.206703798918], "value": 20432.837325799435, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.89576710055377, 407.26099554900594, 417.55321805667927, 418.32649434410604, 434.1555674213654, 18696.15867285972, 18970.90514547427, 19380.321688740896, 20586.691199632016, 21098.050587723552, 21644.8917755411, 21860.8906955465, 21863.453413664665, 22398.776012239872, 22754.31737047887, 5857.0, 5979.880402391971, 6056.182415373934, 6069.362716914727, 6070.6661133637635, 5052.595792336622, 5772.191893134965, 5841.941580584183, 5896.498797602209, 6122.0, 5797.420257974211, 5814.7092645367775, 5849.649021058729, 5861.0, 5868.589198756089, 5359.9732001339935, 5599.580031497654, 5868.17845501628, 5980.162777211194, 6010.429009244115, 5296.523312901857, 5311.973440132813, 5313.38896026958, 5326.493983071641, 5338.572914166857, 26038.95844166231, 26443.73556264432, 26618.20145395641, 26679.733202668114, 26966.325841853915, 2850.2874281429645, 2856.657201135877, 2856.8000239983207, 2869.1249169003445, 2883.855807209652, 15642.374305027784, 16237.188140592923, 16237.188140593042, 16272.83727162725, 16583.419580314752, 1885.9151338189815, 1901.6006638605909, 1908.9331873384504, 1948.5907959328558, 1959.0, 119055.21417178749, 121579.78420215772, 122375.0, 128690.42619147565, 129297.0, 10627.681169564925, 11825.586104486301, 11846.348450835114, 12162.635120946386, 12448.75102497945, 16271.51185464438, 16302.184890755414, 16306.266218020217, 16321.102339371448, 16345.509634710977, 7762.61186940662, 8985.910140898639, 9058.864117038249, 9237.030111838294, 9297.302702297355, 9012.54937253148, 9029.683961061332, 9031.187193152648, 9174.67888623895, 9192.724218273463, 4945.629077819178, 5009.398872135294, 5012.122878496249, 5023.497650234947, 5077.517635824591, 2091.5921395327764, 2129.371835308588, 2134.146341463396, 2140.56118495708, 2147.2591955775547, 1056.2975621211808, 1060.1306928318704, 1066.2536224642627, 1091.6015654286127, 1123.775244951013, 17036.48890533286, 21096.367108986757, 21660.675089873665, 21864.89067554652, 22121.778782212295, 1079.3685693869018, 1096.1066730614523, 1110.938898360598, 1114.5541783286587, 1131.6944425005202, 10849.077828384676, 11361.318320900773, 11588.36264005471, 11596.420178991017, 11610.6516804496, 631568.0, 682771.0, 720708.0, 740973.0, 750138.0, 151181.24409377883, 157084.0, 158267.0, 161073.0, 161903.0, 1076.1498416251075, 1080.7406222506543, 1101.3997371432588, 1103.674416047268, 1115.5036008975928, 18677.0, 19633.0, 19647.901760491106, 19678.803211967697, 19874.50313742168, 48602.99999999859, 57153.71423143024, 58994.70502647631, 59145.11282330855, 59747.103793444, 88643.0, 88923.0, 90633.0, 91394.0, 91903.0, 6101.60339577926, 7207.0, 8487.702930397407, 8663.350248731243, 8875.289976801969, 11523.884761152281, 13072.542461013818, 13608.251546165056, 13643.590692279247, 13932.93033534826, 10450.320729152581, 11150.606174228222, 11456.885431145914, 11812.0, 12234.632961011184, 11029.283096598696, 11045.226834121535, 11071.83392249133, 11184.94407527957, 11223.88776112261, 10943.233973621771, 11876.584319548774, 11895.643130705746, 12033.398330083635, 12058.0, 7760.30157285847, 8262.958685206535, 8702.51899240309, 8848.778780530281, 8968.506732129534, 10143.239257055602, 11430.885691142983, 11723.41382930867, 12091.697707557381, 12114.21257618252], "value": 10294.554375636615, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.1505309181762, 1483.399223314558, 1503.5188739603325, 1503.8420965798591, 1511.1310996177194, 7027.332403421679, 7961.044674639052, 8331.791705207374, 8769.079246679103, 8815.118488151189, 8091.3931455140955, 8452.492850428978, 8455.196756308156, 8616.655333786657, 8616.870746938801, 2384.880755962199, 2398.040783686522, 2411.2766170148884, 2415.7463466336044, 2420.0, 2334.124703236286, 2358.7759162879584, 2368.159303447277, 2368.988155059222, 2373.074500944633, 2320.3966968588206, 2329.860208387502, 2354.1878052072007, 2354.682117914077, 2359.0327965534134, 2355.8468699534646, 2368.976310236893, 2376.251480783565, 2387.7612238776155, 2393.521295740859, 1901.5436295289169, 2406.9518609627708, 2411.0, 2433.720122185936, 2440.2435245074084, 7193.8201544961275, 8621.05168431472, 8644.178803013703, 8661.610227539775, 8720.43317184381, 5174.09453345663, 5328.520433160996, 5357.946420535823, 5364.544013758835, 5366.195070739382, 3018.8528359223355, 3565.447355659877, 3590.9640903591153, 3597.1726502904216, 3673.5040769496045, 1266.936653167338, 1275.489804078367, 1311.2722439046327, 1323.1862404621186, 1323.913945593543, 10893.727656808564, 11011.779764404748, 11038.17213708975, 11084.445777711162, 11099.944500277446, 4205.264078786261, 4552.658550608651, 4603.700759450692, 4787.234042553175, 4817.205161148441, 8828.161324674145, 8853.158949899878, 8916.331275154389, 8925.196732294125, 8927.419717718327, 5211.2443695663915, 5277.762500687479, 5282.260483532308, 5282.471752824764, 5283.815066472655, 4400.889977750518, 4471.686981911235, 4836.733979631154, 4880.438749543777, 4893.706377617284, 3432.3650124726505, 3736.93497353253, 3773.3019391412677, 3836.0985168485386, 3925.4111883217174, 1025.820481415764, 1189.1854079955258, 1201.1711918776016, 1201.645514573203, 1206.2702065250573, 812.2162113560324, 843.5782108945527, 900.7703035725848, 915.2997956563235, 923.3813345058827, 5661.858453538612, 5669.603127781016, 5801.245838041112, 5815.854603634858, 6045.244344456943, 985.8324084905584, 1023.1763430438419, 1044.7179261599326, 1049.2812423489934, 1086.1853609792656, 3298.274379636476, 3752.793596352227, 3975.522937247491, 4050.716449848482, 4058.0, 9439.386439881524, 10193.643222487177, 10214.336068155546, 10225.539850706737, 10262.384256944608, 9925.255605829447, 9943.204543636492, 10177.982201779763, 10185.847212291674, 10188.694339169837, 7917.920820791834, 8111.9594402027615, 8134.0, 8159.388045896581, 8162.755117346489, 4535.523770004234, 5576.525995290446, 5693.003724348306, 5776.277965254343, 5800.100984347433, 9256.444613323221, 9279.67521136784, 9344.392614480037, 9400.294977876823, 9507.144357007903, 9482.004389538999, 9508.952455237957, 9522.761930951781, 9575.994520575518, 9603.03969603034, 5625.874825035091, 6315.747370105328, 6718.22740384862, 6876.553024053421, 6905.930940690529, 8048.557329346942, 8177.0, 8295.875561866698, 8301.211384918422, 8320.625571849403, 7024.121984751906, 7165.928340716526, 7208.35124838746, 7208.35124838746, 7253.238409967089, 6997.265287144776, 7042.154941406859, 7057.823554411181, 7162.0, 7164.605946672983, 6432.807015789534, 6434.034894765822, 6516.771912983023, 6522.119513865568, 6532.477401807939, 4932.556069953722, 5051.0, 5051.924221136613, 5068.265101560327, 5114.2840002400435, 5044.646874718735, 5169.043726910533, 5182.507661772125, 5218.034663587248, 5231.372235331784], "value": 4477.475377198871, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.2391585292225, 374.6590602551678, 381.95989421110784, 386.2834442109884, 387.99315775562405, 4815.69976106451, 5212.1660534314515, 5620.510564700353, 5652.491275785179, 5681.431856814316, 4601.217792975194, 5219.582433405328, 5444.876498165719, 5453.7818487260465, 5473.644213126144, 4303.225419424502, 4332.870013899572, 4343.891402714926, 4354.651627869779, 4374.12517496502, 3826.770393776382, 4210.97894510527, 4245.384419259206, 4246.543191998283, 4406.4271644686305, 3277.3936821687944, 3555.8933232003083, 3714.275716235335, 4053.43251944728, 4259.552746961571, 3231.418344697953, 3753.436984452313, 4105.445764821756, 4266.530681625019, 4304.031592891605, 3800.467934489174, 3814.7901865397394, 3826.253880493305, 3828.1578052828613, 3839.3665045267776, 4420.4253447052, 5168.405633352175, 5218.769626461701, 5247.606429517801, 5318.22885681574, 4108.2129273766, 4605.861824145281, 4938.259261110827, 4952.579030782388, 4997.55071029401, 5814.534837213013, 5823.67969761663, 5830.30036395631, 5851.824445266649, 5876.764929402819, 3715.5541335039698, 3952.6679259236903, 3996.680265578747, 3998.5001874765653, 4002.3596224604216, 5140.717260550706, 5314.874050379879, 5839.678817665027, 5977.176961026885, 6048.516118710492, 3400.795952242873, 3552.946705799415, 3869.5936926622862, 4021.5174179098112, 4029.6977726670616, 3959.7922633596454, 4047.716659833842, 4088.345864661699, 4091.529474110456, 4106.973256685828, 4056.269871423172, 4762.9523704762505, 4771.355866957916, 4786.712797232177, 4794.5924596408995, 5804.477597016289, 5862.237909071793, 5880.970595146997, 5902.0, 5908.350081491032, 1155.8497395338552, 1265.6962329040964, 1314.6581888709004, 1331.3343328335832, 1340.3298350824587, 869.7564681889081, 896.8161526886972, 904.8371293167293, 911.8723378726984, 914.7895983923602, 5203.895922081537, 5221.973890130601, 5229.790808367623, 5241.50205730455, 5247.422783493813, 5489.807856724996, 5507.834764957058, 5566.693831839207, 5723.971380143073, 5747.603083641018, 4103.651189648914, 5063.0, 5125.668315594893, 5212.661177023481, 5281.547574417066, 2622.632831403605, 2622.6493355921716, 2859.7845915485836, 2986.163874115208, 2990.6560745514107, 4775.880602984953, 5142.382914050263, 5403.621746477709, 5463.350275116565, 5805.10020946754, 5643.181738647873, 5814.738336774855, 5852.180694702745, 5869.0, 5876.589618491531, 5307.894881494215, 5603.35561410443, 5608.046632072476, 5966.373530779292, 5974.2830860297045, 4747.347599352584, 5429.809956651657, 5464.972675136599, 5643.71781410936, 5671.631343962629, 4855.93169502723, 4875.414950205998, 5145.691258524501, 5191.948080519146, 5406.161904952178, 5313.734313284398, 5428.782848686008, 5700.657960522382, 5719.428057194414, 5897.734601943009, 3292.489664102068, 3744.6629803317833, 3890.8133019428215, 3899.3760998240145, 3905.906346223062, 4903.656744027884, 5386.165144402624, 5451.100568406168, 5523.6409633373705, 5933.465988060922, 5661.518770904355, 5745.569082318759, 5852.97572924745, 5883.382244864399, 5906.379830117948, 4913.852584422473, 5575.609707320448, 5788.671513879452, 5835.416458354301, 5879.441453061952, 5443.972780136232, 5697.857553561194, 5718.79984200551, 5798.884022319446, 5801.738921748447, 5779.450952159538, 5782.884342313214, 5815.0, 5830.008898487352, 5884.293884733859, 4501.819927202875, 4853.757312134308, 4958.752062396794, 5570.2201691762375, 5718.51392631631], "value": 4167.994617296449, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.97786066418, 742.3467348733114, 784.1961988961314, 847.5380917400017, 873.1792115411511, 15438.305276262567, 15873.84126158738, 16439.671206575862, 16523.256453459595, 16572.337106515755, 14934.701305973875, 14967.25163741814, 15203.92398038011, 15246.695066098699, 15299.770503442458, 874.2699845628907, 895.9552022398854, 896.66375109334, 899.0, 900.5092224737534, 727.538013361514, 751.3801114080906, 879.1077056787366, 898.5192921786845, 900.621738869673, 946.9526523673824, 954.3558098283665, 961.5192403798101, 961.5769061612868, 961.7211008807437, 942.1708896171359, 951.4101257220499, 952.9857052144223, 953.1136043479561, 960.3229723045237, 701.0115736811072, 704.7075463682565, 713.6895450479058, 715.1561157833754, 715.4920006795181, 16035.957662751884, 16263.024218546925, 16284.0, 16285.0, 16800.15999200035, 15033.473828415952, 15115.848841511553, 15118.773218401622, 15186.316615752206, 15314.540563783105, 11818.527258909633, 11947.880521194764, 12071.155019148664, 12084.214526055866, 12202.633920982385, 3824.2351529694174, 3888.0668639526593, 3956.8219430125714, 4056.0, 4085.162541678949, 99600.0, 101967.49016254945, 115033.0, 117479.0, 118585.40707296494, 11358.545658173745, 11603.129765267639, 11673.591424300279, 11723.062155027577, 12041.123345045271, 23400.414989625056, 23664.7633523666, 24143.879280603483, 24182.91176897044, 24272.514549708907, 8160.143184965612, 8207.548584827773, 8229.0, 8291.129431409616, 8453.323734101257, 4402.207602631493, 4447.844325448594, 4495.865124046219, 4540.160070386989, 4558.27067669178, 3722.571904231048, 3784.6026167252594, 3812.4662547243406, 3813.656770890633, 3827.5789663137034, 691.7717153339487, 692.4564217089561, 692.757534862804, 705.5837056136801, 711.6299524247362, 620.8447888027993, 626.5676683088654, 628.233738736279, 656.5830697507022, 663.5024226243526, 9060.776795132573, 9260.861087083698, 9605.327627066039, 10227.488625568692, 10825.350478971286, 726.8546290741767, 730.5689643110591, 733.4315905173534, 739.7706710919632, 740.3817812126935, 4484.170428470678, 4852.611791056707, 4876.21980483121, 4878.170710979142, 4888.804447822118, 85138.0, 93735.0, 101379.0, 101622.0, 102012.0, 86991.0, 87280.56359718287, 89040.10959890448, 90645.0, 90690.0, 75943.62028189935, 76073.23926760661, 78080.0, 78862.6056869712, 79263.81104283441, 9202.171804537624, 9434.764130896783, 9479.715608531755, 9556.713298601053, 9568.330216884751, 48488.0, 50307.24539131989, 50872.0, 52348.738256309996, 53002.734986324824, 51271.0, 69020.54897255218, 70961.58076838538, 72168.63915680388, 73063.0, 13344.733105338033, 13402.262875541548, 13768.655783605089, 13849.446022159404, 13997.230152341312, 15157.469488567847, 15257.313420896307, 15266.0, 15599.220038998232, 16239.756403654192, 14016.18860823803, 14029.438822446988, 14310.141391516543, 14521.27393630293, 14585.978981471195, 14021.859781402056, 14192.006559540732, 14271.57185284443, 14349.713005740035, 14379.0, 13803.723925521634, 13938.0, 14231.928840355731, 14251.643708906946, 14693.265336732908, 10101.393916365041, 10125.0, 10452.68641940743, 10541.894581054397, 11263.549458021587, 9459.668911588062, 9542.570584323861, 11320.305288962474, 11596.652100437, 11731.35477548743], "value": 7766.3260090064905, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1525.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 119.0, 117.0, 117.0, 136.0, 151.0, 117.0, 117.0, 117.0], "value": 121.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 110.0, 121.0, 144.0, 110.0, 99.0, 108.0, 131.0, 111.0, 130.0], "value": 120.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 198.0, 194.0, 200.0, 194.0, 192.0, 200.0, 193.0, 196.0, 194.0], "value": 195.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 84.0, 108.0, 50.0, 63.0, 85.0, 84.0, 92.0, 51.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 83.0, 73.0, 71.0, 82.0, 104.0, 77.0, 71.0, 82.0], "value": 80.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 109.0, 120.0, 85.0, 119.0, 74.0, 128.0, 85.0, 89.0, 86.0], "value": 98.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 100.0, 96.0, 119.0, 97.0, 139.0, 150.0], "value": 108.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 148.0, 93.0, 91.0, 113.0, 123.0, 97.0, 92.0, 92.0, 93.0], "value": 105.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 58.0, 60.0, 61.0, 62.0, 61.0, 61.0, 60.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 71.0, 75.0, 68.0, 67.0, 67.0, 68.0, 67.0, 69.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 83.0, 83.0, 92.0, 103.0, 81.0, 82.0, 84.0, 82.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 127.0, 133.0, 149.0, 148.0, 147.0, 151.0, 204.0, 163.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 166.0, 202.0, 164.0, 223.0, 225.0, 168.0, 165.0, 166.0], "value": 180.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 62.0, 65.0, 81.0, 87.0, 85.0, 89.0, 70.0], "value": 73.7, "unit": "score"}]}]} 21:32:09 INFO - Return code: 0 21:32:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:32:09 ERROR - # TBPL SUCCESS # 21:32:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:32:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:32:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:32:09 INFO - 2017-04-03 21:32:09.424 screenresolution[1668:10351] starting screenresolution argv=screenresolution get 21:32:09 INFO - 2017-04-03 21:32:09.427 screenresolution[1668:10351] Display 0: 1600x1200x32@60 21:32:09 INFO - 2017-04-03 21:32:09.433 screenresolution[1669:10354] starting screenresolution argv=screenresolution list 21:32:09 INFO - Available Modes on Display 0 21:32:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:32:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:32:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:32:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:32:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:32:09 INFO - Intel Iris: 21:32:09 INFO - Chipset Model: Intel Iris 21:32:09 INFO - Type: GPU 21:32:09 INFO - Bus: Built-In 21:32:09 INFO - VRAM (Dynamic, Max): 1536 MB 21:32:09 INFO - Vendor: Intel (0x8086) 21:32:09 INFO - Device ID: 0x0a2e 21:32:09 INFO - Revision ID: 0x0009 21:32:09 INFO - Displays: 21:32:09 INFO - Display: 21:32:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:32:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:32:09 INFO - Display Serial Number: 1600x1200 60 21:32:09 INFO - Main Display: Yes 21:32:09 INFO - Mirror: Off 21:32:09 INFO - Online: Yes 21:32:09 INFO - Rotation: Supported 21:32:09 INFO - Return code: 0 21:32:09 INFO - Running post-action listener: _resource_record_post_action 21:32:09 INFO - [mozharness: 2017-04-04 04:32:09.673640Z] Finished run-tests step (success) 21:32:09 INFO - Running post-run listener: _resource_record_post_run 21:32:09 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 39585792; Write bytes: 424650752; Read time: 252; Write time: 1415 21:32:09 INFO - TinderboxPrint: CPU usage
54.3% 21:32:09 INFO - TinderboxPrint: I/O read bytes / time
39,585,792 / 252 21:32:09 INFO - TinderboxPrint: I/O write bytes / time
424,650,752 / 1,415 21:32:09 INFO - TinderboxPrint: CPU idle
2,173.7 (45.7%) 21:32:09 INFO - TinderboxPrint: CPU system
959.6 (20.2%) 21:32:09 INFO - TinderboxPrint: CPU user
1,628.3 (34.2%) 21:32:09 INFO - TinderboxPrint: Swap in / out
161,783,808 / 0 21:32:09 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 111909376; Write bytes: 104202240; Read time: 9958; Write time: 221 21:32:09 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 38664192; Write bytes: 302004224; Read time: 241; Write time: 1136 21:32:10 INFO - Running post-run listener: _upload_blobber_files 21:32:10 INFO - Blob upload gear active. 21:32:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:32:10 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 21:32:10 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:32:10 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:32:10 INFO - (blobuploader) - INFO - Open directory for files ... 21:32:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:32:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:32:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:32:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:32:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:32:11 INFO - (blobuploader) - INFO - Done attempting. 21:32:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:32:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:32:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:32:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:32:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:32:12 INFO - (blobuploader) - INFO - Done attempting. 21:32:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:32:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:32:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:32:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:32:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:32:13 INFO - (blobuploader) - INFO - Done attempting. 21:32:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:32:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:32:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:32:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:32:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:32:15 INFO - (blobuploader) - INFO - Done attempting. 21:32:15 INFO - (blobuploader) - INFO - Iteration through files over. 21:32:15 INFO - Return code: 0 21:32:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:32:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:32:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e33546c0fd43f7b12a564670414f0cf85c08141b11d07f8eb250a636746b196f847048e1a92297c36b79511beabe26087bbb60fa798eb5618eeed90561d2f00", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b7b60e792bbc75fbf765521ebbc9213530887f1549040e992496ec5e9da2aea0d74cb209358a0ab9f40d4347e9b1dc2a59b892042d5920e0d03689d280f60bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/759e878a4af17d833bf98d8be7fc318abd1c6f598db17e13a89a07c781ce85fcc8eba5b22a93a5ef62472670838dc52c5759af3712f46d665e45d15445a6881a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bfc4b0ca653b9ad70b6e71cc7a3dcf39011d903c38f7fe72b307046d70b94dabe8145b70f11e2e60f8b263530091b86dd13c02dbed3979abeb6a27d3ec46fd4d"} 21:32:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:32:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:32:15 INFO - Contents: 21:32:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e33546c0fd43f7b12a564670414f0cf85c08141b11d07f8eb250a636746b196f847048e1a92297c36b79511beabe26087bbb60fa798eb5618eeed90561d2f00", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b7b60e792bbc75fbf765521ebbc9213530887f1549040e992496ec5e9da2aea0d74cb209358a0ab9f40d4347e9b1dc2a59b892042d5920e0d03689d280f60bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/759e878a4af17d833bf98d8be7fc318abd1c6f598db17e13a89a07c781ce85fcc8eba5b22a93a5ef62472670838dc52c5759af3712f46d665e45d15445a6881a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bfc4b0ca653b9ad70b6e71cc7a3dcf39011d903c38f7fe72b307046d70b94dabe8145b70f11e2e60f8b263530091b86dd13c02dbed3979abeb6a27d3ec46fd4d"} 21:32:15 INFO - Running post-run listener: copy_logs_to_upload_dir 21:32:15 INFO - Copying logs to upload dir... 21:32:15 INFO - mkdir: /builds/slave/test/build/upload/logs 21:32:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.997496 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-04-03 21:32:15.334504) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-03 21:32:15.338375) ========= 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.XGQRih8fqD/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.JoFmfO8YwA/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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/Mozilla-Beta/sha512/5e33546c0fd43f7b12a564670414f0cf85c08141b11d07f8eb250a636746b196f847048e1a92297c36b79511beabe26087bbb60fa798eb5618eeed90561d2f00", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b7b60e792bbc75fbf765521ebbc9213530887f1549040e992496ec5e9da2aea0d74cb209358a0ab9f40d4347e9b1dc2a59b892042d5920e0d03689d280f60bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/759e878a4af17d833bf98d8be7fc318abd1c6f598db17e13a89a07c781ce85fcc8eba5b22a93a5ef62472670838dc52c5759af3712f46d665e45d15445a6881a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bfc4b0ca653b9ad70b6e71cc7a3dcf39011d903c38f7fe72b307046d70b94dabe8145b70f11e2e60f8b263530091b86dd13c02dbed3979abeb6a27d3ec46fd4d"} build_url:https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011001 build_url: 'https://queue.taskcluster.net/v1/task/XUdszF01RfK1fKtSJrkr9A/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e33546c0fd43f7b12a564670414f0cf85c08141b11d07f8eb250a636746b196f847048e1a92297c36b79511beabe26087bbb60fa798eb5618eeed90561d2f00", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b7b60e792bbc75fbf765521ebbc9213530887f1549040e992496ec5e9da2aea0d74cb209358a0ab9f40d4347e9b1dc2a59b892042d5920e0d03689d280f60bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/759e878a4af17d833bf98d8be7fc318abd1c6f598db17e13a89a07c781ce85fcc8eba5b22a93a5ef62472670838dc52c5759af3712f46d665e45d15445a6881a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bfc4b0ca653b9ad70b6e71cc7a3dcf39011d903c38f7fe72b307046d70b94dabe8145b70f11e2e60f8b263530091b86dd13c02dbed3979abeb6a27d3ec46fd4d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-03 21:32:15.377195) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:32:15.377984) ========= 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.XGQRih8fqD/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.JoFmfO8YwA/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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.005181 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:32:15.430849) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-03 21:32:15.431196) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-03 21:32:15.431547) ========= ========= Total master_lag: 0.20 =========