builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0035 starttime: 1490113420.02 results: success (0) buildid: 20170321082228 builduid: 593c4f0c64be4596995919f04c8eff8b revision: 31172c0751dd45780a57e92a688c1f54cc01fe63 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 09:23:40.021566) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 09:23:40.022031) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 09:23:40.022397) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 09:23:40.048874) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 09:23:40.049293) ========= 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.bd3BOMyzub/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.GDuvfzVbcm/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-21 09:23:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 155M=0s 2017-03-21 09:23:40 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138086 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 09:23:40.211636) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 09:23:40.211984) ========= 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.bd3BOMyzub/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.GDuvfzVbcm/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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.037938 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 09:23:40.273180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 09:23:40.273551) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 31172c0751dd45780a57e92a688c1f54cc01fe63 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 31172c0751dd45780a57e92a688c1f54cc01fe63 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bd3BOMyzub/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.GDuvfzVbcm/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-21 09:23:40,363 truncating revision to first 12 chars 2017-03-21 09:23:40,364 Setting DEBUG logging. 2017-03-21 09:23:40,364 attempt 1/10 2017-03-21 09:23:40,364 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/31172c0751dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-21 09:23:40,999 unpacking tar archive at: mozilla-central-31172c0751dd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.912069 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 09:23:41.209039) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 09:23:41.209385) ========= script_repo_revision: 31172c0751dd45780a57e92a688c1f54cc01fe63 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 09:23:41.209773) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 09:23:41.210044) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 09:23:41.231234) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-21 09:23:41.232057) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bd3BOMyzub/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.GDuvfzVbcm/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:23:41 INFO - MultiFileLogger online at 20170321 09:23:41 in /builds/slave/test 09:23:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:23:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:23:41 INFO - {'append_to_log': False, 09:23:41 INFO - 'base_work_dir': '/builds/slave/test', 09:23:41 INFO - 'blob_upload_branch': 'Firefox', 09:23:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:23:41 INFO - 'branch': 'Firefox', 09:23:41 INFO - 'buildbot_json_path': 'buildprops.json', 09:23:41 INFO - 'config_files': ('talos/mac_config.py',), 09:23:41 INFO - 'default_actions': ('clobber', 09:23:41 INFO - 'read-buildbot-config', 09:23:41 INFO - 'download-and-extract', 09:23:41 INFO - 'populate-webroot', 09:23:41 INFO - 'create-virtualenv', 09:23:41 INFO - 'install', 09:23:41 INFO - 'run-tests'), 09:23:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:23:41 INFO - 'download_minidump_stackwalk': True, 09:23:41 INFO - 'download_symbols': 'ondemand', 09:23:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:23:41 INFO - 'tooltool.py': '/tools/tooltool.py', 09:23:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:23:41 INFO - '/tools/misc-python/virtualenv.py')}, 09:23:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:23:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:23:41 INFO - 'gecko_profile': False, 09:23:41 INFO - 'gecko_profile_interval': 0, 09:23:41 INFO - 'installer_path': 'installer.exe', 09:23:41 INFO - 'log_level': 'info', 09:23:41 INFO - 'log_name': 'talos', 09:23:41 INFO - 'log_to_console': True, 09:23:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:23:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:23:41 INFO - 'opt_config_files': (), 09:23:41 INFO - 'pip_index': False, 09:23:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:23:41 INFO - 'cmd': ('bash', 09:23:41 INFO - '-c', 09:23:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:23:41 INFO - 'enabled': True, 09:23:41 INFO - 'halt_on_failure': False, 09:23:41 INFO - 'name': 'check_screen_resolution'},), 09:23:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:23:41 INFO - 'cmd': ('bash', 09:23:41 INFO - '-c', 09:23:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:23:41 INFO - 'enabled': True, 09:23:41 INFO - 'halt_on_failure': False, 09:23:41 INFO - 'name': 'check_screen_resolution'},), 09:23:41 INFO - 'run_cmd_checks_enabled': True, 09:23:41 INFO - 'suite': 'dromaeojs', 09:23:41 INFO - 'system_bits': '32', 09:23:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:23:41 INFO - 'title': 't-yosemite-r7-0035', 09:23:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:23:41 INFO - 'use_talos_json': True, 09:23:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:23:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:23:41 INFO - 'work_dir': 'build'} 09:23:41 INFO - [mozharness: 2017-03-21 16:23:41.436532Z] Running clobber step. 09:23:41 INFO - Running pre-action listener: _resource_record_pre_action 09:23:41 INFO - Running main action method: clobber 09:23:41 INFO - rmtree: /builds/slave/test/build 09:23:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:23:43 INFO - Running post-action listener: _resource_record_post_action 09:23:43 INFO - [mozharness: 2017-03-21 16:23:43.998360Z] Finished clobber step (success) 09:23:43 INFO - [mozharness: 2017-03-21 16:23:43.998490Z] Running read-buildbot-config step. 09:23:43 INFO - Running pre-action listener: _resource_record_pre_action 09:23:43 INFO - Running main action method: read_buildbot_config 09:23:43 INFO - Using buildbot properties: 09:23:43 INFO - { 09:23:43 INFO - "project": "", 09:23:43 INFO - "product": "firefox", 09:23:43 INFO - "script_repo_revision": "production", 09:23:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:23:43 INFO - "repository": "", 09:23:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:23:44 INFO - "buildid": "20170321082228", 09:23:44 INFO - "pgo_build": "False", 09:23:44 INFO - "basedir": "/builds/slave/test", 09:23:44 INFO - "buildnumber": 1, 09:23:44 INFO - "slavename": "t-yosemite-r7-0035", 09:23:44 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:23:44 INFO - "platform": "yosemite_r7", 09:23:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:23:44 INFO - "branch": "mozilla-central", 09:23:44 INFO - "repo_path": "mozilla-central", 09:23:44 INFO - "revision": "31172c0751dd45780a57e92a688c1f54cc01fe63", 09:23:44 INFO - "stage_platform": "macosx64", 09:23:44 INFO - "builduid": "593c4f0c64be4596995919f04c8eff8b", 09:23:44 INFO - "slavebuilddir": "test" 09:23:44 INFO - } 09:23:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:23:44 INFO - Running post-action listener: _resource_record_post_action 09:23:44 INFO - [mozharness: 2017-03-21 16:23:44.001912Z] Finished read-buildbot-config step (success) 09:23:44 INFO - [mozharness: 2017-03-21 16:23:44.002055Z] Running download-and-extract step. 09:23:44 INFO - Running pre-action listener: _resource_record_pre_action 09:23:44 INFO - Running main action method: download_and_extract 09:23:44 INFO - mkdir: /builds/slave/test/build/tests 09:23:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:23:44 INFO - trying https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:23:44 INFO - Downloading https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:23:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:23:44 INFO - Downloaded 1325 bytes. 09:23:44 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:23:44 INFO - Using the following test package requirements: 09:23:44 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:44 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:23:44 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:23:44 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:44 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:23:44 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:44 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:23:44 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:44 INFO - u'jsshell-mac.zip'], 09:23:44 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:44 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:23:44 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:23:44 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:44 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:23:44 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:44 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:23:44 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:44 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:23:44 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:44 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:23:44 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:23:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:23:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:23:44 INFO - Fetch https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:23:46 INFO - Expected file size: 52473703 09:23:46 INFO - Obtained file size: 52473703 09:24:02 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:24:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:24:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:24:02 INFO - Fetch https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:24:04 INFO - Expected file size: 52473703 09:24:04 INFO - Obtained file size: 52473703 09:24:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:24:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:24:20 INFO - Fetch https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:24:22 INFO - Expected file size: 14404171 09:24:22 INFO - Obtained file size: 14404171 09:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:22 INFO - trying https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:24:22 INFO - Downloading https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:24:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:24:24 INFO - Downloaded 75234384 bytes. 09:24:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:24:24 INFO - mkdir: /builds/slave/test/properties 09:24:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:24:24 INFO - Writing to file /builds/slave/test/properties/build_url 09:24:24 INFO - Contents: 09:24:24 INFO - build_url:https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:24:25 INFO - Running post-action listener: _resource_record_post_action 09:24:25 INFO - Running post-action listener: set_extra_try_arguments 09:24:25 INFO - [mozharness: 2017-03-21 16:24:25.558727Z] Finished download-and-extract step (success) 09:24:25 INFO - [mozharness: 2017-03-21 16:24:25.558883Z] Running populate-webroot step. 09:24:25 INFO - Running pre-action listener: _resource_record_pre_action 09:24:25 INFO - Running main action method: populate_webroot 09:24:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:24:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:24:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:24:25 INFO - '--tppagecycles', 09:24:25 INFO - '7'], 09:24:25 INFO - 'tests': ['tsvgm']}}, 09:24:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:24:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:24:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:24:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:24:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:24:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:24:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:24:25 INFO - 'talos_options': ['--disable-e10s'], 09:24:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:24:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:24:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:24:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:24:25 INFO - 'talos_options': ['--disable-e10s'], 09:24:25 INFO - 'tests': ['damp', 'tps']}, 09:24:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:24:25 INFO - 'tests': ['damp', 'tps']}, 09:24:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:24:25 INFO - 'tests': ['dromaeo_dom']}, 09:24:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:24:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:24:25 INFO - 'tests': ['basic_compositor_video']}, 09:24:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:24:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:24:25 INFO - 'tests': ['a11yr', 09:24:25 INFO - 'ts_paint', 09:24:25 INFO - 'tpaint', 09:24:25 INFO - 'sessionrestore', 09:24:25 INFO - 'sessionrestore_no_auto_restore', 09:24:25 INFO - 'tabpaint']}, 09:24:25 INFO - 'other-e10s': {'tests': ['a11yr', 09:24:25 INFO - 'ts_paint', 09:24:25 INFO - 'tpaint', 09:24:25 INFO - 'sessionrestore', 09:24:25 INFO - 'sessionrestore_no_auto_restore', 09:24:25 INFO - 'tabpaint']}, 09:24:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:24:25 INFO - 'ts_paint', 09:24:25 INFO - 'tpaint', 09:24:25 INFO - 'sessionrestore', 09:24:25 INFO - 'sessionrestore_no_auto_restore', 09:24:25 INFO - 'tabpaint']}, 09:24:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:24:25 INFO - 'ts_paint', 09:24:25 INFO - 'tpaint', 09:24:25 INFO - 'sessionrestore', 09:24:25 INFO - 'sessionrestore_no_auto_restore', 09:24:25 INFO - 'tabpaint']}, 09:24:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:24:25 INFO - 'tests': ['a11yr', 09:24:25 INFO - 'ts_paint', 09:24:25 INFO - 'tpaint', 09:24:25 INFO - 'sessionrestore', 09:24:25 INFO - 'sessionrestore_no_auto_restore', 09:24:25 INFO - 'tabpaint']}, 09:24:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:24:25 INFO - 'tests': ['a11yr', 09:24:25 INFO - 'ts_paint', 09:24:25 INFO - 'tpaint', 09:24:25 INFO - 'sessionrestore', 09:24:25 INFO - 'sessionrestore_no_auto_restore', 09:24:25 INFO - 'tabpaint']}, 09:24:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:24:25 INFO - 'tests': ['tsvgx', 09:24:25 INFO - 'tsvgr_opacity', 09:24:25 INFO - 'tart', 09:24:25 INFO - 'tscrollx', 09:24:25 INFO - 'cart', 09:24:25 INFO - 'tsvg_static']}, 09:24:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:24:25 INFO - 'tsvgr_opacity', 09:24:25 INFO - 'tart', 09:24:25 INFO - 'tscrollx', 09:24:25 INFO - 'cart', 09:24:25 INFO - 'tsvg_static']}, 09:24:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:24:25 INFO - 'talos_options': ['--disable-e10s'], 09:24:25 INFO - 'tests': ['tp5o']}, 09:24:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:24:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:24:25 INFO - 'talos_options': ['--disable-e10s', 09:24:25 INFO - '--xperf_path', 09:24:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:24:25 INFO - 'tests': ['tp5n']}, 09:24:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:24:25 INFO - 'talos_options': ['--xperf_path', 09:24:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:24:25 INFO - 'tests': ['tp5n']}}, 09:24:25 INFO - 'talos.zip': {'path': '', 09:24:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:24:25 INFO - Running post-action listener: _resource_record_post_action 09:24:25 INFO - [mozharness: 2017-03-21 16:24:25.577379Z] Finished populate-webroot step (success) 09:24:25 INFO - [mozharness: 2017-03-21 16:24:25.577481Z] Running create-virtualenv step. 09:24:25 INFO - Running pre-action listener: _resource_record_pre_action 09:24:25 INFO - Running main action method: create_virtualenv 09:24:25 INFO - Creating virtualenv /builds/slave/test/build/venv 09:24:25 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:24:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:24:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:24:25 INFO - Using real prefix '/tools/python27' 09:24:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:24:26 INFO - Installing distribute.............................................................................................................................................................................................done. 09:24:29 INFO - Installing pip.................done. 09:24:29 INFO - Return code: 0 09:24:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:24:29 INFO - Reading from file tmpfile_stdout 09:24:29 INFO - Output received: 09:24:29 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:24:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:29 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 0x10135bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10135e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd45a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101139d30>, '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 0x101060db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112ebe8>, '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.bd3BOMyzub/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.GDuvfzVbcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:24:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bd3BOMyzub/Render', 09:24:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:29 INFO - 'HOME': '/Users/cltbld', 09:24:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:29 INFO - 'LOGNAME': 'cltbld', 09:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:29 INFO - 'MOZ_NO_REMOTE': '1', 09:24:29 INFO - 'NO_EM_RESTART': '1', 09:24:29 INFO - 'PAGER': '/bin/cat', 09:24:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:29 INFO - 'PWD': '/builds/slave/test', 09:24:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:29 INFO - 'SHELL': '/bin/bash', 09:24:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDuvfzVbcm/Listeners', 09:24:29 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 09:24:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:29 INFO - 'USER': 'cltbld', 09:24:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:29 INFO - 'XPC_FLAGS': '0x0', 09:24:29 INFO - 'XPC_SERVICE_NAME': '0', 09:24:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:24:29 INFO - Cleaning up... 09:24:29 INFO - Return code: 0 09:24:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:29 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 0x10135bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10135e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd45a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101139d30>, '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 0x101060db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112ebe8>, '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.bd3BOMyzub/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.GDuvfzVbcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:24:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bd3BOMyzub/Render', 09:24:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:29 INFO - 'HOME': '/Users/cltbld', 09:24:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:29 INFO - 'LOGNAME': 'cltbld', 09:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:29 INFO - 'MOZ_NO_REMOTE': '1', 09:24:29 INFO - 'NO_EM_RESTART': '1', 09:24:29 INFO - 'PAGER': '/bin/cat', 09:24:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:29 INFO - 'PWD': '/builds/slave/test', 09:24:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:29 INFO - 'SHELL': '/bin/bash', 09:24:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDuvfzVbcm/Listeners', 09:24:29 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 09:24:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:29 INFO - 'USER': 'cltbld', 09:24:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:29 INFO - 'XPC_FLAGS': '0x0', 09:24:29 INFO - 'XPC_SERVICE_NAME': '0', 09:24:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:30 INFO - Downloading/unpacking psutil>=3.1.1 09:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:24:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:24:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:24:33 INFO - Installing collected packages: psutil 09:24:33 INFO - Running setup.py install for psutil 09:24:33 INFO - building 'psutil._psutil_osx' extension 09:24:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:24:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:24:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:24:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:24:34 INFO - building 'psutil._psutil_posix' extension 09:24:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:24:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:24:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:24:34 INFO - ^ 09:24:34 INFO - 1 warning generated. 09:24:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:24:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:24:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:24:34 INFO - Successfully installed psutil 09:24:34 INFO - Cleaning up... 09:24:34 INFO - Return code: 0 09:24:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:24: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')]} 09:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24: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')]} 09:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:34 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 0x10135bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10135e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd45a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101139d30>, '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 0x101060db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112ebe8>, '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.bd3BOMyzub/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.GDuvfzVbcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:24:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bd3BOMyzub/Render', 09:24:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:34 INFO - 'HOME': '/Users/cltbld', 09:24:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:34 INFO - 'LOGNAME': 'cltbld', 09:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:34 INFO - 'MOZ_NO_REMOTE': '1', 09:24:34 INFO - 'NO_EM_RESTART': '1', 09:24:34 INFO - 'PAGER': '/bin/cat', 09:24:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:34 INFO - 'PWD': '/builds/slave/test', 09:24:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:34 INFO - 'SHELL': '/bin/bash', 09:24:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDuvfzVbcm/Listeners', 09:24:34 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 09:24:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:34 INFO - 'USER': 'cltbld', 09:24:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:34 INFO - 'XPC_FLAGS': '0x0', 09:24:34 INFO - 'XPC_SERVICE_NAME': '0', 09:24:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:24:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:24:37 INFO - Installing collected packages: mozsystemmonitor 09:24:37 INFO - Running setup.py install for mozsystemmonitor 09:24:38 INFO - Successfully installed mozsystemmonitor 09:24:38 INFO - Cleaning up... 09:24:38 INFO - Return code: 0 09:24:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:38 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 0x10135bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10135e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd45a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101139d30>, '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 0x101060db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112ebe8>, '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.bd3BOMyzub/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.GDuvfzVbcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:24:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:24:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bd3BOMyzub/Render', 09:24:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:38 INFO - 'HOME': '/Users/cltbld', 09:24:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:38 INFO - 'LOGNAME': 'cltbld', 09:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:38 INFO - 'MOZ_NO_REMOTE': '1', 09:24:38 INFO - 'NO_EM_RESTART': '1', 09:24:38 INFO - 'PAGER': '/bin/cat', 09:24:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:38 INFO - 'PWD': '/builds/slave/test', 09:24:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:38 INFO - 'SHELL': '/bin/bash', 09:24:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDuvfzVbcm/Listeners', 09:24:38 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 09:24:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:38 INFO - 'USER': 'cltbld', 09:24:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:38 INFO - 'XPC_FLAGS': '0x0', 09:24:38 INFO - 'XPC_SERVICE_NAME': '0', 09:24:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:39 INFO - Downloading/unpacking jsonschema==2.5.1 09:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:24:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:42 INFO - Downloading functools32-3.2.3-2.tar.gz 09:24:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:24:42 INFO - warning: no files found matching '*.txt' 09:24:42 INFO - no previously-included directories found matching 'build' 09:24:42 INFO - no previously-included directories found matching 'dist' 09:24:42 INFO - no previously-included directories found matching '.git*' 09:24:42 INFO - Installing collected packages: jsonschema, functools32 09:24:42 INFO - Running setup.py install for functools32 09:24:42 INFO - warning: no files found matching '*.txt' 09:24:42 INFO - no previously-included directories found matching 'build' 09:24:42 INFO - no previously-included directories found matching 'dist' 09:24:42 INFO - no previously-included directories found matching '.git*' 09:24:42 INFO - Successfully installed jsonschema functools32 09:24:42 INFO - Cleaning up... 09:24:42 INFO - Return code: 0 09:24:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:42 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 0x10135bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10135e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd45a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101139d30>, '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 0x101060db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112ebe8>, '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.bd3BOMyzub/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.GDuvfzVbcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:24:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:24:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bd3BOMyzub/Render', 09:24:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:42 INFO - 'HOME': '/Users/cltbld', 09:24:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:42 INFO - 'LOGNAME': 'cltbld', 09:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:43 INFO - 'MOZ_NO_REMOTE': '1', 09:24:43 INFO - 'NO_EM_RESTART': '1', 09:24:43 INFO - 'PAGER': '/bin/cat', 09:24:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:43 INFO - 'PWD': '/builds/slave/test', 09:24:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:43 INFO - 'SHELL': '/bin/bash', 09:24:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDuvfzVbcm/Listeners', 09:24:43 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 09:24:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:43 INFO - 'USER': 'cltbld', 09:24:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:43 INFO - 'XPC_FLAGS': '0x0', 09:24:43 INFO - 'XPC_SERVICE_NAME': '0', 09:24:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:24:43 INFO - Cleaning up... 09:24:43 INFO - Return code: 0 09:24:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:43 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 0x10135bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10135e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd45a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101139d30>, '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 0x101060db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112ebe8>, '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.bd3BOMyzub/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.GDuvfzVbcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:24:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:24:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bd3BOMyzub/Render', 09:24:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:43 INFO - 'HOME': '/Users/cltbld', 09:24:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:43 INFO - 'LOGNAME': 'cltbld', 09:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:43 INFO - 'MOZ_NO_REMOTE': '1', 09:24:43 INFO - 'NO_EM_RESTART': '1', 09:24:43 INFO - 'PAGER': '/bin/cat', 09:24:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:43 INFO - 'PWD': '/builds/slave/test', 09:24:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:43 INFO - 'SHELL': '/bin/bash', 09:24:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDuvfzVbcm/Listeners', 09:24:43 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 09:24:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:43 INFO - 'USER': 'cltbld', 09:24:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:43 INFO - 'XPC_FLAGS': '0x0', 09:24:43 INFO - 'XPC_SERVICE_NAME': '0', 09:24:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:43 INFO - Downloading/unpacking blobuploader==1.2.4 09:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:46 INFO - Downloading blobuploader-1.2.4.tar.gz 09:24:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:24:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:24:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:47 INFO - Downloading docopt-0.6.1.tar.gz 09:24:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:24:47 INFO - Installing collected packages: blobuploader, requests, docopt 09:24:47 INFO - Running setup.py install for blobuploader 09:24:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:24:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:24:47 INFO - Running setup.py install for requests 09:24:48 INFO - Running setup.py install for docopt 09:24:48 INFO - Successfully installed blobuploader requests docopt 09:24:48 INFO - Cleaning up... 09:24:48 INFO - Return code: 0 09:24:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:48 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 0x10135bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10135e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd45a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101139d30>, '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 0x101060db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112ebe8>, '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.bd3BOMyzub/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.GDuvfzVbcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:24:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:24:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bd3BOMyzub/Render', 09:24:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:48 INFO - 'HOME': '/Users/cltbld', 09:24:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:48 INFO - 'LOGNAME': 'cltbld', 09:24:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:48 INFO - 'MOZ_NO_REMOTE': '1', 09:24:48 INFO - 'NO_EM_RESTART': '1', 09:24:48 INFO - 'PAGER': '/bin/cat', 09:24:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:48 INFO - 'PWD': '/builds/slave/test', 09:24:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:48 INFO - 'SHELL': '/bin/bash', 09:24:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDuvfzVbcm/Listeners', 09:24:48 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 09:24:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:48 INFO - 'USER': 'cltbld', 09:24:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:48 INFO - 'XPC_FLAGS': '0x0', 09:24:48 INFO - 'XPC_SERVICE_NAME': '0', 09:24:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:48 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-6EazuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:48 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-hNS3ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:48 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-6iz_Ty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:49 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Jjzpuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:49 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-siUdVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:49 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-pyNxRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:49 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-qa7ivW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:49 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-TRxmaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:49 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-uDBgJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:49 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-hyiVc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:49 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-j6IpUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:49 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-FKWEjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:49 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-ju3JoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:50 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-kCDvc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:50 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-om_qha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:50 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-KrUid5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:50 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-bPY6uP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:24:50 INFO - Running setup.py install for manifestparser 09:24:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:24:50 INFO - Running setup.py install for mozcrash 09:24:50 INFO - Running setup.py install for mozdebug 09:24:50 INFO - Running setup.py install for mozdevice 09:24:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:24:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:24:51 INFO - Running setup.py install for mozfile 09:24:51 INFO - Running setup.py install for mozhttpd 09:24:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:24:51 INFO - Running setup.py install for mozinfo 09:24:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:24:51 INFO - Running setup.py install for mozInstall 09:24:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:24:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:24:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:24:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:24:51 INFO - Running setup.py install for mozleak 09:24:51 INFO - Running setup.py install for mozlog 09:24:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:24:52 INFO - Running setup.py install for moznetwork 09:24:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:24:52 INFO - Running setup.py install for mozprocess 09:24:52 INFO - Running setup.py install for mozprofile 09:24:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:24:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:24:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:24:52 INFO - Running setup.py install for mozrunner 09:24:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:24:52 INFO - Running setup.py install for mozscreenshot 09:24:52 INFO - Running setup.py install for moztest 09:24:52 INFO - Running setup.py install for mozversion 09:24:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:24:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:24:53 INFO - Cleaning up... 09:24:53 INFO - Return code: 0 09:24:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:53 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 0x10135bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10135e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd45a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101139d30>, '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 0x101060db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112ebe8>, '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.bd3BOMyzub/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.GDuvfzVbcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:24:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bd3BOMyzub/Render', 09:24:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:53 INFO - 'HOME': '/Users/cltbld', 09:24:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:53 INFO - 'LOGNAME': 'cltbld', 09:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:53 INFO - 'MOZ_NO_REMOTE': '1', 09:24:53 INFO - 'NO_EM_RESTART': '1', 09:24:53 INFO - 'PAGER': '/bin/cat', 09:24:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:53 INFO - 'PWD': '/builds/slave/test', 09:24:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:53 INFO - 'SHELL': '/bin/bash', 09:24:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDuvfzVbcm/Listeners', 09:24:53 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 09:24:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:53 INFO - 'USER': 'cltbld', 09:24:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:53 INFO - 'XPC_FLAGS': '0x0', 09:24:53 INFO - 'XPC_SERVICE_NAME': '0', 09:24:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:53 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-OEuhrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:53 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-zz9qUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:53 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-sWrI5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:53 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-ysi1n9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:53 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-GrxCfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:53 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-gXSlLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:54 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Q00eAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:54 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-TtRX58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:54 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-GzVR51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:54 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-S0BmiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:54 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-EbJOC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:54 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-i2DCGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:54 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-_E56mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:54 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Modl35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:54 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-EH4yhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:54 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-A5HRHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:55 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-FnZ7XS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:24:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:57 INFO - Downloading blessings-1.6.tar.gz 09:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:24:57 INFO - Installing collected packages: blessings 09:24:57 INFO - Running setup.py install for blessings 09:24:58 INFO - Successfully installed blessings 09:24:58 INFO - Cleaning up... 09:24:58 INFO - Return code: 0 09:24:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:24:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:24:58 INFO - Reading from file tmpfile_stdout 09:24:58 INFO - Current package versions: 09:24:58 INFO - blessings == 1.6 09:24:58 INFO - blobuploader == 1.2.4 09:24:58 INFO - docopt == 0.6.1 09:24:58 INFO - functools32 == 3.2.3-2 09:24:58 INFO - jsonschema == 2.5.1 09:24:58 INFO - manifestparser == 1.1 09:24:58 INFO - mozInstall == 1.12 09:24:58 INFO - mozcrash == 1.0 09:24:58 INFO - mozdebug == 0.1 09:24:58 INFO - mozdevice == 0.49 09:24:58 INFO - mozfile == 1.2 09:24:58 INFO - mozhttpd == 0.7 09:24:58 INFO - mozinfo == 0.9 09:24:58 INFO - mozleak == 0.1 09:24:58 INFO - mozlog == 3.4 09:24:58 INFO - moznetwork == 0.27 09:24:58 INFO - mozprocess == 0.25 09:24:58 INFO - mozprofile == 0.28 09:24:58 INFO - mozrunner == 6.13 09:24:58 INFO - mozscreenshot == 0.1 09:24:58 INFO - mozsystemmonitor == 0.3 09:24:58 INFO - moztest == 0.8 09:24:58 INFO - mozversion == 1.4 09:24:58 INFO - psutil == 3.1.1 09:24:58 INFO - requests == 1.2.3 09:24:58 INFO - wsgiref == 0.1.2 09:24:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:58 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 0x10135bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10135e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd45a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101139d30>, '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 0x101060db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112ebe8>, '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.bd3BOMyzub/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.GDuvfzVbcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:24:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bd3BOMyzub/Render', 09:24:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:58 INFO - 'HOME': '/Users/cltbld', 09:24:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:58 INFO - 'LOGNAME': 'cltbld', 09:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:58 INFO - 'MOZ_NO_REMOTE': '1', 09:24:58 INFO - 'NO_EM_RESTART': '1', 09:24:58 INFO - 'PAGER': '/bin/cat', 09:24:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:58 INFO - 'PWD': '/builds/slave/test', 09:24:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:58 INFO - 'SHELL': '/bin/bash', 09:24:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDuvfzVbcm/Listeners', 09:24:58 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 09:24:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:58 INFO - 'USER': 'cltbld', 09:24:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:58 INFO - 'XPC_FLAGS': '0x0', 09:24:58 INFO - 'XPC_SERVICE_NAME': '0', 09:24:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:24:58 INFO - Cleaning up... 09:24:58 INFO - Return code: 0 09:24:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10135bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10135e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff96bd45a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101139d30>, '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 0x101060db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112ebe8>, '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.bd3BOMyzub/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.GDuvfzVbcm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:24:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:24:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bd3BOMyzub/Render', 09:24:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:58 INFO - 'HOME': '/Users/cltbld', 09:24:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:58 INFO - 'LOGNAME': 'cltbld', 09:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:58 INFO - 'MOZ_NO_REMOTE': '1', 09:24:58 INFO - 'NO_EM_RESTART': '1', 09:24:58 INFO - 'PAGER': '/bin/cat', 09:24:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:58 INFO - 'PWD': '/builds/slave/test', 09:24:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:58 INFO - 'SHELL': '/bin/bash', 09:24:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDuvfzVbcm/Listeners', 09:24:58 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 09:24:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:58 INFO - 'USER': 'cltbld', 09:24:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:58 INFO - 'XPC_FLAGS': '0x0', 09:24:58 INFO - 'XPC_SERVICE_NAME': '0', 09:24:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:24:59 INFO - Cleaning up... 09:24:59 INFO - Return code: 0 09:24:59 INFO - Running post-action listener: _resource_record_post_action 09:24:59 INFO - Running post-action listener: _start_resource_monitoring 09:24:59 INFO - Starting resource monitoring. 09:24:59 INFO - [mozharness: 2017-03-21 16:24:59.108623Z] Finished create-virtualenv step (success) 09:24:59 INFO - [mozharness: 2017-03-21 16:24:59.109645Z] Running install step. 09:24:59 INFO - Running pre-action listener: _resource_record_pre_action 09:24:59 INFO - Running main action method: install 09:24:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:24:59 INFO - Reading from file tmpfile_stdout 09:24:59 INFO - Detecting whether we're running mozinstall >=1.0... 09:24:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:24:59 INFO - Reading from file tmpfile_stdout 09:24:59 INFO - Output received: 09:24:59 INFO - Usage: mozinstall [options] installer 09:24:59 INFO - Options: 09:24:59 INFO - -h, --help show this help message and exit 09:24:59 INFO - -d DEST, --destination=DEST 09:24:59 INFO - Directory to install application into. [default: 09:24:59 INFO - "/builds/slave/test"] 09:24:59 INFO - --app=APP Application being installed. [default: firefox] 09:24:59 INFO - mkdir: /builds/slave/test/build/application 09:24:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:25:19 INFO - Reading from file tmpfile_stdout 09:25:19 INFO - Output received: 09:25:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:25:19 INFO - Running post-action listener: _resource_record_post_action 09:25:19 INFO - [mozharness: 2017-03-21 16:25:19.892592Z] Finished install step (success) 09:25:19 INFO - [mozharness: 2017-03-21 16:25:19.892731Z] Running run-tests step. 09:25:19 INFO - Running pre-action listener: _resource_record_pre_action 09:25:19 INFO - Running main action method: run_tests 09:25:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:25:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:25:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:25:19 INFO - 2017-03-21 09:25:19.906 screenresolution[1642:7380] starting screenresolution argv=screenresolution get 09:25:19 INFO - 2017-03-21 09:25:19.909 screenresolution[1642:7380] Display 0: 1600x1200x32@60 09:25:19 INFO - 2017-03-21 09:25:19.915 screenresolution[1643:7382] starting screenresolution argv=screenresolution list 09:25:19 INFO - Available Modes on Display 0 09:25:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:25:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:25:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:25:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:25:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:25:20 INFO - Intel Iris: 09:25:20 INFO - Chipset Model: Intel Iris 09:25:20 INFO - Type: GPU 09:25:20 INFO - Bus: Built-In 09:25:20 INFO - VRAM (Dynamic, Max): 1536 MB 09:25:20 INFO - Vendor: Intel (0x8086) 09:25:20 INFO - Device ID: 0x0a2e 09:25:20 INFO - Revision ID: 0x0009 09:25:20 INFO - Displays: 09:25:20 INFO - Display: 09:25:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:25:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:25:20 INFO - Display Serial Number: 1600x1200 60 09:25:20 INFO - Main Display: Yes 09:25:20 INFO - Mirror: Off 09:25:20 INFO - Online: Yes 09:25:20 INFO - Rotation: Supported 09:25:20 INFO - Return code: 0 09:25:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:25:20 INFO - Python 2.7.3 09:25:20 INFO - Return code: 0 09:25:20 INFO - grabbing minidump binary from tooltool 09:25:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:20 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 0x101139d30>, '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 0x101060db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:25:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:25:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:25:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:25:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:25:20 INFO - Return code: 0 09:25:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:25:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:25:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:25:20 INFO - ENV: RUST_BACKTRACE is now 1 09:25:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:25:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:25:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0035 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:25:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bd3BOMyzub/Render', 09:25:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:25:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:25:20 INFO - 'HOME': '/Users/cltbld', 09:25:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:25:20 INFO - 'LOGNAME': 'cltbld', 09:25:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:25:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:25:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:20 INFO - 'MOZ_NO_REMOTE': '1', 09:25:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:25:20 INFO - 'NO_EM_RESTART': '1', 09:25:20 INFO - 'PAGER': '/bin/cat', 09:25:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:25:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:20 INFO - 'PWD': '/builds/slave/test', 09:25:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:25:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:25:20 INFO - 'RUST_BACKTRACE': '1', 09:25:20 INFO - 'SHELL': '/bin/bash', 09:25:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GDuvfzVbcm/Listeners', 09:25:20 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 09:25:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:25:20 INFO - 'USER': 'cltbld', 09:25:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:25:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:25:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:20 INFO - 'XPC_FLAGS': '0x0', 09:25:20 INFO - 'XPC_SERVICE_NAME': '0', 09:25:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:25:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:25:20 INFO - mozversion application_buildid: 20170321082228 09:25:20 INFO - mozversion application_changeset: 31172c0751dd45780a57e92a688c1f54cc01fe63 09:25:20 INFO - mozversion application_display_name: Nightly 09:25:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:25:20 INFO - mozversion application_name: Firefox 09:25:20 INFO - mozversion application_remotingname: firefox 09:25:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:25:20 INFO - mozversion application_vendor: Mozilla 09:25:20 INFO - mozversion application_version: 55.0a1 09:25:20 INFO - mozversion platform_buildid: 20170321082228 09:25:20 INFO - mozversion platform_changeset: 31172c0751dd45780a57e92a688c1f54cc01fe63 09:25:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:25:20 INFO - mozversion platform_version: 55.0a1 09:25:20 INFO - using testdate: 1490113520 09:25:20 INFO - actual date: 1490113520 09:25:20 INFO - starting webserver on 'localhost:49237' 09:25:20 INFO - SUITE-START | Running 2 tests 09:25:20 INFO - TEST-START | dromaeo_css 09:25:20 INFO - operating with platform_type : mac_ 09:25:20 INFO - Initialising browser for dromaeo_css test... 09:25:20 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpmjO3Ff/profile http://localhost:49237/getInfo.html) 09:25:23 INFO - PID 1653 | __metrics Screen width/height:1600/1200 09:25:23 INFO - PID 1653 | colorDepth:24 09:25:23 INFO - PID 1653 | Browser inner width/height: 1024/689 09:25:23 INFO - PID 1653 | __metrics 09:25:23 INFO - PID 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:25:23 INFO - PID 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:25:24 INFO - TEST-INFO | 1653: exit 0 09:25:24 INFO - Browser initialized. 09:25:24 INFO - Running cycle 1/1 for dromaeo_css test... 09:25:24 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpmjO3Ff/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:25:36 INFO - PID 1656 | RSS: Main: 232423424 09:25:36 INFO - PID 1656 | 09:28:41 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 09:28:42 INFO - PID 1656 | RSS: Main: 358264832 09:28:42 INFO - PID 1656 | 09:30:25 INFO - PID 1656 | ************************* 09:30:25 INFO - PID 1656 | A coding exception was thrown and uncaught in a Task. 09:30:25 INFO - PID 1656 | 09:30:25 ERROR - PID 1656 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:30:25 INFO - PID 1656 | Full stack: 09:30:25 INFO - PID 1656 | ************************* 09:31:45 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 09:31:46 INFO - PID 1656 | RSS: Main: 365543424 09:31:46 INFO - PID 1656 | 09:34:49 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 09:34:49 INFO - PID 1656 | RSS: Main: 336101376 09:34:49 INFO - PID 1656 | 09:37:52 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 09:37:52 INFO - PID 1656 | RSS: Main: 336375808 09:37:52 INFO - PID 1656 | 09:40:56 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 09:40:56 INFO - PID 1656 | RSS: Main: 333459456 09:40:56 INFO - PID 1656 | 09:44:00 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 09:44:00 INFO - PID 1656 | RSS: Main: 593829888 09:44:00 INFO - PID 1656 | 09:44:00 INFO - PID 1656 | __start_tp_report 09:44:00 INFO - PID 1656 | _x_x_mozilla_page_load 09:44:00 INFO - PID 1656 | _x_x_mozilla_page_load_details 09:44:00 INFO - PID 1656 | |i|pagename|runs| 09:44:00 INFO - PID 1656 | |0;dojo.html;3252.235724604718;3430.862765489379;3467.306538692262;3475.1485885957936;3510.578730552334;6011.909821352683;6104.267487901447;6113.908291375633;6122.785702500413;6149.3235744068115;6077.4530292273685;6110.103144028831;6125.968084364126;6136.9079463808075;6144.9692751536295;5291.2327712481665;5479.287692599956;5504.91742623861;5511.53151982082;5511.751971161308;5587.916181257264;5630.605857589971;5643.068893632546;5658.858528536778;5704.1443783432605;5400.78396864125;5497.422770609069;5505.642133261325;5508.14623733322;5574.1638754187;5496.80761173361;5503.147012213113;5516.172574113915;5520.806771762969;5533.833984980417;5386.380566234894;5426.375966763833;5456.345238571397;5462.399136095027;5468.863278418072;6059.45464908156;6085.452309292141;6087.512998960073;6116.174316467264;6141.179174348115;6421.518386121013;6421.64680942548;6430.196225471816;6443.0335449682925;6455.322191169954;6425.1968503937005;6511.674416279167;6535.607863528204;6537.313582073909;6593.044008618724;6225.937740622581;6249.937500624981;6280.528960327993;6282.465990390822;6289.559730818799;6764.289749576322;6794.218664853605;6796.93203067963;6800.4219641330055;6821.829454263683;6213.502919766408;6267;6309.305976342599;6377.23473183221;6416.871662566816;5705.914411283834;6456.644884531396;6591.967040164768;6594.769183078569;6597.637129957899;6448.580842245239;6487.643179625166;6488.188976377953;6490.928996715489;6495.837604059936;5432.239486471897;6302.1177035215105;6324.588901721373;6379.649119298483;6488.124103246096;5197.402298735618;5315.548178404802;5342.893142137135;5345.412004679482;5352.304200453916;4627.537246275346;4633.258678611406;4641.628669706415;4645.814167433265;4651.232546629836;5093.0577843099145;6190.907136392958;6199.473044791153;6242.563020588606;6260.248770147611;6373.139626150503;6450.967745161338;6456.644884531302;6555.278919318871;6621.106150669694;6230.376962303733;6239.438450539473;6266.342034086447;6269.62382257066;6276.717547710364;5695.117256825199;5774.249347584787;5784.566157538202;5787.392323806024;5817.941820581825;6470.9676451619325;6561.40947314744;6574.27682954865;6817.048604878248;6853.691583878837;6367.968160159169;6379.075034120119;6384.3934826191435;6390.648514331757;6394.008928616072;6650.135482387259;6741.1910570731825;6769.93230067693;6780.86438271222;6784.151981002375;6409.807705768834;6496.285408605144;6503.772367967098;6506.577072490273;6507.869842603027;4937.28409380645;6275.560710750204;6578.671066446755;6587;6626.602403855784;5505.944940550543;6219.595726277777;6258.374162583706;6300.991841305369;6355.936440635719;6241.344658810941;6335.208098987627;6346.492280617632;6352.809415717536;6361.745530178741;6104.603200791934;6208.192934918432;6226.128342032029;6256.155419018374;6256.687165641791;6191.380861913773;6340.968295158495;6360.173177486897;6382.680865956777;6403.64779937108;6284.24589049317;6285.74857005733;6290.622562646256;6298.433141017331;6312.147860038836 09:44:00 INFO - PID 1656 | |1;ext.html;734.3611058379211;746.3916907720204;748.2704363245832;782.1904329019466;803.9155888631692;35040.47439288413;35804.46293305603;35839.2832143357;35888.46167307486;35946.460803087975;38426;38871.22257554847;38928.63749768759;38932.61067389332;38960.41559376611;2497.500499900018;2828.5757136429547;2881.1644623059287;2885.2642576143053;2886.2495751104757;2543.135333986444;2841.715828417152;2908.81092728973;2909.0545572688857;2911.883524659011;2903.0274857922623;2906.825590464579;2911.723386278311;2917.3290143266954;2919.810212336202;2499.8375105618074;2881.2364723348223;2908.941821163565;2910.9126726198247;2918.591397204393;2542.9491410171695;2830.4197639483855;2893.117599132264;2905.3027273454218;2907.912762617125;36196.55213791445;36580.451293230624;36619.267614647826;36621.901342959754;36664.083397915;2983.226164409356;2993.895213667511;3000.729934305901;3032.6209223847018;3051;26819.463610727675;26824.865875670694;26976.59535106975;27211;27787.444251114863;2744.4373903349965;2772.085211880075;2779.6664400271893;2815.9014434494898;2832.6459192600923;322245;322795;323957.38021309976;325292;325753;37816.62183378186;38487.845364639106;49527;49589.75205123951;49720.751396242784;40256.79871600623;40284.99287517835;40285.194296113914;40375.58685445995;40409;12971.286579238233;13406.798898016537;13417.597472075853;13431.932840335736;13440.395182216824;11297.435128243555;11300.982911538002;11302.813204613398;11306.434678266054;11349.943250283695;11751.529938802523;11770.470328835223;11782.351970641526;11786.764264714659;11879.22785018971;5313.362396512443;5363.758630861543;5371.75827087782;5378.273933019071;5379.35447746273;965.9855102173473;977.726236653738;988.6045581767207;992.3896803465816;1001.2890847498212;18419.447416577525;23184.4203894904;23321.883390582938;23484.295471135894;23519.149869410066;1070.7590792071721;1079.8866119057543;1080.9513571889283;1104.1884215101893;1123.466353482106;11231.494582743797;11856.347900865536;11875.70310742221;11888.702782430508;11947.880521194851;416062;417172;417713;417807;417957;51137.74431127895;51287.74356128195;51407;51447.48552514427;51504.22743658848;1432.2122832442114;1476.534891509171;1495.3196295685436;1496.1696258576594;1504.751715966875;23198.3040508785;23291.650625240298;23321.883390582938;23342.64986025177;23744.4063898397;37310;37396.06509837276;37397.626023739416;37551;37573.62426375701;420586;421153;421507;421770;421821;29908.850455747583;31133.53299700461;31208.063758087294;31229;32150.35699286048;39691;39805.40291895566;40562.594374057066;40951.38572921349;40965;34083.14792130118;34138.14654633654;34180.65819341874;35058.298834022666;35107.64892351146;31702.682973169973;32975.8458453953;33840.49239261364;35209.82395088008;35290.647093528736;34042.65957340494;34849.954501365;34969.475457867644;35020.64979350276;35033.64966350406;29258.414831703674;30508.694913051473;30894.53658195084;30980;31061.844690777307;23338.416539586648;23541.411464713517;23553.41116472102;23561.146619990417;23825.260095088594 09:44:00 INFO - PID 1656 | |2;jquery.html;386.6906474820143;410.55249777742256;421.59948049353113;421.6605632465865;430.3652113133129;13152.868471315283;15076.246187690627;17540.56148596284;21399.288056955444;21704.782952170473;22021.559568808614;22432.77567224327;23179.30462086136;23434.64848027281;23518.76481235187;4111.856085037017;4178.56125106864;4180.787571604246;4232.619064284214;4238.194742998836;2825.3219227385484;4058.1072164123993;4210.894727631819;4221.915561688765;4235.53409124996;2945.720156585121;3957.7229593928437;4139.979300103495;4164.437800896885;4171.124063946575;4154.813033413504;4162.91674166515;4165.770882601456;4165.770882601456;4167.74993500391;3715.294094122135;3793.620637936212;3869.7097717671286;3872.2642697887313;3885.5531613864487;26096;28118.578221326698;28257.01100461474;28278.717212827814;28291.575626365622;1852.9536761580935;1982.187303205693;1989.7313862628505;2002.5193953451212;2017.8083082107323;15346.386144554315;15895.364185432569;15895.761563576556;16154.253776857962;17018.893771904786;1627.951161465146;1644.539528931901;1646.662434200986;1680.420255012019;1699.6515714278544;145002;162579.18710406372;163430;164233;166676;11829.9889018867;12133.27200367963;12162.209456385306;12170.878291216974;12416.510018797622;16366.672666546603;16378.26297816601;16397.91801040987;16415.179241037902;16418.917905410395;8712.91287087121;8942.552872356357;8946;8978.46129232235;9055.411398259092;8545.487270763773;8572.657093716307;8586.914130858611;8745.693900713444;8768.386212965032;7638.236176382317;7765.572893490912;7809.414293927978;7812.257835505618;7829;1867.617138486607;2025.9262590826886;2066.5763518478675;2078.740157480315;2289.725232972054;798.9840203195904;868.3010176807604;924.3575714878095;935.9625614975324;957.3729207369184;17535.509481693945;18795.81204187968;20163.193472260944;20957.790422095582;21023.15907363719;936.4942930817418;965.2037069417618;997.4314640654757;1007.6624330849247;1007.8588997540351;10108;10466.267361284788;10669;10840.295380800222;10853.294535855144;406556;409235;410082;416408;707404;112108;153346.93306133815;157408;159664;159808;1156.1040193849833;1164;1168.182272409317;1168.6260396672983;1183.1303991566192;13573.524926627915;16928.57678558046;17926.91036544809;17952.281908723504;18021.909890450464;60527.394726052175;60926.39073609207;61247.69376153269;62061;62157;84371;85890.14109859071;86856;88000.67998979893;88384.5580772092;6328.0824280478155;7818.6481608328895;8181.79545511367;8437.734339849072;9018.864717029119;13170.341482926007;13718.588442346745;13786;14215.218163001135;14292.571222863331;11406.48670809799;11992.64022079339;12012.579559715368;12093;12147.57483488109;11506.30962142274;11692.766144676889;11795.41022948832;12049.879501204876;12140;11679.416029198675;11961.043116550481;12223.694407639525;12251.632451026484;12296.323702196465;8625.82748345017;8769.210771030637;8803.647854085764;8908.420952638058;8910;11574.074074073886;11619.5933142343;11969.341686207343;11988.64034078979;12047.819282710941 09:44:00 INFO - PID 1656 | |3;mootools.html;1238.5293588436393;1410.2596137028065;1435.504750860953;1440.87752541034;1443.3360654099108;9987.550560224789;10350.171986241101;10365.429901355423;10368.377897326165;10370.274080814328;10090.545925433355;10407.791844163112;10425.478726063686;10442.269041167121;10455.686329410111;2372.5373552157293;2405.8075353971726;2406.6991626046743;2413.758624137581;2414.987925060372;2029.989850050755;2198.7801219877965;2340.1575432844247;2394.1022116706235;2405.410674384769;2079.854410191287;2107.093949601671;2108.219958615307;2111.8416118791074;2113.2814842953394;2303.8156947444163;2386.85678859269;2388.9283321500384;2391.1630929174826;2395.1018368111872;2304.7695230476984;2370.182287110944;2375.786179243876;2381.7975472084895;2382.440126570272;10592.735181620445;10708.982646648556;10716.839247411295;10720.14238860897;10777.299475534066;6054.485368743618;6274.498040156776;6276.623402595813;6291.245050593912;6298.874022519524;3937.803109844525;3965.20695860829;3986.3223252047223;4002.959970400288;4009.3384591542363;1195.4799662146993;1517.8633922946876;1537.6540278437374;1563.5700182449802;1632.9673406531804;13938.93030534841;14034.368453419518;14177.503787367392;14366.640833979025;14405;4889.633277504201;5106.821261255838;5187.325647665779;5204.8959020819375;5214.478552144755;10937;10953;10954.616588419367;10962.671119866416;10983.505742241618;6128.050152226412;6129.662868542184;6131.294901086432;6137.294211165684;6139.6009259398;5264.9736751315995;5597.412271711493;5648.406917273709;5669.348024977098;5679.971600141973;3963.2403453063134;4049.4330793688905;4069.409935559327;4105.261053010427;4106.2198182345255;1215.7629262293767;1220.0178856020812;1234.2594443334108;1235.8331625230658;1238.9221377401539;618.7586841131962;622.5144387377852;644.291279592454;649.7141257846532;662.1755913887145;5384;5934.881302374015;6060.393960603992;6249.25008998923;6295.087212354183;857.4555157474923;885.964561417549;890.4683943616968;895.7491902267376;944.5548311084052;3937.330653788863;3981.900452488653;4035.5964403559997;4153.605407486284;4164.500259968823;12737.980961523055;13285.937125029974;13293.401796919163;13298.800517992238;13313.600591982062;13174.736505270033;13178.934105329412;13184.802227966587;13186.472541098248;13212.273324967027;9676.806463870824;9738.610455581698;9955.25335599833;10036;10038;5396.730163491731;5451.727413629382;6006.159137720635;6149.169862068564;6357.682115894094;11635.941820290844;12175.634730958085;12190.878091218974;12192.390380480763;12194.51221951112;11874.406279686154;11997.280163190237;12224.388780561114;12390.256584604953;12435.129540932048;6744.8651026978205;7410.11078670563;7853.960730196312;7901.762947111366;7905.367570594453;9467.431954082776;9553.808923821623;9657.179139773198;9672.854907176257;9680.225581953286;8551.529665868187;8629.525376104124;8679.86980195285;8709.564521773758;8760;6721.630310332783;8483.024452188078;8599;8772.91227087721;8784.033756286675;7532.472726909064;7614.124375696866;7637.312641862037;7638.197989211053;7640.235976402315;5238.71187084714;5551.444855514416;5671.716414179192;5709.600327977002;5742.023855944416;5584.804531841366;5691.345495268097;5736;5738.282714660668;5753.424657534213 09:44:00 INFO - PID 1656 | |4;prototype.html;353.8637624514562;363.9799811010394;385.95368555773314;393.52580140930166;395.63996762945726;5486.917696234555;5504.889902201953;5868.354481007096;5912.142739302799;5991.490723288525;4994.75026248687;5076.974615126928;5826.960563802667;5852.414758524156;5876.177335173079;3916.314644937146;4415.785658943784;4551.840685576006;4568.771561421932;4582.266837306031;3934.429507721393;4560.749158796281;4591.908161836761;4599.126166028461;4607.0785842831565;3797.6961843052627;4263.786810659471;4537.523560026199;4561.156186105581;4563.498015218323;4457.710248833848;4478.171538265415;4517.164324599943;4526.411566496367;4526.637868970507;3968.6626636735914;3975.8608448704445;3985.1070741397893;3993.820278087493;4021.577734337882;4928.6303527235605;4959.752012399402;5035;5444.96545656324;5704;4794.520547945178;4796.5683088521855;5059.6458247922665;5106.157484015133;5142.280080788692;5573.498385145316;5706.42935706426;6215.658138802364;6226.516907826403;6227.688615569204;3651.8904432867057;4159;4203;4219.556946520602;4228.429162063113;5288.338957630296;6484.481241500715;6509.99095140254;6514.1531600891585;6578.868422631521;4183.602557757069;4258.233517966796;4283.800535849905;4286.357046442996;4295.355696645465;3679.748885378984;4000.1599664070873;4265.893352666209;4350.086481838849;4359.760213188241;4388.319810429389;4419.917120305546;4703.365045718784;5117.5394214520875;5178.844634660966;5921.259842519685;6269.905951410732;6275.560710750295;6276.811695649138;6291.811245662638;1348.7726169185994;1463.8828893688478;1482.6574013059555;1487.4793822162158;1497.697003606869;821.5112008355019;939.2110627073291;976.9071938165863;990.5493000684671;991.6876184002016;5895.204147440127;5898.203742494708;5929.495992840571;5941.792037278675;5990.311114221833;6147.938520614826;6437.7789662567275;6456.709448074848;6475.546711730133;6480.967595161994;5249.632525723239;5663.433656634304;5778.451047150598;5893.823185304448;5905.763769449174;2849.444358350102;3230.935381292361;3383.780054296463;3384.610769761509;3393.117789374731;5581.330240371181;6378.330275321117;6614.768483103165;6701.262861085277;6711.96602548089;6619.602823830585;6624.164360017215;6625.569337993015;6629.5359324846795;6632.602043877383;6242.7826573818575;6310.274318453345;6387.936120638827;6688.799336019927;6700.693364793916;5422;5772.018756811268;6393.264774550986;6398.936010639834;6399.616023038447;5457.744718714797;5532.806351777668;6202.038684003986;6327.930953487463;6347.587406818542;5551.167324901297;5664.688442135557;5958.821235362946;6618.7352505899225;6646.767363142267;3621.4929909813172;4238.9826441654395;4239.660827133884;4259.233337999066;4263.296556068214;5655.660660360391;6116.694165291807;6360.045993101072;6412.326705696021;6438.39449908053;5654.434556544313;6153.72308246139;6188.412100850412;6200.162977997912;6222;4929.802807887788;5126.8718282041755;5203.16749320107;5754.424557544212;6200.844978875564;5662.688552129673;5945.851353716019;6000.0999850022845;6003;6017.669028203491;5638.689872057076;5659.688717120598;6160.199174107337;6183.783567575113;6190.535709821871;5263.763130659053;6028.638281703112;6029.487493563096;6050.9394906050375;6189.381061893775 09:44:00 INFO - PID 1656 | |5;yui.html;711.3455620828837;714.3784907130797;732.7325526182943;736.7053178728507;737.8413641067169;14910.254487275633;15778.605534861634;15780;15785.052896826184;15797.763033554505;14513.49202777903;14560.27198640069;14603.050801697882;14661.706765864703;14677.77983330251;935.3966691484005;948.3124734567452;967.7387105481516;969.2100937735722;969.6266937229183;955.4744890310336;964.2671569607079;964.5900492290783;964.662368171138;966.0098399140867;828.2835347424465;1006.0543089495887;1018.724944265048;1026.6714651311584;1028.4497793680382;1008.5209525475414;1011.4386515483867;1012.4583347908871;1012.9240306977006;1013.4983183324227;744.3710064995071;749.0374868294216;749.2881762325812;749.6064566102785;749.9550026998397;16093.517194484184;16194.676106477922;16263.918680406641;16289;16464.917675411667;14378.784318235235;14815.629609259855;14857.925710371486;14867.92566037163;14879;9857.90142098577;11598.130140239431;11652.76694466106;11696.47365868538;11700.94691477771;3033.4994725870206;3037.112496005117;3086.3364376659124;3111.844407779602;3189.9951515272614;110735.44632276786;113534.99999999917;113675.43162284218;115168.27247591293;117203.99999999914;11074.723131921766;11304.03915667161;11320.660380188607;11711.238769479956;11799.52801887915;24466.633000505008;24923.750762492506;24928.750712493005;24962.500749984898;25059.749402506106;7663.425243106789;7692.0384951881015;7791.454598178187;7918.445708800328;8036.517808931483;3971.0866500704424;4040.4141399496907;4047.1096358801606;4055.6349928506575;4079.204555111725;4088.7751173685733;4130.421740956269;4142.855000074983;4160.022394737234;4173.123644034787;759.7948553890419;766.52858492027;766.8542976834384;773;778.3967425245366;551.9530839878576;559.932808063035;568.9175069614882;576.3631187537794;584.9883002340015;8781.560921953876;9380.48407337603;9405.05949405054;9863.753406164904;9973.052560006789;539.1965970703702;560.5263552298302;577.6216578141324;585.7616385494433;585.7920438244447;4345.283028300295;4769.2369220925175;4828.141165651249;5143.691378517301;5173.405058418255;95876;97413.02586974182;98138.0186198129;99654.5017274909;102044.97955020503;49722;61447;78338;81730;87786.56106719426;68183.31816681869;72316.63841680864;73287;74151;75705.62147189339;10485.68542943713;10500.883094203256;10711.08955738771;10739.301945373525;10783.083437907866;23334.883325583265;36046.45930310995;36910.07724806794;37104.88685339444;37877.43183852299;44695.882602935184;45947.62157135292;46164.53835461737;46840;46859.033964226845;10251.58993640246;11874.406279686154;13048.478060877458;13270.336483175995;13765;14007.019508634297;14101.717965640424;14222.35999380051;14419.78370324467;14419.85580144227;13162;13176.670583235496;13315.46738130464;13609.251491168081;13759.587212383645;13139.73720525565;13412.93293533526;13426.127301725357;13594.864051359755;13886.583402497941;12849.056603773584;13070.411831467416;13098.541551045668;13244.668883277995;13337.599872003855;10647.520861561094;10860.78278434411;10874.510647021061;10908.34549927007;11842.526298947945;8050.538268544348;9853.852192216978;9986.550605222637;10681.61287263614;11081.50133244022 09:44:00 INFO - PID 1656 | __end_tp_report 09:44:00 INFO - PID 1656 | __start_cc_report 09:44:00 INFO - PID 1656 | _x_x_mozilla_cycle_collect,1806 09:44:00 INFO - PID 1656 | __end_cc_report 09:44:00 INFO - PID 1656 | __startTimestamp1490114640349__endTimestamp 09:44:00 INFO - PID 1656 | 09:44:00 INFO - PID 1656 | ------- Summary: start ------- 09:44:00 INFO - PID 1656 | Number of tests: 6 09:44:00 INFO - PID 1656 | 09:44:00 INFO - PID 1656 | [#0] dojo.html Cycles:165 Average:6028.01 Median:6260.25 stddev:677.56 (10.8%) stddev-sans-first:643.70 09:44:00 INFO - PID 1656 | Values: 3252.2 3430.9 3467.3 3475.1 3510.6 6011.9 6104.3 6113.9 6122.8 6149.3 6077.5 6110.1 6126.0 6136.9 6145.0 5291.2 5479.3 5504.9 5511.5 5511.8 5587.9 5630.6 5643.1 5658.9 5704.1 5400.8 5497.4 5505.6 5508.1 5574.2 5496.8 5503.1 5516.2 5520.8 5533.8 5386.4 5426.4 5456.3 5462.4 5468.9 6059.5 6085.5 6087.5 6116.2 6141.2 6421.5 6421.6 6430.2 6443.0 6455.3 6425.2 6511.7 6535.6 6537.3 6593.0 6225.9 6249.9 6280.5 6282.5 6289.6 6764.3 6794.2 6796.9 6800.4 6821.8 6213.5 6267.0 6309.3 6377.2 6416.9 5705.9 6456.6 6592.0 6594.8 6597.6 6448.6 6487.6 6488.2 6490.9 6495.8 5432.2 6302.1 6324.6 6379.6 6488.1 5197.4 5315.5 5342.9 5345.4 5352.3 4627.5 4633.3 4641.6 4645.8 4651.2 5093.1 6190.9 6199.5 6242.6 6260.2 6373.1 6451.0 6456.6 6555.3 6621.1 6230.4 6239.4 6266.3 6269.6 6276.7 5695.1 5774.2 5784.6 5787.4 5817.9 6471.0 6561.4 6574.3 6817.0 6853.7 6368.0 6379.1 6384.4 6390.6 6394.0 6650.1 6741.2 6769.9 6780.9 6784.2 6409.8 6496.3 6503.8 6506.6 6507.9 4937.3 6275.6 6578.7 6587.0 6626.6 5505.9 6219.6 6258.4 6301.0 6355.9 6241.3 6335.2 6346.5 6352.8 6361.7 6104.6 6208.2 6226.1 6256.2 6256.7 6191.4 6341.0 6360.2 6382.7 6403.6 6284.2 6285.7 6290.6 6298.4 6312.1 09:44:00 INFO - PID 1656 | 09:44:00 INFO - PID 1656 | [#1] ext.html Cycles:180 Average:50761.23 Median:31020.92 stddev:103821.81 (334.7%) stddev-sans-first:104045.11 09:44:00 INFO - PID 1656 | Values: 734.4 746.4 748.3 782.2 803.9 35040.5 35804.5 35839.3 35888.5 35946.5 38426.0 38871.2 38928.6 38932.6 38960.4 2497.5 2828.6 2881.2 2885.3 2886.2 2543.1 2841.7 2908.8 2909.1 2911.9 2903.0 2906.8 2911.7 2917.3 2919.8 2499.8 2881.2 2908.9 2910.9 2918.6 2542.9 2830.4 2893.1 2905.3 2907.9 36196.6 36580.5 36619.3 36621.9 36664.1 2983.2 2993.9 3000.7 3032.6 3051.0 26819.5 26824.9 26976.6 27211.0 27787.4 2744.4 2772.1 2779.7 2815.9 2832.6 322245.0 322795.0 323957.4 325292.0 325753.0 37816.6 38487.8 49527.0 49589.8 49720.8 40256.8 40285.0 40285.2 40375.6 40409.0 12971.3 13406.8 13417.6 13431.9 13440.4 11297.4 11301.0 11302.8 11306.4 11349.9 11751.5 11770.5 11782.4 11786.8 11879.2 5313.4 5363.8 5371.8 5378.3 5379.4 966.0 977.7 988.6 992.4 1001.3 18419.4 23184.4 23321.9 23484.3 23519.1 1070.8 1079.9 1081.0 1104.2 1123.5 11231.5 11856.3 11875.7 11888.7 11947.9 416062.0 417172.0 417713.0 417807.0 417957.0 51137.7 51287.7 51407.0 51447.5 51504.2 1432.2 1476.5 1495.3 1496.2 1504.8 23198.3 23291.7 23321.9 23342.6 23744.4 37310.0 37396.1 37397.6 37551.0 37573.6 420586.0 421153.0 421507.0 421770.0 421821.0 29908.9 31133.5 31208.1 31229.0 32150.4 39691.0 39805.4 40562.6 40951.4 40965.0 34083.1 34138.1 34180.7 35058.3 35107.6 31702.7 32975.8 33840.5 35209.8 35290.6 34042.7 34850.0 34969.5 35020.6 35033.6 29258.4 30508.7 30894.5 30980.0 31061.8 23338.4 23541.4 23553.4 23561.1 23825.3 09:44:00 INFO - PID 1656 | 09:44:00 INFO - PID 1656 | [#2] jquery.html Cycles:180 Average:33885.74 Median:21863.17 stddev:84787.75 (387.8%) stddev-sans-first:84988.30 09:44:00 INFO - PID 1656 | Values: 386.7 410.6 421.6 421.7 430.4 13152.9 15076.2 17540.6 21399.3 21704.8 22021.6 22432.8 23179.3 23434.6 23518.8 4111.9 4178.6 4180.8 4232.6 4238.2 2825.3 4058.1 4210.9 4221.9 4235.5 2945.7 3957.7 4140.0 4164.4 4171.1 4154.8 4162.9 4165.8 4165.8 4167.7 3715.3 3793.6 3869.7 3872.3 3885.6 26096.0 28118.6 28257.0 28278.7 28291.6 1853.0 1982.2 1989.7 2002.5 2017.8 15346.4 15895.4 15895.8 16154.3 17018.9 1628.0 1644.5 1646.7 1680.4 1699.7 145002.0 162579.2 163430.0 164233.0 166676.0 11830.0 12133.3 12162.2 12170.9 12416.5 16366.7 16378.3 16397.9 16415.2 16418.9 8712.9 8942.6 8946.0 8978.5 9055.4 8545.5 8572.7 8586.9 8745.7 8768.4 7638.2 7765.6 7809.4 7812.3 7829.0 1867.6 2025.9 2066.6 2078.7 2289.7 799.0 868.3 924.4 936.0 957.4 17535.5 18795.8 20163.2 20957.8 21023.2 936.5 965.2 997.4 1007.7 1007.9 10108.0 10466.3 10669.0 10840.3 10853.3 406556.0 409235.0 410082.0 416408.0 707404.0 112108.0 153346.9 157408.0 159664.0 159808.0 1156.1 1164.0 1168.2 1168.6 1183.1 13573.5 16928.6 17926.9 17952.3 18021.9 60527.4 60926.4 61247.7 62061.0 62157.0 84371.0 85890.1 86856.0 88000.7 88384.6 6328.1 7818.6 8181.8 8437.7 9018.9 13170.3 13718.6 13786.0 14215.2 14292.6 11406.5 11992.6 12012.6 12093.0 12147.6 11506.3 11692.8 11795.4 12049.9 12140.0 11679.4 11961.0 12223.7 12251.6 12296.3 8625.8 8769.2 8803.6 8908.4 8910.0 11574.1 11619.6 11969.3 11988.6 12047.8 09:44:00 INFO - PID 1656 | 09:44:00 INFO - PID 1656 | [#3] mootools.html Cycles:180 Average:6505.71 Median:3984.11 stddev:3982.44 (100.0%) stddev-sans-first:3973.94 09:44:00 INFO - PID 1656 | Values: 1238.5 1410.3 1435.5 1440.9 1443.3 9987.6 10350.2 10365.4 10368.4 10370.3 10090.5 10407.8 10425.5 10442.3 10455.7 2372.5 2405.8 2406.7 2413.8 2415.0 2030.0 2198.8 2340.2 2394.1 2405.4 2079.9 2107.1 2108.2 2111.8 2113.3 2303.8 2386.9 2388.9 2391.2 2395.1 2304.8 2370.2 2375.8 2381.8 2382.4 10592.7 10709.0 10716.8 10720.1 10777.3 6054.5 6274.5 6276.6 6291.2 6298.9 3937.8 3965.2 3986.3 4003.0 4009.3 1195.5 1517.9 1537.7 1563.6 1633.0 13938.9 14034.4 14177.5 14366.6 14405.0 4889.6 5106.8 5187.3 5204.9 5214.5 10937.0 10953.0 10954.6 10962.7 10983.5 6128.1 6129.7 6131.3 6137.3 6139.6 5265.0 5597.4 5648.4 5669.3 5680.0 3963.2 4049.4 4069.4 4105.3 4106.2 1215.8 1220.0 1234.3 1235.8 1238.9 618.8 622.5 644.3 649.7 662.2 5384.0 5934.9 6060.4 6249.3 6295.1 857.5 886.0 890.5 895.7 944.6 3937.3 3981.9 4035.6 4153.6 4164.5 12738.0 13285.9 13293.4 13298.8 13313.6 13174.7 13178.9 13184.8 13186.5 13212.3 9676.8 9738.6 9955.3 10036.0 10038.0 5396.7 5451.7 6006.2 6149.2 6357.7 11635.9 12175.6 12190.9 12192.4 12194.5 11874.4 11997.3 12224.4 12390.3 12435.1 6744.9 7410.1 7854.0 7901.8 7905.4 9467.4 9553.8 9657.2 9672.9 9680.2 8551.5 8629.5 8679.9 8709.6 8760.0 6721.6 8483.0 8599.0 8772.9 8784.0 7532.5 7614.1 7637.3 7638.2 7640.2 5238.7 5551.4 5671.7 5709.6 5742.0 5584.8 5691.3 5736.0 5738.3 5753.4 09:44:00 INFO - PID 1656 | 09:44:00 INFO - PID 1656 | [#4] prototype.html Cycles:180 Average:5006.38 Median:5646.56 stddev:1541.54 (27.3%) stddev-sans-first:1505.79 09:44:00 INFO - PID 1656 | Values: 353.9 364.0 386.0 393.5 395.6 5486.9 5504.9 5868.4 5912.1 5991.5 4994.8 5077.0 5827.0 5852.4 5876.2 3916.3 4415.8 4551.8 4568.8 4582.3 3934.4 4560.7 4591.9 4599.1 4607.1 3797.7 4263.8 4537.5 4561.2 4563.5 4457.7 4478.2 4517.2 4526.4 4526.6 3968.7 3975.9 3985.1 3993.8 4021.6 4928.6 4959.8 5035.0 5445.0 5704.0 4794.5 4796.6 5059.6 5106.2 5142.3 5573.5 5706.4 6215.7 6226.5 6227.7 3651.9 4159.0 4203.0 4219.6 4228.4 5288.3 6484.5 6510.0 6514.2 6578.9 4183.6 4258.2 4283.8 4286.4 4295.4 3679.7 4000.2 4265.9 4350.1 4359.8 4388.3 4419.9 4703.4 5117.5 5178.8 5921.3 6269.9 6275.6 6276.8 6291.8 1348.8 1463.9 1482.7 1487.5 1497.7 821.5 939.2 976.9 990.5 991.7 5895.2 5898.2 5929.5 5941.8 5990.3 6147.9 6437.8 6456.7 6475.5 6481.0 5249.6 5663.4 5778.5 5893.8 5905.8 2849.4 3230.9 3383.8 3384.6 3393.1 5581.3 6378.3 6614.8 6701.3 6712.0 6619.6 6624.2 6625.6 6629.5 6632.6 6242.8 6310.3 6387.9 6688.8 6700.7 5422.0 5772.0 6393.3 6398.9 6399.6 5457.7 5532.8 6202.0 6327.9 6347.6 5551.2 5664.7 5958.8 6618.7 6646.8 3621.5 4239.0 4239.7 4259.2 4263.3 5655.7 6116.7 6360.0 6412.3 6438.4 5654.4 6153.7 6188.4 6200.2 6222.0 4929.8 5126.9 5203.2 5754.4 6200.8 5662.7 5945.9 6000.1 6003.0 6017.7 5638.7 5659.7 6160.2 6183.8 6190.5 5263.8 6028.6 6029.5 6050.9 6189.4 09:44:00 INFO - PID 1656 | 09:44:00 INFO - PID 1656 | [#5] yui.html Cycles:180 Average:19039.95 Median:3150.92 stddev:27434.14 (870.7%) stddev-sans-first:27476.58 09:44:00 INFO - PID 1656 | Values: 711.3 714.4 732.7 736.7 737.8 14910.3 15778.6 15780.0 15785.1 15797.8 14513.5 14560.3 14603.1 14661.7 14677.8 935.4 948.3 967.7 969.2 969.6 955.5 964.3 964.6 964.7 966.0 828.3 1006.1 1018.7 1026.7 1028.4 1008.5 1011.4 1012.5 1012.9 1013.5 744.4 749.0 749.3 749.6 750.0 16093.5 16194.7 16263.9 16289.0 16464.9 14378.8 14815.6 14857.9 14867.9 14879.0 9857.9 11598.1 11652.8 11696.5 11700.9 3033.5 3037.1 3086.3 3111.8 3190.0 110735.4 113535.0 113675.4 115168.3 117204.0 11074.7 11304.0 11320.7 11711.2 11799.5 24466.6 24923.8 24928.8 24962.5 25059.7 7663.4 7692.0 7791.5 7918.4 8036.5 3971.1 4040.4 4047.1 4055.6 4079.2 4088.8 4130.4 4142.9 4160.0 4173.1 759.8 766.5 766.9 773.0 778.4 552.0 559.9 568.9 576.4 585.0 8781.6 9380.5 9405.1 9863.8 9973.1 539.2 560.5 577.6 585.8 585.8 4345.3 4769.2 4828.1 5143.7 5173.4 95876.0 97413.0 98138.0 99654.5 102045.0 49722.0 61447.0 78338.0 81730.0 87786.6 68183.3 72316.6 73287.0 74151.0 75705.6 10485.7 10500.9 10711.1 10739.3 10783.1 23334.9 36046.5 36910.1 37104.9 37877.4 44695.9 45947.6 46164.5 46840.0 46859.0 10251.6 11874.4 13048.5 13270.3 13765.0 14007.0 14101.7 14222.4 14419.8 14419.9 13162.0 13176.7 13315.5 13609.3 13759.6 13139.7 13412.9 13426.1 13594.9 13886.6 12849.1 13070.4 13098.5 13244.7 13337.6 10647.5 10860.8 10874.5 10908.3 11842.5 8050.5 9853.9 9986.6 10681.6 11081.5 09:44:00 INFO - PID 1656 | -------- Summary: end -------- 09:44:00 INFO - PID 1656 | 09:44:01 INFO - TEST-INFO | 1656: exit 0 09:44:01 INFO - TEST-OK | dromaeo_css | took 1120528ms 09:44:01 INFO - TEST-START | kraken 09:44:01 INFO - operating with platform_type : mac_ 09:44:01 INFO - Initialising browser for kraken test... 09:44:01 INFO - TEST-INFO | started process 1777 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmp5XBz3Z/profile http://localhost:49237/getInfo.html) 09:44:03 INFO - PID 1777 | __metrics Screen width/height:1600/1200 09:44:03 INFO - PID 1777 | colorDepth:24 09:44:03 INFO - PID 1777 | Browser inner width/height: 1024/689 09:44:03 INFO - PID 1777 | __metrics 09:44:03 INFO - PID 1777 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:44:03 INFO - PID 1777 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:44:04 INFO - TEST-INFO | 1777: exit 0 09:44:04 INFO - Browser initialized. 09:44:04 INFO - Running cycle 1/1 for kraken test... 09:44:04 INFO - TEST-INFO | started process 1779 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmp5XBz3Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:44:16 INFO - PID 1779 | RSS: Main: 233443328 09:44:16 INFO - PID 1779 | 09:44:19 INFO - PID 1779 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 09:44:20 INFO - PID 1779 | RSS: Main: 284471296 09:44:20 INFO - PID 1779 | 09:44:23 INFO - PID 1779 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 09:44:23 INFO - PID 1779 | RSS: Main: 285876224 09:44:23 INFO - PID 1779 | 09:44:27 INFO - PID 1779 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 09:44:27 INFO - PID 1779 | RSS: Main: 376922112 09:44:27 INFO - PID 1779 | 09:44:29 INFO - PID 1779 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 09:44:30 INFO - PID 1779 | RSS: Main: 280244224 09:44:30 INFO - PID 1779 | 09:44:32 INFO - PID 1779 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:44:32 INFO - PID 1779 | RSS: Main: 283607040 09:44:32 INFO - PID 1779 | 09:44:35 INFO - PID 1779 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 09:44:36 INFO - PID 1779 | RSS: Main: 327319552 09:44:36 INFO - PID 1779 | 09:44:39 INFO - PID 1779 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 09:44:40 INFO - PID 1779 | RSS: Main: 333578240 09:44:40 INFO - PID 1779 | 09:44:43 INFO - PID 1779 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 09:44:43 INFO - PID 1779 | RSS: Main: 335720448 09:44:43 INFO - PID 1779 | 09:44:45 INFO - PID 1779 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:44:45 INFO - PID 1779 | RSS: Main: 302616576 09:44:45 INFO - PID 1779 | 09:44:47 INFO - PID 1779 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:44:48 INFO - PID 1779 | RSS: Main: 306892800 09:44:48 INFO - PID 1779 | 09:44:50 INFO - PID 1779 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:44:51 INFO - PID 1779 | RSS: Main: 309235712 09:44:51 INFO - PID 1779 | 09:44:54 INFO - PID 1779 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:44:55 INFO - PID 1779 | RSS: Main: 325869568 09:44:55 INFO - PID 1779 | 09:44:58 INFO - PID 1779 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:44:58 INFO - PID 1779 | RSS: Main: 319959040 09:44:58 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 09:45:00 INFO - PID 1779 | RSS: Main: 382296064 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | __start_tp_report 09:45:00 INFO - PID 1779 | _x_x_mozilla_page_load 09:45:00 INFO - PID 1779 | _x_x_mozilla_page_load_details 09:45:00 INFO - PID 1779 | |i|pagename|runs| 09:45:00 INFO - PID 1779 | |0;ai-astar;111;110;110;114;156;165;110;111;113;111 09:45:00 INFO - PID 1779 | |1;audio-beat-detection;175;142;140;134;139;138;139;111;131;111 09:45:00 INFO - PID 1779 | |2;audio-dft;184;184;186;184;183;182;184;187;184;184 09:45:00 INFO - PID 1779 | |3;audio-fft;84;63;61;92;62;60;60;61;77;58 09:45:00 INFO - PID 1779 | |4;audio-oscillator;88;70;88;69;71;68;84;69;69;85 09:45:00 INFO - PID 1779 | |5;imaging-gaussian-blur;87;88;88;88;88;88;87;88;88;88 09:45:00 INFO - PID 1779 | |6;imaging-darkroom;95;95;96;97;95;95;96;97;96;95 09:45:00 INFO - PID 1779 | |7;imaging-desaturate;84;83;83;84;86;83;83;84;83;85 09:45:00 INFO - PID 1779 | |8;json-parse-financial;63;60;61;61;61;62;62;64;62;62 09:45:00 INFO - PID 1779 | |9;json-stringify-tinderbox;54;63;62;65;62;58;59;59;58;62 09:45:00 INFO - PID 1779 | |10;stanford-crypto-aes;84;83;82;82;83;83;83;97;102;104 09:45:00 INFO - PID 1779 | |11;stanford-crypto-ccm;137;138;140;138;137;136;152;156;188;143 09:45:00 INFO - PID 1779 | |12;stanford-crypto-pbkdf2;184;187;188;184;187;185;187;184;187;185 09:45:00 INFO - PID 1779 | |13;stanford-crypto-sha256-iterative;69;68;67;69;88;79;67;68;68;69 09:45:00 INFO - PID 1779 | __end_tp_report 09:45:00 INFO - PID 1779 | __start_cc_report 09:45:00 INFO - PID 1779 | _x_x_mozilla_cycle_collect,2181 09:45:00 INFO - PID 1779 | __end_cc_report 09:45:00 INFO - PID 1779 | __startTimestamp1490114700775__endTimestamp 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | ------- Summary: start ------- 09:45:00 INFO - PID 1779 | Number of tests: 14 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | [#0] ai-astar Cycles:10 Average:121.10 Median:112.00 stddev:20.91 (18.7%) stddev-sans-first:21.86 09:45:00 INFO - PID 1779 | Values: 111.0 110.0 110.0 114.0 156.0 165.0 110.0 111.0 113.0 111.0 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:139.00 stddev:17.87 (12.9%) stddev-sans-first:12.17 09:45:00 INFO - PID 1779 | Values: 175.0 142.0 140.0 134.0 139.0 138.0 139.0 111.0 131.0 111.0 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.00 stddev:1.40 (0.8%) stddev-sans-first:1.48 09:45:00 INFO - PID 1779 | Values: 184.0 184.0 186.0 184.0 183.0 182.0 184.0 187.0 184.0 184.0 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | [#3] audio-fft Cycles:10 Average:67.80 Median:62.50 stddev:12.02 (19.2%) stddev-sans-first:11.22 09:45:00 INFO - PID 1779 | Values: 84.0 63.0 61.0 92.0 62.0 60.0 60.0 61.0 77.0 58.0 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:77.50 stddev:8.85 (11.4%) stddev-sans-first:8.27 09:45:00 INFO - PID 1779 | Values: 88.0 70.0 88.0 69.0 71.0 68.0 84.0 69.0 69.0 85.0 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:88.00 stddev:0.42 (0.5%) stddev-sans-first:0.33 09:45:00 INFO - PID 1779 | Values: 87.0 88.0 88.0 88.0 88.0 88.0 87.0 88.0 88.0 88.0 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | [#6] imaging-darkroom Cycles:10 Average:95.70 Median:96.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 09:45:00 INFO - PID 1779 | Values: 95.0 95.0 96.0 97.0 95.0 95.0 96.0 97.0 96.0 95.0 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | [#7] imaging-desaturate Cycles:10 Average:83.80 Median:84.00 stddev:1.03 (1.2%) stddev-sans-first:1.09 09:45:00 INFO - PID 1779 | Values: 84.0 83.0 83.0 84.0 86.0 83.0 83.0 84.0 83.0 85.0 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:62.00 stddev:1.14 (1.8%) stddev-sans-first:1.12 09:45:00 INFO - PID 1779 | Values: 63.0 60.0 61.0 61.0 61.0 62.0 62.0 64.0 62.0 62.0 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:62.00 stddev:3.19 (5.1%) stddev-sans-first:2.47 09:45:00 INFO - PID 1779 | Values: 54.0 63.0 62.0 65.0 62.0 58.0 59.0 59.0 58.0 62.0 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:83.00 stddev:8.94 (10.8%) stddev-sans-first:9.35 09:45:00 INFO - PID 1779 | Values: 84.0 83.0 82.0 82.0 83.0 83.0 83.0 97.0 102.0 104.0 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | [#11] stanford-crypto-ccm Cycles:10 Average:146.50 Median:141.50 stddev:16.10 (11.4%) stddev-sans-first:16.70 09:45:00 INFO - PID 1779 | Values: 137.0 138.0 140.0 138.0 137.0 136.0 152.0 156.0 188.0 143.0 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.80 Median:187.00 stddev:1.55 (0.8%) stddev-sans-first:1.50 09:45:00 INFO - PID 1779 | Values: 184.0 187.0 188.0 184.0 187.0 185.0 187.0 184.0 187.0 185.0 09:45:00 INFO - PID 1779 | 09:45:00 INFO - PID 1779 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:69.00 stddev:6.86 (9.9%) stddev-sans-first:7.23 09:45:00 INFO - PID 1779 | Values: 69.0 68.0 67.0 69.0 88.0 79.0 67.0 68.0 68.0 69.0 09:45:00 INFO - PID 1779 | -------- Summary: end -------- 09:45:00 INFO - PID 1779 | 09:45:01 INFO - TEST-INFO | 1779: exit 0 09:45:01 INFO - TEST-OK | kraken | took 60697ms 09:45:01 INFO - SUITE-END | took 1181s 09:45:01 INFO - Completed test suite (00:19:41) 09:45:01 INFO - javascript benchmark 09:45:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7219.205074482532, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3252.235724604718, 3430.862765489379, 3467.306538692262, 3475.1485885957936, 3510.578730552334, 6011.909821352683, 6104.267487901447, 6113.908291375633, 6122.785702500413, 6149.3235744068115, 6077.4530292273685, 6110.103144028831, 6125.968084364126, 6136.9079463808075, 6144.9692751536295, 5291.2327712481665, 5479.287692599956, 5504.91742623861, 5511.53151982082, 5511.751971161308, 5587.916181257264, 5630.605857589971, 5643.068893632546, 5658.858528536778, 5704.1443783432605, 5400.78396864125, 5497.422770609069, 5505.642133261325, 5508.14623733322, 5574.1638754187, 5496.80761173361, 5503.147012213113, 5516.172574113915, 5520.806771762969, 5533.833984980417, 5386.380566234894, 5426.375966763833, 5456.345238571397, 5462.399136095027, 5468.863278418072, 6059.45464908156, 6085.452309292141, 6087.512998960073, 6116.174316467264, 6141.179174348115, 6421.518386121013, 6421.64680942548, 6430.196225471816, 6443.0335449682925, 6455.322191169954, 6425.1968503937005, 6511.674416279167, 6535.607863528204, 6537.313582073909, 6593.044008618724, 6225.937740622581, 6249.937500624981, 6280.528960327993, 6282.465990390822, 6289.559730818799, 6764.289749576322, 6794.218664853605, 6796.93203067963, 6800.4219641330055, 6821.829454263683, 6213.502919766408, 6267.0, 6309.305976342599, 6377.23473183221, 6416.871662566816, 5705.914411283834, 6456.644884531396, 6591.967040164768, 6594.769183078569, 6597.637129957899, 6448.580842245239, 6487.643179625166, 6488.188976377953, 6490.928996715489, 6495.837604059936, 5432.239486471897, 6302.1177035215105, 6324.588901721373, 6379.649119298483, 6488.124103246096, 5197.402298735618, 5315.548178404802, 5342.893142137135, 5345.412004679482, 5352.304200453916, 4627.537246275346, 4633.258678611406, 4641.628669706415, 4645.814167433265, 4651.232546629836, 5093.0577843099145, 6190.907136392958, 6199.473044791153, 6242.563020588606, 6260.248770147611, 6373.139626150503, 6450.967745161338, 6456.644884531302, 6555.278919318871, 6621.106150669694, 6230.376962303733, 6239.438450539473, 6266.342034086447, 6269.62382257066, 6276.717547710364, 5695.117256825199, 5774.249347584787, 5784.566157538202, 5787.392323806024, 5817.941820581825, 6470.9676451619325, 6561.40947314744, 6574.27682954865, 6817.048604878248, 6853.691583878837, 6367.968160159169, 6379.075034120119, 6384.3934826191435, 6390.648514331757, 6394.008928616072, 6650.135482387259, 6741.1910570731825, 6769.93230067693, 6780.86438271222, 6784.151981002375, 6409.807705768834, 6496.285408605144, 6503.772367967098, 6506.577072490273, 6507.869842603027, 4937.28409380645, 6275.560710750204, 6578.671066446755, 6587.0, 6626.602403855784, 5505.944940550543, 6219.595726277777, 6258.374162583706, 6300.991841305369, 6355.936440635719, 6241.344658810941, 6335.208098987627, 6346.492280617632, 6352.809415717536, 6361.745530178741, 6104.603200791934, 6208.192934918432, 6226.128342032029, 6256.155419018374, 6256.687165641791, 6191.380861913773, 6340.968295158495, 6360.173177486897, 6382.680865956777, 6403.64779937108, 6284.24589049317, 6285.74857005733, 6290.622562646256, 6298.433141017331, 6312.147860038836], "value": 5984.902779194284, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.3611058379211, 746.3916907720204, 748.2704363245832, 782.1904329019466, 803.9155888631692, 35040.47439288413, 35804.46293305603, 35839.2832143357, 35888.46167307486, 35946.460803087975, 38426.0, 38871.22257554847, 38928.63749768759, 38932.61067389332, 38960.41559376611, 2497.500499900018, 2828.5757136429547, 2881.1644623059287, 2885.2642576143053, 2886.2495751104757, 2543.135333986444, 2841.715828417152, 2908.81092728973, 2909.0545572688857, 2911.883524659011, 2903.0274857922623, 2906.825590464579, 2911.723386278311, 2917.3290143266954, 2919.810212336202, 2499.8375105618074, 2881.2364723348223, 2908.941821163565, 2910.9126726198247, 2918.591397204393, 2542.9491410171695, 2830.4197639483855, 2893.117599132264, 2905.3027273454218, 2907.912762617125, 36196.55213791445, 36580.451293230624, 36619.267614647826, 36621.901342959754, 36664.083397915, 2983.226164409356, 2993.895213667511, 3000.729934305901, 3032.6209223847018, 3051.0, 26819.463610727675, 26824.865875670694, 26976.59535106975, 27211.0, 27787.444251114863, 2744.4373903349965, 2772.085211880075, 2779.6664400271893, 2815.9014434494898, 2832.6459192600923, 322245.0, 322795.0, 323957.38021309976, 325292.0, 325753.0, 37816.62183378186, 38487.845364639106, 49527.0, 49589.75205123951, 49720.751396242784, 40256.79871600623, 40284.99287517835, 40285.194296113914, 40375.58685445995, 40409.0, 12971.286579238233, 13406.798898016537, 13417.597472075853, 13431.932840335736, 13440.395182216824, 11297.435128243555, 11300.982911538002, 11302.813204613398, 11306.434678266054, 11349.943250283695, 11751.529938802523, 11770.470328835223, 11782.351970641526, 11786.764264714659, 11879.22785018971, 5313.362396512443, 5363.758630861543, 5371.75827087782, 5378.273933019071, 5379.35447746273, 965.9855102173473, 977.726236653738, 988.6045581767207, 992.3896803465816, 1001.2890847498212, 18419.447416577525, 23184.4203894904, 23321.883390582938, 23484.295471135894, 23519.149869410066, 1070.7590792071721, 1079.8866119057543, 1080.9513571889283, 1104.1884215101893, 1123.466353482106, 11231.494582743797, 11856.347900865536, 11875.70310742221, 11888.702782430508, 11947.880521194851, 416062.0, 417172.0, 417713.0, 417807.0, 417957.0, 51137.74431127895, 51287.74356128195, 51407.0, 51447.48552514427, 51504.22743658848, 1432.2122832442114, 1476.534891509171, 1495.3196295685436, 1496.1696258576594, 1504.751715966875, 23198.3040508785, 23291.650625240298, 23321.883390582938, 23342.64986025177, 23744.4063898397, 37310.0, 37396.06509837276, 37397.626023739416, 37551.0, 37573.62426375701, 420586.0, 421153.0, 421507.0, 421770.0, 421821.0, 29908.850455747583, 31133.53299700461, 31208.063758087294, 31229.0, 32150.35699286048, 39691.0, 39805.40291895566, 40562.594374057066, 40951.38572921349, 40965.0, 34083.14792130118, 34138.14654633654, 34180.65819341874, 35058.298834022666, 35107.64892351146, 31702.682973169973, 32975.8458453953, 33840.49239261364, 35209.82395088008, 35290.647093528736, 34042.65957340494, 34849.954501365, 34969.475457867644, 35020.64979350276, 35033.64966350406, 29258.414831703674, 30508.694913051473, 30894.53658195084, 30980.0, 31061.844690777307, 23338.416539586648, 23541.411464713517, 23553.41116472102, 23561.146619990417, 23825.260095088594], "value": 14718.663590541202, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.6906474820143, 410.55249777742256, 421.59948049353113, 421.6605632465865, 430.3652113133129, 13152.868471315283, 15076.246187690627, 17540.56148596284, 21399.288056955444, 21704.782952170473, 22021.559568808614, 22432.77567224327, 23179.30462086136, 23434.64848027281, 23518.76481235187, 4111.856085037017, 4178.56125106864, 4180.787571604246, 4232.619064284214, 4238.194742998836, 2825.3219227385484, 4058.1072164123993, 4210.894727631819, 4221.915561688765, 4235.53409124996, 2945.720156585121, 3957.7229593928437, 4139.979300103495, 4164.437800896885, 4171.124063946575, 4154.813033413504, 4162.91674166515, 4165.770882601456, 4165.770882601456, 4167.74993500391, 3715.294094122135, 3793.620637936212, 3869.7097717671286, 3872.2642697887313, 3885.5531613864487, 26096.0, 28118.578221326698, 28257.01100461474, 28278.717212827814, 28291.575626365622, 1852.9536761580935, 1982.187303205693, 1989.7313862628505, 2002.5193953451212, 2017.8083082107323, 15346.386144554315, 15895.364185432569, 15895.761563576556, 16154.253776857962, 17018.893771904786, 1627.951161465146, 1644.539528931901, 1646.662434200986, 1680.420255012019, 1699.6515714278544, 145002.0, 162579.18710406372, 163430.0, 164233.0, 166676.0, 11829.9889018867, 12133.27200367963, 12162.209456385306, 12170.878291216974, 12416.510018797622, 16366.672666546603, 16378.26297816601, 16397.91801040987, 16415.179241037902, 16418.917905410395, 8712.91287087121, 8942.552872356357, 8946.0, 8978.46129232235, 9055.411398259092, 8545.487270763773, 8572.657093716307, 8586.914130858611, 8745.693900713444, 8768.386212965032, 7638.236176382317, 7765.572893490912, 7809.414293927978, 7812.257835505618, 7829.0, 1867.617138486607, 2025.9262590826886, 2066.5763518478675, 2078.740157480315, 2289.725232972054, 798.9840203195904, 868.3010176807604, 924.3575714878095, 935.9625614975324, 957.3729207369184, 17535.509481693945, 18795.81204187968, 20163.193472260944, 20957.790422095582, 21023.15907363719, 936.4942930817418, 965.2037069417618, 997.4314640654757, 1007.6624330849247, 1007.8588997540351, 10108.0, 10466.267361284788, 10669.0, 10840.295380800222, 10853.294535855144, 406556.0, 409235.0, 410082.0, 416408.0, 707404.0, 112108.0, 153346.93306133815, 157408.0, 159664.0, 159808.0, 1156.1040193849833, 1164.0, 1168.182272409317, 1168.6260396672983, 1183.1303991566192, 13573.524926627915, 16928.57678558046, 17926.91036544809, 17952.281908723504, 18021.909890450464, 60527.394726052175, 60926.39073609207, 61247.69376153269, 62061.0, 62157.0, 84371.0, 85890.14109859071, 86856.0, 88000.67998979893, 88384.5580772092, 6328.0824280478155, 7818.6481608328895, 8181.79545511367, 8437.734339849072, 9018.864717029119, 13170.341482926007, 13718.588442346745, 13786.0, 14215.218163001135, 14292.571222863331, 11406.48670809799, 11992.64022079339, 12012.579559715368, 12093.0, 12147.57483488109, 11506.30962142274, 11692.766144676889, 11795.41022948832, 12049.879501204876, 12140.0, 11679.416029198675, 11961.043116550481, 12223.694407639525, 12251.632451026484, 12296.323702196465, 8625.82748345017, 8769.210771030637, 8803.647854085764, 8908.420952638058, 8910.0, 11574.074074073886, 11619.5933142343, 11969.341686207343, 11988.64034078979, 12047.819282710941], "value": 9634.02742401002, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1238.5293588436393, 1410.2596137028065, 1435.504750860953, 1440.87752541034, 1443.3360654099108, 9987.550560224789, 10350.171986241101, 10365.429901355423, 10368.377897326165, 10370.274080814328, 10090.545925433355, 10407.791844163112, 10425.478726063686, 10442.269041167121, 10455.686329410111, 2372.5373552157293, 2405.8075353971726, 2406.6991626046743, 2413.758624137581, 2414.987925060372, 2029.989850050755, 2198.7801219877965, 2340.1575432844247, 2394.1022116706235, 2405.410674384769, 2079.854410191287, 2107.093949601671, 2108.219958615307, 2111.8416118791074, 2113.2814842953394, 2303.8156947444163, 2386.85678859269, 2388.9283321500384, 2391.1630929174826, 2395.1018368111872, 2304.7695230476984, 2370.182287110944, 2375.786179243876, 2381.7975472084895, 2382.440126570272, 10592.735181620445, 10708.982646648556, 10716.839247411295, 10720.14238860897, 10777.299475534066, 6054.485368743618, 6274.498040156776, 6276.623402595813, 6291.245050593912, 6298.874022519524, 3937.803109844525, 3965.20695860829, 3986.3223252047223, 4002.959970400288, 4009.3384591542363, 1195.4799662146993, 1517.8633922946876, 1537.6540278437374, 1563.5700182449802, 1632.9673406531804, 13938.93030534841, 14034.368453419518, 14177.503787367392, 14366.640833979025, 14405.0, 4889.633277504201, 5106.821261255838, 5187.325647665779, 5204.8959020819375, 5214.478552144755, 10937.0, 10953.0, 10954.616588419367, 10962.671119866416, 10983.505742241618, 6128.050152226412, 6129.662868542184, 6131.294901086432, 6137.294211165684, 6139.6009259398, 5264.9736751315995, 5597.412271711493, 5648.406917273709, 5669.348024977098, 5679.971600141973, 3963.2403453063134, 4049.4330793688905, 4069.409935559327, 4105.261053010427, 4106.2198182345255, 1215.7629262293767, 1220.0178856020812, 1234.2594443334108, 1235.8331625230658, 1238.9221377401539, 618.7586841131962, 622.5144387377852, 644.291279592454, 649.7141257846532, 662.1755913887145, 5384.0, 5934.881302374015, 6060.393960603992, 6249.25008998923, 6295.087212354183, 857.4555157474923, 885.964561417549, 890.4683943616968, 895.7491902267376, 944.5548311084052, 3937.330653788863, 3981.900452488653, 4035.5964403559997, 4153.605407486284, 4164.500259968823, 12737.980961523055, 13285.937125029974, 13293.401796919163, 13298.800517992238, 13313.600591982062, 13174.736505270033, 13178.934105329412, 13184.802227966587, 13186.472541098248, 13212.273324967027, 9676.806463870824, 9738.610455581698, 9955.25335599833, 10036.0, 10038.0, 5396.730163491731, 5451.727413629382, 6006.159137720635, 6149.169862068564, 6357.682115894094, 11635.941820290844, 12175.634730958085, 12190.878091218974, 12192.390380480763, 12194.51221951112, 11874.406279686154, 11997.280163190237, 12224.388780561114, 12390.256584604953, 12435.129540932048, 6744.8651026978205, 7410.11078670563, 7853.960730196312, 7901.762947111366, 7905.367570594453, 9467.431954082776, 9553.808923821623, 9657.179139773198, 9672.854907176257, 9680.225581953286, 8551.529665868187, 8629.525376104124, 8679.86980195285, 8709.564521773758, 8760.0, 6721.630310332783, 8483.024452188078, 8599.0, 8772.91227087721, 8784.033756286675, 7532.472726909064, 7614.124375696866, 7637.312641862037, 7638.197989211053, 7640.235976402315, 5238.71187084714, 5551.444855514416, 5671.716414179192, 5709.600327977002, 5742.023855944416, 5584.804531841366, 5691.345495268097, 5736.0, 5738.282714660668, 5753.424657534213], "value": 4989.017330933935, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.8637624514562, 363.9799811010394, 385.95368555773314, 393.52580140930166, 395.63996762945726, 5486.917696234555, 5504.889902201953, 5868.354481007096, 5912.142739302799, 5991.490723288525, 4994.75026248687, 5076.974615126928, 5826.960563802667, 5852.414758524156, 5876.177335173079, 3916.314644937146, 4415.785658943784, 4551.840685576006, 4568.771561421932, 4582.266837306031, 3934.429507721393, 4560.749158796281, 4591.908161836761, 4599.126166028461, 4607.0785842831565, 3797.6961843052627, 4263.786810659471, 4537.523560026199, 4561.156186105581, 4563.498015218323, 4457.710248833848, 4478.171538265415, 4517.164324599943, 4526.411566496367, 4526.637868970507, 3968.6626636735914, 3975.8608448704445, 3985.1070741397893, 3993.820278087493, 4021.577734337882, 4928.6303527235605, 4959.752012399402, 5035.0, 5444.96545656324, 5704.0, 4794.520547945178, 4796.5683088521855, 5059.6458247922665, 5106.157484015133, 5142.280080788692, 5573.498385145316, 5706.42935706426, 6215.658138802364, 6226.516907826403, 6227.688615569204, 3651.8904432867057, 4159.0, 4203.0, 4219.556946520602, 4228.429162063113, 5288.338957630296, 6484.481241500715, 6509.99095140254, 6514.1531600891585, 6578.868422631521, 4183.602557757069, 4258.233517966796, 4283.800535849905, 4286.357046442996, 4295.355696645465, 3679.748885378984, 4000.1599664070873, 4265.893352666209, 4350.086481838849, 4359.760213188241, 4388.319810429389, 4419.917120305546, 4703.365045718784, 5117.5394214520875, 5178.844634660966, 5921.259842519685, 6269.905951410732, 6275.560710750295, 6276.811695649138, 6291.811245662638, 1348.7726169185994, 1463.8828893688478, 1482.6574013059555, 1487.4793822162158, 1497.697003606869, 821.5112008355019, 939.2110627073291, 976.9071938165863, 990.5493000684671, 991.6876184002016, 5895.204147440127, 5898.203742494708, 5929.495992840571, 5941.792037278675, 5990.311114221833, 6147.938520614826, 6437.7789662567275, 6456.709448074848, 6475.546711730133, 6480.967595161994, 5249.632525723239, 5663.433656634304, 5778.451047150598, 5893.823185304448, 5905.763769449174, 2849.444358350102, 3230.935381292361, 3383.780054296463, 3384.610769761509, 3393.117789374731, 5581.330240371181, 6378.330275321117, 6614.768483103165, 6701.262861085277, 6711.96602548089, 6619.602823830585, 6624.164360017215, 6625.569337993015, 6629.5359324846795, 6632.602043877383, 6242.7826573818575, 6310.274318453345, 6387.936120638827, 6688.799336019927, 6700.693364793916, 5422.0, 5772.018756811268, 6393.264774550986, 6398.936010639834, 6399.616023038447, 5457.744718714797, 5532.806351777668, 6202.038684003986, 6327.930953487463, 6347.587406818542, 5551.167324901297, 5664.688442135557, 5958.821235362946, 6618.7352505899225, 6646.767363142267, 3621.4929909813172, 4238.9826441654395, 4239.660827133884, 4259.233337999066, 4263.296556068214, 5655.660660360391, 6116.694165291807, 6360.045993101072, 6412.326705696021, 6438.39449908053, 5654.434556544313, 6153.72308246139, 6188.412100850412, 6200.162977997912, 6222.0, 4929.802807887788, 5126.8718282041755, 5203.16749320107, 5754.424557544212, 6200.844978875564, 5662.688552129673, 5945.851353716019, 6000.0999850022845, 6003.0, 6017.669028203491, 5638.689872057076, 5659.688717120598, 6160.199174107337, 6183.783567575113, 6190.535709821871, 5263.763130659053, 6028.638281703112, 6029.487493563096, 6050.9394906050375, 6189.381061893775], "value": 4524.290545272011, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.3455620828837, 714.3784907130797, 732.7325526182943, 736.7053178728507, 737.8413641067169, 14910.254487275633, 15778.605534861634, 15780.0, 15785.052896826184, 15797.763033554505, 14513.49202777903, 14560.27198640069, 14603.050801697882, 14661.706765864703, 14677.77983330251, 935.3966691484005, 948.3124734567452, 967.7387105481516, 969.2100937735722, 969.6266937229183, 955.4744890310336, 964.2671569607079, 964.5900492290783, 964.662368171138, 966.0098399140867, 828.2835347424465, 1006.0543089495887, 1018.724944265048, 1026.6714651311584, 1028.4497793680382, 1008.5209525475414, 1011.4386515483867, 1012.4583347908871, 1012.9240306977006, 1013.4983183324227, 744.3710064995071, 749.0374868294216, 749.2881762325812, 749.6064566102785, 749.9550026998397, 16093.517194484184, 16194.676106477922, 16263.918680406641, 16289.0, 16464.917675411667, 14378.784318235235, 14815.629609259855, 14857.925710371486, 14867.92566037163, 14879.0, 9857.90142098577, 11598.130140239431, 11652.76694466106, 11696.47365868538, 11700.94691477771, 3033.4994725870206, 3037.112496005117, 3086.3364376659124, 3111.844407779602, 3189.9951515272614, 110735.44632276786, 113534.99999999917, 113675.43162284218, 115168.27247591293, 117203.99999999914, 11074.723131921766, 11304.03915667161, 11320.660380188607, 11711.238769479956, 11799.52801887915, 24466.633000505008, 24923.750762492506, 24928.750712493005, 24962.500749984898, 25059.749402506106, 7663.425243106789, 7692.0384951881015, 7791.454598178187, 7918.445708800328, 8036.517808931483, 3971.0866500704424, 4040.4141399496907, 4047.1096358801606, 4055.6349928506575, 4079.204555111725, 4088.7751173685733, 4130.421740956269, 4142.855000074983, 4160.022394737234, 4173.123644034787, 759.7948553890419, 766.52858492027, 766.8542976834384, 773.0, 778.3967425245366, 551.9530839878576, 559.932808063035, 568.9175069614882, 576.3631187537794, 584.9883002340015, 8781.560921953876, 9380.48407337603, 9405.05949405054, 9863.753406164904, 9973.052560006789, 539.1965970703702, 560.5263552298302, 577.6216578141324, 585.7616385494433, 585.7920438244447, 4345.283028300295, 4769.2369220925175, 4828.141165651249, 5143.691378517301, 5173.405058418255, 95876.0, 97413.02586974182, 98138.0186198129, 99654.5017274909, 102044.97955020503, 49722.0, 61447.0, 78338.0, 81730.0, 87786.56106719426, 68183.31816681869, 72316.63841680864, 73287.0, 74151.0, 75705.62147189339, 10485.68542943713, 10500.883094203256, 10711.08955738771, 10739.301945373525, 10783.083437907866, 23334.883325583265, 36046.45930310995, 36910.07724806794, 37104.88685339444, 37877.43183852299, 44695.882602935184, 45947.62157135292, 46164.53835461737, 46840.0, 46859.033964226845, 10251.58993640246, 11874.406279686154, 13048.478060877458, 13270.336483175995, 13765.0, 14007.019508634297, 14101.717965640424, 14222.35999380051, 14419.78370324467, 14419.85580144227, 13162.0, 13176.670583235496, 13315.46738130464, 13609.251491168081, 13759.587212383645, 13139.73720525565, 13412.93293533526, 13426.127301725357, 13594.864051359755, 13886.583402497941, 12849.056603773584, 13070.411831467416, 13098.541551045668, 13244.668883277995, 13337.599872003855, 10647.520861561094, 10860.78278434411, 10874.510647021061, 10908.34549927007, 11842.526298947945, 8050.538268544348, 9853.852192216978, 9986.550605222637, 10681.61287263614, 11081.50133244022], "value": 7389.403948079649, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1466.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 110.0, 114.0, 156.0, 165.0, 110.0, 111.0, 113.0, 111.0], "value": 121.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 142.0, 140.0, 134.0, 139.0, 138.0, 139.0, 111.0, 131.0, 111.0], "value": 136.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 186.0, 184.0, 183.0, 182.0, 184.0, 187.0, 184.0, 184.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 63.0, 61.0, 92.0, 62.0, 60.0, 60.0, 61.0, 77.0, 58.0], "value": 67.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 88.0, 69.0, 71.0, 68.0, 84.0, 69.0, 69.0, 85.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 88.0, 88.0, 88.0, 88.0, 87.0, 88.0, 88.0, 88.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 97.0, 95.0, 95.0, 96.0, 97.0, 96.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 84.0, 86.0, 83.0, 83.0, 84.0, 83.0, 85.0], "value": 83.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 61.0, 61.0, 61.0, 62.0, 62.0, 64.0, 62.0, 62.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 63.0, 62.0, 65.0, 62.0, 58.0, 59.0, 59.0, 58.0, 62.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 82.0, 82.0, 83.0, 83.0, 83.0, 97.0, 102.0, 104.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 138.0, 140.0, 138.0, 137.0, 136.0, 152.0, 156.0, 188.0, 143.0], "value": 146.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 187.0, 188.0, 184.0, 187.0, 185.0, 187.0, 184.0, 187.0, 185.0], "value": 185.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 67.0, 69.0, 88.0, 79.0, 67.0, 68.0, 68.0, 69.0], "value": 71.2, "unit": "score"}]}]} 09:45:02 INFO - Return code: 0 09:45:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:45:02 ERROR - # TBPL SUCCESS # 09:45:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:45:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:45:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:45:02 INFO - 2017-03-21 09:45:02.095 screenresolution[1788:9923] starting screenresolution argv=screenresolution get 09:45:02 INFO - 2017-03-21 09:45:02.098 screenresolution[1788:9923] Display 0: 1600x1200x32@60 09:45:02 INFO - 2017-03-21 09:45:02.104 screenresolution[1789:9926] starting screenresolution argv=screenresolution list 09:45:02 INFO - Available Modes on Display 0 09:45:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:45:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:45:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:45:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:45:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:45:02 INFO - Intel Iris: 09:45:02 INFO - Chipset Model: Intel Iris 09:45:02 INFO - Type: GPU 09:45:02 INFO - Bus: Built-In 09:45:02 INFO - VRAM (Dynamic, Max): 1536 MB 09:45:02 INFO - Vendor: Intel (0x8086) 09:45:02 INFO - Device ID: 0x0a2e 09:45:02 INFO - Revision ID: 0x0009 09:45:02 INFO - Displays: 09:45:02 INFO - Display: 09:45:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:45:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:45:02 INFO - Display Serial Number: 1600x1200 60 09:45:02 INFO - Main Display: Yes 09:45:02 INFO - Mirror: Off 09:45:02 INFO - Online: Yes 09:45:02 INFO - Rotation: Supported 09:45:02 INFO - Return code: 0 09:45:02 INFO - Running post-action listener: _resource_record_post_action 09:45:02 INFO - [mozharness: 2017-03-21 16:45:02.349658Z] Finished run-tests step (success) 09:45:02 INFO - Running post-run listener: _resource_record_post_run 09:45:02 INFO - Total resource usage - Wall time: 1202s; CPU: 53.0%; Read bytes: 39916544; Write bytes: 514370560; Read time: 396; Write time: 1839 09:45:02 INFO - TinderboxPrint: CPU usage
53.4% 09:45:02 INFO - TinderboxPrint: I/O read bytes / time
39,916,544 / 396 09:45:02 INFO - TinderboxPrint: I/O write bytes / time
514,370,560 / 1,839 09:45:02 INFO - TinderboxPrint: CPU idle
2,239.4 (46.6%) 09:45:02 INFO - TinderboxPrint: CPU system
959.6 (20.0%) 09:45:02 INFO - TinderboxPrint: CPU user
1,610.5 (33.5%) 09:45:02 INFO - TinderboxPrint: Swap in / out
249,331,712 / 0 09:45:02 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202967552; Write bytes: 204260864; Read time: 15581; Write time: 414 09:45:02 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 38949888; Write bytes: 300168704; Read time: 373; Write time: 1396 09:45:02 INFO - Running post-run listener: _upload_blobber_files 09:45:02 INFO - Blob upload gear active. 09:45:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:45:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:45:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:45:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:45:03 INFO - (blobuploader) - INFO - Open directory for files ... 09:45:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:45:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:45:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:45:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:45:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:45:04 INFO - (blobuploader) - INFO - Done attempting. 09:45:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:45:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:45:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:45:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:45:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:45:05 INFO - (blobuploader) - INFO - Done attempting. 09:45:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:45:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:45:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:45:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:45:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:45:06 INFO - (blobuploader) - INFO - Done attempting. 09:45:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:45:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:45:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:45:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:45:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:45:09 INFO - (blobuploader) - INFO - Done attempting. 09:45:09 INFO - (blobuploader) - INFO - Iteration through files over. 09:45:09 INFO - Return code: 0 09:45:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:45:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:45:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8af6913d39f2d7141f5e8228ed78f4a7f4c416f6efc5da8c17e45a0c274904c6f778fcfdb8e0767d9169b24cc51686b3a857a057ee88f81d035d824c797ed5bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22a492cb556cbdaeeb27142fc4b58ffc8f90a3d9c21828bab1aa930cc87ae6023fc6d5773894200009f59e210a49ddf6817f207b1631f459110481893c560fe1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a67167ad97fb866e720ceed92b65116292cd1905b0b5b8213b585cb00358b543e54fb2e53480503d8583362f4cc07d000c88d3f02007308b4fa2970147b8f433", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b6985551518ad496e6d28dd90f6a8e2e865154290255e9497d40127c93c010d4aee24c5ffbe4ecd2ee436fe2af8003ca80ed7e7b6fa23b0061f5050e085e665"} 09:45:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:45:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:45:09 INFO - Contents: 09:45:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8af6913d39f2d7141f5e8228ed78f4a7f4c416f6efc5da8c17e45a0c274904c6f778fcfdb8e0767d9169b24cc51686b3a857a057ee88f81d035d824c797ed5bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22a492cb556cbdaeeb27142fc4b58ffc8f90a3d9c21828bab1aa930cc87ae6023fc6d5773894200009f59e210a49ddf6817f207b1631f459110481893c560fe1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a67167ad97fb866e720ceed92b65116292cd1905b0b5b8213b585cb00358b543e54fb2e53480503d8583362f4cc07d000c88d3f02007308b4fa2970147b8f433", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b6985551518ad496e6d28dd90f6a8e2e865154290255e9497d40127c93c010d4aee24c5ffbe4ecd2ee436fe2af8003ca80ed7e7b6fa23b0061f5050e085e665"} 09:45:09 INFO - Running post-run listener: copy_logs_to_upload_dir 09:45:09 INFO - Copying logs to upload dir... 09:45:09 INFO - mkdir: /builds/slave/test/build/upload/logs 09:45:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.330557 ========= master_lag: 0.27 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-21 09:45:09.837179) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 09:45:09.841208) ========= 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.bd3BOMyzub/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.GDuvfzVbcm/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8af6913d39f2d7141f5e8228ed78f4a7f4c416f6efc5da8c17e45a0c274904c6f778fcfdb8e0767d9169b24cc51686b3a857a057ee88f81d035d824c797ed5bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22a492cb556cbdaeeb27142fc4b58ffc8f90a3d9c21828bab1aa930cc87ae6023fc6d5773894200009f59e210a49ddf6817f207b1631f459110481893c560fe1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a67167ad97fb866e720ceed92b65116292cd1905b0b5b8213b585cb00358b543e54fb2e53480503d8583362f4cc07d000c88d3f02007308b4fa2970147b8f433", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b6985551518ad496e6d28dd90f6a8e2e865154290255e9497d40127c93c010d4aee24c5ffbe4ecd2ee436fe2af8003ca80ed7e7b6fa23b0061f5050e085e665"} build_url:https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011262 build_url: 'https://queue.taskcluster.net/v1/task/HNMOARDVQwaXd-lrjJTVQg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8af6913d39f2d7141f5e8228ed78f4a7f4c416f6efc5da8c17e45a0c274904c6f778fcfdb8e0767d9169b24cc51686b3a857a057ee88f81d035d824c797ed5bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22a492cb556cbdaeeb27142fc4b58ffc8f90a3d9c21828bab1aa930cc87ae6023fc6d5773894200009f59e210a49ddf6817f207b1631f459110481893c560fe1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a67167ad97fb866e720ceed92b65116292cd1905b0b5b8213b585cb00358b543e54fb2e53480503d8583362f4cc07d000c88d3f02007308b4fa2970147b8f433", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b6985551518ad496e6d28dd90f6a8e2e865154290255e9497d40127c93c010d4aee24c5ffbe4ecd2ee436fe2af8003ca80ed7e7b6fa23b0061f5050e085e665"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 09:45:09.881479) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 09:45:09.881862) ========= 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.bd3BOMyzub/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.GDuvfzVbcm/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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.005062 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 09:45:09.931173) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 09:45:09.934610) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 09:45:09.934996) ========= ========= Total master_lag: 0.42 =========