builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0014 starttime: 1494013268.55 results: success (0) buildid: 20170505114842 builduid: 99d68c66ed9441a4bc4d3226ad5e2ab1 revision: 93700f9bdc9cb4e278d331049dff765c75771632 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 12:41:08.552582) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 12:41:08.553037) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 12:41:08.553325) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 12:41:08.578769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 12:41:08.579156) ========= 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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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-05-05 12:41:08-- 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% 7.68M=0.002s 2017-05-05 12:41:08 (7.68 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151453 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 12:41:08.756348) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 12:41:08.756873) ========= 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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.019956 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 12:41:08.806094) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-05 12:41:08.806475) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 93700f9bdc9cb4e278d331049dff765c75771632 --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 93700f9bdc9cb4e278d331049dff765c75771632 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hdTSzfkyBw/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.1qks7Tvqh6/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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-05-05 12:41:08,881 truncating revision to first 12 chars 2017-05-05 12:41:08,881 Setting DEBUG logging. 2017-05-05 12:41:08,881 attempt 1/10 2017-05-05 12:41:08,881 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/93700f9bdc9c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-05 12:41:11,646 unpacking tar archive at: mozilla-beta-93700f9bdc9c/testing/mozharness/ program finished with exit code 0 elapsedTime=3.011976 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-05 12:41:11.842800) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 12:41:11.843167) ========= script_repo_revision: 93700f9bdc9cb4e278d331049dff765c75771632 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 12:41:11.843564) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 12:41:11.843830) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 12:41:11.864771) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-05-05 12:41:11.865180) ========= /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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 12:41:12 INFO - MultiFileLogger online at 20170505 12:41:12 in /builds/slave/test 12:41:12 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 12:41:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:41:12 INFO - {'append_to_log': False, 12:41:12 INFO - 'base_work_dir': '/builds/slave/test', 12:41:12 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:41:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:41:12 INFO - 'branch': 'Mozilla-Beta', 12:41:12 INFO - 'buildbot_json_path': 'buildprops.json', 12:41:12 INFO - 'config_files': ('talos/mac_config.py',), 12:41:12 INFO - 'default_actions': ('clobber', 12:41:12 INFO - 'read-buildbot-config', 12:41:12 INFO - 'download-and-extract', 12:41:12 INFO - 'populate-webroot', 12:41:12 INFO - 'create-virtualenv', 12:41:12 INFO - 'install', 12:41:12 INFO - 'run-tests'), 12:41:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:41:12 INFO - 'download_minidump_stackwalk': True, 12:41:12 INFO - 'download_symbols': 'ondemand', 12:41:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:41:12 INFO - 'tooltool.py': '/tools/tooltool.py', 12:41:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:41:12 INFO - '/tools/misc-python/virtualenv.py')}, 12:41:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:41:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:41:12 INFO - 'gecko_profile': False, 12:41:12 INFO - 'gecko_profile_interval': 0, 12:41:12 INFO - 'installer_path': 'installer.exe', 12:41:12 INFO - 'log_level': 'info', 12:41:12 INFO - 'log_name': 'talos', 12:41:12 INFO - 'log_to_console': True, 12:41:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:41:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:41:12 INFO - 'opt_config_files': (), 12:41:12 INFO - 'pip_index': False, 12:41:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:41:12 INFO - 'cmd': ('bash', 12:41:12 INFO - '-c', 12:41:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:41:12 INFO - 'enabled': True, 12:41:12 INFO - 'halt_on_failure': False, 12:41:12 INFO - 'name': 'check_screen_resolution'},), 12:41:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:41:12 INFO - 'cmd': ('bash', 12:41:12 INFO - '-c', 12:41:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:41:12 INFO - 'enabled': True, 12:41:12 INFO - 'halt_on_failure': False, 12:41:12 INFO - 'name': 'check_screen_resolution'},), 12:41:12 INFO - 'run_cmd_checks_enabled': True, 12:41:12 INFO - 'suite': 'dromaeojs-e10s', 12:41:12 INFO - 'system_bits': '32', 12:41:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:41:12 INFO - 'title': 't-yosemite-r7-0014', 12:41:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:41:12 INFO - 'use_talos_json': True, 12:41:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:41:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:41:12 INFO - 'work_dir': 'build'} 12:41:12 INFO - [mozharness: 2017-05-05 19:41:12.069678Z] Running clobber step. 12:41:12 INFO - Running pre-action listener: _resource_record_pre_action 12:41:12 INFO - Running main action method: clobber 12:41:12 INFO - rmtree: /builds/slave/test/build 12:41:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:41:13 INFO - Running post-action listener: _resource_record_post_action 12:41:13 INFO - [mozharness: 2017-05-05 19:41:13.606935Z] Finished clobber step (success) 12:41:13 INFO - [mozharness: 2017-05-05 19:41:13.607160Z] Running read-buildbot-config step. 12:41:13 INFO - Running pre-action listener: _resource_record_pre_action 12:41:13 INFO - Running main action method: read_buildbot_config 12:41:13 INFO - Using buildbot properties: 12:41:13 INFO - { 12:41:13 INFO - "project": "", 12:41:13 INFO - "product": "firefox", 12:41:13 INFO - "script_repo_revision": "production", 12:41:13 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 12:41:13 INFO - "repository": "", 12:41:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 12:41:13 INFO - "buildid": "20170505114842", 12:41:13 INFO - "pgo_build": "False", 12:41:13 INFO - "basedir": "/builds/slave/test", 12:41:13 INFO - "buildnumber": 23, 12:41:13 INFO - "slavename": "t-yosemite-r7-0014", 12:41:13 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:41:13 INFO - "platform": "yosemite_r7", 12:41:13 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:41:13 INFO - "branch": "mozilla-beta", 12:41:13 INFO - "repo_path": "releases/mozilla-beta", 12:41:13 INFO - "revision": "93700f9bdc9cb4e278d331049dff765c75771632", 12:41:13 INFO - "stage_platform": "macosx64", 12:41:13 INFO - "builduid": "99d68c66ed9441a4bc4d3226ad5e2ab1", 12:41:13 INFO - "slavebuilddir": "test" 12:41:13 INFO - } 12:41:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 12:41:13 INFO - Running post-action listener: _resource_record_post_action 12:41:13 INFO - [mozharness: 2017-05-05 19:41:13.612609Z] Finished read-buildbot-config step (success) 12:41:13 INFO - [mozharness: 2017-05-05 19:41:13.612785Z] Running download-and-extract step. 12:41:13 INFO - Running pre-action listener: _resource_record_pre_action 12:41:13 INFO - Running main action method: download_and_extract 12:41:13 INFO - mkdir: /builds/slave/test/build/tests 12:41:13 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')]} 12:41:13 INFO - trying https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 12:41:13 INFO - Downloading https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:41:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 12:41:14 INFO - Downloaded 1287 bytes. 12:41:14 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:41:14 INFO - Using the following test package requirements: 12:41:14 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:41:14 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 12:41:14 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:41:14 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:41:14 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 12:41:14 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:41:14 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 12:41:14 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:41:14 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:41:14 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 12:41:14 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:41:14 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:41:14 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 12:41:14 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:41:14 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 12:41:14 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:41:14 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 12:41:14 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:41:14 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 12:41:14 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 12:41:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:41:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:41:14 INFO - Fetch https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:41:15 INFO - Expected file size: 51227627 12:41:15 INFO - Obtained file size: 51227627 12:41:31 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:41:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:41:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:41:31 INFO - Fetch https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:41:34 INFO - Expected file size: 51227627 12:41:34 INFO - Obtained file size: 51227627 12:41:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 12:41:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 12:41:49 INFO - Fetch https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 12:41:50 INFO - Expected file size: 14404449 12:41:50 INFO - Obtained file size: 14404449 12:41:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:41:50 INFO - trying https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:41:50 INFO - Downloading https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 12:41:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 12:41:52 INFO - Downloaded 59559704 bytes. 12:41:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:41:52 INFO - mkdir: /builds/slave/test/properties 12:41:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:41:52 INFO - Writing to file /builds/slave/test/properties/build_url 12:41:52 INFO - Contents: 12:41:52 INFO - build_url:https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:41:52 INFO - Running post-action listener: _resource_record_post_action 12:41:52 INFO - Running post-action listener: set_extra_try_arguments 12:41:52 INFO - [mozharness: 2017-05-05 19:41:52.911924Z] Finished download-and-extract step (success) 12:41:52 INFO - [mozharness: 2017-05-05 19:41:52.912080Z] Running populate-webroot step. 12:41:52 INFO - Running pre-action listener: _resource_record_pre_action 12:41:52 INFO - Running main action method: populate_webroot 12:41:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:41:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:41:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:41:52 INFO - '--tppagecycles', 12:41:52 INFO - '7'], 12:41:52 INFO - 'tests': ['tsvgm']}}, 12:41:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:41:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:41:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:41:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:41:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:41:52 INFO - 'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:41:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:41:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:41:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:41:52 INFO - 'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['damp', 'tps']}, 12:41:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:41:52 INFO - 'tests': ['damp', 'tps']}, 12:41:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['dromaeo_dom']}, 12:41:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:41:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['basic_compositor_video']}, 12:41:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:41:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['a11yr', 12:41:52 INFO - 'ts_paint', 12:41:52 INFO - 'tpaint', 12:41:52 INFO - 'sessionrestore', 12:41:52 INFO - 'sessionrestore_no_auto_restore', 12:41:52 INFO - 'tabpaint']}, 12:41:52 INFO - 'other-e10s': {'tests': ['a11yr', 12:41:52 INFO - 'ts_paint', 12:41:52 INFO - 'tpaint', 12:41:52 INFO - 'sessionrestore', 12:41:52 INFO - 'sessionrestore_no_auto_restore', 12:41:52 INFO - 'tabpaint']}, 12:41:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:41:52 INFO - 'ts_paint', 12:41:52 INFO - 'tpaint', 12:41:52 INFO - 'sessionrestore', 12:41:52 INFO - 'sessionrestore_no_auto_restore', 12:41:52 INFO - 'tabpaint']}, 12:41:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:41:52 INFO - 'ts_paint', 12:41:52 INFO - 'tpaint', 12:41:52 INFO - 'sessionrestore', 12:41:52 INFO - 'sessionrestore_no_auto_restore', 12:41:52 INFO - 'tabpaint']}, 12:41:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['a11yr', 12:41:52 INFO - 'ts_paint', 12:41:52 INFO - 'tpaint', 12:41:52 INFO - 'sessionrestore', 12:41:52 INFO - 'sessionrestore_no_auto_restore', 12:41:52 INFO - 'tabpaint']}, 12:41:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['a11yr', 12:41:52 INFO - 'ts_paint', 12:41:52 INFO - 'tpaint', 12:41:52 INFO - 'sessionrestore', 12:41:52 INFO - 'sessionrestore_no_auto_restore', 12:41:52 INFO - 'tabpaint']}, 12:41:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['tsvgx', 12:41:52 INFO - 'tsvgr_opacity', 12:41:52 INFO - 'tart', 12:41:52 INFO - 'tscrollx', 12:41:52 INFO - 'cart', 12:41:52 INFO - 'tsvg_static']}, 12:41:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:41:52 INFO - 'tsvgr_opacity', 12:41:52 INFO - 'tart', 12:41:52 INFO - 'tscrollx', 12:41:52 INFO - 'cart', 12:41:52 INFO - 'tsvg_static']}, 12:41:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:41:52 INFO - 'talos_options': ['--disable-e10s'], 12:41:52 INFO - 'tests': ['tp5o']}, 12:41:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:41:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:41:52 INFO - 'talos_options': ['--disable-e10s', 12:41:52 INFO - '--xperf_path', 12:41:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:41:52 INFO - 'tests': ['tp5n']}, 12:41:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:41:52 INFO - 'talos_options': ['--xperf_path', 12:41:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:41:52 INFO - 'tests': ['tp5n']}}, 12:41:52 INFO - 'talos.zip': {'path': '', 12:41:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:41:52 INFO - Running post-action listener: _resource_record_post_action 12:41:52 INFO - [mozharness: 2017-05-05 19:41:52.930502Z] Finished populate-webroot step (success) 12:41:52 INFO - [mozharness: 2017-05-05 19:41:52.930635Z] Running create-virtualenv step. 12:41:52 INFO - Running pre-action listener: _resource_record_pre_action 12:41:52 INFO - Running main action method: create_virtualenv 12:41:52 INFO - Creating virtualenv /builds/slave/test/build/venv 12:41:52 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 12:41:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:41:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:41:53 INFO - Using real prefix '/tools/python27' 12:41:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:41:53 INFO - Installing distribute.............................................................................................................................................................................................done. 12:41:56 INFO - Installing pip.................done. 12:41:56 INFO - Return code: 0 12:41:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:41:56 INFO - Reading from file tmpfile_stdout 12:41:56 INFO - Output received: 12:41:56 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) 12:41:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:41:56 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')]} 12:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:56 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')]} 12:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:56 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 0x10e6ebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd0d604a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e3f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4bebe8>, '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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 12:41:56 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 12:41:56 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 12:41:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdTSzfkyBw/Render', 12:41:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:56 INFO - 'HOME': '/Users/cltbld', 12:41:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:56 INFO - 'LOGNAME': 'cltbld', 12:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:56 INFO - 'MOZ_NO_REMOTE': '1', 12:41:56 INFO - 'NO_EM_RESTART': '1', 12:41:56 INFO - 'PAGER': '/bin/cat', 12:41:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:56 INFO - 'PWD': '/builds/slave/test', 12:41:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:56 INFO - 'SHELL': '/bin/bash', 12:41:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1qks7Tvqh6/Listeners', 12:41:56 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 12:41:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:56 INFO - 'USER': 'cltbld', 12:41:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:56 INFO - 'XPC_FLAGS': '0x0', 12:41:56 INFO - 'XPC_SERVICE_NAME': '0', 12:41:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:57 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 12:41:57 INFO - Cleaning up... 12:41:57 INFO - Return code: 0 12:41:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:41:57 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')]} 12:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:57 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')]} 12:41:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:57 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 0x10e6ebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd0d604a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e3f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4bebe8>, '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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 12:41:57 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 12:41:57 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 12:41:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdTSzfkyBw/Render', 12:41:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:57 INFO - 'HOME': '/Users/cltbld', 12:41:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:57 INFO - 'LOGNAME': 'cltbld', 12:41:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:57 INFO - 'MOZ_NO_REMOTE': '1', 12:41:57 INFO - 'NO_EM_RESTART': '1', 12:41:57 INFO - 'PAGER': '/bin/cat', 12:41:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:57 INFO - 'PWD': '/builds/slave/test', 12:41:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:57 INFO - 'SHELL': '/bin/bash', 12:41:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1qks7Tvqh6/Listeners', 12:41:57 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 12:41:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:57 INFO - 'USER': 'cltbld', 12:41:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:57 INFO - 'XPC_FLAGS': '0x0', 12:41:57 INFO - 'XPC_SERVICE_NAME': '0', 12:41:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:57 INFO - Downloading/unpacking psutil>=3.1.1 12:41:57 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 12:41:57 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 12:41:57 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 12:41:57 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 12:41:57 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 12:41:57 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 12:41:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:41:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:41:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:41:59 INFO - Installing collected packages: psutil 12:41:59 INFO - Running setup.py install for psutil 12:41:59 INFO - building 'psutil._psutil_osx' extension 12:41:59 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 12:42:00 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 12:42:00 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 12:42:00 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 12:42:00 INFO - building 'psutil._psutil_posix' extension 12:42:00 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 12:42:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:42:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:42:00 INFO - ^ 12:42:00 INFO - 1 warning generated. 12:42:00 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 12:42:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:42:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:42:00 INFO - Successfully installed psutil 12:42:00 INFO - Cleaning up... 12:42:00 INFO - Return code: 0 12:42:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:42:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd0d604a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e3f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4bebe8>, '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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 12:42:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:42:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:42:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdTSzfkyBw/Render', 12:42:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:00 INFO - 'HOME': '/Users/cltbld', 12:42:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:00 INFO - 'LOGNAME': 'cltbld', 12:42:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:00 INFO - 'MOZ_NO_REMOTE': '1', 12:42:00 INFO - 'NO_EM_RESTART': '1', 12:42:00 INFO - 'PAGER': '/bin/cat', 12:42:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:00 INFO - 'PWD': '/builds/slave/test', 12:42:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:00 INFO - 'SHELL': '/bin/bash', 12:42:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1qks7Tvqh6/Listeners', 12:42:00 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 12:42:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:00 INFO - 'USER': 'cltbld', 12:42:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:00 INFO - 'XPC_FLAGS': '0x0', 12:42:00 INFO - 'XPC_SERVICE_NAME': '0', 12:42:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:42:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:42:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:42:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:42:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:42:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:42:02 INFO - Installing collected packages: mozsystemmonitor 12:42:02 INFO - Running setup.py install for mozsystemmonitor 12:42:02 INFO - Successfully installed mozsystemmonitor 12:42:02 INFO - Cleaning up... 12:42:02 INFO - Return code: 0 12:42:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:42: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')]} 12:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42: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')]} 12:42:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42: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', '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 0x10e6ebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd0d604a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e3f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4bebe8>, '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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 12:42: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:42: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 jsonschema==2.5.1 12:42:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdTSzfkyBw/Render', 12:42:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:02 INFO - 'HOME': '/Users/cltbld', 12:42:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:02 INFO - 'LOGNAME': 'cltbld', 12:42:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:02 INFO - 'MOZ_NO_REMOTE': '1', 12:42:02 INFO - 'NO_EM_RESTART': '1', 12:42:02 INFO - 'PAGER': '/bin/cat', 12:42:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:02 INFO - 'PWD': '/builds/slave/test', 12:42:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:02 INFO - 'SHELL': '/bin/bash', 12:42:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1qks7Tvqh6/Listeners', 12:42:02 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 12:42:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:02 INFO - 'USER': 'cltbld', 12:42:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:02 INFO - 'XPC_FLAGS': '0x0', 12:42:02 INFO - 'XPC_SERVICE_NAME': '0', 12:42:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:02 INFO - Downloading/unpacking jsonschema==2.5.1 12:42: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 12:42: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 12:42: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 12:42: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 12:42: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 12:42: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 12:42:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:42:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:42:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:42:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:42:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:42:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:42:04 INFO - Downloading functools32-3.2.3-2.tar.gz 12:42:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:42:04 INFO - warning: no files found matching '*.txt' 12:42:04 INFO - no previously-included directories found matching 'build' 12:42:04 INFO - no previously-included directories found matching 'dist' 12:42:04 INFO - no previously-included directories found matching '.git*' 12:42:04 INFO - Installing collected packages: jsonschema, functools32 12:42:04 INFO - Running setup.py install for functools32 12:42:04 INFO - warning: no files found matching '*.txt' 12:42:04 INFO - no previously-included directories found matching 'build' 12:42:04 INFO - no previously-included directories found matching 'dist' 12:42:04 INFO - no previously-included directories found matching '.git*' 12:42:04 INFO - Successfully installed jsonschema functools32 12:42:04 INFO - Cleaning up... 12:42:04 INFO - Return code: 0 12:42:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:42:04 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')]} 12:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:04 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')]} 12:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:05 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 0x10e6ebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd0d604a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e3f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4bebe8>, '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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 12:42:05 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 12:42:05 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 12:42:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdTSzfkyBw/Render', 12:42:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:05 INFO - 'HOME': '/Users/cltbld', 12:42:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:05 INFO - 'LOGNAME': 'cltbld', 12:42:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:05 INFO - 'MOZ_NO_REMOTE': '1', 12:42:05 INFO - 'NO_EM_RESTART': '1', 12:42:05 INFO - 'PAGER': '/bin/cat', 12:42:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:05 INFO - 'PWD': '/builds/slave/test', 12:42:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:05 INFO - 'SHELL': '/bin/bash', 12:42:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1qks7Tvqh6/Listeners', 12:42:05 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 12:42:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:05 INFO - 'USER': 'cltbld', 12:42:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:05 INFO - 'XPC_FLAGS': '0x0', 12:42:05 INFO - 'XPC_SERVICE_NAME': '0', 12:42:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:42:05 INFO - Cleaning up... 12:42:05 INFO - Return code: 0 12:42:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:42:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:06 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')]} 12:42:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:06 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 0x10e6ebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd0d604a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e3f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4bebe8>, '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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 12:42:06 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 12:42:06 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 12:42:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdTSzfkyBw/Render', 12:42:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:06 INFO - 'HOME': '/Users/cltbld', 12:42:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:06 INFO - 'LOGNAME': 'cltbld', 12:42:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:06 INFO - 'MOZ_NO_REMOTE': '1', 12:42:06 INFO - 'NO_EM_RESTART': '1', 12:42:06 INFO - 'PAGER': '/bin/cat', 12:42:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:06 INFO - 'PWD': '/builds/slave/test', 12:42:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:06 INFO - 'SHELL': '/bin/bash', 12:42:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1qks7Tvqh6/Listeners', 12:42:06 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 12:42:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:06 INFO - 'USER': 'cltbld', 12:42:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:06 INFO - 'XPC_FLAGS': '0x0', 12:42:06 INFO - 'XPC_SERVICE_NAME': '0', 12:42:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:06 INFO - Downloading/unpacking blobuploader==1.2.4 12:42:06 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 12:42:06 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 12:42:06 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 12:42:06 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 12:42:06 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 12:42:06 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 12:42:08 INFO - Downloading blobuploader-1.2.4.tar.gz 12:42:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:42:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:42:08 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 12:42:08 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 12:42:08 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 12:42:08 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 12:42:08 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 12:42:08 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 12:42:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:42:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:42:08 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 12:42:08 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 12:42:08 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 12:42:08 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 12:42:08 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 12:42:08 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 12:42:09 INFO - Downloading docopt-0.6.1.tar.gz 12:42:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:42:09 INFO - Installing collected packages: blobuploader, requests, docopt 12:42:09 INFO - Running setup.py install for blobuploader 12:42:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:42:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:42:09 INFO - Running setup.py install for requests 12:42:10 INFO - Running setup.py install for docopt 12:42:10 INFO - Successfully installed blobuploader requests docopt 12:42:10 INFO - Cleaning up... 12:42:10 INFO - Return code: 0 12:42:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:42:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:10 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 0x10e6ebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd0d604a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e3f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4bebe8>, '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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 12:42:10 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 12:42:10 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 12:42:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdTSzfkyBw/Render', 12:42:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:10 INFO - 'HOME': '/Users/cltbld', 12:42:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:10 INFO - 'LOGNAME': 'cltbld', 12:42:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:10 INFO - 'MOZ_NO_REMOTE': '1', 12:42:10 INFO - 'NO_EM_RESTART': '1', 12:42:10 INFO - 'PAGER': '/bin/cat', 12:42:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:10 INFO - 'PWD': '/builds/slave/test', 12:42:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:10 INFO - 'SHELL': '/bin/bash', 12:42:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1qks7Tvqh6/Listeners', 12:42:10 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 12:42:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:10 INFO - 'USER': 'cltbld', 12:42:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:10 INFO - 'XPC_FLAGS': '0x0', 12:42:10 INFO - 'XPC_SERVICE_NAME': '0', 12:42:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:42:10 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-zUH9HQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:42:10 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-lKIsLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:42:10 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-XUgZG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:42:10 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-H5u0lL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:42:10 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-KYmhnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:42:11 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-QVpPWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:42:11 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-dJPWxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:42:11 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-wjtvPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:42:11 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-FP1h0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:42:11 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-6Wt5Jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:42:11 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-jCyCDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:42:11 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-EEP2Io-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:42:11 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Ptz8CL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:42:11 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-5jWCCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:11 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Ss5xtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:42:12 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-I1SuED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:42:12 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-wykPes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:42:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:42:12 INFO - Running setup.py install for manifestparser 12:42:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:42:12 INFO - Running setup.py install for mozcrash 12:42:12 INFO - Running setup.py install for mozdebug 12:42:12 INFO - Running setup.py install for mozdevice 12:42:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:42:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:42:12 INFO - Running setup.py install for mozfile 12:42:12 INFO - Running setup.py install for mozhttpd 12:42:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:42:13 INFO - Running setup.py install for mozinfo 12:42:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:42:13 INFO - Running setup.py install for mozInstall 12:42:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:42:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:42:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:42:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:42:13 INFO - Running setup.py install for mozleak 12:42:13 INFO - Running setup.py install for mozlog 12:42:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:42:13 INFO - Running setup.py install for moznetwork 12:42:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:42:13 INFO - Running setup.py install for mozprocess 12:42:14 INFO - Running setup.py install for mozprofile 12:42:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:42:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:42:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:42:14 INFO - Running setup.py install for mozrunner 12:42:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:42:14 INFO - Running setup.py install for mozscreenshot 12:42:14 INFO - Running setup.py install for moztest 12:42:14 INFO - Running setup.py install for mozversion 12:42:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:42:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:42:14 INFO - Cleaning up... 12:42:14 INFO - Return code: 0 12:42:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:14 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 0x10e6ebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd0d604a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e3f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4bebe8>, '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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 12:42:14 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 12:42:14 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 12:42:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdTSzfkyBw/Render', 12:42:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:14 INFO - 'HOME': '/Users/cltbld', 12:42:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:14 INFO - 'LOGNAME': 'cltbld', 12:42:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:14 INFO - 'MOZ_NO_REMOTE': '1', 12:42:14 INFO - 'NO_EM_RESTART': '1', 12:42:14 INFO - 'PAGER': '/bin/cat', 12:42:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:14 INFO - 'PWD': '/builds/slave/test', 12:42:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:14 INFO - 'SHELL': '/bin/bash', 12:42:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1qks7Tvqh6/Listeners', 12:42:14 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 12:42:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:14 INFO - 'USER': 'cltbld', 12:42:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:14 INFO - 'XPC_FLAGS': '0x0', 12:42:14 INFO - 'XPC_SERVICE_NAME': '0', 12:42:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:42:15 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-FUccdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:42:15 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)) 12:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:42:15 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-SgJejL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:42:15 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)) 12:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:42:15 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-CP6wOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:42:15 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)) 12:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:42:15 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-1f3Ca4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:42:15 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)) 12:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:42:15 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-didC5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:42:15 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)) 12:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:42:15 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-kXN6BP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:42:15 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)) 12:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:42:15 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-XTr6el-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:42:15 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)) 12:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:42:15 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-aFNVLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:42:15 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)) 12:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:42:15 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-XsJ_9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:42:16 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)) 12:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:42:16 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-K1Q0Vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:42:16 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)) 12:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:42:16 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-JXGREY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:42:16 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)) 12:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:42:16 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-dy9WFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:42:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:42:16 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-6LwYgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:42:16 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)) 12:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:42:16 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-IleqwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:42:16 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)) 12:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:16 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-UDdV8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:16 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)) 12:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:42:16 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-AZKiAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:42:16 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)) 12:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:42:16 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-SJkQU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:42:16 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)) 12:42:16 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)) 12:42:16 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)) 12:42:16 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)) 12:42:16 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)) 12:42:16 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)) 12:42:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:42:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:42:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:42:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:42:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:42:18 INFO - Downloading blessings-1.6.tar.gz 12:42:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:42:18 INFO - Installing collected packages: blessings 12:42:18 INFO - Running setup.py install for blessings 12:42:18 INFO - Successfully installed blessings 12:42:18 INFO - Cleaning up... 12:42:18 INFO - Return code: 0 12:42:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:42:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:42:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:42:18 INFO - Reading from file tmpfile_stdout 12:42:18 INFO - Current package versions: 12:42:18 INFO - blessings == 1.6 12:42:18 INFO - blobuploader == 1.2.4 12:42:18 INFO - docopt == 0.6.1 12:42:18 INFO - functools32 == 3.2.3-2 12:42:18 INFO - jsonschema == 2.5.1 12:42:18 INFO - manifestparser == 1.1 12:42:18 INFO - mozInstall == 1.12 12:42:18 INFO - mozcrash == 1.0 12:42:18 INFO - mozdebug == 0.1 12:42:18 INFO - mozdevice == 0.49 12:42:18 INFO - mozfile == 1.2 12:42:18 INFO - mozhttpd == 0.7 12:42:18 INFO - mozinfo == 0.9 12:42:18 INFO - mozleak == 0.1 12:42:18 INFO - mozlog == 3.4 12:42:18 INFO - moznetwork == 0.27 12:42:18 INFO - mozprocess == 0.25 12:42:18 INFO - mozprofile == 0.28 12:42:18 INFO - mozrunner == 6.13 12:42:18 INFO - mozscreenshot == 0.1 12:42:18 INFO - mozsystemmonitor == 0.3 12:42:18 INFO - moztest == 0.8 12:42:18 INFO - mozversion == 1.4 12:42:18 INFO - psutil == 3.1.1 12:42:18 INFO - requests == 1.2.3 12:42:18 INFO - wsgiref == 0.1.2 12:42:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:42: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')]} 12:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42: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')]} 12:42:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42: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 0x10e6ebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd0d604a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e3f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4bebe8>, '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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 12:42: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 12:42: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 12:42:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdTSzfkyBw/Render', 12:42:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:18 INFO - 'HOME': '/Users/cltbld', 12:42:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:18 INFO - 'LOGNAME': 'cltbld', 12:42:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:18 INFO - 'MOZ_NO_REMOTE': '1', 12:42:18 INFO - 'NO_EM_RESTART': '1', 12:42:18 INFO - 'PAGER': '/bin/cat', 12:42:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:18 INFO - 'PWD': '/builds/slave/test', 12:42:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:18 INFO - 'SHELL': '/bin/bash', 12:42:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1qks7Tvqh6/Listeners', 12:42:18 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 12:42:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:18 INFO - 'USER': 'cltbld', 12:42:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:18 INFO - 'XPC_FLAGS': '0x0', 12:42:18 INFO - 'XPC_SERVICE_NAME': '0', 12:42:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:19 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)) 12:42:19 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)) 12:42:19 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)) 12:42:19 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)) 12:42:19 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)) 12:42:19 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)) 12:42:19 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)) 12:42:19 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)) 12:42:19 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)) 12:42:19 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)) 12:42:19 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)) 12:42:19 INFO - Cleaning up... 12:42:19 INFO - Return code: 0 12:42:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:42:19 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')]} 12:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:19 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')]} 12:42:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:19 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 0x10e6ebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6ee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd0d604a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e3f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4bebe8>, '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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 12:42:19 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 12:42:19 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 12:42:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdTSzfkyBw/Render', 12:42:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:19 INFO - 'HOME': '/Users/cltbld', 12:42:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:19 INFO - 'LOGNAME': 'cltbld', 12:42:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:19 INFO - 'MOZ_NO_REMOTE': '1', 12:42:19 INFO - 'NO_EM_RESTART': '1', 12:42:19 INFO - 'PAGER': '/bin/cat', 12:42:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:19 INFO - 'PWD': '/builds/slave/test', 12:42:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:19 INFO - 'SHELL': '/bin/bash', 12:42:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1qks7Tvqh6/Listeners', 12:42:19 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 12:42:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:19 INFO - 'USER': 'cltbld', 12:42:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:19 INFO - 'XPC_FLAGS': '0x0', 12:42:19 INFO - 'XPC_SERVICE_NAME': '0', 12:42:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:42:19 INFO - Cleaning up... 12:42:19 INFO - Return code: 0 12:42:19 INFO - Running post-action listener: _resource_record_post_action 12:42:19 INFO - Running post-action listener: _start_resource_monitoring 12:42:19 INFO - Starting resource monitoring. 12:42:19 INFO - [mozharness: 2017-05-05 19:42:19.468438Z] Finished create-virtualenv step (success) 12:42:19 INFO - [mozharness: 2017-05-05 19:42:19.469436Z] Running install step. 12:42:19 INFO - Running pre-action listener: _resource_record_pre_action 12:42:19 INFO - Running main action method: install 12:42:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:42:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:42:19 INFO - Reading from file tmpfile_stdout 12:42:19 INFO - Detecting whether we're running mozinstall >=1.0... 12:42:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:42:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:42:19 INFO - Reading from file tmpfile_stdout 12:42:19 INFO - Output received: 12:42:19 INFO - Usage: mozinstall [options] installer 12:42:19 INFO - Options: 12:42:19 INFO - -h, --help show this help message and exit 12:42:19 INFO - -d DEST, --destination=DEST 12:42:19 INFO - Directory to install application into. [default: 12:42:19 INFO - "/builds/slave/test"] 12:42:19 INFO - --app=APP Application being installed. [default: firefox] 12:42:19 INFO - mkdir: /builds/slave/test/build/application 12:42:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:42:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:42:34 INFO - Reading from file tmpfile_stdout 12:42:34 INFO - Output received: 12:42:34 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:42:34 INFO - Running post-action listener: _resource_record_post_action 12:42:34 INFO - [mozharness: 2017-05-05 19:42:34.543519Z] Finished install step (success) 12:42:34 INFO - [mozharness: 2017-05-05 19:42:34.543652Z] Running run-tests step. 12:42:34 INFO - Running pre-action listener: _resource_record_pre_action 12:42:34 INFO - Running main action method: run_tests 12:42:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:42:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:42:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:42:34 INFO - 2017-05-05 12:42:34.558 screenresolution[1910:14905] starting screenresolution argv=screenresolution get 12:42:34 INFO - 2017-05-05 12:42:34.561 screenresolution[1910:14905] Display 0: 1600x1200x32@60 12:42:34 INFO - 2017-05-05 12:42:34.567 screenresolution[1911:14907] starting screenresolution argv=screenresolution list 12:42:34 INFO - Available Modes on Display 0 12:42:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:42:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:42:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:42:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:42:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:42:34 INFO - Intel Iris: 12:42:34 INFO - Chipset Model: Intel Iris 12:42:34 INFO - Type: GPU 12:42:34 INFO - Bus: Built-In 12:42:34 INFO - VRAM (Dynamic, Max): 1536 MB 12:42:34 INFO - Vendor: Intel (0x8086) 12:42:34 INFO - Device ID: 0x0a2e 12:42:34 INFO - Revision ID: 0x0009 12:42:34 INFO - Displays: 12:42:34 INFO - Display: 12:42:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:42:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:42:34 INFO - Display Serial Number: 1600x1200 60 12:42:34 INFO - Main Display: Yes 12:42:34 INFO - Mirror: Off 12:42:34 INFO - Online: Yes 12:42:34 INFO - Rotation: Supported 12:42:34 INFO - Return code: 0 12:42:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:42:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:42:34 INFO - Python 2.7.3 12:42:34 INFO - Return code: 0 12:42:34 INFO - grabbing minidump binary from tooltool 12:42: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')]} 12:42: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 0x10e4c9d30>, '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 0x10e3f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4bebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:42: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 12:42: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 12:42: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 12:42:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:42:34 INFO - Return code: 0 12:42:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:42:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:42:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:42:34 INFO - ENV: RUST_BACKTRACE is now 1 12:42:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:42:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:42: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/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--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 12:42: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/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0014 --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 12:42:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdTSzfkyBw/Render', 12:42:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:34 INFO - 'HOME': '/Users/cltbld', 12:42:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:34 INFO - 'LOGNAME': 'cltbld', 12:42:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:42:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:42:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:34 INFO - 'MOZ_NO_REMOTE': '1', 12:42:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:42:34 INFO - 'NO_EM_RESTART': '1', 12:42:34 INFO - 'PAGER': '/bin/cat', 12:42:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:34 INFO - 'PWD': '/builds/slave/test', 12:42:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:42:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:34 INFO - 'RUST_BACKTRACE': '1', 12:42:34 INFO - 'SHELL': '/bin/bash', 12:42:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1qks7Tvqh6/Listeners', 12:42:34 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 12:42:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:34 INFO - 'USER': 'cltbld', 12:42:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:34 INFO - 'XPC_FLAGS': '0x0', 12:42:34 INFO - 'XPC_SERVICE_NAME': '0', 12:42:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42: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/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--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 12:42:35 INFO - mozversion application_buildid: 20170505114842 12:42:35 INFO - mozversion application_changeset: 93700f9bdc9cb4e278d331049dff765c75771632 12:42:35 INFO - mozversion application_display_name: Firefox 12:42:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:42:35 INFO - mozversion application_name: Firefox 12:42:35 INFO - mozversion application_remotingname: firefox 12:42:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:42:35 INFO - mozversion application_vendor: Mozilla 12:42:35 INFO - mozversion application_version: 54.0 12:42:35 INFO - mozversion platform_buildid: 20170505114842 12:42:35 INFO - mozversion platform_changeset: 93700f9bdc9cb4e278d331049dff765c75771632 12:42:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:42:35 INFO - mozversion platform_version: 54.0 12:42:35 INFO - using testdate: 1494013355 12:42:35 INFO - actual date: 1494013355 12:42:35 INFO - starting webserver on 'localhost:52168' 12:42:35 INFO - SUITE-START | Running 2 tests 12:42:35 INFO - TEST-START | dromaeo_css 12:42:35 INFO - operating with platform_type : mac_ 12:42:35 INFO - Initialising browser for dromaeo_css test... 12:42:35 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpny28ke/profile http://localhost:52168/getInfo.html) 12:42:37 INFO - PROCESS | 1921 | 2017-05-05 12:42:37.837 plugin-container[1924:15066] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 12:42:37 INFO - PROCESS | 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:42:37 INFO - PROCESS | 1921 | 2017-05-05 12:42:37.838 plugin-container[1924:15066] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d17, name = 'com.apple.CFPasteboardClient' 12:42:37 INFO - PROCESS | 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:42:37 INFO - PROCESS | 1921 | 2017-05-05 12:42:37.838 plugin-container[1924:15066] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:42:37 INFO - PROCESS | 1921 | __metrics Screen width/height:1600/1200 12:42:37 INFO - PROCESS | 1921 | colorDepth:24 12:42:37 INFO - PROCESS | 1921 | Browser inner width/height: 1024/689 12:42:37 INFO - PROCESS | 1921 | __metrics 12:42:37 ERROR - PROCESS | 1921 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 12:42:38 INFO - TEST-INFO | 1921: exit 0 12:42:38 INFO - Browser initialized. 12:42:38 INFO - Running cycle 1/1 for dromaeo_css test... 12:42:38 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpny28ke/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:42:41 INFO - PROCESS | 1925 | 2017-05-05 12:42:41.022 plugin-container[1926:15194] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 12:42:41 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:42:41 INFO - PROCESS | 1925 | 2017-05-05 12:42:41.023 plugin-container[1926:15194] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 12:42:41 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:42:41 INFO - PROCESS | 1925 | 2017-05-05 12:42:41.023 plugin-container[1926:15194] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:42:50 INFO - PROCESS | 1925 | RSS: Main: 174514176 12:42:50 INFO - PROCESS | 1925 | 12:45:54 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52168/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52168/tests/dromaeo/cssquery-ext.html) 12:45:55 INFO - PROCESS | 1925 | RSS: Main: 193736704 12:45:55 INFO - PROCESS | 1925 | 12:48:57 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52168/tests/dromaeo/cssquery-ext.html (next: http://localhost:52168/tests/dromaeo/cssquery-jquery.html) 12:48:58 INFO - PROCESS | 1925 | RSS: Main: 203333632 12:48:58 INFO - PROCESS | 1925 | 12:52:00 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52168/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52168/tests/dromaeo/cssquery-mootools.html) 12:52:01 INFO - PROCESS | 1925 | RSS: Main: 204820480 12:52:01 INFO - PROCESS | 1925 | 12:55:03 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52168/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52168/tests/dromaeo/cssquery-prototype.html) 12:55:03 INFO - PROCESS | 1925 | RSS: Main: 208277504 12:55:03 INFO - PROCESS | 1925 | 12:58:06 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52168/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52168/tests/dromaeo/cssquery-yui.html) 12:58:07 INFO - PROCESS | 1925 | RSS: Main: 210780160 12:58:07 INFO - PROCESS | 1925 | 13:01:09 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52168/tests/dromaeo/cssquery-yui.html (next: http://localhost:52168/tests/dromaeo/cssquery-dojo.html) 13:01:10 INFO - PROCESS | 1925 | RSS: Main: 206524416 13:01:10 INFO - PROCESS | 1925 | 13:01:10 INFO - PROCESS | 1925 | __start_tp_report 13:01:10 INFO - PROCESS | 1925 | _x_x_mozilla_page_load 13:01:10 INFO - PROCESS | 1925 | _x_x_mozilla_page_load_details 13:01:10 INFO - PROCESS | 1925 | |i|pagename|runs| 13:01:10 INFO - PROCESS | 1925 | |0;dojo.html;3517.067976986101;3588.156783155958;3612;3614.5843228028766;3625.2749450109986;6136.509079273653;6163.876722465548;6169.8766024679535;6186.876262474748;6225.501959843213;6156.076588511714;6218.657973811439;6218.937810621904;6220.84447888803;6233.532485063615;5514.869451762379;5546.084895992157;5567.944320556783;5575.526080283161;5585.804496842611;5601.327840659126;5605.411431799664;5616.803411880585;5620.381758006616;5627.212190293341;4875.268709693557;5059.873503162414;5593.3847276799515;5614.522765564931;5650.74571644277;4532.297493888436;4665.720056796603;5489.752961116759;5520.033994051025;5565.666060036411;5471.7264136793;5583.413741557119;5593;5618.269624948731;5659.915101273484;6203.441690247854;6216.129741836146;6225.8132256032395;6242.063690446424;6247.219097612799;6249.437550620421;6324.588901721373;6332.461740752042;6385.361463853624;6589.374009469093;5602.663840169562;6626.171728533934;6699.866002679968;6702.798916032527;6722.899156512656;6252.280987686474;6283.465905398047;6287.339829317902;6302.086197501337;6303.5902666327;6491.8701625967215;6528.151340325728;6729.865402692017;6874.621895795679;6912.861742765217;6333.113364129025;6353.110564520878;6429.7749578764515;6435.774747883802;6450.096986421905;6605.537612367184;6642.169728783902;6651.135352404156;6659.733610655617;6681.7327306908155;6244.937550624527;6459.870802584016;6476.708548115346;6537.477001839841;6561.835954101186;6327.462165715966;6431.839204019937;6433.678316084177;6450.870982580416;6495.025746138022;4343.717658352197;4871.074495845849;5152.510511501401;5225.529702326733;5311.070562651521;4348.565143485626;4701.965567575128;4733.715977041388;4736.881577960509;4766.046790641885;6154.907676384858;6280.214973128359;6296.275928268308;6300.401461861115;6347.682615869188;5610.635308704921;6381.425671689571;6385.201849768779;6522.152120224436;6552.475801935833;6242.063690446469;6263.592866463665;6283.08895210192;6283.748650054038;6298.149749783721;5918.112283157561;5921.082232254007;5933.6736479493175;5953.702314884238;5965.313988891333;6556.934430655823;6591.901121483277;6746.628935408599;6761.121054262914;6833.863322733518;6666.866662666816;6674.332566743286;6676.298988606321;6680.899786503109;6698.966505167443;6742.561733487308;6840.897386539305;6870.793876183722;6878.27778083314;6895.55178913369;6242.28213755424;6300.936990630035;6439.130717353098;6448.129502517288;6519.576227545194;6527.836804079936;6644.435223005905;6678.499112566674;6706.966465167839;6707.061011458497;6369.649669268235;6420.133282006869;6424.775132870327;6427.935720642734;6430.7749228776765;6196.969015155076;6219.440250377488;6281.15204447412;6353.078803573363;6411.070394792822;6161.784337548164;6249.281332646622;6325.272593651789;6334.746610135543;6356.77751278703;6434.131392261985;6437.839054023687;6458.935410645833;6459.160309159779;6469.514786390945;4991.875203119951;6224.844378890564;6239.656818875005;6247.812565623039;6260.499160067093 13:01:10 INFO - PROCESS | 1925 | |1;ext.html;779.8674225381684;825.4758228524887;853.0147679430258;984.7291994701452;990.7424069741874;38257.234855302915;39110;40363.59636403635;40665.59334406663;40772.59227407725;31490.842545787295;38979.61020389795;39188.60811391885;39274.8036259819;39338.60661393385;8202.712905048309;8355.37334699897;9759.853602195974;11198.776024479506;11429.542818287258;10891.455427228646;11453.59912403062;11534.019608333301;11537.538498460093;11562.728099909004;11518.596849110283;11520.539178432853;11525.423728813568;11532.942335288311;11544;11416.086713062936;11440.084793216563;11469.541218351256;11471.139664525193;11475.081993440504;11184.720381990433;11223.943880280627;11244.26912250701;11249.43752812356;11270.661880143609;35154.82422587871;35184.64815351839;35271.765488208046;35361.46957795635;35419.4687079694;3900.1614652849776;3919.573933337309;3948.2300951314505;3966.504186976628;4024.7383920045104;25222.991080356765;25820.096296629716;26193.869030654914;26500.93996240148;26595.069172578867;3171.5084161954933;3237.190702324419;3256.3324518473655;3283.589551306087;3293.5389045533643;277289.99999999796;363847;364220;423152;428741;45360.3195952061;45430.72846357669;45560.54439455563;45608.54391456043;45622.543774562495;46935.295970560466;47065;47150.76424617855;47175.5282447178;47235.05529889383;14529.128252304685;14577.562673119823;14638.048526845721;14644.341004654816;14693.26533673312;12534.623961281177;12723.68190795219;12728.554500592434;12743.617691469271;12752.043596730096;11475.368854712904;12134.453949572291;12138.817917731241;12210.694732631755;12239.143259971717;5939.792107276225;5943.732532036069;5948.553858460633;5981.132735753292;6015.578909476382;1142.9085673146294;1161.4773351991469;1167.177140116216;1177.6879127031343;1208.975820483603;24200.51598968011;26633;26870.92516299365;27647.723522764918;27810.16569502918;910.1990248581338;929.1034152043311;933.8692583038244;944.2304521814699;1305.2103477396038;9925.801483970281;10450.843237351446;10453.738656533495;10505.737356565995;10561.683149505527;440942;443139;444018;444145;445555;49408;49427.752861236186;49589.75205123951;49697.75151124221;49743.50256497461;1455.8835293176521;1483.243545791654;1485.0644094220572;1492.455253832365;1494.880409567232;17061.914690426467;19494.707579386602;19590.412287631392;19866.205351786346;19945.900270498554;42376;42588.5741142597;42750.71747847445;42917;43004.784976074916;452735;457555;457869;458097;458355;30580.694193058676;30820;31082;31844.68155318417;33118.503222452164;41056;41304.38043429411;41460.792696036326;42306.25009374381;44265.557344427434;25752.742472575035;28262.71737282601;29192.416151677273;29896.25259368533;30165.849170754005;29407.97072102466;29928.70071299259;30053.849730751208;30181.547276790425;30414.239644009078;28170.85914570414;33654.15864603405;34142;35054.29891402209;35140.64859351476;26598.202053938414;26798;29555;29835.70164298416;30561.54157687681;21874.687518748928;22771.65842512397;23748.168814091423;24473.38816529601;24481.632775508024 13:01:10 INFO - PROCESS | 1925 | |2;jquery.html;400.8657099871543;429.29595463439944;443.12040599410165;443.1673600091493;451.1811062920801;19894.90052549739;20001.799982000175;20092.799072009293;21543.461413464673;22175.11299548018;19228.519287017833;22630.54738905225;23166.536669266603;23571.76428235717;23706.76293237067;6718.596884186964;6753.932460675404;6965.094537710091;7227.602481863495;7250.564966102024;7052.118485189351;7101.538400003983;7130.821729456779;7133.393661538749;7182.497225194238;6762.884124119539;7023.227444981048;7111.217766045756;7116.786496405116;7157.14114306284;6625.93374066258;6728.226253980757;7043.859122817541;7054.788356349292;7101.893471597904;6324.652144132071;6852;6853.211880633691;6861.833488307;6870.828229294258;26219;26527.867360663266;26808.525531095784;26873.731262687317;26883.865580672165;2927.4291513154944;3044.2845931206148;3059.281068948795;3065.7700672449655;3069.8227334708695;16562.66874662512;16864;16969.745453818203;17341.479755607354;17801.821981780147;1870.2612468075033;2065.8760474371584;2099.296735593578;2107.5784843031456;2139.668351405535;158701;159840;166120;166953;168660.15669921695;17076;20661;21015.8949205253;21127.83796891155;21343.46641333979;25345;32258.516122258185;32260.83869580637;32273.031809045766;32453.675463245538;10647.627333043307;10836.891631083747;11032.007119359296;11062;11221.60724374643;9138.451692898447;9179.403338782959;9202.63189472427;9326.580303886205;9382;9480.525973701288;9607.279454040836;9639.084286992724;9731.978142295098;9772.9022709772;3928.155446578999;3983.382575700771;3998.3402738547816;4016.216837716617;4017.819198136091;837.6481877611296;858.0261403307167;867.1805144138855;953.9189168920719;959.2278216036158;16663.83336166647;19144.521386965436;19732;22049.33851984443;23477.647835282143;1140.2759247877657;1144.6680462665734;1152.1358980764426;1181.976360472803;1196.3599474281218;10991.395473249047;11694;11709.941450292694;11731.35477548726;11759.412029398496;477642;818281;820313;824014;832002;125096;126835;154514;160466.7906641884;171942;1102.6747109602686;1160.8955194032408;1319.7558451686468;1348.9595312140652;1372.1766939836018;18073;18943.526411839815;20094.799052009294;21327.78672213258;21409.464763381042;52001.479985199665;56291.71854140867;66440;68276.317236827;68568.62862742817;96428.51785741026;96522.55216171904;96544.03455965631;97494;98983;10091.646792362231;11887.702807429883;12231.75536489283;12600.747985040065;12637.87362126367;13595.86404135946;13628.523001694894;13684.794728078889;13996.860031399963;14031.719365612427;11487.023602993839;11918.642440726446;11942.343171125323;12055.21641093326;12089;10819.404932728461;10882.40146791934;10888.61889833852;11114.944425277821;11224.7755044897;10608.886066963167;11869.881301187223;12121.272723636257;12161.574344897886;12186.14696971239;11623.186376953532;12190.152801358376;12199.51201951912;12450.25298482094;12556.49774009066;11772.823407649063;12007.219530730474;12026.819597705866;12095.516179352728;12101.69745756363 13:01:10 INFO - PROCESS | 1925 | |3;mootools.html;1610.339760698114;1659.796647430613;1672.4480921295974;1674.3218996306493;1684.15792103948;11077.667669969904;11345.319280843156;11697.18119731617;11793.941030294858;11812;11493.138014648894;11627.47676354564;11688.532458701642;11696.649100526998;11730.35483048432;2388.7491813359607;2440.7315195328542;2479.950400991979;2489.5020995800915;2490.2778194323714;2431.465077682907;2455.042533411971;2482.056818409011;2492.9002839886384;2494.5135698538793;2444.1201167579657;2454.288256405649;2465.3097132802845;2475.665785119004;2476.0838489758817;2414.4929564791414;2421.2736179146186;2439.9878000609974;2447.3881529617597;2460.5817011107974;2426.211481268595;2435.512897420523;2440.2679196241056;2449.2284930246915;2453.914113006036;11280.266782659182;11467.942660286728;11471.254368466107;11477.196596238267;11502.194846360759;5948.970255148696;6035.185249991239;6084.939150608481;6107.4197951194565;6136.846578835521;3662.706983441345;3710.628937106295;3749.550053993511;3785.072657199004;3853.094522787143;1264.20986883198;1661.2358315175034;1911.3692481481078;1946.7274581558452;1973.200853654258;14168.858311416858;14457.204853733041;14938.925305373403;16059.277332520065;16107.19464026794;4755.83354582588;4796.112719146969;4808.158572249842;4926.75366231687;4940.654154209243;12242.387880605935;12284.570040048555;12295.4467048983;12304.507819687113;12316.322602256962;5961.910571341434;6056.3035250946705;6059.515238780887;6060.878782424327;6077.817665470043;5398.487143721419;5527;5530.838523910024;5531.668099914018;5554;3910.530736311604;4041.2927737645796;4042.4744783178585;4080.9795951020646;4139.5653456387245;1060.3425875956802;1069.4919913041242;1069.8555694981078;1074.7152004718755;1076.3864597179688;940.8353538130937;956.550421301987;965.2374624046925;982.3025651787168;1004.7387679203314;6698.832529186712;6830.214525329551;6859.622720750307;6869.244383117853;6927.272636373209;960.841461158915;981.2003217377815;1026.5483187397522;1029.351508549609;1032.607609108534;4226.725262857904;4279.272523670983;4378.321360189176;4417.160739459492;4495.707778994355;12253.632391028104;14130.71738565223;14704.926475367554;14716;14740.926295368454;12089.637310880687;13132.671683207996;13548.254845983565;14523.346449409803;14561.344739486749;8465.492070475791;10333.844992325121;10627.734306642396;10644.520996555173;10686.358818470917;6072.544559157993;6184.381561843779;7152.713891444284;7316.268373162641;7331.04696389466;12059.336736479227;12111.939440302742;12323.01415886709;12340.938295308466;12344.444499997342;12390.5663301784;12581.182223155465;12755.979521638432;12849.807252891402;12955.611331660066;9371.953140234255;9535.094166054214;9586.66446674363;9614.03859614033;9675.612975480903;10505.31715438494;10522.000409961041;10609.522571484147;10645.946770266099;10649.680509584725;8986.236169925369;9118.544072796467;9141.542922853963;9199.632014719336;9343.71968840936;7833.529988200727;8920;8966.641334346814;8981.281497480317;9055.23030542385;8137.389695772959;8275.710350137717;8280.917190828255;8300.12848650883;8330.666773329;6044;6065.150878876872;6100.328963814066;6168.753249869955;6285.59143655661;5918.319393269829;6074.757009719562;6140.846478838065;6208.099825525183;6240.812775616739 13:01:10 INFO - PROCESS | 1925 | |4;prototype.html;393.7420989252041;396.4726913205435;398.26718837339297;399.2055808940209;405.11482410932115;4541.955350269436;5047.798088076473;5566.359868615111;5602.691851948146;5625.746841392137;4954.504549545053;5158.716270605115;5176.715280659562;5186.340371081255;5396.64921780084;3841.8079096045226;3885.825137868803;4047.7166598338126;4225.345071513912;4229.238737027334;3707.3326801175776;4216.789160541976;4231.238377092138;4281.978590107045;4308.3106702927535;3506.8772592959253;3669.3578623740873;4120.670346372283;4274.503923626721;4288;4383.780810959455;4414.97792511037;4421.248387774086;4454.398656181406;4478.39541661878;4089.3661482470266;4097.487814023247;4108.465899433085;4118.608732170439;4118.91762164758;4848.757562121879;5170.095233334151;5173.2669555698885;5348.679079255218;5432.076546987022;5102.464241254652;5125.846224613268;5169.836786722957;5171.146760784466;5237.3453318335205;5073.6955782652785;5390.62539052543;5542.584306177053;5865.560082993792;5910.34986151523;4024.7987600619854;4209.98960249541;4466.754328511931;4620.204776110472;4652.209124448852;4841.951580484185;5040.874657712206;5217.321748172713;5967.134765459027;6054.545909056838;4337.587929146789;4409.867703968822;4576.633869290449;4760;4787.2101103318255;4016.3975403689096;4383.276759334674;4642.792873852823;4829.227323628203;4858.076965376567;4678.60231880294;4787.832425865078;4915.4347250065985;5059.873503162376;5207.0994087157915;4868.95131048692;5167.534921856975;5671.262735844396;5728.77084916607;5855.560832937546;1414.7382734194207;1414.8797352225176;1446.8119144511147;1485.970280594382;1637.2059551117698;1127.5433449452985;1223.3944197622056;1238.5912648825865;1238.7027113492877;1251.7559075980278;4915.508449155127;5054.873628159326;5253.318937939823;5326.242340027781;5554.555635549146;5395.435323756067;5695.345035320991;5712.65724056558;5807.216025836458;6078.726457309432;5147.56245719118;5316.388615309212;5411.594130440233;5502.752376143083;5559.415566563591;2771.9307017324727;2832.9008484702936;2839.4463079699262;3136.9372612547622;3158.3525377297597;5680.829575112753;5869.354371019184;6164.599301045418;6187.783427580013;6258.153844615849;5244.348030370421;6252.280258087746;6292.811215663537;6318.336574659686;6359.236891573041;5356.866078348073;5406.999705054577;5655.429402038906;6178.135061091541;6446.968485042371;5367.543758780547;5375.435579264121;5659.971700141473;5715.714214289352;6059.848503787441;5237.450067742984;5807.593468457337;6020.2842189975645;6073.331933487401;6084.482818960438;5926.348101708895;6009.639421634716;6036.396360363929;6214.658193799384;6290.339514350927;4657.86026419208;4904.8773780655765;5079.7300674831295;5242.348660172033;5264.657797243167;5461.290032295776;5722.71091563378;5908.940910590839;6024.909626355696;6141.754329826936;5200.635955483232;5308.83256643533;5821.912671309849;5848.883022339444;5876.471117599436;5624.437556244343;5719.5138413235345;5741.971290143522;5842.211301474246;5924.851878703067;4346.066405176556;4968.403791545038;5328.626996110235;5525.888553088457;5770.913436298375;5642.322921249476;5782.624129431573;5794.971025144847;5889.234399528034;5892.322382926019;4787.880302992453;5454.836354909359;5751.964646363695;5771.384012476513;5779.7110144493445 13:01:10 INFO - PROCESS | 1925 | |5;yui.html;713.3829237709383;719.5574721546249;747.2938073520526;770.6069904348781;790.7351037402468;16374.918125409371;16739.246733896958;17265.309387624497;17501.124943752824;17560.736588951175;13967.231802250872;16139.193040347995;16234.944728592633;16313.673726525492;16355.83644163558;1008.8234558952191;1019.0980981831059;1029.4646783672486;1030.3766221436;1033.1837848099992;910.9544522773868;945.6571668231094;1016.1362841584631;1018.251585084962;1056.0179033498844;1080.8594882665282;1081.4214395298518;1081.507913899174;1082.832161015044;1083.6099004358425;1072.554889720765;1072.8068947589431;1073.0128281980558;1074.505727365413;1074.5809134437575;792.73839632921;798.3254150243035;798.4610387988084;801.783518450021;802.1577343789056;14420.495282665057;16090.517284481484;16326.673466530721;16340.83659163405;16941.98348099118;11558.653240402802;13682.726345473135;13923.094998825043;14029.85970140306;14060.437582496688;10281.383117012929;10400.47997600117;11990.760184796343;12019.63941081769;12433.191842530297;3812.7140464464997;3837.1174629835295;3843.5195600549932;3848.1534062506203;3903.4535165076627;93998.0600194003;115536;121215.3939230307;123427;124035;10143.695689129338;11473.909978552025;11547.711307217218;11669.299842009506;11941.283522988648;22981.08075676954;23154.65268020981;23883.28350149498;23898.64152037721;23935.401614959417;8262.958685206535;8293.751187464386;8312.916870831214;8327.20891515305;8389.874151887727;4026.6979976501293;4053.7365071270274;4637.976810115928;4655.51117132703;4910.90178196434;3443.328550932594;3512.754107212522;3516.24400753834;3526.347625689256;3532.7703699259464;597.6712807955607;602.3915844996498;619.7582942652369;624.7407325959675;627.297426881886;708.7661071846278;729.5768454296496;743.2159072178903;759.3621358059146;777.6034222546542;10201.13290370312;10503;10816.188785841094;11050.723731906606;11303.491342889589;695.2803848017278;704.1831475488409;731.912170539528;759.472166844038;762.5653377574729;4451.732896026248;5189.922151167735;5300.867478313073;5303.337082864642;5340.332458442695;98013;106522;108361;109880.4505977465;109907;94534.52732736293;95400.52299738457;96222;97131;99613;68043.63912721717;69620.99999999898;79106.60446697845;83967.80160992048;85170.29659406777;10101;10194.286399951932;10263.7434064149;10283.07452329294;10308.639197628047;55190;55661.886762265334;55738;56519.43480565141;58054.12918806306;71232.64383678048;71571;72008;73152;73519.63240183765;11561;11561.364124973206;11624.483970244983;11690.240134391577;11937.880621193677;13003.483075823746;13466.79799802984;13497.662558436117;13508.32458377097;13540.390682419118;12308.913642114592;12792.296423696785;12896.29070401137;12960.805587916;13165.868341316464;12575.748485030064;12755.93622031884;13761.31193440304;13906;14174.14955102697;13249.271290079138;13769.586912392644;13835.79246311286;13979.790303145664;14013.789793152908;9493.525323733924;10131.64539241123;10266.538005789907;10393.792124157324;10621.309614875008;9308.48803315824;10116.342437741523;10959.958803913616;11531.159842743135;11562.94218528902 13:01:10 INFO - PROCESS | 1925 | __end_tp_report 13:01:10 INFO - PROCESS | 1925 | __start_cc_report 13:01:10 INFO - PROCESS | 1925 | _x_x_mozilla_cycle_collect,400 13:01:10 INFO - PROCESS | 1925 | __end_cc_report 13:01:10 INFO - PROCESS | 1925 | __startTimestamp1494014470099__endTimestamp 13:01:10 INFO - PROCESS | 1925 | 13:01:10 INFO - PROCESS | 1925 | ------- Summary: start ------- 13:01:10 INFO - PROCESS | 1925 | Number of tests: 6 13:01:10 INFO - PROCESS | 1925 | 13:01:10 INFO - PROCESS | 1925 | [#0] dojo.html Cycles:165 Average:6071.78 Median:6283.75 stddev:705.41 (11.2%) stddev-sans-first:678.51 13:01:10 INFO - PROCESS | 1925 | Values: 3517.1 3588.2 3612.0 3614.6 3625.3 6136.5 6163.9 6169.9 6186.9 6225.5 6156.1 6218.7 6218.9 6220.8 6233.5 5514.9 5546.1 5567.9 5575.5 5585.8 5601.3 5605.4 5616.8 5620.4 5627.2 4875.3 5059.9 5593.4 5614.5 5650.7 4532.3 4665.7 5489.8 5520.0 5565.7 5471.7 5583.4 5593.0 5618.3 5659.9 6203.4 6216.1 6225.8 6242.1 6247.2 6249.4 6324.6 6332.5 6385.4 6589.4 5602.7 6626.2 6699.9 6702.8 6722.9 6252.3 6283.5 6287.3 6302.1 6303.6 6491.9 6528.2 6729.9 6874.6 6912.9 6333.1 6353.1 6429.8 6435.8 6450.1 6605.5 6642.2 6651.1 6659.7 6681.7 6244.9 6459.9 6476.7 6537.5 6561.8 6327.5 6431.8 6433.7 6450.9 6495.0 4343.7 4871.1 5152.5 5225.5 5311.1 4348.6 4702.0 4733.7 4736.9 4766.0 6154.9 6280.2 6296.3 6300.4 6347.7 5610.6 6381.4 6385.2 6522.2 6552.5 6242.1 6263.6 6283.1 6283.7 6298.1 5918.1 5921.1 5933.7 5953.7 5965.3 6556.9 6591.9 6746.6 6761.1 6833.9 6666.9 6674.3 6676.3 6680.9 6699.0 6742.6 6840.9 6870.8 6878.3 6895.6 6242.3 6300.9 6439.1 6448.1 6519.6 6527.8 6644.4 6678.5 6707.0 6707.1 6369.6 6420.1 6424.8 6427.9 6430.8 6197.0 6219.4 6281.2 6353.1 6411.1 6161.8 6249.3 6325.3 6334.7 6356.8 6434.1 6437.8 6458.9 6459.2 6469.5 4991.9 6224.8 6239.7 6247.8 6260.5 13:01:10 INFO - PROCESS | 1925 | 13:01:10 INFO - PROCESS | 1925 | [#1] ext.html Cycles:180 Average:55054.34 Median:30951.00 stddev:113386.03 (366.3%) stddev-sans-first:113630.88 13:01:10 INFO - PROCESS | 1925 | Values: 779.9 825.5 853.0 984.7 990.7 38257.2 39110.0 40363.6 40665.6 40772.6 31490.8 38979.6 39188.6 39274.8 39338.6 8202.7 8355.4 9759.9 11198.8 11429.5 10891.5 11453.6 11534.0 11537.5 11562.7 11518.6 11520.5 11525.4 11532.9 11544.0 11416.1 11440.1 11469.5 11471.1 11475.1 11184.7 11223.9 11244.3 11249.4 11270.7 35154.8 35184.6 35271.8 35361.5 35419.5 3900.2 3919.6 3948.2 3966.5 4024.7 25223.0 25820.1 26193.9 26500.9 26595.1 3171.5 3237.2 3256.3 3283.6 3293.5 277290.0 363847.0 364220.0 423152.0 428741.0 45360.3 45430.7 45560.5 45608.5 45622.5 46935.3 47065.0 47150.8 47175.5 47235.1 14529.1 14577.6 14638.0 14644.3 14693.3 12534.6 12723.7 12728.6 12743.6 12752.0 11475.4 12134.5 12138.8 12210.7 12239.1 5939.8 5943.7 5948.6 5981.1 6015.6 1142.9 1161.5 1167.2 1177.7 1209.0 24200.5 26633.0 26870.9 27647.7 27810.2 910.2 929.1 933.9 944.2 1305.2 9925.8 10450.8 10453.7 10505.7 10561.7 440942.0 443139.0 444018.0 444145.0 445555.0 49408.0 49427.8 49589.8 49697.8 49743.5 1455.9 1483.2 1485.1 1492.5 1494.9 17061.9 19494.7 19590.4 19866.2 19945.9 42376.0 42588.6 42750.7 42917.0 43004.8 452735.0 457555.0 457869.0 458097.0 458355.0 30580.7 30820.0 31082.0 31844.7 33118.5 41056.0 41304.4 41460.8 42306.3 44265.6 25752.7 28262.7 29192.4 29896.3 30165.8 29408.0 29928.7 30053.8 30181.5 30414.2 28170.9 33654.2 34142.0 35054.3 35140.6 26598.2 26798.0 29555.0 29835.7 30561.5 21874.7 22771.7 23748.2 24473.4 24481.6 13:01:10 INFO - PROCESS | 1925 | 13:01:10 INFO - PROCESS | 1925 | [#2] jquery.html Cycles:180 Average:43825.64 Median:11774.57 stddev:128113.71 (1088.1%) stddev-sans-first:128431.61 13:01:10 INFO - PROCESS | 1925 | Values: 400.9 429.3 443.1 443.2 451.2 19894.9 20001.8 20092.8 21543.5 22175.1 19228.5 22630.5 23166.5 23571.8 23706.8 6718.6 6753.9 6965.1 7227.6 7250.6 7052.1 7101.5 7130.8 7133.4 7182.5 6762.9 7023.2 7111.2 7116.8 7157.1 6625.9 6728.2 7043.9 7054.8 7101.9 6324.7 6852.0 6853.2 6861.8 6870.8 26219.0 26527.9 26808.5 26873.7 26883.9 2927.4 3044.3 3059.3 3065.8 3069.8 16562.7 16864.0 16969.7 17341.5 17801.8 1870.3 2065.9 2099.3 2107.6 2139.7 158701.0 159840.0 166120.0 166953.0 168660.2 17076.0 20661.0 21015.9 21127.8 21343.5 25345.0 32258.5 32260.8 32273.0 32453.7 10647.6 10836.9 11032.0 11062.0 11221.6 9138.5 9179.4 9202.6 9326.6 9382.0 9480.5 9607.3 9639.1 9732.0 9772.9 3928.2 3983.4 3998.3 4016.2 4017.8 837.6 858.0 867.2 953.9 959.2 16663.8 19144.5 19732.0 22049.3 23477.6 1140.3 1144.7 1152.1 1182.0 1196.4 10991.4 11694.0 11709.9 11731.4 11759.4 477642.0 818281.0 820313.0 824014.0 832002.0 125096.0 126835.0 154514.0 160466.8 171942.0 1102.7 1160.9 1319.8 1349.0 1372.2 18073.0 18943.5 20094.8 21327.8 21409.5 52001.5 56291.7 66440.0 68276.3 68568.6 96428.5 96522.6 96544.0 97494.0 98983.0 10091.6 11887.7 12231.8 12600.7 12637.9 13595.9 13628.5 13684.8 13996.9 14031.7 11487.0 11918.6 11942.3 12055.2 12089.0 10819.4 10882.4 10888.6 11114.9 11224.8 10608.9 11869.9 12121.3 12161.6 12186.1 11623.2 12190.2 12199.5 12450.3 12556.5 11772.8 12007.2 12026.8 12095.5 12101.7 13:01:10 INFO - PROCESS | 1925 | 13:01:10 INFO - PROCESS | 1925 | [#3] mootools.html Cycles:180 Average:6955.53 Median:2475.87 stddev:4280.46 (172.9%) stddev-sans-first:4273.63 13:01:10 INFO - PROCESS | 1925 | Values: 1610.3 1659.8 1672.4 1674.3 1684.2 11077.7 11345.3 11697.2 11793.9 11812.0 11493.1 11627.5 11688.5 11696.6 11730.4 2388.7 2440.7 2480.0 2489.5 2490.3 2431.5 2455.0 2482.1 2492.9 2494.5 2444.1 2454.3 2465.3 2475.7 2476.1 2414.5 2421.3 2440.0 2447.4 2460.6 2426.2 2435.5 2440.3 2449.2 2453.9 11280.3 11467.9 11471.3 11477.2 11502.2 5949.0 6035.2 6084.9 6107.4 6136.8 3662.7 3710.6 3749.6 3785.1 3853.1 1264.2 1661.2 1911.4 1946.7 1973.2 14168.9 14457.2 14938.9 16059.3 16107.2 4755.8 4796.1 4808.2 4926.8 4940.7 12242.4 12284.6 12295.4 12304.5 12316.3 5961.9 6056.3 6059.5 6060.9 6077.8 5398.5 5527.0 5530.8 5531.7 5554.0 3910.5 4041.3 4042.5 4081.0 4139.6 1060.3 1069.5 1069.9 1074.7 1076.4 940.8 956.6 965.2 982.3 1004.7 6698.8 6830.2 6859.6 6869.2 6927.3 960.8 981.2 1026.5 1029.4 1032.6 4226.7 4279.3 4378.3 4417.2 4495.7 12253.6 14130.7 14704.9 14716.0 14740.9 12089.6 13132.7 13548.3 14523.3 14561.3 8465.5 10333.8 10627.7 10644.5 10686.4 6072.5 6184.4 7152.7 7316.3 7331.0 12059.3 12111.9 12323.0 12340.9 12344.4 12390.6 12581.2 12756.0 12849.8 12955.6 9372.0 9535.1 9586.7 9614.0 9675.6 10505.3 10522.0 10609.5 10645.9 10649.7 8986.2 9118.5 9141.5 9199.6 9343.7 7833.5 8920.0 8966.6 8981.3 9055.2 8137.4 8275.7 8280.9 8300.1 8330.7 6044.0 6065.2 6100.3 6168.8 6285.6 5918.3 6074.8 6140.8 6208.1 6240.8 13:01:10 INFO - PROCESS | 1925 | 13:01:10 INFO - PROCESS | 1925 | [#4] prototype.html Cycles:180 Average:4807.11 Median:5193.49 stddev:1371.06 (26.4%) stddev-sans-first:1334.29 13:01:10 INFO - PROCESS | 1925 | Values: 393.7 396.5 398.3 399.2 405.1 4542.0 5047.8 5566.4 5602.7 5625.7 4954.5 5158.7 5176.7 5186.3 5396.6 3841.8 3885.8 4047.7 4225.3 4229.2 3707.3 4216.8 4231.2 4282.0 4308.3 3506.9 3669.4 4120.7 4274.5 4288.0 4383.8 4415.0 4421.2 4454.4 4478.4 4089.4 4097.5 4108.5 4118.6 4118.9 4848.8 5170.1 5173.3 5348.7 5432.1 5102.5 5125.8 5169.8 5171.1 5237.3 5073.7 5390.6 5542.6 5865.6 5910.3 4024.8 4210.0 4466.8 4620.2 4652.2 4842.0 5040.9 5217.3 5967.1 6054.5 4337.6 4409.9 4576.6 4760.0 4787.2 4016.4 4383.3 4642.8 4829.2 4858.1 4678.6 4787.8 4915.4 5059.9 5207.1 4869.0 5167.5 5671.3 5728.8 5855.6 1414.7 1414.9 1446.8 1486.0 1637.2 1127.5 1223.4 1238.6 1238.7 1251.8 4915.5 5054.9 5253.3 5326.2 5554.6 5395.4 5695.3 5712.7 5807.2 6078.7 5147.6 5316.4 5411.6 5502.8 5559.4 2771.9 2832.9 2839.4 3136.9 3158.4 5680.8 5869.4 6164.6 6187.8 6258.2 5244.3 6252.3 6292.8 6318.3 6359.2 5356.9 5407.0 5655.4 6178.1 6447.0 5367.5 5375.4 5660.0 5715.7 6059.8 5237.5 5807.6 6020.3 6073.3 6084.5 5926.3 6009.6 6036.4 6214.7 6290.3 4657.9 4904.9 5079.7 5242.3 5264.7 5461.3 5722.7 5908.9 6024.9 6141.8 5200.6 5308.8 5821.9 5848.9 5876.5 5624.4 5719.5 5742.0 5842.2 5924.9 4346.1 4968.4 5328.6 5525.9 5770.9 5642.3 5782.6 5795.0 5889.2 5892.3 4787.9 5454.8 5752.0 5771.4 5779.7 13:01:10 INFO - PROCESS | 1925 | 13:01:10 INFO - PROCESS | 1925 | [#5] yui.html Cycles:180 Average:21478.56 Median:16333.76 stddev:31369.91 (192.1%) stddev-sans-first:31419.16 13:01:10 INFO - PROCESS | 1925 | Values: 713.4 719.6 747.3 770.6 790.7 16374.9 16739.2 17265.3 17501.1 17560.7 13967.2 16139.2 16234.9 16313.7 16355.8 1008.8 1019.1 1029.5 1030.4 1033.2 911.0 945.7 1016.1 1018.3 1056.0 1080.9 1081.4 1081.5 1082.8 1083.6 1072.6 1072.8 1073.0 1074.5 1074.6 792.7 798.3 798.5 801.8 802.2 14420.5 16090.5 16326.7 16340.8 16942.0 11558.7 13682.7 13923.1 14029.9 14060.4 10281.4 10400.5 11990.8 12019.6 12433.2 3812.7 3837.1 3843.5 3848.2 3903.5 93998.1 115536.0 121215.4 123427.0 124035.0 10143.7 11473.9 11547.7 11669.3 11941.3 22981.1 23154.7 23883.3 23898.6 23935.4 8263.0 8293.8 8312.9 8327.2 8389.9 4026.7 4053.7 4638.0 4655.5 4910.9 3443.3 3512.8 3516.2 3526.3 3532.8 597.7 602.4 619.8 624.7 627.3 708.8 729.6 743.2 759.4 777.6 10201.1 10503.0 10816.2 11050.7 11303.5 695.3 704.2 731.9 759.5 762.6 4451.7 5189.9 5300.9 5303.3 5340.3 98013.0 106522.0 108361.0 109880.5 109907.0 94534.5 95400.5 96222.0 97131.0 99613.0 68043.6 69621.0 79106.6 83967.8 85170.3 10101.0 10194.3 10263.7 10283.1 10308.6 55190.0 55661.9 55738.0 56519.4 58054.1 71232.6 71571.0 72008.0 73152.0 73519.6 11561.0 11561.4 11624.5 11690.2 11937.9 13003.5 13466.8 13497.7 13508.3 13540.4 12308.9 12792.3 12896.3 12960.8 13165.9 12575.7 12755.9 13761.3 13906.0 14174.1 13249.3 13769.6 13835.8 13979.8 14013.8 9493.5 10131.6 10266.5 10393.8 10621.3 9308.5 10116.3 10960.0 11531.2 11562.9 13:01:10 INFO - PROCESS | 1925 | -------- Summary: end -------- 13:01:10 INFO - PROCESS | 1925 | 13:01:10 INFO - TEST-INFO | 1925: exit 0 13:01:10 INFO - TEST-OK | dromaeo_css | took 1115533ms 13:01:10 INFO - TEST-START | kraken 13:01:10 INFO - operating with platform_type : mac_ 13:01:10 INFO - Initialising browser for kraken test... 13:01:10 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpczrft4/profile http://localhost:52168/getInfo.html) 13:01:13 INFO - PROCESS | 1931 | 2017-05-05 13:01:13.379 plugin-container[1933:17425] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 13:01:13 INFO - PROCESS | 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:01:13 INFO - PROCESS | 1931 | 2017-05-05 13:01:13.380 plugin-container[1933:17425] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x421f, name = 'com.apple.CFPasteboardClient' 13:01:13 INFO - PROCESS | 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:01:13 INFO - PROCESS | 1931 | 2017-05-05 13:01:13.380 plugin-container[1933:17425] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:01:13 INFO - PROCESS | 1931 | __metrics Screen width/height:1600/1200 13:01:13 INFO - PROCESS | 1931 | colorDepth:24 13:01:13 INFO - PROCESS | 1931 | Browser inner width/height: 1024/689 13:01:13 INFO - PROCESS | 1931 | __metrics 13:01:13 ERROR - PROCESS | 1931 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 13:01:14 INFO - TEST-INFO | 1931: exit 0 13:01:14 INFO - Browser initialized. 13:01:14 INFO - Running cycle 1/1 for kraken test... 13:01:14 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpczrft4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:01:16 INFO - PROCESS | 1934 | 2017-05-05 13:01:16.647 plugin-container[1935:17544] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 13:01:16 INFO - PROCESS | 1934 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:01:16 INFO - PROCESS | 1934 | 2017-05-05 13:01:16.648 plugin-container[1935:17544] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x850b, name = 'com.apple.CFPasteboardClient' 13:01:16 INFO - PROCESS | 1934 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:01:16 INFO - PROCESS | 1934 | 2017-05-05 13:01:16.648 plugin-container[1935:17544] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:01:26 INFO - PROCESS | 1934 | RSS: Main: 174252032 13:01:26 INFO - PROCESS | 1934 | 13:01:29 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52168/tests/kraken/driver.html?testName=audio-beat-detection) 13:01:30 INFO - PROCESS | 1934 | RSS: Main: 187727872 13:01:30 INFO - PROCESS | 1934 | 13:01:32 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52168/tests/kraken/driver.html?testName=audio-dft) 13:01:33 INFO - PROCESS | 1934 | RSS: Main: 195686400 13:01:33 INFO - PROCESS | 1934 | 13:01:36 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52168/tests/kraken/driver.html?testName=audio-fft) 13:01:37 INFO - PROCESS | 1934 | RSS: Main: 199507968 13:01:37 INFO - PROCESS | 1934 | 13:01:39 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52168/tests/kraken/driver.html?testName=audio-oscillator) 13:01:40 INFO - PROCESS | 1934 | RSS: Main: 199868416 13:01:40 INFO - PROCESS | 1934 | 13:01:42 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52168/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:01:42 INFO - PROCESS | 1934 | RSS: Main: 201150464 13:01:42 INFO - PROCESS | 1934 | 13:01:45 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52168/tests/kraken/driver.html?testName=imaging-darkroom) 13:01:46 INFO - PROCESS | 1934 | RSS: Main: 204976128 13:01:46 INFO - PROCESS | 1934 | 13:01:49 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52168/tests/kraken/driver.html?testName=imaging-desaturate) 13:01:50 INFO - PROCESS | 1934 | RSS: Main: 204558336 13:01:50 INFO - PROCESS | 1934 | 13:01:53 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52168/tests/kraken/driver.html?testName=json-parse-financial) 13:01:54 INFO - PROCESS | 1934 | RSS: Main: 204824576 13:01:54 INFO - PROCESS | 1934 | 13:01:56 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52168/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:01:56 INFO - PROCESS | 1934 | RSS: Main: 204288000 13:01:56 INFO - PROCESS | 1934 | 13:01:58 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:01:59 INFO - PROCESS | 1934 | RSS: Main: 204292096 13:01:59 INFO - PROCESS | 1934 | 13:02:01 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:02:02 INFO - PROCESS | 1934 | RSS: Main: 204341248 13:02:02 INFO - PROCESS | 1934 | 13:02:05 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:02:06 INFO - PROCESS | 1934 | RSS: Main: 205017088 13:02:06 INFO - PROCESS | 1934 | 13:02:08 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:02:09 INFO - PROCESS | 1934 | RSS: Main: 204464128 13:02:09 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52168/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52168/tests/kraken/driver.html?testName=ai-astar) 13:02:11 INFO - PROCESS | 1934 | RSS: Main: 206499840 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | __start_tp_report 13:02:11 INFO - PROCESS | 1934 | _x_x_mozilla_page_load 13:02:11 INFO - PROCESS | 1934 | _x_x_mozilla_page_load_details 13:02:11 INFO - PROCESS | 1934 | |i|pagename|runs| 13:02:11 INFO - PROCESS | 1934 | |0;ai-astar;136;122;119;120;120;118;121;136;151;121 13:02:11 INFO - PROCESS | 1934 | |1;audio-beat-detection;164;139;100;174;135;133;110;110;132;112 13:02:11 INFO - PROCESS | 1934 | |2;audio-dft;204;201;194;233;226;203;217;199;197;199 13:02:11 INFO - PROCESS | 1934 | |3;audio-fft;84;62;61;110;87;83;67;63;72;63 13:02:11 INFO - PROCESS | 1934 | |4;audio-oscillator;89;69;84;70;71;73;103;96;71;82 13:02:11 INFO - PROCESS | 1934 | |5;imaging-gaussian-blur;112;88;135;86;129;135;89;90;91;91 13:02:11 INFO - PROCESS | 1934 | |6;imaging-darkroom;100;101;95;151;152;110;152;147;97;99 13:02:11 INFO - PROCESS | 1934 | |7;imaging-desaturate;134;76;135;75;85;135;147;139;90;91 13:02:11 INFO - PROCESS | 1934 | |8;json-parse-financial;64;69;80;111;104;75;69;68;68;68 13:02:11 INFO - PROCESS | 1934 | |9;json-stringify-tinderbox;56;63;58;72;64;56;65;68;85;81 13:02:11 INFO - PROCESS | 1934 | |10;stanford-crypto-aes;83;82;91;100;75;91;99;99;97;85 13:02:11 INFO - PROCESS | 1934 | |11;stanford-crypto-ccm;145;147;144;146;154;146;153;146;182;170 13:02:11 INFO - PROCESS | 1934 | |12;stanford-crypto-pbkdf2;167;173;164;209;225;180;178;144;171;174 13:02:11 INFO - PROCESS | 1934 | |13;stanford-crypto-sha256-iterative;63;67;66;68;64;66;62;64;64;80 13:02:11 INFO - PROCESS | 1934 | __end_tp_report 13:02:11 INFO - PROCESS | 1934 | __start_cc_report 13:02:11 INFO - PROCESS | 1934 | _x_x_mozilla_cycle_collect,989 13:02:11 INFO - PROCESS | 1934 | __end_cc_report 13:02:11 INFO - PROCESS | 1934 | __startTimestamp1494014531583__endTimestamp 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | ------- Summary: start ------- 13:02:11 INFO - PROCESS | 1934 | Number of tests: 14 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | [#0] ai-astar Cycles:10 Average:126.40 Median:121.50 stddev:10.93 (9.0%) stddev-sans-first:11.02 13:02:11 INFO - PROCESS | 1934 | Values: 136.0 122.0 119.0 120.0 120.0 118.0 121.0 136.0 151.0 121.0 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:134.00 stddev:24.10 (18.0%) stddev-sans-first:22.39 13:02:11 INFO - PROCESS | 1934 | Values: 164.0 139.0 100.0 174.0 135.0 133.0 110.0 110.0 132.0 112.0 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | [#2] audio-dft Cycles:10 Average:207.30 Median:203.50 stddev:13.31 (6.5%) stddev-sans-first:14.06 13:02:11 INFO - PROCESS | 1934 | Values: 204.0 201.0 194.0 233.0 226.0 203.0 217.0 199.0 197.0 199.0 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | [#3] audio-fft Cycles:10 Average:75.20 Median:69.50 stddev:15.77 (22.7%) stddev-sans-first:16.41 13:02:11 INFO - PROCESS | 1934 | Values: 84.0 62.0 61.0 110.0 87.0 83.0 67.0 63.0 72.0 63.0 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | [#4] audio-oscillator Cycles:10 Average:80.80 Median:77.50 stddev:12.07 (15.6%) stddev-sans-first:12.43 13:02:11 INFO - PROCESS | 1934 | Values: 89.0 69.0 84.0 70.0 71.0 73.0 103.0 96.0 71.0 82.0 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | [#5] imaging-gaussian-blur Cycles:10 Average:104.60 Median:88.50 stddev:20.94 (23.7%) stddev-sans-first:22.04 13:02:11 INFO - PROCESS | 1934 | Values: 112.0 88.0 135.0 86.0 129.0 135.0 89.0 90.0 91.0 91.0 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | [#6] imaging-darkroom Cycles:10 Average:120.40 Median:152.00 stddev:26.23 (17.3%) stddev-sans-first:26.76 13:02:11 INFO - PROCESS | 1934 | Values: 100.0 101.0 95.0 151.0 152.0 110.0 152.0 147.0 97.0 99.0 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | [#7] imaging-desaturate Cycles:10 Average:110.70 Median:75.50 stddev:29.44 (39.0%) stddev-sans-first:29.99 13:02:11 INFO - PROCESS | 1934 | Values: 134.0 76.0 135.0 75.0 85.0 135.0 147.0 139.0 90.0 91.0 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | [#8] json-parse-financial Cycles:10 Average:77.60 Median:68.50 stddev:16.45 (24.0%) stddev-sans-first:16.69 13:02:11 INFO - PROCESS | 1934 | Values: 64.0 69.0 80.0 111.0 104.0 75.0 69.0 68.0 68.0 68.0 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:66.50 stddev:9.99 (15.0%) stddev-sans-first:9.80 13:02:11 INFO - PROCESS | 1934 | Values: 56.0 63.0 58.0 72.0 64.0 56.0 65.0 68.0 85.0 81.0 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:91.00 stddev:8.66 (9.5%) stddev-sans-first:8.79 13:02:11 INFO - PROCESS | 1934 | Values: 83.0 82.0 91.0 100.0 75.0 91.0 99.0 99.0 97.0 85.0 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | [#11] stanford-crypto-ccm Cycles:10 Average:153.30 Median:150.00 stddev:12.73 (8.5%) stddev-sans-first:13.14 13:02:11 INFO - PROCESS | 1934 | Values: 145.0 147.0 144.0 146.0 154.0 146.0 153.0 146.0 182.0 170.0 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.50 Median:176.00 stddev:22.94 (13.0%) stddev-sans-first:23.95 13:02:11 INFO - PROCESS | 1934 | Values: 167.0 173.0 164.0 209.0 225.0 180.0 178.0 144.0 171.0 174.0 13:02:11 INFO - PROCESS | 1934 | 13:02:11 INFO - PROCESS | 1934 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.00 stddev:5.13 (7.8%) stddev-sans-first:5.29 13:02:11 INFO - PROCESS | 1934 | Values: 63.0 67.0 66.0 68.0 64.0 66.0 62.0 64.0 64.0 80.0 13:02:11 INFO - PROCESS | 1934 | -------- Summary: end -------- 13:02:11 INFO - PROCESS | 1934 | 13:02:12 INFO - TEST-INFO | 1934: exit 0 13:02:12 INFO - TEST-OK | kraken | took 61779ms 13:02:12 INFO - SUITE-END | took 1177s 13:02:12 INFO - Completed test suite (00:19:37) 13:02:12 INFO - javascript benchmark 13:02:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7907.88136029639, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3517.067976986101, 3588.156783155958, 3612.0, 3614.5843228028766, 3625.2749450109986, 6136.509079273653, 6163.876722465548, 6169.8766024679535, 6186.876262474748, 6225.501959843213, 6156.076588511714, 6218.657973811439, 6218.937810621904, 6220.84447888803, 6233.532485063615, 5514.869451762379, 5546.084895992157, 5567.944320556783, 5575.526080283161, 5585.804496842611, 5601.327840659126, 5605.411431799664, 5616.803411880585, 5620.381758006616, 5627.212190293341, 4875.268709693557, 5059.873503162414, 5593.3847276799515, 5614.522765564931, 5650.74571644277, 4532.297493888436, 4665.720056796603, 5489.752961116759, 5520.033994051025, 5565.666060036411, 5471.7264136793, 5583.413741557119, 5593.0, 5618.269624948731, 5659.915101273484, 6203.441690247854, 6216.129741836146, 6225.8132256032395, 6242.063690446424, 6247.219097612799, 6249.437550620421, 6324.588901721373, 6332.461740752042, 6385.361463853624, 6589.374009469093, 5602.663840169562, 6626.171728533934, 6699.866002679968, 6702.798916032527, 6722.899156512656, 6252.280987686474, 6283.465905398047, 6287.339829317902, 6302.086197501337, 6303.5902666327, 6491.8701625967215, 6528.151340325728, 6729.865402692017, 6874.621895795679, 6912.861742765217, 6333.113364129025, 6353.110564520878, 6429.7749578764515, 6435.774747883802, 6450.096986421905, 6605.537612367184, 6642.169728783902, 6651.135352404156, 6659.733610655617, 6681.7327306908155, 6244.937550624527, 6459.870802584016, 6476.708548115346, 6537.477001839841, 6561.835954101186, 6327.462165715966, 6431.839204019937, 6433.678316084177, 6450.870982580416, 6495.025746138022, 4343.717658352197, 4871.074495845849, 5152.510511501401, 5225.529702326733, 5311.070562651521, 4348.565143485626, 4701.965567575128, 4733.715977041388, 4736.881577960509, 4766.046790641885, 6154.907676384858, 6280.214973128359, 6296.275928268308, 6300.401461861115, 6347.682615869188, 5610.635308704921, 6381.425671689571, 6385.201849768779, 6522.152120224436, 6552.475801935833, 6242.063690446469, 6263.592866463665, 6283.08895210192, 6283.748650054038, 6298.149749783721, 5918.112283157561, 5921.082232254007, 5933.6736479493175, 5953.702314884238, 5965.313988891333, 6556.934430655823, 6591.901121483277, 6746.628935408599, 6761.121054262914, 6833.863322733518, 6666.866662666816, 6674.332566743286, 6676.298988606321, 6680.899786503109, 6698.966505167443, 6742.561733487308, 6840.897386539305, 6870.793876183722, 6878.27778083314, 6895.55178913369, 6242.28213755424, 6300.936990630035, 6439.130717353098, 6448.129502517288, 6519.576227545194, 6527.836804079936, 6644.435223005905, 6678.499112566674, 6706.966465167839, 6707.061011458497, 6369.649669268235, 6420.133282006869, 6424.775132870327, 6427.935720642734, 6430.7749228776765, 6196.969015155076, 6219.440250377488, 6281.15204447412, 6353.078803573363, 6411.070394792822, 6161.784337548164, 6249.281332646622, 6325.272593651789, 6334.746610135543, 6356.77751278703, 6434.131392261985, 6437.839054023687, 6458.935410645833, 6459.160309159779, 6469.514786390945, 4991.875203119951, 6224.844378890564, 6239.656818875005, 6247.812565623039, 6260.499160067093], "value": 6026.677698332598, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.8674225381684, 825.4758228524887, 853.0147679430258, 984.7291994701452, 990.7424069741874, 38257.234855302915, 39110.0, 40363.59636403635, 40665.59334406663, 40772.59227407725, 31490.842545787295, 38979.61020389795, 39188.60811391885, 39274.8036259819, 39338.60661393385, 8202.712905048309, 8355.37334699897, 9759.853602195974, 11198.776024479506, 11429.542818287258, 10891.455427228646, 11453.59912403062, 11534.019608333301, 11537.538498460093, 11562.728099909004, 11518.596849110283, 11520.539178432853, 11525.423728813568, 11532.942335288311, 11544.0, 11416.086713062936, 11440.084793216563, 11469.541218351256, 11471.139664525193, 11475.081993440504, 11184.720381990433, 11223.943880280627, 11244.26912250701, 11249.43752812356, 11270.661880143609, 35154.82422587871, 35184.64815351839, 35271.765488208046, 35361.46957795635, 35419.4687079694, 3900.1614652849776, 3919.573933337309, 3948.2300951314505, 3966.504186976628, 4024.7383920045104, 25222.991080356765, 25820.096296629716, 26193.869030654914, 26500.93996240148, 26595.069172578867, 3171.5084161954933, 3237.190702324419, 3256.3324518473655, 3283.589551306087, 3293.5389045533643, 277289.99999999796, 363847.0, 364220.0, 423152.0, 428741.0, 45360.3195952061, 45430.72846357669, 45560.54439455563, 45608.54391456043, 45622.543774562495, 46935.295970560466, 47065.0, 47150.76424617855, 47175.5282447178, 47235.05529889383, 14529.128252304685, 14577.562673119823, 14638.048526845721, 14644.341004654816, 14693.26533673312, 12534.623961281177, 12723.68190795219, 12728.554500592434, 12743.617691469271, 12752.043596730096, 11475.368854712904, 12134.453949572291, 12138.817917731241, 12210.694732631755, 12239.143259971717, 5939.792107276225, 5943.732532036069, 5948.553858460633, 5981.132735753292, 6015.578909476382, 1142.9085673146294, 1161.4773351991469, 1167.177140116216, 1177.6879127031343, 1208.975820483603, 24200.51598968011, 26633.0, 26870.92516299365, 27647.723522764918, 27810.16569502918, 910.1990248581338, 929.1034152043311, 933.8692583038244, 944.2304521814699, 1305.2103477396038, 9925.801483970281, 10450.843237351446, 10453.738656533495, 10505.737356565995, 10561.683149505527, 440942.0, 443139.0, 444018.0, 444145.0, 445555.0, 49408.0, 49427.752861236186, 49589.75205123951, 49697.75151124221, 49743.50256497461, 1455.8835293176521, 1483.243545791654, 1485.0644094220572, 1492.455253832365, 1494.880409567232, 17061.914690426467, 19494.707579386602, 19590.412287631392, 19866.205351786346, 19945.900270498554, 42376.0, 42588.5741142597, 42750.71747847445, 42917.0, 43004.784976074916, 452735.0, 457555.0, 457869.0, 458097.0, 458355.0, 30580.694193058676, 30820.0, 31082.0, 31844.68155318417, 33118.503222452164, 41056.0, 41304.38043429411, 41460.792696036326, 42306.25009374381, 44265.557344427434, 25752.742472575035, 28262.71737282601, 29192.416151677273, 29896.25259368533, 30165.849170754005, 29407.97072102466, 29928.70071299259, 30053.849730751208, 30181.547276790425, 30414.239644009078, 28170.85914570414, 33654.15864603405, 34142.0, 35054.29891402209, 35140.64859351476, 26598.202053938414, 26798.0, 29555.0, 29835.70164298416, 30561.54157687681, 21874.687518748928, 22771.65842512397, 23748.168814091423, 24473.38816529601, 24481.632775508024], "value": 18299.135146559147, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.8657099871543, 429.29595463439944, 443.12040599410165, 443.1673600091493, 451.1811062920801, 19894.90052549739, 20001.799982000175, 20092.799072009293, 21543.461413464673, 22175.11299548018, 19228.519287017833, 22630.54738905225, 23166.536669266603, 23571.76428235717, 23706.76293237067, 6718.596884186964, 6753.932460675404, 6965.094537710091, 7227.602481863495, 7250.564966102024, 7052.118485189351, 7101.538400003983, 7130.821729456779, 7133.393661538749, 7182.497225194238, 6762.884124119539, 7023.227444981048, 7111.217766045756, 7116.786496405116, 7157.14114306284, 6625.93374066258, 6728.226253980757, 7043.859122817541, 7054.788356349292, 7101.893471597904, 6324.652144132071, 6852.0, 6853.211880633691, 6861.833488307, 6870.828229294258, 26219.0, 26527.867360663266, 26808.525531095784, 26873.731262687317, 26883.865580672165, 2927.4291513154944, 3044.2845931206148, 3059.281068948795, 3065.7700672449655, 3069.8227334708695, 16562.66874662512, 16864.0, 16969.745453818203, 17341.479755607354, 17801.821981780147, 1870.2612468075033, 2065.8760474371584, 2099.296735593578, 2107.5784843031456, 2139.668351405535, 158701.0, 159840.0, 166120.0, 166953.0, 168660.15669921695, 17076.0, 20661.0, 21015.8949205253, 21127.83796891155, 21343.46641333979, 25345.0, 32258.516122258185, 32260.83869580637, 32273.031809045766, 32453.675463245538, 10647.627333043307, 10836.891631083747, 11032.007119359296, 11062.0, 11221.60724374643, 9138.451692898447, 9179.403338782959, 9202.63189472427, 9326.580303886205, 9382.0, 9480.525973701288, 9607.279454040836, 9639.084286992724, 9731.978142295098, 9772.9022709772, 3928.155446578999, 3983.382575700771, 3998.3402738547816, 4016.216837716617, 4017.819198136091, 837.6481877611296, 858.0261403307167, 867.1805144138855, 953.9189168920719, 959.2278216036158, 16663.83336166647, 19144.521386965436, 19732.0, 22049.33851984443, 23477.647835282143, 1140.2759247877657, 1144.6680462665734, 1152.1358980764426, 1181.976360472803, 1196.3599474281218, 10991.395473249047, 11694.0, 11709.941450292694, 11731.35477548726, 11759.412029398496, 477642.0, 818281.0, 820313.0, 824014.0, 832002.0, 125096.0, 126835.0, 154514.0, 160466.7906641884, 171942.0, 1102.6747109602686, 1160.8955194032408, 1319.7558451686468, 1348.9595312140652, 1372.1766939836018, 18073.0, 18943.526411839815, 20094.799052009294, 21327.78672213258, 21409.464763381042, 52001.479985199665, 56291.71854140867, 66440.0, 68276.317236827, 68568.62862742817, 96428.51785741026, 96522.55216171904, 96544.03455965631, 97494.0, 98983.0, 10091.646792362231, 11887.702807429883, 12231.75536489283, 12600.747985040065, 12637.87362126367, 13595.86404135946, 13628.523001694894, 13684.794728078889, 13996.860031399963, 14031.719365612427, 11487.023602993839, 11918.642440726446, 11942.343171125323, 12055.21641093326, 12089.0, 10819.404932728461, 10882.40146791934, 10888.61889833852, 11114.944425277821, 11224.7755044897, 10608.886066963167, 11869.881301187223, 12121.272723636257, 12161.574344897886, 12186.14696971239, 11623.186376953532, 12190.152801358376, 12199.51201951912, 12450.25298482094, 12556.49774009066, 11772.823407649063, 12007.219530730474, 12026.819597705866, 12095.516179352728, 12101.69745756363], "value": 11932.574495283125, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1610.339760698114, 1659.796647430613, 1672.4480921295974, 1674.3218996306493, 1684.15792103948, 11077.667669969904, 11345.319280843156, 11697.18119731617, 11793.941030294858, 11812.0, 11493.138014648894, 11627.47676354564, 11688.532458701642, 11696.649100526998, 11730.35483048432, 2388.7491813359607, 2440.7315195328542, 2479.950400991979, 2489.5020995800915, 2490.2778194323714, 2431.465077682907, 2455.042533411971, 2482.056818409011, 2492.9002839886384, 2494.5135698538793, 2444.1201167579657, 2454.288256405649, 2465.3097132802845, 2475.665785119004, 2476.0838489758817, 2414.4929564791414, 2421.2736179146186, 2439.9878000609974, 2447.3881529617597, 2460.5817011107974, 2426.211481268595, 2435.512897420523, 2440.2679196241056, 2449.2284930246915, 2453.914113006036, 11280.266782659182, 11467.942660286728, 11471.254368466107, 11477.196596238267, 11502.194846360759, 5948.970255148696, 6035.185249991239, 6084.939150608481, 6107.4197951194565, 6136.846578835521, 3662.706983441345, 3710.628937106295, 3749.550053993511, 3785.072657199004, 3853.094522787143, 1264.20986883198, 1661.2358315175034, 1911.3692481481078, 1946.7274581558452, 1973.200853654258, 14168.858311416858, 14457.204853733041, 14938.925305373403, 16059.277332520065, 16107.19464026794, 4755.83354582588, 4796.112719146969, 4808.158572249842, 4926.75366231687, 4940.654154209243, 12242.387880605935, 12284.570040048555, 12295.4467048983, 12304.507819687113, 12316.322602256962, 5961.910571341434, 6056.3035250946705, 6059.515238780887, 6060.878782424327, 6077.817665470043, 5398.487143721419, 5527.0, 5530.838523910024, 5531.668099914018, 5554.0, 3910.530736311604, 4041.2927737645796, 4042.4744783178585, 4080.9795951020646, 4139.5653456387245, 1060.3425875956802, 1069.4919913041242, 1069.8555694981078, 1074.7152004718755, 1076.3864597179688, 940.8353538130937, 956.550421301987, 965.2374624046925, 982.3025651787168, 1004.7387679203314, 6698.832529186712, 6830.214525329551, 6859.622720750307, 6869.244383117853, 6927.272636373209, 960.841461158915, 981.2003217377815, 1026.5483187397522, 1029.351508549609, 1032.607609108534, 4226.725262857904, 4279.272523670983, 4378.321360189176, 4417.160739459492, 4495.707778994355, 12253.632391028104, 14130.71738565223, 14704.926475367554, 14716.0, 14740.926295368454, 12089.637310880687, 13132.671683207996, 13548.254845983565, 14523.346449409803, 14561.344739486749, 8465.492070475791, 10333.844992325121, 10627.734306642396, 10644.520996555173, 10686.358818470917, 6072.544559157993, 6184.381561843779, 7152.713891444284, 7316.268373162641, 7331.04696389466, 12059.336736479227, 12111.939440302742, 12323.01415886709, 12340.938295308466, 12344.444499997342, 12390.5663301784, 12581.182223155465, 12755.979521638432, 12849.807252891402, 12955.611331660066, 9371.953140234255, 9535.094166054214, 9586.66446674363, 9614.03859614033, 9675.612975480903, 10505.31715438494, 10522.000409961041, 10609.522571484147, 10645.946770266099, 10649.680509584725, 8986.236169925369, 9118.544072796467, 9141.542922853963, 9199.632014719336, 9343.71968840936, 7833.529988200727, 8920.0, 8966.641334346814, 8981.281497480317, 9055.23030542385, 8137.389695772959, 8275.710350137717, 8280.917190828255, 8300.12848650883, 8330.666773329, 6044.0, 6065.150878876872, 6100.328963814066, 6168.753249869955, 6285.59143655661, 5918.319393269829, 6074.757009719562, 6140.846478838065, 6208.099825525183, 6240.812775616739], "value": 5359.776470998724, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.7420989252041, 396.4726913205435, 398.26718837339297, 399.2055808940209, 405.11482410932115, 4541.955350269436, 5047.798088076473, 5566.359868615111, 5602.691851948146, 5625.746841392137, 4954.504549545053, 5158.716270605115, 5176.715280659562, 5186.340371081255, 5396.64921780084, 3841.8079096045226, 3885.825137868803, 4047.7166598338126, 4225.345071513912, 4229.238737027334, 3707.3326801175776, 4216.789160541976, 4231.238377092138, 4281.978590107045, 4308.3106702927535, 3506.8772592959253, 3669.3578623740873, 4120.670346372283, 4274.503923626721, 4288.0, 4383.780810959455, 4414.97792511037, 4421.248387774086, 4454.398656181406, 4478.39541661878, 4089.3661482470266, 4097.487814023247, 4108.465899433085, 4118.608732170439, 4118.91762164758, 4848.757562121879, 5170.095233334151, 5173.2669555698885, 5348.679079255218, 5432.076546987022, 5102.464241254652, 5125.846224613268, 5169.836786722957, 5171.146760784466, 5237.3453318335205, 5073.6955782652785, 5390.62539052543, 5542.584306177053, 5865.560082993792, 5910.34986151523, 4024.7987600619854, 4209.98960249541, 4466.754328511931, 4620.204776110472, 4652.209124448852, 4841.951580484185, 5040.874657712206, 5217.321748172713, 5967.134765459027, 6054.545909056838, 4337.587929146789, 4409.867703968822, 4576.633869290449, 4760.0, 4787.2101103318255, 4016.3975403689096, 4383.276759334674, 4642.792873852823, 4829.227323628203, 4858.076965376567, 4678.60231880294, 4787.832425865078, 4915.4347250065985, 5059.873503162376, 5207.0994087157915, 4868.95131048692, 5167.534921856975, 5671.262735844396, 5728.77084916607, 5855.560832937546, 1414.7382734194207, 1414.8797352225176, 1446.8119144511147, 1485.970280594382, 1637.2059551117698, 1127.5433449452985, 1223.3944197622056, 1238.5912648825865, 1238.7027113492877, 1251.7559075980278, 4915.508449155127, 5054.873628159326, 5253.318937939823, 5326.242340027781, 5554.555635549146, 5395.435323756067, 5695.345035320991, 5712.65724056558, 5807.216025836458, 6078.726457309432, 5147.56245719118, 5316.388615309212, 5411.594130440233, 5502.752376143083, 5559.415566563591, 2771.9307017324727, 2832.9008484702936, 2839.4463079699262, 3136.9372612547622, 3158.3525377297597, 5680.829575112753, 5869.354371019184, 6164.599301045418, 6187.783427580013, 6258.153844615849, 5244.348030370421, 6252.280258087746, 6292.811215663537, 6318.336574659686, 6359.236891573041, 5356.866078348073, 5406.999705054577, 5655.429402038906, 6178.135061091541, 6446.968485042371, 5367.543758780547, 5375.435579264121, 5659.971700141473, 5715.714214289352, 6059.848503787441, 5237.450067742984, 5807.593468457337, 6020.2842189975645, 6073.331933487401, 6084.482818960438, 5926.348101708895, 6009.639421634716, 6036.396360363929, 6214.658193799384, 6290.339514350927, 4657.86026419208, 4904.8773780655765, 5079.7300674831295, 5242.348660172033, 5264.657797243167, 5461.290032295776, 5722.71091563378, 5908.940910590839, 6024.909626355696, 6141.754329826936, 5200.635955483232, 5308.83256643533, 5821.912671309849, 5848.883022339444, 5876.471117599436, 5624.437556244343, 5719.5138413235345, 5741.971290143522, 5842.211301474246, 5924.851878703067, 4346.066405176556, 4968.403791545038, 5328.626996110235, 5525.888553088457, 5770.913436298375, 5642.322921249476, 5782.624129431573, 5794.971025144847, 5889.234399528034, 5892.322382926019, 4787.880302992453, 5454.836354909359, 5751.964646363695, 5771.384012476513, 5779.7110144493445], "value": 4405.362595651051, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.3829237709383, 719.5574721546249, 747.2938073520526, 770.6069904348781, 790.7351037402468, 16374.918125409371, 16739.246733896958, 17265.309387624497, 17501.124943752824, 17560.736588951175, 13967.231802250872, 16139.193040347995, 16234.944728592633, 16313.673726525492, 16355.83644163558, 1008.8234558952191, 1019.0980981831059, 1029.4646783672486, 1030.3766221436, 1033.1837848099992, 910.9544522773868, 945.6571668231094, 1016.1362841584631, 1018.251585084962, 1056.0179033498844, 1080.8594882665282, 1081.4214395298518, 1081.507913899174, 1082.832161015044, 1083.6099004358425, 1072.554889720765, 1072.8068947589431, 1073.0128281980558, 1074.505727365413, 1074.5809134437575, 792.73839632921, 798.3254150243035, 798.4610387988084, 801.783518450021, 802.1577343789056, 14420.495282665057, 16090.517284481484, 16326.673466530721, 16340.83659163405, 16941.98348099118, 11558.653240402802, 13682.726345473135, 13923.094998825043, 14029.85970140306, 14060.437582496688, 10281.383117012929, 10400.47997600117, 11990.760184796343, 12019.63941081769, 12433.191842530297, 3812.7140464464997, 3837.1174629835295, 3843.5195600549932, 3848.1534062506203, 3903.4535165076627, 93998.0600194003, 115536.0, 121215.3939230307, 123427.0, 124035.0, 10143.695689129338, 11473.909978552025, 11547.711307217218, 11669.299842009506, 11941.283522988648, 22981.08075676954, 23154.65268020981, 23883.28350149498, 23898.64152037721, 23935.401614959417, 8262.958685206535, 8293.751187464386, 8312.916870831214, 8327.20891515305, 8389.874151887727, 4026.6979976501293, 4053.7365071270274, 4637.976810115928, 4655.51117132703, 4910.90178196434, 3443.328550932594, 3512.754107212522, 3516.24400753834, 3526.347625689256, 3532.7703699259464, 597.6712807955607, 602.3915844996498, 619.7582942652369, 624.7407325959675, 627.297426881886, 708.7661071846278, 729.5768454296496, 743.2159072178903, 759.3621358059146, 777.6034222546542, 10201.13290370312, 10503.0, 10816.188785841094, 11050.723731906606, 11303.491342889589, 695.2803848017278, 704.1831475488409, 731.912170539528, 759.472166844038, 762.5653377574729, 4451.732896026248, 5189.922151167735, 5300.867478313073, 5303.337082864642, 5340.332458442695, 98013.0, 106522.0, 108361.0, 109880.4505977465, 109907.0, 94534.52732736293, 95400.52299738457, 96222.0, 97131.0, 99613.0, 68043.63912721717, 69620.99999999898, 79106.60446697845, 83967.80160992048, 85170.29659406777, 10101.0, 10194.286399951932, 10263.7434064149, 10283.07452329294, 10308.639197628047, 55190.0, 55661.886762265334, 55738.0, 56519.43480565141, 58054.12918806306, 71232.64383678048, 71571.0, 72008.0, 73152.0, 73519.63240183765, 11561.0, 11561.364124973206, 11624.483970244983, 11690.240134391577, 11937.880621193677, 13003.483075823746, 13466.79799802984, 13497.662558436117, 13508.32458377097, 13540.390682419118, 12308.913642114592, 12792.296423696785, 12896.29070401137, 12960.805587916, 13165.868341316464, 12575.748485030064, 12755.93622031884, 13761.31193440304, 13906.0, 14174.14955102697, 13249.271290079138, 13769.586912392644, 13835.79246311286, 13979.790303145664, 14013.789793152908, 9493.525323733924, 10131.64539241123, 10266.538005789907, 10393.792124157324, 10621.309614875008, 9308.48803315824, 10116.342437741523, 10959.958803913616, 11531.159842743135, 11562.94218528902], "value": 7869.595015534155, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1589.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 122.0, 119.0, 120.0, 120.0, 118.0, 121.0, 136.0, 151.0, 121.0], "value": 126.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 139.0, 100.0, 174.0, 135.0, 133.0, 110.0, 110.0, 132.0, 112.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 201.0, 194.0, 233.0, 226.0, 203.0, 217.0, 199.0, 197.0, 199.0], "value": 207.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 62.0, 61.0, 110.0, 87.0, 83.0, 67.0, 63.0, 72.0, 63.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 84.0, 70.0, 71.0, 73.0, 103.0, 96.0, 71.0, 82.0], "value": 80.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 88.0, 135.0, 86.0, 129.0, 135.0, 89.0, 90.0, 91.0, 91.0], "value": 104.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 101.0, 95.0, 151.0, 152.0, 110.0, 152.0, 147.0, 97.0, 99.0], "value": 120.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 76.0, 135.0, 75.0, 85.0, 135.0, 147.0, 139.0, 90.0, 91.0], "value": 110.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 80.0, 111.0, 104.0, 75.0, 69.0, 68.0, 68.0, 68.0], "value": 77.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 58.0, 72.0, 64.0, 56.0, 65.0, 68.0, 85.0, 81.0], "value": 66.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 91.0, 100.0, 75.0, 91.0, 99.0, 99.0, 97.0, 85.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 144.0, 146.0, 154.0, 146.0, 153.0, 146.0, 182.0, 170.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 173.0, 164.0, 209.0, 225.0, 180.0, 178.0, 144.0, 171.0, 174.0], "value": 178.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 66.0, 68.0, 64.0, 66.0, 62.0, 64.0, 64.0, 80.0], "value": 66.4, "unit": "score"}]}]} 13:02:12 INFO - Return code: 0 13:02:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:02:12 ERROR - # TBPL SUCCESS # 13:02:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:02:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:02:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:02:12 INFO - 2017-05-05 13:02:12.887 screenresolution[1939:17921] starting screenresolution argv=screenresolution get 13:02:12 INFO - 2017-05-05 13:02:12.890 screenresolution[1939:17921] Display 0: 1600x1200x32@60 13:02:12 INFO - 2017-05-05 13:02:12.896 screenresolution[1940:17924] starting screenresolution argv=screenresolution list 13:02:12 INFO - Available Modes on Display 0 13:02:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:02:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:02:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:02:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:02:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:02:13 INFO - Intel Iris: 13:02:13 INFO - Chipset Model: Intel Iris 13:02:13 INFO - Type: GPU 13:02:13 INFO - Bus: Built-In 13:02:13 INFO - VRAM (Dynamic, Max): 1536 MB 13:02:13 INFO - Vendor: Intel (0x8086) 13:02:13 INFO - Device ID: 0x0a2e 13:02:13 INFO - Revision ID: 0x0009 13:02:13 INFO - Displays: 13:02:13 INFO - Display: 13:02:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:02:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:02:13 INFO - Display Serial Number: 1600x1200 60 13:02:13 INFO - Main Display: Yes 13:02:13 INFO - Mirror: Off 13:02:13 INFO - Online: Yes 13:02:13 INFO - Rotation: Supported 13:02:13 INFO - Return code: 0 13:02:13 INFO - Running post-action listener: _resource_record_post_action 13:02:13 INFO - [mozharness: 2017-05-05 20:02:13.135870Z] Finished run-tests step (success) 13:02:13 INFO - Running post-run listener: _resource_record_post_run 13:02:13 INFO - Total resource usage - Wall time: 1193s; CPU: 55.0%; Read bytes: 2180096; Write bytes: 426598400; Read time: 24; Write time: 1638 13:02:13 INFO - TinderboxPrint: CPU usage
54.6% 13:02:13 INFO - TinderboxPrint: I/O read bytes / time
2,180,096 / 24 13:02:13 INFO - TinderboxPrint: I/O write bytes / time
426,598,400 / 1,638 13:02:13 INFO - TinderboxPrint: CPU idle
2,166.8 (45.4%) 13:02:13 INFO - TinderboxPrint: CPU system
959.2 (20.1%) 13:02:13 INFO - TinderboxPrint: CPU user
1,647.7 (34.5%) 13:02:13 INFO - TinderboxPrint: Swap in / out
124,678,144 / 0 13:02:13 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 122831360; Write bytes: 125593088; Read time: 11198; Write time: 288 13:02:13 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 1958912; Write bytes: 299596288; Read time: 21; Write time: 1343 13:02:13 INFO - Running post-run listener: _upload_blobber_files 13:02:13 INFO - Blob upload gear active. 13:02:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:02:13 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 13:02:13 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'] 13:02:13 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 13:02:14 INFO - (blobuploader) - INFO - Open directory for files ... 13:02:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:02:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:02:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:14 INFO - (blobuploader) - INFO - Done attempting. 13:02:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:02:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:02:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:15 INFO - (blobuploader) - INFO - Done attempting. 13:02:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:02:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:02:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:16 INFO - (blobuploader) - INFO - Done attempting. 13:02:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:02:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:02:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:18 INFO - (blobuploader) - INFO - Done attempting. 13:02:18 INFO - (blobuploader) - INFO - Iteration through files over. 13:02:18 INFO - Return code: 0 13:02:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:02:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:02:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c92632aa9a49c363507dcb8ccf8b24ef558cc8562093aed4b977fb25726794bfc5e254688d1bc1b21136a53d3ed6b3a93872cf002d7f2675c6e26d77574ed261", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/646076758b0fb3fe2ae0c116f664521942dfbd1d597ecc8da58371469a3cfd3edf4fb4692dc15bae0f990e8e2fbf488cf8446a6b86fd8ae2a39258ab92ac9a5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dd8cea1c70c376417f569fdc65da3eeca259c6afed72224bcb5f26f8e881ef14cb85178197016236f6280b198ce3309fd666ae580d19397936b7f67b63c43b17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c2d0ba6b26744b379b036708f2b2e59471b680436f5b3d52aa64c8b127e80d2357cee26431593425c1ad7d44827cbe3437de7a3a373c30c18374afb075de47a9"} 13:02:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:02:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:02:18 INFO - Contents: 13:02:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c92632aa9a49c363507dcb8ccf8b24ef558cc8562093aed4b977fb25726794bfc5e254688d1bc1b21136a53d3ed6b3a93872cf002d7f2675c6e26d77574ed261", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/646076758b0fb3fe2ae0c116f664521942dfbd1d597ecc8da58371469a3cfd3edf4fb4692dc15bae0f990e8e2fbf488cf8446a6b86fd8ae2a39258ab92ac9a5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dd8cea1c70c376417f569fdc65da3eeca259c6afed72224bcb5f26f8e881ef14cb85178197016236f6280b198ce3309fd666ae580d19397936b7f67b63c43b17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c2d0ba6b26744b379b036708f2b2e59471b680436f5b3d52aa64c8b127e80d2357cee26431593425c1ad7d44827cbe3437de7a3a373c30c18374afb075de47a9"} 13:02:18 INFO - Running post-run listener: copy_logs_to_upload_dir 13:02:18 INFO - Copying logs to upload dir... 13:02:18 INFO - mkdir: /builds/slave/test/build/upload/logs 13:02:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.149521 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-05-05 13:02:19.055476) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 13:02:19.058548) ========= 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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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/c92632aa9a49c363507dcb8ccf8b24ef558cc8562093aed4b977fb25726794bfc5e254688d1bc1b21136a53d3ed6b3a93872cf002d7f2675c6e26d77574ed261", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/646076758b0fb3fe2ae0c116f664521942dfbd1d597ecc8da58371469a3cfd3edf4fb4692dc15bae0f990e8e2fbf488cf8446a6b86fd8ae2a39258ab92ac9a5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dd8cea1c70c376417f569fdc65da3eeca259c6afed72224bcb5f26f8e881ef14cb85178197016236f6280b198ce3309fd666ae580d19397936b7f67b63c43b17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c2d0ba6b26744b379b036708f2b2e59471b680436f5b3d52aa64c8b127e80d2357cee26431593425c1ad7d44827cbe3437de7a3a373c30c18374afb075de47a9"} build_url:https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011377 build_url: 'https://queue.taskcluster.net/v1/task/e92LTEmISuKFqlpmpDBKtQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c92632aa9a49c363507dcb8ccf8b24ef558cc8562093aed4b977fb25726794bfc5e254688d1bc1b21136a53d3ed6b3a93872cf002d7f2675c6e26d77574ed261", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/646076758b0fb3fe2ae0c116f664521942dfbd1d597ecc8da58371469a3cfd3edf4fb4692dc15bae0f990e8e2fbf488cf8446a6b86fd8ae2a39258ab92ac9a5e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dd8cea1c70c376417f569fdc65da3eeca259c6afed72224bcb5f26f8e881ef14cb85178197016236f6280b198ce3309fd666ae580d19397936b7f67b63c43b17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c2d0ba6b26744b379b036708f2b2e59471b680436f5b3d52aa64c8b127e80d2357cee26431593425c1ad7d44827cbe3437de7a3a373c30c18374afb075de47a9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 13:02:19.093785) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 13:02:19.094083) ========= 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.hdTSzfkyBw/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.1qks7Tvqh6/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.005374 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 13:02:19.143011) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 13:02:19.143333) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 13:02:19.143642) ========= ========= Total master_lag: 0.19 =========