builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0072 starttime: 1486400679.27 results: success (0) buildid: 20170206072550 builduid: 2614cecb7e164b468ec5ce404bbd8e3d revision: 14147cb471a102f9ecc4a30cbb13efaf5c5607f1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 09:04:39.273836) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 09:04:39.274269) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 09:04:39.274541) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 09:04:39.300365) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 09:04:39.300656) ========= 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.pKYHg8KQdJ/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.VOn9JKezqu/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-02-06 09:04:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.6M=0.001s 2017-02-06 09:04:39 (16.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088782 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 09:04:39.416287) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 09:04:39.416600) ========= 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.pKYHg8KQdJ/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.VOn9JKezqu/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.026013 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 09:04:39.464996) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-06 09:04:39.468380) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 14147cb471a102f9ecc4a30cbb13efaf5c5607f1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 14147cb471a102f9ecc4a30cbb13efaf5c5607f1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pKYHg8KQdJ/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.VOn9JKezqu/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-02-06 09:04:39,572 truncating revision to first 12 chars 2017-02-06 09:04:39,572 Setting DEBUG logging. 2017-02-06 09:04:39,572 attempt 1/10 2017-02-06 09:04:39,573 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/14147cb471a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-06 09:04:40,549 unpacking tar archive at: mozilla-aurora-14147cb471a1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.243128 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-06 09:04:40.737540) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 09:04:40.737860) ========= script_repo_revision: 14147cb471a102f9ecc4a30cbb13efaf5c5607f1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 09:04:40.738287) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 09:04:40.738553) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 09:04:40.757990) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-06 09:04:40.758296) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pKYHg8KQdJ/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.VOn9JKezqu/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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:04:40 INFO - MultiFileLogger online at 20170206 09:04:40 in /builds/slave/test 09:04:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:04:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:04:40 INFO - {'append_to_log': False, 09:04:40 INFO - 'base_work_dir': '/builds/slave/test', 09:04:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:04:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:04:40 INFO - 'branch': 'Mozilla-Aurora', 09:04:40 INFO - 'buildbot_json_path': 'buildprops.json', 09:04:40 INFO - 'config_files': ('talos/mac_config.py',), 09:04:40 INFO - 'default_actions': ('clobber', 09:04:40 INFO - 'read-buildbot-config', 09:04:40 INFO - 'download-and-extract', 09:04:40 INFO - 'populate-webroot', 09:04:40 INFO - 'create-virtualenv', 09:04:40 INFO - 'install', 09:04:40 INFO - 'run-tests'), 09:04:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:04:40 INFO - 'download_minidump_stackwalk': True, 09:04:40 INFO - 'download_symbols': 'ondemand', 09:04:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:04:40 INFO - 'tooltool.py': '/tools/tooltool.py', 09:04:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:04:40 INFO - '/tools/misc-python/virtualenv.py')}, 09:04:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:04:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:04:40 INFO - 'installer_path': 'installer.exe', 09:04:40 INFO - 'log_level': 'info', 09:04:40 INFO - 'log_name': 'talos', 09:04:40 INFO - 'log_to_console': True, 09:04:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:04:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:04:40 INFO - 'opt_config_files': (), 09:04:40 INFO - 'pip_index': False, 09:04:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:04:40 INFO - 'cmd': ('bash', 09:04:40 INFO - '-c', 09:04:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:04:40 INFO - 'enabled': True, 09:04:40 INFO - 'halt_on_failure': False, 09:04:40 INFO - 'name': 'check_screen_resolution'},), 09:04:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:04:40 INFO - 'cmd': ('bash', 09:04:40 INFO - '-c', 09:04:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:04:40 INFO - 'enabled': True, 09:04:40 INFO - 'halt_on_failure': False, 09:04:40 INFO - 'name': 'check_screen_resolution'},), 09:04:40 INFO - 'run_cmd_checks_enabled': True, 09:04:40 INFO - 'sps_profile': False, 09:04:40 INFO - 'sps_profile_interval': 0, 09:04:40 INFO - 'suite': 'dromaeojs', 09:04:40 INFO - 'system_bits': '32', 09:04:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:04:40 INFO - 'title': 't-yosemite-r7-0072', 09:04:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:04:40 INFO - 'use_talos_json': True, 09:04:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:04:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:04:40 INFO - 'work_dir': 'build'} 09:04:40 INFO - [mozharness: 2017-02-06 17:04:40.975513Z] Running clobber step. 09:04:40 INFO - Running pre-action listener: _resource_record_pre_action 09:04:40 INFO - Running main action method: clobber 09:04:40 INFO - rmtree: /builds/slave/test/build 09:04:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:04:43 INFO - Running post-action listener: _resource_record_post_action 09:04:43 INFO - [mozharness: 2017-02-06 17:04:43.342776Z] Finished clobber step (success) 09:04:43 INFO - [mozharness: 2017-02-06 17:04:43.342947Z] Running read-buildbot-config step. 09:04:43 INFO - Running pre-action listener: _resource_record_pre_action 09:04:43 INFO - Running main action method: read_buildbot_config 09:04:43 INFO - Using buildbot properties: 09:04:43 INFO - { 09:04:43 INFO - "project": "", 09:04:43 INFO - "product": "firefox", 09:04:43 INFO - "script_repo_revision": "production", 09:04:43 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:04:43 INFO - "repository": "", 09:04:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:04:43 INFO - "buildid": "20170206072550", 09:04:43 INFO - "pgo_build": "False", 09:04:43 INFO - "basedir": "/builds/slave/test", 09:04:43 INFO - "buildnumber": 1, 09:04:43 INFO - "slavename": "t-yosemite-r7-0072", 09:04:43 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:04:43 INFO - "platform": "yosemite_r7", 09:04:43 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:04:43 INFO - "branch": "mozilla-aurora", 09:04:43 INFO - "repo_path": "releases/mozilla-aurora", 09:04:43 INFO - "revision": "14147cb471a102f9ecc4a30cbb13efaf5c5607f1", 09:04:43 INFO - "stage_platform": "macosx64", 09:04:43 INFO - "builduid": "2614cecb7e164b468ec5ce404bbd8e3d", 09:04:43 INFO - "slavebuilddir": "test" 09:04:43 INFO - } 09:04:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 09:04:43 INFO - Running post-action listener: _resource_record_post_action 09:04:43 INFO - [mozharness: 2017-02-06 17:04:43.347173Z] Finished read-buildbot-config step (success) 09:04:43 INFO - [mozharness: 2017-02-06 17:04:43.347344Z] Running download-and-extract step. 09:04:43 INFO - Running pre-action listener: _resource_record_pre_action 09:04:43 INFO - Running main action method: download_and_extract 09:04:43 INFO - mkdir: /builds/slave/test/build/tests 09:04: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:04:43 INFO - trying https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 09:04:43 INFO - Downloading https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:04:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 09:04:44 INFO - Downloaded 1199 bytes. 09:04:44 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:04:44 INFO - Using the following test package requirements: 09:04:44 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:04:44 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:04:44 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 09:04:44 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:04:44 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 09:04:44 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:04:44 INFO - u'jsshell-mac.zip'], 09:04:44 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:04:44 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 09:04:44 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:04:44 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:04:44 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 09:04:44 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:04:44 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 09:04:44 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:04:44 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 09:04:44 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:04:44 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 09:04:44 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:04:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:04:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:04:44 INFO - Fetch https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:04:45 INFO - Expected file size: 16356096 09:04:45 INFO - Obtained file size: 16356096 09:04:52 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:04:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:04:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:04:52 INFO - Fetch https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:04:53 INFO - Expected file size: 16356096 09:04:53 INFO - Obtained file size: 16356096 09:04:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 09:04:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:04:59 INFO - Fetch https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 09:05:00 INFO - Expected file size: 14403485 09:05:00 INFO - Obtained file size: 14403485 09:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:01 INFO - trying https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:05:01 INFO - Downloading https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 09:05:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 09:05:03 INFO - Downloaded 73830267 bytes. 09:05:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:05:03 INFO - mkdir: /builds/slave/test/properties 09:05:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:05:03 INFO - Writing to file /builds/slave/test/properties/build_url 09:05:03 INFO - Contents: 09:05:03 INFO - build_url:https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:05:04 INFO - Running post-action listener: _resource_record_post_action 09:05:04 INFO - Running post-action listener: set_extra_try_arguments 09:05:04 INFO - [mozharness: 2017-02-06 17:05:04.028885Z] Finished download-and-extract step (success) 09:05:04 INFO - [mozharness: 2017-02-06 17:05:04.029037Z] Running populate-webroot step. 09:05:04 INFO - Running pre-action listener: _resource_record_pre_action 09:05:04 INFO - Running main action method: populate_webroot 09:05:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:05:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:05:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:05:04 INFO - '--tppagecycles', 09:05:04 INFO - '7'], 09:05:04 INFO - 'tests': ['tsvgm']}}, 09:05:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:05:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:05:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:05:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:05:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:05:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:05:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:05:04 INFO - 'talos_options': ['--disable-e10s'], 09:05:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:05:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:05:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:05:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:05:04 INFO - 'talos_options': ['--disable-e10s'], 09:05:04 INFO - 'tests': ['damp', 'tps']}, 09:05:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:05:04 INFO - 'tests': ['damp', 'tps']}, 09:05:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:05:04 INFO - 'tests': ['dromaeo_dom']}, 09:05:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:05:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:05:04 INFO - 'tests': ['basic_compositor_video']}, 09:05:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:05:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:05:04 INFO - 'tests': ['a11yr', 09:05:04 INFO - 'ts_paint', 09:05:04 INFO - 'tpaint', 09:05:04 INFO - 'sessionrestore', 09:05:04 INFO - 'sessionrestore_no_auto_restore', 09:05:04 INFO - 'tabpaint']}, 09:05:04 INFO - 'other-e10s': {'tests': ['a11yr', 09:05:04 INFO - 'ts_paint', 09:05:04 INFO - 'tpaint', 09:05:04 INFO - 'sessionrestore', 09:05:04 INFO - 'sessionrestore_no_auto_restore', 09:05:04 INFO - 'tabpaint']}, 09:05:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:05:04 INFO - 'ts_paint', 09:05:04 INFO - 'tpaint', 09:05:04 INFO - 'sessionrestore', 09:05:04 INFO - 'sessionrestore_no_auto_restore', 09:05:04 INFO - 'tabpaint']}, 09:05:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:05:04 INFO - 'ts_paint', 09:05:04 INFO - 'tpaint', 09:05:04 INFO - 'sessionrestore', 09:05:04 INFO - 'sessionrestore_no_auto_restore', 09:05:04 INFO - 'tabpaint']}, 09:05:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:05:04 INFO - 'tests': ['a11yr', 09:05:04 INFO - 'ts_paint', 09:05:04 INFO - 'tpaint', 09:05:04 INFO - 'sessionrestore', 09:05:04 INFO - 'sessionrestore_no_auto_restore', 09:05:04 INFO - 'tabpaint']}, 09:05:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:05:04 INFO - 'tests': ['a11yr', 09:05:04 INFO - 'ts_paint', 09:05:04 INFO - 'tpaint', 09:05:04 INFO - 'sessionrestore', 09:05:04 INFO - 'sessionrestore_no_auto_restore', 09:05:04 INFO - 'tabpaint']}, 09:05:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:05:04 INFO - 'tests': ['tsvgx', 09:05:04 INFO - 'tsvgr_opacity', 09:05:04 INFO - 'tart', 09:05:04 INFO - 'tscrollx', 09:05:04 INFO - 'cart', 09:05:04 INFO - 'tsvg_static']}, 09:05:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:05:04 INFO - 'tsvgr_opacity', 09:05:04 INFO - 'tart', 09:05:04 INFO - 'tscrollx', 09:05:04 INFO - 'cart', 09:05:04 INFO - 'tsvg_static']}, 09:05:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:05:04 INFO - 'talos_options': ['--disable-e10s'], 09:05:04 INFO - 'tests': ['tp5o']}, 09:05:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:05:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:05:04 INFO - 'talos_options': ['--disable-e10s', 09:05:04 INFO - '--xperf_path', 09:05:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:05:04 INFO - 'tests': ['tp5n']}, 09:05:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:05:04 INFO - 'talos_options': ['--xperf_path', 09:05:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:05:04 INFO - 'tests': ['tp5n']}}, 09:05:04 INFO - 'talos.zip': {'path': '', 09:05:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:05:04 INFO - Running post-action listener: _resource_record_post_action 09:05:04 INFO - [mozharness: 2017-02-06 17:05:04.048003Z] Finished populate-webroot step (success) 09:05:04 INFO - [mozharness: 2017-02-06 17:05:04.048135Z] Running create-virtualenv step. 09:05:04 INFO - Running pre-action listener: _resource_record_pre_action 09:05:04 INFO - Running main action method: create_virtualenv 09:05:04 INFO - Creating virtualenv /builds/slave/test/build/venv 09:05:04 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:05:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:05:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:05:04 INFO - Using real prefix '/tools/python27' 09:05:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:05:04 INFO - Installing distribute.............................................................................................................................................................................................done. 09:05:07 INFO - Installing pip.................done. 09:05:07 INFO - Return code: 0 09:05:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:05:08 INFO - Reading from file tmpfile_stdout 09:05:08 INFO - Output received: 09:05:08 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:05:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:05:08 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:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:08 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:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:08 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 0x101fb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa91b470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d93d30>, '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 0x101cbadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d88be8>, '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.pKYHg8KQdJ/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.VOn9JKezqu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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:05:08 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:05:08 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:05:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pKYHg8KQdJ/Render', 09:05:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:08 INFO - 'HOME': '/Users/cltbld', 09:05:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:08 INFO - 'LOGNAME': 'cltbld', 09:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:08 INFO - 'MOZ_NO_REMOTE': '1', 09:05:08 INFO - 'NO_EM_RESTART': '1', 09:05:08 INFO - 'PAGER': '/bin/cat', 09:05:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:08 INFO - 'PWD': '/builds/slave/test', 09:05:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:08 INFO - 'SHELL': '/bin/bash', 09:05:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOn9JKezqu/Listeners', 09:05:08 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 09:05:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:08 INFO - 'USER': 'cltbld', 09:05:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:08 INFO - 'XPC_FLAGS': '0x0', 09:05:08 INFO - 'XPC_SERVICE_NAME': '0', 09:05:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:08 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:05:08 INFO - Cleaning up... 09:05:08 INFO - Return code: 0 09:05:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:05:08 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:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:08 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:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:08 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 0x101fb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa91b470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d93d30>, '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 0x101cbadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d88be8>, '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.pKYHg8KQdJ/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.VOn9JKezqu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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:05:08 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:05:08 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:05:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pKYHg8KQdJ/Render', 09:05:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:08 INFO - 'HOME': '/Users/cltbld', 09:05:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:08 INFO - 'LOGNAME': 'cltbld', 09:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:08 INFO - 'MOZ_NO_REMOTE': '1', 09:05:08 INFO - 'NO_EM_RESTART': '1', 09:05:08 INFO - 'PAGER': '/bin/cat', 09:05:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:08 INFO - 'PWD': '/builds/slave/test', 09:05:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:08 INFO - 'SHELL': '/bin/bash', 09:05:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOn9JKezqu/Listeners', 09:05:08 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 09:05:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:08 INFO - 'USER': 'cltbld', 09:05:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:08 INFO - 'XPC_FLAGS': '0x0', 09:05:08 INFO - 'XPC_SERVICE_NAME': '0', 09:05:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:08 INFO - Downloading/unpacking psutil>=3.1.1 09:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:05:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:05:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:05:12 INFO - Installing collected packages: psutil 09:05:12 INFO - Running setup.py install for psutil 09:05:12 INFO - building 'psutil._psutil_osx' extension 09:05:12 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:05:12 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:05:12 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:05:13 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:05:13 INFO - building 'psutil._psutil_posix' extension 09:05:13 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:05:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:05:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:05:13 INFO - ^ 09:05:13 INFO - 1 warning generated. 09:05:13 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:05:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:05:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:05:13 INFO - Successfully installed psutil 09:05:13 INFO - Cleaning up... 09:05:13 INFO - Return code: 0 09:05:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:05:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:13 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 0x101fb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa91b470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d93d30>, '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 0x101cbadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d88be8>, '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.pKYHg8KQdJ/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.VOn9JKezqu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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:05:13 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:05:13 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:05:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pKYHg8KQdJ/Render', 09:05:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:13 INFO - 'HOME': '/Users/cltbld', 09:05:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:13 INFO - 'LOGNAME': 'cltbld', 09:05:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:13 INFO - 'MOZ_NO_REMOTE': '1', 09:05:13 INFO - 'NO_EM_RESTART': '1', 09:05:13 INFO - 'PAGER': '/bin/cat', 09:05:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:13 INFO - 'PWD': '/builds/slave/test', 09:05:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:13 INFO - 'SHELL': '/bin/bash', 09:05:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOn9JKezqu/Listeners', 09:05:13 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 09:05:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:13 INFO - 'USER': 'cltbld', 09:05:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:13 INFO - 'XPC_FLAGS': '0x0', 09:05:13 INFO - 'XPC_SERVICE_NAME': '0', 09:05:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:05:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:05:16 INFO - Installing collected packages: mozsystemmonitor 09:05:16 INFO - Running setup.py install for mozsystemmonitor 09:05:16 INFO - Successfully installed mozsystemmonitor 09:05:16 INFO - Cleaning up... 09:05:16 INFO - Return code: 0 09:05:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:05:16 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:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:16 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:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:16 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 0x101fb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa91b470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d93d30>, '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 0x101cbadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d88be8>, '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.pKYHg8KQdJ/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.VOn9JKezqu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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:05:16 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:05:16 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:05:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pKYHg8KQdJ/Render', 09:05:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:16 INFO - 'HOME': '/Users/cltbld', 09:05:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:16 INFO - 'LOGNAME': 'cltbld', 09:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:16 INFO - 'MOZ_NO_REMOTE': '1', 09:05:16 INFO - 'NO_EM_RESTART': '1', 09:05:16 INFO - 'PAGER': '/bin/cat', 09:05:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:16 INFO - 'PWD': '/builds/slave/test', 09:05:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:16 INFO - 'SHELL': '/bin/bash', 09:05:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOn9JKezqu/Listeners', 09:05:16 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 09:05:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:16 INFO - 'USER': 'cltbld', 09:05:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:16 INFO - 'XPC_FLAGS': '0x0', 09:05:16 INFO - 'XPC_SERVICE_NAME': '0', 09:05:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:16 INFO - Downloading/unpacking jsonschema==2.5.1 09:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:05:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:05:19 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:05:19 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:05:19 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:05:19 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:05:19 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:05:19 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:05:19 INFO - Downloading functools32-3.2.3-2.tar.gz 09:05:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:05:20 INFO - warning: no files found matching '*.txt' 09:05:20 INFO - no previously-included directories found matching 'build' 09:05:20 INFO - no previously-included directories found matching 'dist' 09:05:20 INFO - no previously-included directories found matching '.git*' 09:05:20 INFO - Installing collected packages: jsonschema, functools32 09:05:20 INFO - Running setup.py install for functools32 09:05:20 INFO - warning: no files found matching '*.txt' 09:05:20 INFO - no previously-included directories found matching 'build' 09:05:20 INFO - no previously-included directories found matching 'dist' 09:05:20 INFO - no previously-included directories found matching '.git*' 09:05:20 INFO - Successfully installed jsonschema functools32 09:05:20 INFO - Cleaning up... 09:05:20 INFO - Return code: 0 09:05:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:05: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:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05: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:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:20 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 0x101fb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa91b470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d93d30>, '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 0x101cbadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d88be8>, '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.pKYHg8KQdJ/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.VOn9JKezqu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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:05:20 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:05:20 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:05:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pKYHg8KQdJ/Render', 09:05:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:20 INFO - 'HOME': '/Users/cltbld', 09:05:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:20 INFO - 'LOGNAME': 'cltbld', 09:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:20 INFO - 'MOZ_NO_REMOTE': '1', 09:05:20 INFO - 'NO_EM_RESTART': '1', 09:05:20 INFO - 'PAGER': '/bin/cat', 09:05:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:20 INFO - 'PWD': '/builds/slave/test', 09:05:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:20 INFO - 'SHELL': '/bin/bash', 09:05:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOn9JKezqu/Listeners', 09:05:20 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 09:05:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:20 INFO - 'USER': 'cltbld', 09:05:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:20 INFO - 'XPC_FLAGS': '0x0', 09:05:20 INFO - 'XPC_SERVICE_NAME': '0', 09:05:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:05:20 INFO - Cleaning up... 09:05:20 INFO - Return code: 0 09:05:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:05: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:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05: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:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:20 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 0x101fb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa91b470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d93d30>, '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 0x101cbadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d88be8>, '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.pKYHg8KQdJ/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.VOn9JKezqu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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:05:20 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:05:20 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:05:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pKYHg8KQdJ/Render', 09:05:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:20 INFO - 'HOME': '/Users/cltbld', 09:05:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:20 INFO - 'LOGNAME': 'cltbld', 09:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:20 INFO - 'MOZ_NO_REMOTE': '1', 09:05:20 INFO - 'NO_EM_RESTART': '1', 09:05:20 INFO - 'PAGER': '/bin/cat', 09:05:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:20 INFO - 'PWD': '/builds/slave/test', 09:05:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:20 INFO - 'SHELL': '/bin/bash', 09:05:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOn9JKezqu/Listeners', 09:05:20 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 09:05:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:20 INFO - 'USER': 'cltbld', 09:05:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:20 INFO - 'XPC_FLAGS': '0x0', 09:05:20 INFO - 'XPC_SERVICE_NAME': '0', 09:05:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:20 INFO - Downloading/unpacking blobuploader==1.2.4 09:05:20 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:05:20 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:05:20 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:05:20 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:05:20 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:05:20 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:05:23 INFO - Downloading blobuploader-1.2.4.tar.gz 09:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:05:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:05:23 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:05:23 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:05:23 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:05:23 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:05:23 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:05:23 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:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:05:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:05:24 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:05:24 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:05:24 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:05:24 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:05:24 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:05:24 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:05:24 INFO - Downloading docopt-0.6.1.tar.gz 09:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:05:24 INFO - Installing collected packages: blobuploader, requests, docopt 09:05:24 INFO - Running setup.py install for blobuploader 09:05:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:05:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:05:24 INFO - Running setup.py install for requests 09:05:25 INFO - Running setup.py install for docopt 09:05:25 INFO - Successfully installed blobuploader requests docopt 09:05:25 INFO - Cleaning up... 09:05:25 INFO - Return code: 0 09:05:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:25 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 0x101fb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa91b470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d93d30>, '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 0x101cbadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d88be8>, '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.pKYHg8KQdJ/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.VOn9JKezqu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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:05:25 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:05:25 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:05:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pKYHg8KQdJ/Render', 09:05:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:25 INFO - 'HOME': '/Users/cltbld', 09:05:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:25 INFO - 'LOGNAME': 'cltbld', 09:05:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:25 INFO - 'MOZ_NO_REMOTE': '1', 09:05:25 INFO - 'NO_EM_RESTART': '1', 09:05:25 INFO - 'PAGER': '/bin/cat', 09:05:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:25 INFO - 'PWD': '/builds/slave/test', 09:05:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:25 INFO - 'SHELL': '/bin/bash', 09:05:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOn9JKezqu/Listeners', 09:05:25 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 09:05:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:25 INFO - 'USER': 'cltbld', 09:05:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:25 INFO - 'XPC_FLAGS': '0x0', 09:05:25 INFO - 'XPC_SERVICE_NAME': '0', 09:05:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:05:25 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-eKzlIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:05:26 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-ReAwsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:05:26 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-IKbHp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:05:26 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-RNb7wA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:05:26 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-grakVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:05:26 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-2OlBjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:05:26 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-c81Qgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:05:26 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-mzZJVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:05:26 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-n6gG7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:05:26 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-hEBBFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:05:26 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-BsSncL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:05:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-vfwiEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:05:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-7cBKZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:05:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-Was6LC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-hBcF3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:05:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-8jwltz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:05:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-ooDGs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:05:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:05:27 INFO - Running setup.py install for manifestparser 09:05:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:05:27 INFO - Running setup.py install for mozcrash 09:05:27 INFO - Running setup.py install for mozdebug 09:05:28 INFO - Running setup.py install for mozdevice 09:05:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:05:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:05:28 INFO - Running setup.py install for mozfile 09:05:28 INFO - Running setup.py install for mozhttpd 09:05:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:05:28 INFO - Running setup.py install for mozinfo 09:05:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:05:28 INFO - Running setup.py install for mozInstall 09:05:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:05:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:05:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:05:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:05:28 INFO - Running setup.py install for mozleak 09:05:28 INFO - Running setup.py install for mozlog 09:05:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:05:29 INFO - Running setup.py install for moznetwork 09:05:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:05:29 INFO - Running setup.py install for mozprocess 09:05:29 INFO - Running setup.py install for mozprofile 09:05:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:05:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:05:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:05:29 INFO - Running setup.py install for mozrunner 09:05:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:05:29 INFO - Running setup.py install for mozscreenshot 09:05:29 INFO - Running setup.py install for moztest 09:05:30 INFO - Running setup.py install for mozversion 09:05:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:05:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:05:30 INFO - Cleaning up... 09:05:30 INFO - Return code: 0 09:05:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:05:30 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:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:30 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:05:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:30 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 0x101fb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa91b470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d93d30>, '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 0x101cbadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d88be8>, '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.pKYHg8KQdJ/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.VOn9JKezqu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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:05:30 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:05:30 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:05:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pKYHg8KQdJ/Render', 09:05:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:30 INFO - 'HOME': '/Users/cltbld', 09:05:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:30 INFO - 'LOGNAME': 'cltbld', 09:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:30 INFO - 'MOZ_NO_REMOTE': '1', 09:05:30 INFO - 'NO_EM_RESTART': '1', 09:05:30 INFO - 'PAGER': '/bin/cat', 09:05:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:30 INFO - 'PWD': '/builds/slave/test', 09:05:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:30 INFO - 'SHELL': '/bin/bash', 09:05:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOn9JKezqu/Listeners', 09:05:30 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 09:05:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:30 INFO - 'USER': 'cltbld', 09:05:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:30 INFO - 'XPC_FLAGS': '0x0', 09:05:30 INFO - 'XPC_SERVICE_NAME': '0', 09:05:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:05:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-bG9ZmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:05:30 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:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:05:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-0YmUDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:05:30 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:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:05:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-_fWOBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:05:30 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:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:05:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-wJ_B_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:05:30 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:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:05:30 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-cGiaMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:05:31 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:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:05:31 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-f0fxI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:05:31 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:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:05:31 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-pDGpKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:05:31 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:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:05:31 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-8luPAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:05:31 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:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:05:31 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-6tSHXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:05:31 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:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:05:31 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-tGdpKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:05:31 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:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:05:31 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-vErnQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:05:31 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:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:05:31 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-uiP_Kg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:05:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:05:31 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-gimsmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:05:31 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:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:05:31 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-uxGu1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:05:32 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:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:32 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-lBU2w_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:32 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:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:05:32 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-E01mKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:05:32 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:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:05:32 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-VipD6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:05:32 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:05:32 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:05:32 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:05:32 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:05:32 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:05:32 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:05:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:34 INFO - Downloading blessings-1.6.tar.gz 09:05:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:05:35 INFO - Installing collected packages: blessings 09:05:35 INFO - Running setup.py install for blessings 09:05:35 INFO - Successfully installed blessings 09:05:35 INFO - Cleaning up... 09:05:35 INFO - Return code: 0 09:05:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:05:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:05:35 INFO - Reading from file tmpfile_stdout 09:05:35 INFO - Current package versions: 09:05:35 INFO - blessings == 1.6 09:05:35 INFO - blobuploader == 1.2.4 09:05:35 INFO - docopt == 0.6.1 09:05:35 INFO - functools32 == 3.2.3-2 09:05:35 INFO - jsonschema == 2.5.1 09:05:35 INFO - manifestparser == 1.1 09:05:35 INFO - mozInstall == 1.12 09:05:35 INFO - mozcrash == 1.0 09:05:35 INFO - mozdebug == 0.1 09:05:35 INFO - mozdevice == 0.49 09:05:35 INFO - mozfile == 1.2 09:05:35 INFO - mozhttpd == 0.7 09:05:35 INFO - mozinfo == 0.9 09:05:35 INFO - mozleak == 0.1 09:05:35 INFO - mozlog == 3.4 09:05:35 INFO - moznetwork == 0.27 09:05:35 INFO - mozprocess == 0.24 09:05:35 INFO - mozprofile == 0.28 09:05:35 INFO - mozrunner == 6.13 09:05:35 INFO - mozscreenshot == 0.1 09:05:35 INFO - mozsystemmonitor == 0.3 09:05:35 INFO - moztest == 0.8 09:05:35 INFO - mozversion == 1.4 09:05:35 INFO - psutil == 3.1.1 09:05:35 INFO - requests == 1.2.3 09:05:35 INFO - wsgiref == 0.1.2 09:05:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:05:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:36 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 0x101fb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa91b470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d93d30>, '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 0x101cbadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d88be8>, '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.pKYHg8KQdJ/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.VOn9JKezqu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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:05:36 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:05:36 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:05:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pKYHg8KQdJ/Render', 09:05:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:36 INFO - 'HOME': '/Users/cltbld', 09:05:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:36 INFO - 'LOGNAME': 'cltbld', 09:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:36 INFO - 'MOZ_NO_REMOTE': '1', 09:05:36 INFO - 'NO_EM_RESTART': '1', 09:05:36 INFO - 'PAGER': '/bin/cat', 09:05:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:36 INFO - 'PWD': '/builds/slave/test', 09:05:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:36 INFO - 'SHELL': '/bin/bash', 09:05:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOn9JKezqu/Listeners', 09:05:36 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 09:05:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:36 INFO - 'USER': 'cltbld', 09:05:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:36 INFO - 'XPC_FLAGS': '0x0', 09:05:36 INFO - 'XPC_SERVICE_NAME': '0', 09:05:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:36 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:05:36 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:05:36 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:05:36 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:05:36 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:05:36 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:05:36 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:05:36 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:05:36 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:05:36 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:05:36 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:05:36 INFO - Cleaning up... 09:05:36 INFO - Return code: 0 09:05:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:37 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 0x101fb5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa91b470af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d93d30>, '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 0x101cbadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d88be8>, '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.pKYHg8KQdJ/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.VOn9JKezqu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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:05:37 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:05:37 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:05:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pKYHg8KQdJ/Render', 09:05:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:37 INFO - 'HOME': '/Users/cltbld', 09:05:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:37 INFO - 'LOGNAME': 'cltbld', 09:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:37 INFO - 'MOZ_NO_REMOTE': '1', 09:05:37 INFO - 'NO_EM_RESTART': '1', 09:05:37 INFO - 'PAGER': '/bin/cat', 09:05:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:37 INFO - 'PWD': '/builds/slave/test', 09:05:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:37 INFO - 'SHELL': '/bin/bash', 09:05:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOn9JKezqu/Listeners', 09:05:37 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 09:05:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:37 INFO - 'USER': 'cltbld', 09:05:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:37 INFO - 'XPC_FLAGS': '0x0', 09:05:37 INFO - 'XPC_SERVICE_NAME': '0', 09:05:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:05:37 INFO - Cleaning up... 09:05:37 INFO - Return code: 0 09:05:37 INFO - Running post-action listener: _resource_record_post_action 09:05:37 INFO - Running post-action listener: _start_resource_monitoring 09:05:37 INFO - Starting resource monitoring. 09:05:37 INFO - [mozharness: 2017-02-06 17:05:37.377203Z] Finished create-virtualenv step (success) 09:05:37 INFO - [mozharness: 2017-02-06 17:05:37.378112Z] Running install step. 09:05:37 INFO - Running pre-action listener: _resource_record_pre_action 09:05:37 INFO - Running main action method: install 09:05:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:05:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:05:37 INFO - Reading from file tmpfile_stdout 09:05:37 INFO - Detecting whether we're running mozinstall >=1.0... 09:05:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:05:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:05:37 INFO - Reading from file tmpfile_stdout 09:05:37 INFO - Output received: 09:05:37 INFO - Usage: mozinstall [options] installer 09:05:37 INFO - Options: 09:05:37 INFO - -h, --help show this help message and exit 09:05:37 INFO - -d DEST, --destination=DEST 09:05:37 INFO - Directory to install application into. [default: 09:05:37 INFO - "/builds/slave/test"] 09:05:37 INFO - --app=APP Application being installed. [default: firefox] 09:05:37 INFO - mkdir: /builds/slave/test/build/application 09:05:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:05:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:05:57 INFO - Reading from file tmpfile_stdout 09:05:57 INFO - Output received: 09:05:57 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:05:57 INFO - Running post-action listener: _resource_record_post_action 09:05:57 INFO - [mozharness: 2017-02-06 17:05:57.757066Z] Finished install step (success) 09:05:57 INFO - [mozharness: 2017-02-06 17:05:57.757205Z] Running run-tests step. 09:05:57 INFO - Running pre-action listener: _resource_record_pre_action 09:05:57 INFO - Running main action method: run_tests 09:05:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:05:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:05:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:05:57 INFO - 2017-02-06 09:05:57.769 screenresolution[1618:7424] starting screenresolution argv=screenresolution get 09:05:57 INFO - 2017-02-06 09:05:57.772 screenresolution[1618:7424] Display 0: 1600x1200x32@60 09:05:57 INFO - 2017-02-06 09:05:57.777 screenresolution[1619:7427] starting screenresolution argv=screenresolution list 09:05:57 INFO - Available Modes on Display 0 09:05:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:05:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:05:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:05:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:05:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:05:58 INFO - Intel Iris: 09:05:58 INFO - Chipset Model: Intel Iris 09:05:58 INFO - Type: GPU 09:05:58 INFO - Bus: Built-In 09:05:58 INFO - VRAM (Dynamic, Max): 1536 MB 09:05:58 INFO - Vendor: Intel (0x8086) 09:05:58 INFO - Device ID: 0x0a2e 09:05:58 INFO - Revision ID: 0x0009 09:05:58 INFO - Displays: 09:05:58 INFO - Display: 09:05:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:05:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:05:58 INFO - Display Serial Number: 1600x1200 60 09:05:58 INFO - Main Display: Yes 09:05:58 INFO - Mirror: Off 09:05:58 INFO - Online: Yes 09:05:58 INFO - Rotation: Supported 09:05:58 INFO - Return code: 0 09:05:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:05:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:05:58 INFO - Python 2.7.3 09:05:58 INFO - Return code: 0 09:05:58 INFO - grabbing minidump binary from tooltool 09:05: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:05:58 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 0x101d93d30>, '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 0x101cbadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d88be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:05:58 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:05:58 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:05:58 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:05:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:05:58 INFO - Return code: 0 09:05:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:05:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:05:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:05:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:05:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:05:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--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:05:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0072 --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:05:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pKYHg8KQdJ/Render', 09:05:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:58 INFO - 'HOME': '/Users/cltbld', 09:05:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:58 INFO - 'LOGNAME': 'cltbld', 09:05:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:05:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:58 INFO - 'MOZ_NO_REMOTE': '1', 09:05:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:05:58 INFO - 'NO_EM_RESTART': '1', 09:05:58 INFO - 'PAGER': '/bin/cat', 09:05:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:58 INFO - 'PWD': '/builds/slave/test', 09:05:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:05:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:58 INFO - 'SHELL': '/bin/bash', 09:05:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOn9JKezqu/Listeners', 09:05:58 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 09:05:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:58 INFO - 'USER': 'cltbld', 09:05:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:58 INFO - 'XPC_FLAGS': '0x0', 09:05:58 INFO - 'XPC_SERVICE_NAME': '0', 09:05:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--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:05:58 INFO - mozversion application_buildid: 20170206072550 09:05:58 INFO - mozversion application_changeset: 14147cb471a102f9ecc4a30cbb13efaf5c5607f1 09:05:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:05:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:05:58 INFO - mozversion application_name: Firefox 09:05:58 INFO - mozversion application_remotingname: firefox-dev 09:05:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:05:58 INFO - mozversion application_vendor: Mozilla 09:05:58 INFO - mozversion application_version: 53.0a2 09:05:58 INFO - mozversion platform_buildid: 20170206072550 09:05:58 INFO - mozversion platform_changeset: 14147cb471a102f9ecc4a30cbb13efaf5c5607f1 09:05:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:05:58 INFO - mozversion platform_version: 53.0a2 09:05:58 INFO - using testdate: 1486400758 09:05:58 INFO - actual date: 1486400758 09:05:58 INFO - starting webserver on 'localhost:49240' 09:05:58 INFO - SUITE-START | Running 2 tests 09:05:58 INFO - TEST-START | dromaeo_css 09:05:58 INFO - operating with platform_type : mac_ 09:05:58 INFO - Initialising browser for dromaeo_css test... 09:05:58 INFO - TEST-INFO | started process 1629 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpWMFtsh/profile http://localhost:49240/getInfo.html) 09:06:00 INFO - PROCESS | 1629 | __metrics Screen width/height:1600/1200 09:06:00 INFO - PROCESS | 1629 | colorDepth:24 09:06:00 INFO - PROCESS | 1629 | Browser inner width/height: 1024/689 09:06:00 INFO - PROCESS | 1629 | __metrics 09:06:00 INFO - PROCESS | 1629 | 1486400760866 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:06:00 INFO - PROCESS | 1629 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:06:00 INFO - PROCESS | 1629 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:06:01 INFO - TEST-INFO | 1629: exit 0 09:06:01 INFO - Browser initialized. 09:06:01 INFO - Running cycle 1/1 for dromaeo_css test... 09:06:01 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpWMFtsh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:06:13 INFO - PROCESS | 1631 | RSS: Main: 213295104 09:06:13 INFO - PROCESS | 1631 | 09:09:16 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 09:09:18 INFO - PROCESS | 1631 | RSS: Main: 384937984 09:09:18 INFO - PROCESS | 1631 | 09:11:03 INFO - PROCESS | 1631 | ************************* 09:11:03 INFO - PROCESS | 1631 | A coding exception was thrown and uncaught in a Task. 09:11:03 INFO - PROCESS | 1631 | 09:11:03 ERROR - PROCESS | 1631 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:11:03 INFO - PROCESS | 1631 | Full stack: 09:11:03 INFO - PROCESS | 1631 | ************************* 09:12:21 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 09:12:22 INFO - PROCESS | 1631 | RSS: Main: 349478912 09:12:22 INFO - PROCESS | 1631 | 09:15:24 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 09:15:25 INFO - PROCESS | 1631 | RSS: Main: 321101824 09:15:25 INFO - PROCESS | 1631 | 09:18:27 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 09:18:28 INFO - PROCESS | 1631 | RSS: Main: 341286912 09:18:28 INFO - PROCESS | 1631 | 09:21:32 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 09:21:32 INFO - PROCESS | 1631 | RSS: Main: 309702656 09:21:32 INFO - PROCESS | 1631 | 09:24:35 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 09:24:36 INFO - PROCESS | 1631 | RSS: Main: 485257216 09:24:36 INFO - PROCESS | 1631 | 09:24:36 INFO - PROCESS | 1631 | __start_tp_report 09:24:36 INFO - PROCESS | 1631 | _x_x_mozilla_page_load 09:24:36 INFO - PROCESS | 1631 | _x_x_mozilla_page_load_details 09:24:36 INFO - PROCESS | 1631 | |i|pagename|runs| 09:24:36 INFO - PROCESS | 1631 | |0;dojo.html;3319.804870246711;3334.54983577217;3335.3329334133164;3335.4996750487426;3373.4939759036142;5640.8589785255335;5647.181158731982;5652.152177173426;5662.603617746754;5668.659880407178;5596.664200147984;5676.091825307952;5693.259876216086;5693.601447898649;5693.943060569393;4299.355096735483;5139.697004327792;5168.896622067557;5169.819056333029;5180.1452760294505;5094.6688465249645;5108.974455127719;5118.897622047557;5127.282180494733;5286.735663216843;4170.916581668364;5172;5224.79100835968;5232.084385232588;5241.475852414766;5225.608079394042;5234.136367499358;5253.290805741214;5253.44838791927;5289.153735402318;4980.252962055721;5008.923081537487;5146.459621739738;5148.073346797575;5167.534921857051;5518.061929471999;5577.079781835992;5622.353429355636;5635.5773317001385;5662.546996240291;6188.133661287423;6219.346759887322;6237.937620623781;6253.937460625381;6268.091126786636;6160;6217.782377616762;6217.813465596039;6218.440340369343;6229.563930524865;5681.147827825776;5711.657300561979;5811.447912448395;5826.679532625746;5866.384029676908;6299.937000630027;6300.905486417708;6304.747810087637;6305.306416294204;6309.400606942333;6195.2255968004;6204.348543402878;6206.844828879224;6219.533534984895;6220.0047992321915;6127.281156068254;6175.62946223219;6189.721462534197;6216.347283535254;6254.468370188585;6236.03341482071;6259.061140828821;6270.184875966095;6271.655058971709;6282.780102696383;6243.157173781572;6261.906071408933;6263.31103578606;6279.7488100476385;6287;4826.107170173529;4914.434839993446;4930.457649658535;4982.427020892644;4999.825006124768;4446.62203712688;4447.08834688888;4454.487733910577;4512.706674066175;4516.81932722694;5722.656640601577;5767.798127065616;5770.567207459458;5770.567207459458;5790.305163380422;6110.072772100023;6111.6525128775675;6157.322694503601;6234.251889773256;6248.4376406123665;5636.887262254814;5682.914756278659;5692.943070569241;5711.885762284731;5781.277340332458;5455;5464.23500709901;5469.945300547023;5478.424765399655;5491.313585801775;6323.462505687068;6336.651484168323;6342.809715708536;6347.904781428282;6350.428461438492;6115.514161902999;6115.970584430425;6213.689315534115;6231.688415579112;6282.528810339151;6324.6521441321165;6338.080978258219;6338.904916426165;6339.619622822645;6352.555321127662;6024.849378765566;6058.4244496772735;6073.14975903365;6258.468030217483;6299.622022678654;6115.057807494094;6161.815145545641;6205.503559715302;6340.873182536231;6353.777617783356;5004.799808007639;6122.916604401293;6137.232845894263;6143.051335359142;6167.352427994996;6111.6786684274075;6118.741008805849;6121.910283036266;6131.070842498352;6143.508519318533;5808.6805225711305;5813.244278243801;5935.614185077956;5962.314333851489;5977.282726072899;6052.092186172109;6070.969645151746;6115.266168059683;6120.7551697931585;6137.60105593135;5082.949170508247;5428;5933.08037254209;5943.019401798655;5971.731272092853 09:24:36 INFO - PROCESS | 1631 | |1;ext.html;489.8310083021356;496.43902390298956;498.820424647127;501.19057721779336;501.9133575808376;24647.260582182542;32502.67497325026;39153.80423097884;41036.58963410365;41073.38389924146;42812.571874281246;43122.13755724884;43211.56788432115;43244.78377608115;43298.134037319236;9139.177474027336;9304.441733495978;9327.440353578808;9351.298652601083;9353.95323023384;8406.327493800482;8590.398672092955;9264.166225039746;11083;11359.602413915514;11299.152563557725;11343.035841953444;11363;11368.147388945821;11379;11240.887591124107;11325.830112548318;11328.32030078198;11332.943335283271;11357;8322.16778322169;8541.871871921927;10985.340879547173;11259.943700281528;11261.605843795509;39317.01707457308;39336.803315983525;40137.39793903094;40396.99007524806;40765.592344076475;3945.60543945606;3980.9450495618685;4011.4183443400543;4019.919601607981;4089.89775255618;29124.563131552834;29423.85288073546;29455.70544294551;30954.0713778587;31498.842505787554;3237.077432931627;3240.821754803485;3275.967240327614;3349.7103615108117;3387.0854869185428;376429;378613;378691;379313.99999999726;379883;59073.70463147657;59088.99999999914;61210.69394652998;61670.38329616736;61683;64208.036879446845;64225;64312;64369;64483;15715.528534143994;16142.03147811135;16180;16181.757273640906;16208.75686864698;11774.88225117755;13101.606951791462;13199.67000824987;13206.27365494907;13218.537351192854;11483.712907177387;14237;15461.613459663373;15703.68592628141;15886;6238.251409830849;6780.099245219248;6829.351211634887;6958.617276049765;6997.615131167733;842.5913431985482;882.8101958079044;891.0866361978893;907.841127802632;925.7916968682127;29851.25371865677;29858.850705746332;30123.246918827204;30381.69530912498;30700.53949190764;1085.0831047764627;1086.239632257423;1087.0705546757408;1123.612353737959;1213.2356615332285;12420.378981050912;13026.934865325613;15297.541073767805;15507;15971.840281597035;629728;631681;633574;635313;635317;55593.444065558826;55954.88090238172;55996.44003559994;56059.43940560542;56089.59776005551;1439.6251579741322;1458.358322338168;1473.6684246044554;1476.5496523560203;1479.496971029855;24560.385990350384;27426.45147097007;29820.807167713916;30454.086377408712;30566.694333056384;50586;50780.746096269286;50869.49130508648;50894;50943;546411;546531;547420;550519;550834;25923.611145833205;38674.806625967816;39606.603933960294;39727.40408893926;39881.60118398895;40568.39147412732;46711.76644116758;47696;49001.26498102603;50820.98358032745;39077;39366;39448.40827387534;39837;41212.793936030124;38618.6138138615;39359;39496.605033949294;40945.38581921214;41043.38434923419;37379.626203737615;37840.81079594584;38427;38571;38751;33490;34407;35371.64628353684;35443.468347975315;35468.46797297991;25245.359051661584;25271;25293.74706252914;25812.483750324514;25858.09496667683 09:24:36 INFO - PROCESS | 1631 | |2;jquery.html;388.6871068789625;389.314805941543;398.4362127589461;398.844450664241;419.42119874573086;14100.929495352535;20037.899810500945;21049.47376315593;21289.148434062638;21337.786622133775;16767;18191.81808181918;21218.78781212191;21688.783112168872;21693.45766355842;5722.284714410699;6081.179040829497;6089.299730531001;6093.81718548442;6117.44942955134;5843.889660964424;6030.9396906030815;6076.848078798022;6105.847353816146;6158.168647232634;6006.549508786837;6042.848928776772;6044.667543285118;6051.2133422655;6054.697265136748;5776.711164441782;5937.317208521033;6013.007853704134;6020;6023.006203976339;5302.992431438014;5306.946930530684;5316.20256961455;5317.41508434072;5319.281896943901;26371.604425933627;26875.790589423523;27149.72850271492;27501.037463688874;27535.724642753517;3047.6342838859478;3091.7990330628436;3124.297033167519;3147.0244224290545;3149.9527507087414;14779.33492992818;14788.852111478855;14814.407423703147;14944.551663450115;15356.462523811613;1747;1997.951075685277;2058.9279375221945;2266.5693518231483;2279.943001424978;161961;162209;166957.3304266966;170471;177796;23519;24150.758492415203;24189.51620967606;24253.02987880465;24478.632820507708;42865.142697145886;43431;44217;44279.89300267519;44403.333949990774;6342.904856427157;8348.540830254395;8353.081161072278;8426.283765879969;8448.281896038783;7970.681172753025;8524.019737730241;8778.956105219433;8792.252658524098;8935.463872167691;4323.697341186087;4810.2063159578975;4813.927791083137;4837.685550439211;4899.657024008286;1675.2712570032074;1731.8268173182832;1760.3310741918244;2014.733424896991;2045.2534824788845;996.7557948302567;1046.2048842879315;1046.2467023742893;1052;1069.994650026745;20725.58548829045;21287.46781330448;21340;21639.56720865574;21709;1043.5147656339839;1057.2440704895882;1073.3935326540636;1077.2459278505078;1080.0765345629375;10553.102986246102;11287.887121128848;11375.886241137649;11407.543698252142;11431.428428578538;750975;753208;757266;766462;778539;157590.21204893902;159381;159451.405485946;160277;160846;1034.4414016431194;1043.634727845248;1065.541817018674;1066.919981001428;1070.4380200394855;18454.446366609023;18548.44354669362;18631;18707.607972432128;18813.153408096943;60528.69735651294;60825;60891.69554152201;61487.692561536904;61868.69065654643;87764.56117719371;90276.5486172565;90439.54780226057;91741;92295;6198.6280823150755;6697.698603562949;7474.663640136316;7643.541387516544;7963.362930965393;10088;10797.946010270212;13221.669458263621;13801.378937947615;13914.51299204523;11326.263792853439;11709.941450292694;11766.764664706829;11820.468078936641;11975.880241197476;11262.873712628672;11515.654530364103;11579.13156513248;11940.402979850798;12003.979661728501;9843.557039933077;11623.941880290544;11631.243969141979;11753.177277590486;11854.288742675468;7655.57894315818;7747.30274275318;7907.169747176464;7926.167752386147;8040.316573091352;10280.691579252634;11659.941700291443;11674.649760507198;11676.124290678403;12060.457279422622 09:24:36 INFO - PROCESS | 1631 | |3;mootools.html;1299.6036208956268;1437.9784303235447;1455.8980871339004;1461.3058797071385;1464.3044553836928;8102.837943241132;8533.573321333932;8544.530050847201;8546.102659220778;8564.229219370256;7483.176850546435;7728.96135519323;8004.119546849842;8292.460990035643;8345.374096942724;2310.6880571122933;2312.2485192312524;2354.5761762882676;2355.5524450354465;2359.4101474631343;2335.964960525585;2375.8812059397046;2377.976220237793;2382.463945612241;2383.976160238393;2036.3381900882198;2116;2260.547890421922;2315.907363705458;2343.355577216262;2325.3140323604575;2327.476317828492;2329.7204335479682;2336.6845475860882;2340.099061861196;2288.21056735426;2338.275134708246;2363.988180059089;2365.5387199496104;2369.2300002499255;8462.534560599164;8462.576871156418;8478.618462169217;8480.575971201477;8480.982282126122;5032.547070763612;5188.377394712658;5195.272661827347;5202.323697919284;5203.271541984125;3219.275662975835;3389.1359752136473;3414.5048967899766;3513.2622149348667;3618.6019537850816;1057.740853490892;1175.2067354535739;1211.8485189351331;1216.8904798568171;1260.7604555134494;10734.785304293948;10826.079783218436;10875.510602022929;10883.619073332475;10894.18293627981;4505.07645932583;4646.697964632288;4693.83571575;4730.692504987165;4747.311639812277;9371;9551.90448095524;9554.235661147091;9558.713238602853;9562;5260.605454590921;5267.341582302212;5269.288646032813;5269.57843372537;5277.445868183862;4264.978675106605;4397.252467080604;4596.126735920165;4713.505081966413;4715.104130215248;3798.7910664913143;3838.616138386195;3907.159960608483;3915.2169566086895;3941.1723538056754;1257.8930790882878;1264.6206138158516;1274.6048724895313;1276.259769333784;1292.3215311961296;780.5082797837326;797.8204903896577;910.4309806371018;926.4719110107309;926.5321012888448;5415.458454154553;5557.3331200256225;5654.321481422174;5735.139729040678;5928.792492262836;950.0641867760389;992.5136683025265;993.836017057192;999.5651891427252;1028.5320179318392;3608.0979755061235;3673.9816300918324;3704.4616252314686;3832.6550610445192;3854.5759966403675;9746.61013559464;9828.950855245677;10044.799104017879;10086.344387614781;10096.44469554167;9731;9974;9974.900250997542;9984.101430871257;9988.350757200758;7800.219978002155;7873.448858579959;7960.880586791202;7961.761147165595;7981.600919953979;4728.361671174347;5261.289725887109;5421.2681288025615;5434.266374039454;5600.019996500678;7602.46782725204;8419.494830310201;8925.73222803317;9240.445573265626;9348.20540254086;9091.272698184262;9113.498757568132;9230.907690923274;9318.440893546409;9513.381630194015;6626.83432914181;6854.8286292841085;6858.3141685831015;7215.530990485601;7415.4809163360205;7642.923570764221;7949.125596184531;7954.761357159295;8095.311898488694;8111.432199745961;7014.0531028310525;7125.3230943060325;7240.96379518099;7314.305141011595;7379.704811807683;6977.197622273504;7074.151101867603;7090.4682148840075;7178.174509931425;7201.207867134506;6324.841878952879;6329.2404911409085;6388.201474815648;6408.551401401857;6411.583247089111;4955.4301255356095;4969.155243608523;4972.7762250699525;4987.700737955734;5016.498350164954;4891.51084891508;4994.675346102492;5004.199328107486;5103.209002604603;5192.3769147702515 09:24:36 INFO - PROCESS | 1631 | |4;prototype.html;348.84127721886546;350.5057868405548;360.68980676618105;371.54856848928546;372.8527231743461;4672.579467847889;5132.743362831858;5288.8413347599635;5331.360236771588;5344.599155063366;4548.454185497745;4888.680056384774;5197.1424714922;5213.895722085556;5245.44922783108;3176.2694580246557;3812.1803812180374;3859.5561510426237;4037.555868854424;4079.469668943048;3688.6680198782105;4006.659433948118;4011.3982902564703;4026.8590599329027;4092.4679791627195;3315.353506066318;3765.2469506098755;3975.2447035063246;3986.681065514752;3994.2810294147043;3618.5115009473648;3903.4925459690344;3982.3230050891416;4001.2997725398086;4003.73975691582;3288.753343499247;3416.4021296273054;3423.3495635829113;3433.9313213735586;3455.6198818129988;4343.1747967886;4625.745583992879;4937.6049916007;4951.727654978975;5181.160687955759;4346.3266642342605;4531.184386810372;4545.522720114373;4568.291914753218;4603.654725895572;5170.663906846081;5312.73436328182;5508.717810489298;5580.748866301026;5591.993441180586;3775.4714339992424;3791.3554695701796;3801.327415936979;3816.9809150954347;3869.613038696136;4867.780949857265;4984.928240428289;5567.916481252784;5650.152477128422;5904.789518148769;3715.275521273326;3760.7367484275837;3821.197548514843;3850.3828392075397;3873.69010479161;3421.665550435332;3839.500864887547;3902.707296952683;3911.7457365271166;3924.5682974872743;3788.7726736395903;4096.365063415175;4550.613197878151;4592.288195329729;4599.05719327537;4895.6083513318845;4986.925196122061;5018.996200759862;5178.689278643294;5410.458954104558;1259.557806311768;1260.7959398774149;1263.5451237554657;1264.6016504800957;1272.4210484229654;786.7176502301212;791.7505985614513;806.3226889412921;824.9317134311082;861.7845538615346;4482.932756008662;4942.838432968249;4944.802207911715;4950.8762280943265;4974.477679843564;5097.235414687752;5366.382865970463;5424.674519528841;5520.889582208333;5579.2746942897165;4147.465437787978;4579.542045795461;5048.724083332926;5073.619478539124;5091.465396133427;2327.5336537980966;2559.8720063996725;2841.5169421198448;2853.885844566194;2871.3395918938763;5111.7699703513435;5367.886625757052;5425.83722488326;5673.085570956027;5683.907586537686;4717.575418212377;4896.95103048972;5385.623006389594;5573.6098473106495;5640.997445906726;5024.422191447957;5454.699991500426;5461.252399232208;5655.575831812631;5817.912731308949;4569.794359253908;5062.924056139087;5093.032323858455;5349.732513374393;5579.832605021856;4568;4911;5327.706976116351;5479.945200548103;5489.533389661923;4902.8774280643265;5233.528982391603;5528.640638358494;5602.719864006865;5604.635698679573;3520.8239588019983;3634.5093412388987;3637.981365217743;3647;3653.013686304736;5420.83737487876;5433.402325744244;5442.591805614516;5456.61803673739;5462.590305727007;4440.289553671397;4888.780004899717;4897.3878265216845;5204.449819588299;5307;4901.946081592475;5118.930450291952;5194.259922925711;5212.843614691566;5237.338768496118;4662.790174442226;4727.628987593559;5119.436861945109;5124.8206312778875;5168.71572063525;4650.906981860411;4742.691725037862;4971.130052240916;5232.011835502484;5374.543163831118;4716.528347165256;5005.899882002413;5211.368259029166;5258;5377.8117765878005 09:24:36 INFO - PROCESS | 1631 | |5;yui.html;706.3678008182674;720.783764870539;756.2475337039647;761.0676920772052;844.8817165596818;15008.624784380396;15271.38914443421;15799.605009874744;15819.525414237563;15965.361385544578;13543.525976590796;13633.113847599923;13874.791878121836;13874.930625346884;13891.722165556683;853.0531110467376;861.014138811061;863.4387648028769;866.8786369908217;866.9393142480029;678.5724993254267;699.867025265198;846.6190214403513;861.0743450790394;862.5126803356115;920.7099763574486;928.8978212396631;930.2511478259999;933.7945651956559;934.3739694404732;905.9003509613971;922.446532080753;924.0713083351189;925.6251218256617;927.4388994658236;529.875479262373;546.6064433607796;663.7709990053423;691.636890632422;692.2731132311103;14836.480723174636;15311.464098756602;15315.463958761502;15336.463223787225;15355;13944.721105577933;13995.930020349935;14009.789853152211;14034.649133771636;14064.578062658136;12514.374281285991;12543.435545400478;12778.872211277861;12803.423845926956;13085.934570327183;3622.786366567203;4180.979095104505;4184.497860256758;4214.936775948363;4216.198922204771;110289;115975;116133;116860.41569792181;117046;10625.9468702656;10917.290376125526;10966.122710183166;10990.945045274882;11476.311421314747;18823;21762.673559896615;21791.891040544695;21813.23653672114;22149.446263843533;7424.368928641018;7703.114141873644;7758.185390534026;7895.960520197362;7962.6416811243635;4310.612044915973;4323.459567554056;4369.890752731207;4371.912561748747;4524.276115821519;3406.7785593936314;3477.791332520006;3479.1302174456387;3517.944616615005;3572.678458938708;655.3381085103983;657.3262406033854;661.4047357378302;663.598522893652;671.3085521912438;639.7568923808923;643.1542521584101;653.411929263657;663.3167837127767;675.8411773233287;9107.772305692411;9632.325737198329;9682.080202380763;9907.207423406111;10121.797564048826;624.497279689854;648.3905129178557;658.3219284137347;658.9176352955881;679.3987321220756;4168.478940132483;4560.4071470708595;4885.853424397274;4919.631027672939;5554.250176226238;86356.7046494303;99966.50016750015;100236.49881750545;105856;106586.46706766571;91791;91925;93670.53164734133;96975.51512242535;97057;66932.665336673;75991.62004190055;82953.58523207346;84471.5776421114;85074;7926;10824.512896919816;10881.238313317992;10911.890881091087;11122.944385278022;45230.77384613187;45911.54088459073;47934.04131917272;48518.78703032311;49640.75179624079;67032;67103.66448167728;69263.65368173127;69969.95045074429;71047;12582.685432864251;12806.61580152597;13145.21128732279;13285.269310188032;13452.058355914993;14532.564023079325;14551.34518946671;15257.313420895864;15606.609834754221;15806.9209653951;13625.523106691218;13874.861251387762;13952.9535284852;14177.574672759834;14463.638409039859;13381.732365352833;13451.125676830974;13521;13565.186088834702;13664.360466568583;13168.275744834125;13244.735105298032;13485.730285394433;13761.17432953986;14731.26343682833;10935.617253396093;11153.665390038312;11296.322220666458;11529;11790.764184716429;9443.338966272295;9887;9904.207663387057;11293;11333 09:24:36 INFO - PROCESS | 1631 | __end_tp_report 09:24:36 INFO - PROCESS | 1631 | __start_cc_report 09:24:36 INFO - PROCESS | 1631 | _x_x_mozilla_cycle_collect,1917 09:24:36 INFO - PROCESS | 1631 | __end_cc_report 09:24:36 INFO - PROCESS | 1631 | __startTimestamp1486401876239__endTimestamp 09:24:36 INFO - PROCESS | 1631 | 09:24:36 INFO - PROCESS | 1631 | ------- Summary: start ------- 09:24:36 INFO - PROCESS | 1631 | Number of tests: 6 09:24:36 INFO - PROCESS | 1631 | 09:24:36 INFO - PROCESS | 1631 | [#0] dojo.html Cycles:165 Average:5717.91 Median:5943.02 stddev:665.53 (11.2%) stddev-sans-first:640.43 09:24:36 INFO - PROCESS | 1631 | Values: 3319.8 3334.5 3335.3 3335.5 3373.5 5640.9 5647.2 5652.2 5662.6 5668.7 5596.7 5676.1 5693.3 5693.6 5693.9 4299.4 5139.7 5168.9 5169.8 5180.1 5094.7 5109.0 5118.9 5127.3 5286.7 4170.9 5172.0 5224.8 5232.1 5241.5 5225.6 5234.1 5253.3 5253.4 5289.2 4980.3 5008.9 5146.5 5148.1 5167.5 5518.1 5577.1 5622.4 5635.6 5662.5 6188.1 6219.3 6237.9 6253.9 6268.1 6160.0 6217.8 6217.8 6218.4 6229.6 5681.1 5711.7 5811.4 5826.7 5866.4 6299.9 6300.9 6304.7 6305.3 6309.4 6195.2 6204.3 6206.8 6219.5 6220.0 6127.3 6175.6 6189.7 6216.3 6254.5 6236.0 6259.1 6270.2 6271.7 6282.8 6243.2 6261.9 6263.3 6279.7 6287.0 4826.1 4914.4 4930.5 4982.4 4999.8 4446.6 4447.1 4454.5 4512.7 4516.8 5722.7 5767.8 5770.6 5770.6 5790.3 6110.1 6111.7 6157.3 6234.3 6248.4 5636.9 5682.9 5692.9 5711.9 5781.3 5455.0 5464.2 5469.9 5478.4 5491.3 6323.5 6336.7 6342.8 6347.9 6350.4 6115.5 6116.0 6213.7 6231.7 6282.5 6324.7 6338.1 6338.9 6339.6 6352.6 6024.8 6058.4 6073.1 6258.5 6299.6 6115.1 6161.8 6205.5 6340.9 6353.8 5004.8 6122.9 6137.2 6143.1 6167.4 6111.7 6118.7 6121.9 6131.1 6143.5 5808.7 5813.2 5935.6 5962.3 5977.3 6052.1 6071.0 6115.3 6120.8 6137.6 5082.9 5428.0 5933.1 5943.0 5971.7 09:24:36 INFO - PROCESS | 1631 | 09:24:36 INFO - PROCESS | 1631 | [#1] ext.html Cycles:180 Average:66362.61 Median:39115.40 stddev:141699.05 (362.3%) stddev-sans-first:142010.24 09:24:36 INFO - PROCESS | 1631 | Values: 489.8 496.4 498.8 501.2 501.9 24647.3 32502.7 39153.8 41036.6 41073.4 42812.6 43122.1 43211.6 43244.8 43298.1 9139.2 9304.4 9327.4 9351.3 9354.0 8406.3 8590.4 9264.2 11083.0 11359.6 11299.2 11343.0 11363.0 11368.1 11379.0 11240.9 11325.8 11328.3 11332.9 11357.0 8322.2 8541.9 10985.3 11259.9 11261.6 39317.0 39336.8 40137.4 40397.0 40765.6 3945.6 3980.9 4011.4 4019.9 4089.9 29124.6 29423.9 29455.7 30954.1 31498.8 3237.1 3240.8 3276.0 3349.7 3387.1 376429.0 378613.0 378691.0 379314.0 379883.0 59073.7 59089.0 61210.7 61670.4 61683.0 64208.0 64225.0 64312.0 64369.0 64483.0 15715.5 16142.0 16180.0 16181.8 16208.8 11774.9 13101.6 13199.7 13206.3 13218.5 11483.7 14237.0 15461.6 15703.7 15886.0 6238.3 6780.1 6829.4 6958.6 6997.6 842.6 882.8 891.1 907.8 925.8 29851.3 29858.9 30123.2 30381.7 30700.5 1085.1 1086.2 1087.1 1123.6 1213.2 12420.4 13026.9 15297.5 15507.0 15971.8 629728.0 631681.0 633574.0 635313.0 635317.0 55593.4 55954.9 55996.4 56059.4 56089.6 1439.6 1458.4 1473.7 1476.5 1479.5 24560.4 27426.5 29820.8 30454.1 30566.7 50586.0 50780.7 50869.5 50894.0 50943.0 546411.0 546531.0 547420.0 550519.0 550834.0 25923.6 38674.8 39606.6 39727.4 39881.6 40568.4 46711.8 47696.0 49001.3 50821.0 39077.0 39366.0 39448.4 39837.0 41212.8 38618.6 39359.0 39496.6 40945.4 41043.4 37379.6 37840.8 38427.0 38571.0 38751.0 33490.0 34407.0 35371.6 35443.5 35468.5 25245.4 25271.0 25293.7 25812.5 25858.1 09:24:36 INFO - PROCESS | 1631 | 09:24:36 INFO - PROCESS | 1631 | [#2] jquery.html Cycles:180 Average:43773.07 Median:24221.27 stddev:127570.44 (526.7%) stddev-sans-first:127886.71 09:24:36 INFO - PROCESS | 1631 | Values: 388.7 389.3 398.4 398.8 419.4 14100.9 20037.9 21049.5 21289.1 21337.8 16767.0 18191.8 21218.8 21688.8 21693.5 5722.3 6081.2 6089.3 6093.8 6117.4 5843.9 6030.9 6076.8 6105.8 6158.2 6006.5 6042.8 6044.7 6051.2 6054.7 5776.7 5937.3 6013.0 6020.0 6023.0 5303.0 5306.9 5316.2 5317.4 5319.3 26371.6 26875.8 27149.7 27501.0 27535.7 3047.6 3091.8 3124.3 3147.0 3150.0 14779.3 14788.9 14814.4 14944.6 15356.5 1747.0 1998.0 2058.9 2266.6 2279.9 161961.0 162209.0 166957.3 170471.0 177796.0 23519.0 24150.8 24189.5 24253.0 24478.6 42865.1 43431.0 44217.0 44279.9 44403.3 6342.9 8348.5 8353.1 8426.3 8448.3 7970.7 8524.0 8779.0 8792.3 8935.5 4323.7 4810.2 4813.9 4837.7 4899.7 1675.3 1731.8 1760.3 2014.7 2045.3 996.8 1046.2 1046.2 1052.0 1070.0 20725.6 21287.5 21340.0 21639.6 21709.0 1043.5 1057.2 1073.4 1077.2 1080.1 10553.1 11287.9 11375.9 11407.5 11431.4 750975.0 753208.0 757266.0 766462.0 778539.0 157590.2 159381.0 159451.4 160277.0 160846.0 1034.4 1043.6 1065.5 1066.9 1070.4 18454.4 18548.4 18631.0 18707.6 18813.2 60528.7 60825.0 60891.7 61487.7 61868.7 87764.6 90276.5 90439.5 91741.0 92295.0 6198.6 6697.7 7474.7 7643.5 7963.4 10088.0 10797.9 13221.7 13801.4 13914.5 11326.3 11709.9 11766.8 11820.5 11975.9 11262.9 11515.7 11579.1 11940.4 12004.0 9843.6 11623.9 11631.2 11753.2 11854.3 7655.6 7747.3 7907.2 7926.2 8040.3 10280.7 11659.9 11674.6 11676.1 12060.5 09:24:36 INFO - PROCESS | 1631 | 09:24:36 INFO - PROCESS | 1631 | [#3] mootools.html Cycles:180 Average:5434.95 Median:5260.95 stddev:2948.76 (56.0%) stddev-sans-first:2940.65 09:24:36 INFO - PROCESS | 1631 | Values: 1299.6 1438.0 1455.9 1461.3 1464.3 8102.8 8533.6 8544.5 8546.1 8564.2 7483.2 7729.0 8004.1 8292.5 8345.4 2310.7 2312.2 2354.6 2355.6 2359.4 2336.0 2375.9 2378.0 2382.5 2384.0 2036.3 2116.0 2260.5 2315.9 2343.4 2325.3 2327.5 2329.7 2336.7 2340.1 2288.2 2338.3 2364.0 2365.5 2369.2 8462.5 8462.6 8478.6 8480.6 8481.0 5032.5 5188.4 5195.3 5202.3 5203.3 3219.3 3389.1 3414.5 3513.3 3618.6 1057.7 1175.2 1211.8 1216.9 1260.8 10734.8 10826.1 10875.5 10883.6 10894.2 4505.1 4646.7 4693.8 4730.7 4747.3 9371.0 9551.9 9554.2 9558.7 9562.0 5260.6 5267.3 5269.3 5269.6 5277.4 4265.0 4397.3 4596.1 4713.5 4715.1 3798.8 3838.6 3907.2 3915.2 3941.2 1257.9 1264.6 1274.6 1276.3 1292.3 780.5 797.8 910.4 926.5 926.5 5415.5 5557.3 5654.3 5735.1 5928.8 950.1 992.5 993.8 999.6 1028.5 3608.1 3674.0 3704.5 3832.7 3854.6 9746.6 9829.0 10044.8 10086.3 10096.4 9731.0 9974.0 9974.9 9984.1 9988.4 7800.2 7873.4 7960.9 7961.8 7981.6 4728.4 5261.3 5421.3 5434.3 5600.0 7602.5 8419.5 8925.7 9240.4 9348.2 9091.3 9113.5 9230.9 9318.4 9513.4 6626.8 6854.8 6858.3 7215.5 7415.5 7642.9 7949.1 7954.8 8095.3 8111.4 7014.1 7125.3 7241.0 7314.3 7379.7 6977.2 7074.2 7090.5 7178.2 7201.2 6324.8 6329.2 6388.2 6408.6 6411.6 4955.4 4969.2 4972.8 4987.7 5016.5 4891.5 4994.7 5004.2 5103.2 5192.4 09:24:36 INFO - PROCESS | 1631 | 09:24:36 INFO - PROCESS | 1631 | [#4] prototype.html Cycles:180 Average:4340.64 Median:4897.17 stddev:1302.47 (26.6%) stddev-sans-first:1271.20 09:24:36 INFO - PROCESS | 1631 | Values: 348.8 350.5 360.7 371.5 372.9 4672.6 5132.7 5288.8 5331.4 5344.6 4548.5 4888.7 5197.1 5213.9 5245.4 3176.3 3812.2 3859.6 4037.6 4079.5 3688.7 4006.7 4011.4 4026.9 4092.5 3315.4 3765.2 3975.2 3986.7 3994.3 3618.5 3903.5 3982.3 4001.3 4003.7 3288.8 3416.4 3423.3 3433.9 3455.6 4343.2 4625.7 4937.6 4951.7 5181.2 4346.3 4531.2 4545.5 4568.3 4603.7 5170.7 5312.7 5508.7 5580.7 5592.0 3775.5 3791.4 3801.3 3817.0 3869.6 4867.8 4984.9 5567.9 5650.2 5904.8 3715.3 3760.7 3821.2 3850.4 3873.7 3421.7 3839.5 3902.7 3911.7 3924.6 3788.8 4096.4 4550.6 4592.3 4599.1 4895.6 4986.9 5019.0 5178.7 5410.5 1259.6 1260.8 1263.5 1264.6 1272.4 786.7 791.8 806.3 824.9 861.8 4482.9 4942.8 4944.8 4950.9 4974.5 5097.2 5366.4 5424.7 5520.9 5579.3 4147.5 4579.5 5048.7 5073.6 5091.5 2327.5 2559.9 2841.5 2853.9 2871.3 5111.8 5367.9 5425.8 5673.1 5683.9 4717.6 4897.0 5385.6 5573.6 5641.0 5024.4 5454.7 5461.3 5655.6 5817.9 4569.8 5062.9 5093.0 5349.7 5579.8 4568.0 4911.0 5327.7 5479.9 5489.5 4902.9 5233.5 5528.6 5602.7 5604.6 3520.8 3634.5 3638.0 3647.0 3653.0 5420.8 5433.4 5442.6 5456.6 5462.6 4440.3 4888.8 4897.4 5204.4 5307.0 4901.9 5118.9 5194.3 5212.8 5237.3 4662.8 4727.6 5119.4 5124.8 5168.7 4650.9 4742.7 4971.1 5232.0 5374.5 4716.5 5005.9 5211.4 5258.0 5377.8 09:24:36 INFO - PROCESS | 1631 | 09:24:36 INFO - PROCESS | 1631 | [#5] yui.html Cycles:180 Average:20857.76 Median:4263.41 stddev:30352.44 (711.9%) stddev-sans-first:30399.87 09:24:36 INFO - PROCESS | 1631 | Values: 706.4 720.8 756.2 761.1 844.9 15008.6 15271.4 15799.6 15819.5 15965.4 13543.5 13633.1 13874.8 13874.9 13891.7 853.1 861.0 863.4 866.9 866.9 678.6 699.9 846.6 861.1 862.5 920.7 928.9 930.3 933.8 934.4 905.9 922.4 924.1 925.6 927.4 529.9 546.6 663.8 691.6 692.3 14836.5 15311.5 15315.5 15336.5 15355.0 13944.7 13995.9 14009.8 14034.6 14064.6 12514.4 12543.4 12778.9 12803.4 13085.9 3622.8 4181.0 4184.5 4214.9 4216.2 110289.0 115975.0 116133.0 116860.4 117046.0 10625.9 10917.3 10966.1 10990.9 11476.3 18823.0 21762.7 21791.9 21813.2 22149.4 7424.4 7703.1 7758.2 7896.0 7962.6 4310.6 4323.5 4369.9 4371.9 4524.3 3406.8 3477.8 3479.1 3517.9 3572.7 655.3 657.3 661.4 663.6 671.3 639.8 643.2 653.4 663.3 675.8 9107.8 9632.3 9682.1 9907.2 10121.8 624.5 648.4 658.3 658.9 679.4 4168.5 4560.4 4885.9 4919.6 5554.3 86356.7 99966.5 100236.5 105856.0 106586.5 91791.0 91925.0 93670.5 96975.5 97057.0 66932.7 75991.6 82953.6 84471.6 85074.0 7926.0 10824.5 10881.2 10911.9 11122.9 45230.8 45911.5 47934.0 48518.8 49640.8 67032.0 67103.7 69263.7 69970.0 71047.0 12582.7 12806.6 13145.2 13285.3 13452.1 14532.6 14551.3 15257.3 15606.6 15806.9 13625.5 13874.9 13953.0 14177.6 14463.6 13381.7 13451.1 13521.0 13565.2 13664.4 13168.3 13244.7 13485.7 13761.2 14731.3 10935.6 11153.7 11296.3 11529.0 11790.8 9443.3 9887.0 9904.2 11293.0 11333.0 09:24:36 INFO - PROCESS | 1631 | -------- Summary: end -------- 09:24:36 INFO - PROCESS | 1631 | 09:24:36 INFO - TEST-INFO | 1631: exit 0 09:24:36 INFO - TEST-OK | dromaeo_css | took 1118549ms 09:24:36 INFO - TEST-START | kraken 09:24:36 INFO - operating with platform_type : mac_ 09:24:36 INFO - Initialising browser for kraken test... 09:24:36 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpFVQptm/profile http://localhost:49240/getInfo.html) 09:24:39 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 09:24:39 INFO - PROCESS | 1640 | colorDepth:24 09:24:39 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 09:24:39 INFO - PROCESS | 1640 | __metrics 09:24:39 INFO - PROCESS | 1640 | 1486401879331 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:24:39 INFO - PROCESS | 1640 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:24:39 INFO - PROCESS | 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:24:40 INFO - TEST-INFO | 1640: exit 0 09:24:40 INFO - Browser initialized. 09:24:40 INFO - Running cycle 1/1 for kraken test... 09:24:40 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpFVQptm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:24:52 INFO - PROCESS | 1641 | RSS: Main: 212893696 09:24:52 INFO - PROCESS | 1641 | 09:24:54 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 09:24:55 INFO - PROCESS | 1641 | RSS: Main: 266108928 09:24:55 INFO - PROCESS | 1641 | 09:24:58 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 09:24:59 INFO - PROCESS | 1641 | RSS: Main: 276230144 09:24:59 INFO - PROCESS | 1641 | 09:25:02 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 09:25:02 INFO - PROCESS | 1641 | RSS: Main: 371392512 09:25:02 INFO - PROCESS | 1641 | 09:25:04 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 09:25:05 INFO - PROCESS | 1641 | RSS: Main: 276033536 09:25:05 INFO - PROCESS | 1641 | 09:25:07 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:25:08 INFO - PROCESS | 1641 | RSS: Main: 277327872 09:25:08 INFO - PROCESS | 1641 | 09:25:10 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 09:25:11 INFO - PROCESS | 1641 | RSS: Main: 316375040 09:25:11 INFO - PROCESS | 1641 | 09:25:14 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 09:25:15 INFO - PROCESS | 1641 | RSS: Main: 318541824 09:25:15 INFO - PROCESS | 1641 | 09:25:17 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 09:25:18 INFO - PROCESS | 1641 | RSS: Main: 312541184 09:25:18 INFO - PROCESS | 1641 | 09:25:19 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:25:20 INFO - PROCESS | 1641 | RSS: Main: 293625856 09:25:20 INFO - PROCESS | 1641 | 09:25:22 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:25:23 INFO - PROCESS | 1641 | RSS: Main: 293048320 09:25:23 INFO - PROCESS | 1641 | 09:25:25 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:25:26 INFO - PROCESS | 1641 | RSS: Main: 294993920 09:25:26 INFO - PROCESS | 1641 | 09:25:28 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:25:29 INFO - PROCESS | 1641 | RSS: Main: 315625472 09:25:29 INFO - PROCESS | 1641 | 09:25:32 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:25:33 INFO - PROCESS | 1641 | RSS: Main: 309223424 09:25:33 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 09:25:35 INFO - PROCESS | 1641 | RSS: Main: 353501184 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | __start_tp_report 09:25:35 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 09:25:35 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 09:25:35 INFO - PROCESS | 1641 | |i|pagename|runs| 09:25:35 INFO - PROCESS | 1641 | |0;ai-astar;115;114;116;115;115;116;116;115;113;116 09:25:35 INFO - PROCESS | 1641 | |1;audio-beat-detection;132;130;138;173;109;107;110;109;128;106 09:25:35 INFO - PROCESS | 1641 | |2;audio-dft;184;184;183;182;184;184;185;184;184;184 09:25:35 INFO - PROCESS | 1641 | |3;audio-fft;82;61;58;91;60;59;60;58;74;59 09:25:35 INFO - PROCESS | 1641 | |4;audio-oscillator;85;72;111;101;98;56;93;88;61;88 09:25:35 INFO - PROCESS | 1641 | |5;imaging-gaussian-blur;90;87;90;86;90;86;90;88;89;85 09:25:35 INFO - PROCESS | 1641 | |6;imaging-darkroom;94;94;95;95;92;94;95;95;94;94 09:25:35 INFO - PROCESS | 1641 | |7;imaging-desaturate;86;88;86;87;85;85;85;85;86;84 09:25:35 INFO - PROCESS | 1641 | |8;json-parse-financial;56;53;53;59;58;57;58;57;57;57 09:25:35 INFO - PROCESS | 1641 | |9;json-stringify-tinderbox;64;72;70;76;73;69;68;68;70;70 09:25:35 INFO - PROCESS | 1641 | |10;stanford-crypto-aes;84;84;90;73;91;108;100;102;95;84 09:25:35 INFO - PROCESS | 1641 | |11;stanford-crypto-ccm;145;138;138;139;143;140;144;140;190;150 09:25:35 INFO - PROCESS | 1641 | |12;stanford-crypto-pbkdf2;164;220;235;168;168;173;167;164;166;167 09:25:35 INFO - PROCESS | 1641 | |13;stanford-crypto-sha256-iterative;64;65;66;66;68;81;82;84;81;65 09:25:35 INFO - PROCESS | 1641 | __end_tp_report 09:25:35 INFO - PROCESS | 1641 | __start_cc_report 09:25:35 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,2024 09:25:35 INFO - PROCESS | 1641 | __end_cc_report 09:25:35 INFO - PROCESS | 1641 | __startTimestamp1486401935362__endTimestamp 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | ------- Summary: start ------- 09:25:35 INFO - PROCESS | 1641 | Number of tests: 14 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | [#0] ai-astar Cycles:10 Average:115.10 Median:115.50 stddev:0.99 (0.9%) stddev-sans-first:1.05 09:25:35 INFO - PROCESS | 1641 | Values: 115.0 114.0 116.0 115.0 115.0 116.0 116.0 115.0 113.0 116.0 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | [#1] audio-beat-detection Cycles:10 Average:124.20 Median:129.00 stddev:21.01 (16.3%) stddev-sans-first:22.09 09:25:35 INFO - PROCESS | 1641 | Values: 132.0 130.0 138.0 173.0 109.0 107.0 110.0 109.0 128.0 106.0 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:0.79 (0.4%) stddev-sans-first:0.83 09:25:35 INFO - PROCESS | 1641 | Values: 184.0 184.0 183.0 182.0 184.0 184.0 185.0 184.0 184.0 184.0 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | [#3] audio-fft Cycles:10 Average:66.20 Median:60.50 stddev:11.87 (19.6%) stddev-sans-first:11.13 09:25:35 INFO - PROCESS | 1641 | Values: 82.0 61.0 58.0 91.0 60.0 59.0 60.0 58.0 74.0 59.0 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | [#4] audio-oscillator Cycles:10 Average:85.30 Median:86.50 stddev:17.54 (20.3%) stddev-sans-first:18.60 09:25:35 INFO - PROCESS | 1641 | Values: 85.0 72.0 111.0 101.0 98.0 56.0 93.0 88.0 61.0 88.0 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:89.50 stddev:1.97 (2.2%) stddev-sans-first:1.96 09:25:35 INFO - PROCESS | 1641 | Values: 90.0 87.0 90.0 86.0 90.0 86.0 90.0 88.0 89.0 85.0 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.50 stddev:0.92 (1.0%) stddev-sans-first:0.97 09:25:35 INFO - PROCESS | 1641 | Values: 94.0 94.0 95.0 95.0 92.0 94.0 95.0 95.0 94.0 94.0 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:86.00 stddev:1.16 (1.3%) stddev-sans-first:1.22 09:25:35 INFO - PROCESS | 1641 | Values: 86.0 88.0 86.0 87.0 85.0 85.0 85.0 85.0 86.0 84.0 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:2.01 (3.5%) stddev-sans-first:2.13 09:25:35 INFO - PROCESS | 1641 | Values: 56.0 53.0 53.0 59.0 58.0 57.0 58.0 57.0 57.0 57.0 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.00 stddev:3.23 (4.6%) stddev-sans-first:2.60 09:25:35 INFO - PROCESS | 1641 | Values: 64.0 72.0 70.0 76.0 73.0 69.0 68.0 68.0 70.0 70.0 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:84.00 stddev:10.43 (12.4%) stddev-sans-first:10.74 09:25:35 INFO - PROCESS | 1641 | Values: 84.0 84.0 90.0 73.0 91.0 108.0 100.0 102.0 95.0 84.0 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:143.50 stddev:15.67 (10.9%) stddev-sans-first:16.61 09:25:35 INFO - PROCESS | 1641 | Values: 145.0 138.0 138.0 139.0 143.0 140.0 144.0 140.0 190.0 150.0 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.20 Median:168.00 stddev:25.82 (15.4%) stddev-sans-first:26.80 09:25:35 INFO - PROCESS | 1641 | Values: 164.0 220.0 235.0 168.0 168.0 173.0 167.0 164.0 166.0 167.0 09:25:35 INFO - PROCESS | 1641 | 09:25:35 INFO - PROCESS | 1641 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:74.50 stddev:8.53 (11.5%) stddev-sans-first:8.52 09:25:35 INFO - PROCESS | 1641 | Values: 64.0 65.0 66.0 66.0 68.0 81.0 82.0 84.0 81.0 65.0 09:25:35 INFO - PROCESS | 1641 | -------- Summary: end -------- 09:25:35 INFO - PROCESS | 1641 | 09:25:36 INFO - TEST-INFO | 1641: exit 0 09:25:36 INFO - TEST-OK | kraken | took 59375ms 09:25:36 INFO - SUITE-END | took 1177s 09:25:36 INFO - Completed test suite (00:19:38) 09:25:36 INFO - javascript benchmark 09:25:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3319.804870246711, 3334.54983577217, 3335.3329334133164, 3335.4996750487426, 3373.4939759036142, 5640.8589785255335, 5647.181158731982, 5652.152177173426, 5662.603617746754, 5668.659880407178, 5596.664200147984, 5676.091825307952, 5693.259876216086, 5693.601447898649, 5693.943060569393, 4299.355096735483, 5139.697004327792, 5168.896622067557, 5169.819056333029, 5180.1452760294505, 5094.6688465249645, 5108.974455127719, 5118.897622047557, 5127.282180494733, 5286.735663216843, 4170.916581668364, 5172.0, 5224.79100835968, 5232.084385232588, 5241.475852414766, 5225.608079394042, 5234.136367499358, 5253.290805741214, 5253.44838791927, 5289.153735402318, 4980.252962055721, 5008.923081537487, 5146.459621739738, 5148.073346797575, 5167.534921857051, 5518.061929471999, 5577.079781835992, 5622.353429355636, 5635.5773317001385, 5662.546996240291, 6188.133661287423, 6219.346759887322, 6237.937620623781, 6253.937460625381, 6268.091126786636, 6160.0, 6217.782377616762, 6217.813465596039, 6218.440340369343, 6229.563930524865, 5681.147827825776, 5711.657300561979, 5811.447912448395, 5826.679532625746, 5866.384029676908, 6299.937000630027, 6300.905486417708, 6304.747810087637, 6305.306416294204, 6309.400606942333, 6195.2255968004, 6204.348543402878, 6206.844828879224, 6219.533534984895, 6220.0047992321915, 6127.281156068254, 6175.62946223219, 6189.721462534197, 6216.347283535254, 6254.468370188585, 6236.03341482071, 6259.061140828821, 6270.184875966095, 6271.655058971709, 6282.780102696383, 6243.157173781572, 6261.906071408933, 6263.31103578606, 6279.7488100476385, 6287.0, 4826.107170173529, 4914.434839993446, 4930.457649658535, 4982.427020892644, 4999.825006124768, 4446.62203712688, 4447.08834688888, 4454.487733910577, 4512.706674066175, 4516.81932722694, 5722.656640601577, 5767.798127065616, 5770.567207459458, 5770.567207459458, 5790.305163380422, 6110.072772100023, 6111.6525128775675, 6157.322694503601, 6234.251889773256, 6248.4376406123665, 5636.887262254814, 5682.914756278659, 5692.943070569241, 5711.885762284731, 5781.277340332458, 5455.0, 5464.23500709901, 5469.945300547023, 5478.424765399655, 5491.313585801775, 6323.462505687068, 6336.651484168323, 6342.809715708536, 6347.904781428282, 6350.428461438492, 6115.514161902999, 6115.970584430425, 6213.689315534115, 6231.688415579112, 6282.528810339151, 6324.6521441321165, 6338.080978258219, 6338.904916426165, 6339.619622822645, 6352.555321127662, 6024.849378765566, 6058.4244496772735, 6073.14975903365, 6258.468030217483, 6299.622022678654, 6115.057807494094, 6161.815145545641, 6205.503559715302, 6340.873182536231, 6353.777617783356, 5004.799808007639, 6122.916604401293, 6137.232845894263, 6143.051335359142, 6167.352427994996, 6111.6786684274075, 6118.741008805849, 6121.910283036266, 6131.070842498352, 6143.508519318533, 5808.6805225711305, 5813.244278243801, 5935.614185077956, 5962.314333851489, 5977.282726072899, 6052.092186172109, 6070.969645151746, 6115.266168059683, 6120.7551697931585, 6137.60105593135, 5082.949170508247, 5428.0, 5933.08037254209, 5943.019401798655, 5971.731272092853], "value": 5674.600431798157, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.8310083021356, 496.43902390298956, 498.820424647127, 501.19057721779336, 501.9133575808376, 24647.260582182542, 32502.67497325026, 39153.80423097884, 41036.58963410365, 41073.38389924146, 42812.571874281246, 43122.13755724884, 43211.56788432115, 43244.78377608115, 43298.134037319236, 9139.177474027336, 9304.441733495978, 9327.440353578808, 9351.298652601083, 9353.95323023384, 8406.327493800482, 8590.398672092955, 9264.166225039746, 11083.0, 11359.602413915514, 11299.152563557725, 11343.035841953444, 11363.0, 11368.147388945821, 11379.0, 11240.887591124107, 11325.830112548318, 11328.32030078198, 11332.943335283271, 11357.0, 8322.16778322169, 8541.871871921927, 10985.340879547173, 11259.943700281528, 11261.605843795509, 39317.01707457308, 39336.803315983525, 40137.39793903094, 40396.99007524806, 40765.592344076475, 3945.60543945606, 3980.9450495618685, 4011.4183443400543, 4019.919601607981, 4089.89775255618, 29124.563131552834, 29423.85288073546, 29455.70544294551, 30954.0713778587, 31498.842505787554, 3237.077432931627, 3240.821754803485, 3275.967240327614, 3349.7103615108117, 3387.0854869185428, 376429.0, 378613.0, 378691.0, 379313.99999999726, 379883.0, 59073.70463147657, 59088.99999999914, 61210.69394652998, 61670.38329616736, 61683.0, 64208.036879446845, 64225.0, 64312.0, 64369.0, 64483.0, 15715.528534143994, 16142.03147811135, 16180.0, 16181.757273640906, 16208.75686864698, 11774.88225117755, 13101.606951791462, 13199.67000824987, 13206.27365494907, 13218.537351192854, 11483.712907177387, 14237.0, 15461.613459663373, 15703.68592628141, 15886.0, 6238.251409830849, 6780.099245219248, 6829.351211634887, 6958.617276049765, 6997.615131167733, 842.5913431985482, 882.8101958079044, 891.0866361978893, 907.841127802632, 925.7916968682127, 29851.25371865677, 29858.850705746332, 30123.246918827204, 30381.69530912498, 30700.53949190764, 1085.0831047764627, 1086.239632257423, 1087.0705546757408, 1123.612353737959, 1213.2356615332285, 12420.378981050912, 13026.934865325613, 15297.541073767805, 15507.0, 15971.840281597035, 629728.0, 631681.0, 633574.0, 635313.0, 635317.0, 55593.444065558826, 55954.88090238172, 55996.44003559994, 56059.43940560542, 56089.59776005551, 1439.6251579741322, 1458.358322338168, 1473.6684246044554, 1476.5496523560203, 1479.496971029855, 24560.385990350384, 27426.45147097007, 29820.807167713916, 30454.086377408712, 30566.694333056384, 50586.0, 50780.746096269286, 50869.49130508648, 50894.0, 50943.0, 546411.0, 546531.0, 547420.0, 550519.0, 550834.0, 25923.611145833205, 38674.806625967816, 39606.603933960294, 39727.40408893926, 39881.60118398895, 40568.39147412732, 46711.76644116758, 47696.0, 49001.26498102603, 50820.98358032745, 39077.0, 39366.0, 39448.40827387534, 39837.0, 41212.793936030124, 38618.6138138615, 39359.0, 39496.605033949294, 40945.38581921214, 41043.38434923419, 37379.626203737615, 37840.81079594584, 38427.0, 38571.0, 38751.0, 33490.0, 34407.0, 35371.64628353684, 35443.468347975315, 35468.46797297991, 25245.359051661584, 25271.0, 25293.74706252914, 25812.483750324514, 25858.09496667683], "value": 20026.26311591524, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.6871068789625, 389.314805941543, 398.4362127589461, 398.844450664241, 419.42119874573086, 14100.929495352535, 20037.899810500945, 21049.47376315593, 21289.148434062638, 21337.786622133775, 16767.0, 18191.81808181918, 21218.78781212191, 21688.783112168872, 21693.45766355842, 5722.284714410699, 6081.179040829497, 6089.299730531001, 6093.81718548442, 6117.44942955134, 5843.889660964424, 6030.9396906030815, 6076.848078798022, 6105.847353816146, 6158.168647232634, 6006.549508786837, 6042.848928776772, 6044.667543285118, 6051.2133422655, 6054.697265136748, 5776.711164441782, 5937.317208521033, 6013.007853704134, 6020.0, 6023.006203976339, 5302.992431438014, 5306.946930530684, 5316.20256961455, 5317.41508434072, 5319.281896943901, 26371.604425933627, 26875.790589423523, 27149.72850271492, 27501.037463688874, 27535.724642753517, 3047.6342838859478, 3091.7990330628436, 3124.297033167519, 3147.0244224290545, 3149.9527507087414, 14779.33492992818, 14788.852111478855, 14814.407423703147, 14944.551663450115, 15356.462523811613, 1747.0, 1997.951075685277, 2058.9279375221945, 2266.5693518231483, 2279.943001424978, 161961.0, 162209.0, 166957.3304266966, 170471.0, 177796.0, 23519.0, 24150.758492415203, 24189.51620967606, 24253.02987880465, 24478.632820507708, 42865.142697145886, 43431.0, 44217.0, 44279.89300267519, 44403.333949990774, 6342.904856427157, 8348.540830254395, 8353.081161072278, 8426.283765879969, 8448.281896038783, 7970.681172753025, 8524.019737730241, 8778.956105219433, 8792.252658524098, 8935.463872167691, 4323.697341186087, 4810.2063159578975, 4813.927791083137, 4837.685550439211, 4899.657024008286, 1675.2712570032074, 1731.8268173182832, 1760.3310741918244, 2014.733424896991, 2045.2534824788845, 996.7557948302567, 1046.2048842879315, 1046.2467023742893, 1052.0, 1069.994650026745, 20725.58548829045, 21287.46781330448, 21340.0, 21639.56720865574, 21709.0, 1043.5147656339839, 1057.2440704895882, 1073.3935326540636, 1077.2459278505078, 1080.0765345629375, 10553.102986246102, 11287.887121128848, 11375.886241137649, 11407.543698252142, 11431.428428578538, 750975.0, 753208.0, 757266.0, 766462.0, 778539.0, 157590.21204893902, 159381.0, 159451.405485946, 160277.0, 160846.0, 1034.4414016431194, 1043.634727845248, 1065.541817018674, 1066.919981001428, 1070.4380200394855, 18454.446366609023, 18548.44354669362, 18631.0, 18707.607972432128, 18813.153408096943, 60528.69735651294, 60825.0, 60891.69554152201, 61487.692561536904, 61868.69065654643, 87764.56117719371, 90276.5486172565, 90439.54780226057, 91741.0, 92295.0, 6198.6280823150755, 6697.698603562949, 7474.663640136316, 7643.541387516544, 7963.362930965393, 10088.0, 10797.946010270212, 13221.669458263621, 13801.378937947615, 13914.51299204523, 11326.263792853439, 11709.941450292694, 11766.764664706829, 11820.468078936641, 11975.880241197476, 11262.873712628672, 11515.654530364103, 11579.13156513248, 11940.402979850798, 12003.979661728501, 9843.557039933077, 11623.941880290544, 11631.243969141979, 11753.177277590486, 11854.288742675468, 7655.57894315818, 7747.30274275318, 7907.169747176464, 7926.167752386147, 8040.316573091352, 10280.691579252634, 11659.941700291443, 11674.649760507198, 11676.124290678403, 12060.457279422622], "value": 10742.51502983508, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1299.6036208956268, 1437.9784303235447, 1455.8980871339004, 1461.3058797071385, 1464.3044553836928, 8102.837943241132, 8533.573321333932, 8544.530050847201, 8546.102659220778, 8564.229219370256, 7483.176850546435, 7728.96135519323, 8004.119546849842, 8292.460990035643, 8345.374096942724, 2310.6880571122933, 2312.2485192312524, 2354.5761762882676, 2355.5524450354465, 2359.4101474631343, 2335.964960525585, 2375.8812059397046, 2377.976220237793, 2382.463945612241, 2383.976160238393, 2036.3381900882198, 2116.0, 2260.547890421922, 2315.907363705458, 2343.355577216262, 2325.3140323604575, 2327.476317828492, 2329.7204335479682, 2336.6845475860882, 2340.099061861196, 2288.21056735426, 2338.275134708246, 2363.988180059089, 2365.5387199496104, 2369.2300002499255, 8462.534560599164, 8462.576871156418, 8478.618462169217, 8480.575971201477, 8480.982282126122, 5032.547070763612, 5188.377394712658, 5195.272661827347, 5202.323697919284, 5203.271541984125, 3219.275662975835, 3389.1359752136473, 3414.5048967899766, 3513.2622149348667, 3618.6019537850816, 1057.740853490892, 1175.2067354535739, 1211.8485189351331, 1216.8904798568171, 1260.7604555134494, 10734.785304293948, 10826.079783218436, 10875.510602022929, 10883.619073332475, 10894.18293627981, 4505.07645932583, 4646.697964632288, 4693.83571575, 4730.692504987165, 4747.311639812277, 9371.0, 9551.90448095524, 9554.235661147091, 9558.713238602853, 9562.0, 5260.605454590921, 5267.341582302212, 5269.288646032813, 5269.57843372537, 5277.445868183862, 4264.978675106605, 4397.252467080604, 4596.126735920165, 4713.505081966413, 4715.104130215248, 3798.7910664913143, 3838.616138386195, 3907.159960608483, 3915.2169566086895, 3941.1723538056754, 1257.8930790882878, 1264.6206138158516, 1274.6048724895313, 1276.259769333784, 1292.3215311961296, 780.5082797837326, 797.8204903896577, 910.4309806371018, 926.4719110107309, 926.5321012888448, 5415.458454154553, 5557.3331200256225, 5654.321481422174, 5735.139729040678, 5928.792492262836, 950.0641867760389, 992.5136683025265, 993.836017057192, 999.5651891427252, 1028.5320179318392, 3608.0979755061235, 3673.9816300918324, 3704.4616252314686, 3832.6550610445192, 3854.5759966403675, 9746.61013559464, 9828.950855245677, 10044.799104017879, 10086.344387614781, 10096.44469554167, 9731.0, 9974.0, 9974.900250997542, 9984.101430871257, 9988.350757200758, 7800.219978002155, 7873.448858579959, 7960.880586791202, 7961.761147165595, 7981.600919953979, 4728.361671174347, 5261.289725887109, 5421.2681288025615, 5434.266374039454, 5600.019996500678, 7602.46782725204, 8419.494830310201, 8925.73222803317, 9240.445573265626, 9348.20540254086, 9091.272698184262, 9113.498757568132, 9230.907690923274, 9318.440893546409, 9513.381630194015, 6626.83432914181, 6854.8286292841085, 6858.3141685831015, 7215.530990485601, 7415.4809163360205, 7642.923570764221, 7949.125596184531, 7954.761357159295, 8095.311898488694, 8111.432199745961, 7014.0531028310525, 7125.3230943060325, 7240.96379518099, 7314.305141011595, 7379.704811807683, 6977.197622273504, 7074.151101867603, 7090.4682148840075, 7178.174509931425, 7201.207867134506, 6324.841878952879, 6329.2404911409085, 6388.201474815648, 6408.551401401857, 6411.583247089111, 4955.4301255356095, 4969.155243608523, 4972.7762250699525, 4987.700737955734, 5016.498350164954, 4891.51084891508, 4994.675346102492, 5004.199328107486, 5103.209002604603, 5192.3769147702515], "value": 4426.389183235536, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.84127721886546, 350.5057868405548, 360.68980676618105, 371.54856848928546, 372.8527231743461, 4672.579467847889, 5132.743362831858, 5288.8413347599635, 5331.360236771588, 5344.599155063366, 4548.454185497745, 4888.680056384774, 5197.1424714922, 5213.895722085556, 5245.44922783108, 3176.2694580246557, 3812.1803812180374, 3859.5561510426237, 4037.555868854424, 4079.469668943048, 3688.6680198782105, 4006.659433948118, 4011.3982902564703, 4026.8590599329027, 4092.4679791627195, 3315.353506066318, 3765.2469506098755, 3975.2447035063246, 3986.681065514752, 3994.2810294147043, 3618.5115009473648, 3903.4925459690344, 3982.3230050891416, 4001.2997725398086, 4003.73975691582, 3288.753343499247, 3416.4021296273054, 3423.3495635829113, 3433.9313213735586, 3455.6198818129988, 4343.1747967886, 4625.745583992879, 4937.6049916007, 4951.727654978975, 5181.160687955759, 4346.3266642342605, 4531.184386810372, 4545.522720114373, 4568.291914753218, 4603.654725895572, 5170.663906846081, 5312.73436328182, 5508.717810489298, 5580.748866301026, 5591.993441180586, 3775.4714339992424, 3791.3554695701796, 3801.327415936979, 3816.9809150954347, 3869.613038696136, 4867.780949857265, 4984.928240428289, 5567.916481252784, 5650.152477128422, 5904.789518148769, 3715.275521273326, 3760.7367484275837, 3821.197548514843, 3850.3828392075397, 3873.69010479161, 3421.665550435332, 3839.500864887547, 3902.707296952683, 3911.7457365271166, 3924.5682974872743, 3788.7726736395903, 4096.365063415175, 4550.613197878151, 4592.288195329729, 4599.05719327537, 4895.6083513318845, 4986.925196122061, 5018.996200759862, 5178.689278643294, 5410.458954104558, 1259.557806311768, 1260.7959398774149, 1263.5451237554657, 1264.6016504800957, 1272.4210484229654, 786.7176502301212, 791.7505985614513, 806.3226889412921, 824.9317134311082, 861.7845538615346, 4482.932756008662, 4942.838432968249, 4944.802207911715, 4950.8762280943265, 4974.477679843564, 5097.235414687752, 5366.382865970463, 5424.674519528841, 5520.889582208333, 5579.2746942897165, 4147.465437787978, 4579.542045795461, 5048.724083332926, 5073.619478539124, 5091.465396133427, 2327.5336537980966, 2559.8720063996725, 2841.5169421198448, 2853.885844566194, 2871.3395918938763, 5111.7699703513435, 5367.886625757052, 5425.83722488326, 5673.085570956027, 5683.907586537686, 4717.575418212377, 4896.95103048972, 5385.623006389594, 5573.6098473106495, 5640.997445906726, 5024.422191447957, 5454.699991500426, 5461.252399232208, 5655.575831812631, 5817.912731308949, 4569.794359253908, 5062.924056139087, 5093.032323858455, 5349.732513374393, 5579.832605021856, 4568.0, 4911.0, 5327.706976116351, 5479.945200548103, 5489.533389661923, 4902.8774280643265, 5233.528982391603, 5528.640638358494, 5602.719864006865, 5604.635698679573, 3520.8239588019983, 3634.5093412388987, 3637.981365217743, 3647.0, 3653.013686304736, 5420.83737487876, 5433.402325744244, 5442.591805614516, 5456.61803673739, 5462.590305727007, 4440.289553671397, 4888.780004899717, 4897.3878265216845, 5204.449819588299, 5307.0, 4901.946081592475, 5118.930450291952, 5194.259922925711, 5212.843614691566, 5237.338768496118, 4662.790174442226, 4727.628987593559, 5119.436861945109, 5124.8206312778875, 5168.71572063525, 4650.906981860411, 4742.691725037862, 4971.130052240916, 5232.011835502484, 5374.543163831118, 4716.528347165256, 5005.899882002413, 5211.368259029166, 5258.0, 5377.8117765878005], "value": 3938.6144392416304, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.3678008182674, 720.783764870539, 756.2475337039647, 761.0676920772052, 844.8817165596818, 15008.624784380396, 15271.38914443421, 15799.605009874744, 15819.525414237563, 15965.361385544578, 13543.525976590796, 13633.113847599923, 13874.791878121836, 13874.930625346884, 13891.722165556683, 853.0531110467376, 861.014138811061, 863.4387648028769, 866.8786369908217, 866.9393142480029, 678.5724993254267, 699.867025265198, 846.6190214403513, 861.0743450790394, 862.5126803356115, 920.7099763574486, 928.8978212396631, 930.2511478259999, 933.7945651956559, 934.3739694404732, 905.9003509613971, 922.446532080753, 924.0713083351189, 925.6251218256617, 927.4388994658236, 529.875479262373, 546.6064433607796, 663.7709990053423, 691.636890632422, 692.2731132311103, 14836.480723174636, 15311.464098756602, 15315.463958761502, 15336.463223787225, 15355.0, 13944.721105577933, 13995.930020349935, 14009.789853152211, 14034.649133771636, 14064.578062658136, 12514.374281285991, 12543.435545400478, 12778.872211277861, 12803.423845926956, 13085.934570327183, 3622.786366567203, 4180.979095104505, 4184.497860256758, 4214.936775948363, 4216.198922204771, 110289.0, 115975.0, 116133.0, 116860.41569792181, 117046.0, 10625.9468702656, 10917.290376125526, 10966.122710183166, 10990.945045274882, 11476.311421314747, 18823.0, 21762.673559896615, 21791.891040544695, 21813.23653672114, 22149.446263843533, 7424.368928641018, 7703.114141873644, 7758.185390534026, 7895.960520197362, 7962.6416811243635, 4310.612044915973, 4323.459567554056, 4369.890752731207, 4371.912561748747, 4524.276115821519, 3406.7785593936314, 3477.791332520006, 3479.1302174456387, 3517.944616615005, 3572.678458938708, 655.3381085103983, 657.3262406033854, 661.4047357378302, 663.598522893652, 671.3085521912438, 639.7568923808923, 643.1542521584101, 653.411929263657, 663.3167837127767, 675.8411773233287, 9107.772305692411, 9632.325737198329, 9682.080202380763, 9907.207423406111, 10121.797564048826, 624.497279689854, 648.3905129178557, 658.3219284137347, 658.9176352955881, 679.3987321220756, 4168.478940132483, 4560.4071470708595, 4885.853424397274, 4919.631027672939, 5554.250176226238, 86356.7046494303, 99966.50016750015, 100236.49881750545, 105856.0, 106586.46706766571, 91791.0, 91925.0, 93670.53164734133, 96975.51512242535, 97057.0, 66932.665336673, 75991.62004190055, 82953.58523207346, 84471.5776421114, 85074.0, 7926.0, 10824.512896919816, 10881.238313317992, 10911.890881091087, 11122.944385278022, 45230.77384613187, 45911.54088459073, 47934.04131917272, 48518.78703032311, 49640.75179624079, 67032.0, 67103.66448167728, 69263.65368173127, 69969.95045074429, 71047.0, 12582.685432864251, 12806.61580152597, 13145.21128732279, 13285.269310188032, 13452.058355914993, 14532.564023079325, 14551.34518946671, 15257.313420895864, 15606.609834754221, 15806.9209653951, 13625.523106691218, 13874.861251387762, 13952.9535284852, 14177.574672759834, 14463.638409039859, 13381.732365352833, 13451.125676830974, 13521.0, 13565.186088834702, 13664.360466568583, 13168.275744834125, 13244.735105298032, 13485.730285394433, 13761.17432953986, 14731.26343682833, 10935.617253396093, 11153.665390038312, 11296.322220666458, 11529.0, 11790.764184716429, 9443.338966272295, 9887.0, 9904.207663387057, 11293.0, 11333.0], "value": 7560.84250639673, "unit": "score"}], "name": "dromaeo_css", "value": 7375.236114096544, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 116.0, 115.0, 115.0, 116.0, 116.0, 115.0, 113.0, 116.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 130.0, 138.0, 173.0, 109.0, 107.0, 110.0, 109.0, 128.0, 106.0], "value": 124.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 182.0, 184.0, 184.0, 185.0, 184.0, 184.0, 184.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 58.0, 91.0, 60.0, 59.0, 60.0, 58.0, 74.0, 59.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 72.0, 111.0, 101.0, 98.0, 56.0, 93.0, 88.0, 61.0, 88.0], "value": 85.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 86.0, 90.0, 86.0, 90.0, 88.0, 89.0, 85.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 92.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 86.0, 87.0, 85.0, 85.0, 85.0, 85.0, 86.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 53.0, 59.0, 58.0, 57.0, 58.0, 57.0, 57.0, 57.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 70.0, 76.0, 73.0, 69.0, 68.0, 68.0, 70.0, 70.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 90.0, 73.0, 91.0, 108.0, 100.0, 102.0, 95.0, 84.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 138.0, 138.0, 139.0, 143.0, 140.0, 144.0, 140.0, 190.0, 150.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 220.0, 235.0, 168.0, 168.0, 173.0, 167.0, 164.0, 166.0, 167.0], "value": 179.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 66.0, 66.0, 68.0, 81.0, 82.0, 84.0, 81.0, 65.0], "value": 72.2, "unit": "score"}], "name": "kraken", "value": 1458.3000000000002, "alertThreshold": 2.0}]} 09:25:36 INFO - Return code: 0 09:25:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:25:36 ERROR - # TBPL SUCCESS # 09:25:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:25:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:25:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:25:36 INFO - 2017-02-06 09:25:36.677 screenresolution[1645:9775] starting screenresolution argv=screenresolution get 09:25:36 INFO - 2017-02-06 09:25:36.680 screenresolution[1645:9775] Display 0: 1600x1200x32@60 09:25:36 INFO - 2017-02-06 09:25:36.686 screenresolution[1646:9778] starting screenresolution argv=screenresolution list 09:25:36 INFO - Available Modes on Display 0 09:25:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:25:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:25:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:25:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:25:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:25:36 INFO - Intel Iris: 09:25:36 INFO - Chipset Model: Intel Iris 09:25:36 INFO - Type: GPU 09:25:36 INFO - Bus: Built-In 09:25:36 INFO - VRAM (Dynamic, Max): 1536 MB 09:25:36 INFO - Vendor: Intel (0x8086) 09:25:36 INFO - Device ID: 0x0a2e 09:25:36 INFO - Revision ID: 0x0009 09:25:36 INFO - Displays: 09:25:36 INFO - Display: 09:25:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:25:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:25:36 INFO - Display Serial Number: 1600x1200 60 09:25:36 INFO - Main Display: Yes 09:25:36 INFO - Mirror: Off 09:25:36 INFO - Online: Yes 09:25:36 INFO - Rotation: Supported 09:25:36 INFO - Return code: 0 09:25:36 INFO - Running post-action listener: _resource_record_post_action 09:25:36 INFO - [mozharness: 2017-02-06 17:25:36.933272Z] Finished run-tests step (success) 09:25:36 INFO - Running post-run listener: _resource_record_post_run 09:25:36 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 39134208; Write bytes: 510248960; Read time: 381; Write time: 1697 09:25:36 INFO - TinderboxPrint: CPU usage
53.7% 09:25:36 INFO - TinderboxPrint: I/O read bytes / time
39,134,208 / 381 09:25:36 INFO - TinderboxPrint: I/O write bytes / time
510,248,960 / 1,697 09:25:36 INFO - TinderboxPrint: CPU idle
2,220.3 (46.3%) 09:25:36 INFO - TinderboxPrint: CPU system
966.6 (20.1%) 09:25:36 INFO - TinderboxPrint: CPU user
1,610.9 (33.6%) 09:25:36 INFO - TinderboxPrint: Swap in / out
246,001,664 / 0 09:25:36 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203364864; Write bytes: 200471552; Read time: 15616; Write time: 484 09:25:37 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36946944; Write bytes: 296800256; Read time: 353; Write time: 1173 09:25:37 INFO - Running post-run listener: _upload_blobber_files 09:25:37 INFO - Blob upload gear active. 09:25:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:25:37 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:25:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:25:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:25:37 INFO - (blobuploader) - INFO - Open directory for files ... 09:25:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:25:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:25:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:25:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:25:38 INFO - (blobuploader) - INFO - Done attempting. 09:25:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:25:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:25:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:25:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:25:39 INFO - (blobuploader) - INFO - Done attempting. 09:25:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:25:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:25:40 INFO - (blobuploader) - INFO - Done attempting. 09:25:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:25:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:25:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:25:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:25:42 INFO - (blobuploader) - INFO - Done attempting. 09:25:42 INFO - (blobuploader) - INFO - Iteration through files over. 09:25:42 INFO - Return code: 0 09:25:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:25:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:25:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32fe468b195aafc8cb9ab0c83a303925c519ab8a14a8a4be1e6fa799ecab95e158b277d377b2eccadc570eca2a2a92bc59f4ce50f37bf213015132ca7baff6e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b0d344cf7601cec3ad417eb2e016208b6e3fd56e9944a76ade1bb134b9cc0f1b27254ae10aa049a4c0aea7d909bca357167e8e5a005158ad6386b7ac29bfbc4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5d27b296af037eb100f095b1a4fe34cb063a4af3d427ddfd5d1ee0e8b2174eb02f7441165ced4010b34c409c4bb947ee8e323b05eee9c2c73caa4c99ffb4fc8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69cb2f4866cd30631a38f9fa983e9644b6e630b95f3d5a73c78f2ae512dde4753c567b72fc61ea411abf36ba488bfc5eff6dfb3dce1e6d8560bc093515e2571a"} 09:25:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:25:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:25:42 INFO - Contents: 09:25:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32fe468b195aafc8cb9ab0c83a303925c519ab8a14a8a4be1e6fa799ecab95e158b277d377b2eccadc570eca2a2a92bc59f4ce50f37bf213015132ca7baff6e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b0d344cf7601cec3ad417eb2e016208b6e3fd56e9944a76ade1bb134b9cc0f1b27254ae10aa049a4c0aea7d909bca357167e8e5a005158ad6386b7ac29bfbc4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5d27b296af037eb100f095b1a4fe34cb063a4af3d427ddfd5d1ee0e8b2174eb02f7441165ced4010b34c409c4bb947ee8e323b05eee9c2c73caa4c99ffb4fc8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69cb2f4866cd30631a38f9fa983e9644b6e630b95f3d5a73c78f2ae512dde4753c567b72fc61ea411abf36ba488bfc5eff6dfb3dce1e6d8560bc093515e2571a"} 09:25:42 INFO - Running post-run listener: copy_logs_to_upload_dir 09:25:42 INFO - Copying logs to upload dir... 09:25:42 INFO - mkdir: /builds/slave/test/build/upload/logs 09:25:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.093805 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-06 09:25:42.904023) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 09:25:42.911588) ========= 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.pKYHg8KQdJ/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.VOn9JKezqu/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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/Mozilla-Aurora/sha512/32fe468b195aafc8cb9ab0c83a303925c519ab8a14a8a4be1e6fa799ecab95e158b277d377b2eccadc570eca2a2a92bc59f4ce50f37bf213015132ca7baff6e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b0d344cf7601cec3ad417eb2e016208b6e3fd56e9944a76ade1bb134b9cc0f1b27254ae10aa049a4c0aea7d909bca357167e8e5a005158ad6386b7ac29bfbc4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5d27b296af037eb100f095b1a4fe34cb063a4af3d427ddfd5d1ee0e8b2174eb02f7441165ced4010b34c409c4bb947ee8e323b05eee9c2c73caa4c99ffb4fc8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69cb2f4866cd30631a38f9fa983e9644b6e630b95f3d5a73c78f2ae512dde4753c567b72fc61ea411abf36ba488bfc5eff6dfb3dce1e6d8560bc093515e2571a"} build_url:https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011143 build_url: 'https://queue.taskcluster.net/v1/task/KN5I49fbQFqxfKTgcVV5cQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32fe468b195aafc8cb9ab0c83a303925c519ab8a14a8a4be1e6fa799ecab95e158b277d377b2eccadc570eca2a2a92bc59f4ce50f37bf213015132ca7baff6e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b0d344cf7601cec3ad417eb2e016208b6e3fd56e9944a76ade1bb134b9cc0f1b27254ae10aa049a4c0aea7d909bca357167e8e5a005158ad6386b7ac29bfbc4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5d27b296af037eb100f095b1a4fe34cb063a4af3d427ddfd5d1ee0e8b2174eb02f7441165ced4010b34c409c4bb947ee8e323b05eee9c2c73caa4c99ffb4fc8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69cb2f4866cd30631a38f9fa983e9644b6e630b95f3d5a73c78f2ae512dde4753c567b72fc61ea411abf36ba488bfc5eff6dfb3dce1e6d8560bc093515e2571a"}' ========= master_lag: 0.49 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 09:25:43.412655) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 09:25:43.412977) ========= 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.pKYHg8KQdJ/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.VOn9JKezqu/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.004854 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 09:25:43.445115) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 09:25:43.445428) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 09:25:43.445729) ========= ========= Total master_lag: 0.64 =========