builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0320 starttime: 1493022950.11 results: success (0) buildid: 20170424004007 builduid: f7d438f834de421391ab7103051501ea revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-24 01:35:50.110085) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-24 01:35:50.110785) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-24 01:35:50.111271) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-24 01:35:50.141713) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 01:35:50.142104) ========= 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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-24 01:35:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.5M=0.001s 2017-04-24 01:35:50 (17.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086661 ========= master_lag: 0.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 01:35:50.385185) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 01:35:50.385506) ========= 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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.026329 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 01:35:50.436421) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-24 01:35:50.438964) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --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 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.F0U8wSSBcT/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.m0vTTwjAvC/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-24 01:35:50,545 truncating revision to first 12 chars 2017-04-24 01:35:50,545 Setting DEBUG logging. 2017-04-24 01:35:50,545 attempt 1/10 2017-04-24 01:35:50,545 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-24 01:35:52,380 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.102379 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-24 01:35:52.566125) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-24 01:35:52.566477) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-24 01:35:52.566857) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-24 01:35:52.567125) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-24 01:35:52.589210) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-04-24 01:35:52.589883) ========= /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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 01:35:52 INFO - MultiFileLogger online at 20170424 01:35:52 in /builds/slave/test 01:35:52 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 01:35:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:35:52 INFO - {'append_to_log': False, 01:35:52 INFO - 'base_work_dir': '/builds/slave/test', 01:35:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:35:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:35:52 INFO - 'branch': 'Mozilla-Aurora', 01:35:52 INFO - 'buildbot_json_path': 'buildprops.json', 01:35:52 INFO - 'config_files': ('talos/mac_config.py',), 01:35:52 INFO - 'default_actions': ('clobber', 01:35:52 INFO - 'read-buildbot-config', 01:35:52 INFO - 'download-and-extract', 01:35:52 INFO - 'populate-webroot', 01:35:52 INFO - 'create-virtualenv', 01:35:52 INFO - 'install', 01:35:52 INFO - 'run-tests'), 01:35:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:35:52 INFO - 'download_minidump_stackwalk': True, 01:35:52 INFO - 'download_symbols': 'ondemand', 01:35:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:35:52 INFO - 'tooltool.py': '/tools/tooltool.py', 01:35:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:35:52 INFO - '/tools/misc-python/virtualenv.py')}, 01:35:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:35:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:35:52 INFO - 'gecko_profile': False, 01:35:52 INFO - 'gecko_profile_interval': 0, 01:35:52 INFO - 'installer_path': 'installer.exe', 01:35:52 INFO - 'log_level': 'info', 01:35:52 INFO - 'log_name': 'talos', 01:35:52 INFO - 'log_to_console': True, 01:35:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:35:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:35:52 INFO - 'opt_config_files': (), 01:35:52 INFO - 'pip_index': False, 01:35:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:35:52 INFO - 'cmd': ('bash', 01:35:52 INFO - '-c', 01:35:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:35:52 INFO - 'enabled': True, 01:35:52 INFO - 'halt_on_failure': False, 01:35:52 INFO - 'name': 'check_screen_resolution'},), 01:35:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:35:52 INFO - 'cmd': ('bash', 01:35:52 INFO - '-c', 01:35:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:35:52 INFO - 'enabled': True, 01:35:52 INFO - 'halt_on_failure': False, 01:35:52 INFO - 'name': 'check_screen_resolution'},), 01:35:52 INFO - 'run_cmd_checks_enabled': True, 01:35:52 INFO - 'suite': 'dromaeojs', 01:35:52 INFO - 'system_bits': '32', 01:35:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:35:52 INFO - 'title': 't-yosemite-r7-0320', 01:35:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:35:52 INFO - 'use_talos_json': True, 01:35:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:35:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:35:52 INFO - 'work_dir': 'build'} 01:35:52 INFO - [mozharness: 2017-04-24 08:35:52.805149Z] Running clobber step. 01:35:52 INFO - Running pre-action listener: _resource_record_pre_action 01:35:52 INFO - Running main action method: clobber 01:35:52 INFO - rmtree: /builds/slave/test/build 01:35:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:35:55 INFO - Running post-action listener: _resource_record_post_action 01:35:55 INFO - [mozharness: 2017-04-24 08:35:55.264524Z] Finished clobber step (success) 01:35:55 INFO - [mozharness: 2017-04-24 08:35:55.264654Z] Running read-buildbot-config step. 01:35:55 INFO - Running pre-action listener: _resource_record_pre_action 01:35:55 INFO - Running main action method: read_buildbot_config 01:35:55 INFO - Using buildbot properties: 01:35:55 INFO - { 01:35:55 INFO - "project": "", 01:35:55 INFO - "product": "firefox", 01:35:55 INFO - "script_repo_revision": "production", 01:35:55 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 01:35:55 INFO - "repository": "", 01:35:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 01:35:55 INFO - "buildid": "20170424004007", 01:35:55 INFO - "stage_platform": "macosx64", 01:35:55 INFO - "basedir": "/builds/slave/test", 01:35:55 INFO - "nightly_build": "True", 01:35:55 INFO - "buildnumber": 4, 01:35:55 INFO - "slavename": "t-yosemite-r7-0320", 01:35:55 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 01:35:55 INFO - "platform": "yosemite_r7", 01:35:55 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:35:55 INFO - "branch": "mozilla-aurora", 01:35:55 INFO - "repo_path": "releases/mozilla-aurora", 01:35:55 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 01:35:55 INFO - "pgo_build": "False", 01:35:55 INFO - "builduid": "f7d438f834de421391ab7103051501ea", 01:35:55 INFO - "slavebuilddir": "test" 01:35:55 INFO - } 01:35:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 01:35:55 INFO - Running post-action listener: _resource_record_post_action 01:35:55 INFO - [mozharness: 2017-04-24 08:35:55.268234Z] Finished read-buildbot-config step (success) 01:35:55 INFO - [mozharness: 2017-04-24 08:35:55.268369Z] Running download-and-extract step. 01:35:55 INFO - Running pre-action listener: _resource_record_pre_action 01:35:55 INFO - Running main action method: download_and_extract 01:35:55 INFO - mkdir: /builds/slave/test/build/tests 01:35:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:55 INFO - trying https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 01:35:55 INFO - Downloading https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:35:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 01:35:56 INFO - Downloaded 1199 bytes. 01:35:56 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:35:56 INFO - Using the following test package requirements: 01:35:56 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:35:56 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:35:56 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 01:35:56 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:35:56 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 01:35:56 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:35:56 INFO - u'jsshell-mac.zip'], 01:35:56 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:35:56 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 01:35:56 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:35:56 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:35:56 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 01:35:56 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:35:56 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 01:35:56 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:35:56 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 01:35:56 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:35:56 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 01:35:56 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:35:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:35:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:35:56 INFO - Fetch https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:35:58 INFO - Expected file size: 51796509 01:35:58 INFO - Obtained file size: 51796509 01:36:14 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:36:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:36:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:36:14 INFO - Fetch https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:36:16 INFO - Expected file size: 51796509 01:36:16 INFO - Obtained file size: 51796509 01:36:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 01:36:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:36:31 INFO - Fetch https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 01:36:32 INFO - Expected file size: 14404424 01:36:32 INFO - Obtained file size: 14404424 01:36:33 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')]} 01:36:33 INFO - trying https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:36:33 INFO - Downloading https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 01:36:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 01:36:35 INFO - Downloaded 74780070 bytes. 01:36:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:36:35 INFO - mkdir: /builds/slave/test/properties 01:36:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:36:35 INFO - Writing to file /builds/slave/test/properties/build_url 01:36:35 INFO - Contents: 01:36:35 INFO - build_url:https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:36:35 INFO - Running post-action listener: _resource_record_post_action 01:36:35 INFO - Running post-action listener: set_extra_try_arguments 01:36:35 INFO - [mozharness: 2017-04-24 08:36:35.887102Z] Finished download-and-extract step (success) 01:36:35 INFO - [mozharness: 2017-04-24 08:36:35.887266Z] Running populate-webroot step. 01:36:35 INFO - Running pre-action listener: _resource_record_pre_action 01:36:35 INFO - Running main action method: populate_webroot 01:36:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:36:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:36:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:36:35 INFO - '--tppagecycles', 01:36:35 INFO - '7'], 01:36:35 INFO - 'tests': ['tsvgm']}}, 01:36:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:36:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:36:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:36:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:36:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:36:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:36:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:36:35 INFO - 'talos_options': ['--disable-e10s'], 01:36:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:36:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:36:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:36:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:36:35 INFO - 'talos_options': ['--disable-e10s'], 01:36:35 INFO - 'tests': ['damp', 'tps']}, 01:36:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:36:35 INFO - 'tests': ['damp', 'tps']}, 01:36:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:36:35 INFO - 'tests': ['dromaeo_dom']}, 01:36:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:36:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:36:35 INFO - 'tests': ['basic_compositor_video']}, 01:36:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:36:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:36:35 INFO - 'tests': ['a11yr', 01:36:35 INFO - 'ts_paint', 01:36:35 INFO - 'tpaint', 01:36:35 INFO - 'sessionrestore', 01:36:35 INFO - 'sessionrestore_no_auto_restore', 01:36:35 INFO - 'tabpaint']}, 01:36:35 INFO - 'other-e10s': {'tests': ['a11yr', 01:36:35 INFO - 'ts_paint', 01:36:35 INFO - 'tpaint', 01:36:35 INFO - 'sessionrestore', 01:36:35 INFO - 'sessionrestore_no_auto_restore', 01:36:35 INFO - 'tabpaint']}, 01:36:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:36:35 INFO - 'ts_paint', 01:36:35 INFO - 'tpaint', 01:36:35 INFO - 'sessionrestore', 01:36:35 INFO - 'sessionrestore_no_auto_restore', 01:36:35 INFO - 'tabpaint']}, 01:36:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:36:35 INFO - 'ts_paint', 01:36:35 INFO - 'tpaint', 01:36:35 INFO - 'sessionrestore', 01:36:35 INFO - 'sessionrestore_no_auto_restore', 01:36:35 INFO - 'tabpaint']}, 01:36:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:36:35 INFO - 'tests': ['a11yr', 01:36:35 INFO - 'ts_paint', 01:36:35 INFO - 'tpaint', 01:36:35 INFO - 'sessionrestore', 01:36:35 INFO - 'sessionrestore_no_auto_restore', 01:36:35 INFO - 'tabpaint']}, 01:36:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:36:35 INFO - 'tests': ['a11yr', 01:36:35 INFO - 'ts_paint', 01:36:35 INFO - 'tpaint', 01:36:35 INFO - 'sessionrestore', 01:36:35 INFO - 'sessionrestore_no_auto_restore', 01:36:35 INFO - 'tabpaint']}, 01:36:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:36:35 INFO - 'tests': ['tsvgx', 01:36:35 INFO - 'tsvgr_opacity', 01:36:35 INFO - 'tart', 01:36:35 INFO - 'tscrollx', 01:36:35 INFO - 'cart', 01:36:35 INFO - 'tsvg_static']}, 01:36:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:36:35 INFO - 'tsvgr_opacity', 01:36:35 INFO - 'tart', 01:36:35 INFO - 'tscrollx', 01:36:35 INFO - 'cart', 01:36:35 INFO - 'tsvg_static']}, 01:36:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:36:35 INFO - 'talos_options': ['--disable-e10s'], 01:36:35 INFO - 'tests': ['tp5o']}, 01:36:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:36:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:36:35 INFO - 'talos_options': ['--disable-e10s', 01:36:35 INFO - '--xperf_path', 01:36:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:36:35 INFO - 'tests': ['tp5n']}, 01:36:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:36:35 INFO - 'talos_options': ['--xperf_path', 01:36:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:36:35 INFO - 'tests': ['tp5n']}}, 01:36:35 INFO - 'talos.zip': {'path': '', 01:36:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:36:35 INFO - Running post-action listener: _resource_record_post_action 01:36:35 INFO - [mozharness: 2017-04-24 08:36:35.905854Z] Finished populate-webroot step (success) 01:36:35 INFO - [mozharness: 2017-04-24 08:36:35.905983Z] Running create-virtualenv step. 01:36:35 INFO - Running pre-action listener: _resource_record_pre_action 01:36:35 INFO - Running main action method: create_virtualenv 01:36:35 INFO - Creating virtualenv /builds/slave/test/build/venv 01:36:35 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 01:36:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:36:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:36:36 INFO - Using real prefix '/tools/python27' 01:36:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:36:36 INFO - Installing distribute.............................................................................................................................................................................................done. 01:36:39 INFO - Installing pip.................done. 01:36:39 INFO - Return code: 0 01:36:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:36:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:36:39 INFO - Reading from file tmpfile_stdout 01:36:39 INFO - Output received: 01:36:39 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) 01:36:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:36:39 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')]} 01:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:39 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')]} 01:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:39 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 0x105a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff000c6c550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105879d30>, '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 0x1057a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10586ebe8>, '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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 01:36:39 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 01:36:39 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 01:36:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F0U8wSSBcT/Render', 01:36:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:39 INFO - 'HOME': '/Users/cltbld', 01:36:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:39 INFO - 'LOGNAME': 'cltbld', 01:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:39 INFO - 'MOZ_NO_REMOTE': '1', 01:36:39 INFO - 'NO_EM_RESTART': '1', 01:36:39 INFO - 'PAGER': '/bin/cat', 01:36:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:39 INFO - 'PWD': '/builds/slave/test', 01:36:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:39 INFO - 'SHELL': '/bin/bash', 01:36:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m0vTTwjAvC/Listeners', 01:36:39 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 01:36:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:39 INFO - 'USER': 'cltbld', 01:36:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:39 INFO - 'XPC_FLAGS': '0x0', 01:36:39 INFO - 'XPC_SERVICE_NAME': '0', 01:36:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:40 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 01:36:40 INFO - Cleaning up... 01:36:40 INFO - Return code: 0 01:36:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff000c6c550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105879d30>, '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 0x1057a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10586ebe8>, '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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 01:36:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:36:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:36:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F0U8wSSBcT/Render', 01:36:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:40 INFO - 'HOME': '/Users/cltbld', 01:36:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:40 INFO - 'LOGNAME': 'cltbld', 01:36:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:40 INFO - 'MOZ_NO_REMOTE': '1', 01:36:40 INFO - 'NO_EM_RESTART': '1', 01:36:40 INFO - 'PAGER': '/bin/cat', 01:36:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:40 INFO - 'PWD': '/builds/slave/test', 01:36:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:40 INFO - 'SHELL': '/bin/bash', 01:36:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m0vTTwjAvC/Listeners', 01:36:40 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 01:36:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:40 INFO - 'USER': 'cltbld', 01:36:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:40 INFO - 'XPC_FLAGS': '0x0', 01:36:40 INFO - 'XPC_SERVICE_NAME': '0', 01:36:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:40 INFO - Downloading/unpacking psutil>=3.1.1 01:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:36:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:36:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:36:43 INFO - Installing collected packages: psutil 01:36:43 INFO - Running setup.py install for psutil 01:36:43 INFO - building 'psutil._psutil_osx' extension 01:36:43 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 01:36:44 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 01:36:44 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 01:36:44 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 01:36:44 INFO - building 'psutil._psutil_posix' extension 01:36:44 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 01:36:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:36:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:36:44 INFO - ^ 01:36:44 INFO - 1 warning generated. 01:36:44 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 01:36:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:36:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:36:44 INFO - Successfully installed psutil 01:36:44 INFO - Cleaning up... 01:36:45 INFO - Return code: 0 01:36:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:45 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 0x105a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff000c6c550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105879d30>, '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 0x1057a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10586ebe8>, '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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 01:36:45 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 01:36:45 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 01:36:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F0U8wSSBcT/Render', 01:36:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:45 INFO - 'HOME': '/Users/cltbld', 01:36:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:45 INFO - 'LOGNAME': 'cltbld', 01:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:45 INFO - 'MOZ_NO_REMOTE': '1', 01:36:45 INFO - 'NO_EM_RESTART': '1', 01:36:45 INFO - 'PAGER': '/bin/cat', 01:36:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:45 INFO - 'PWD': '/builds/slave/test', 01:36:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:45 INFO - 'SHELL': '/bin/bash', 01:36:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m0vTTwjAvC/Listeners', 01:36:45 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 01:36:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:45 INFO - 'USER': 'cltbld', 01:36:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:45 INFO - 'XPC_FLAGS': '0x0', 01:36:45 INFO - 'XPC_SERVICE_NAME': '0', 01:36:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:36:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:36:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:36:49 INFO - Installing collected packages: mozsystemmonitor 01:36:49 INFO - Running setup.py install for mozsystemmonitor 01:36:49 INFO - Successfully installed mozsystemmonitor 01:36:49 INFO - Cleaning up... 01:36:49 INFO - Return code: 0 01:36:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff000c6c550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105879d30>, '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 0x1057a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10586ebe8>, '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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 01:36:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:36:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:36:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F0U8wSSBcT/Render', 01:36:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:49 INFO - 'HOME': '/Users/cltbld', 01:36:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:49 INFO - 'LOGNAME': 'cltbld', 01:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:49 INFO - 'MOZ_NO_REMOTE': '1', 01:36:49 INFO - 'NO_EM_RESTART': '1', 01:36:49 INFO - 'PAGER': '/bin/cat', 01:36:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:49 INFO - 'PWD': '/builds/slave/test', 01:36:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:49 INFO - 'SHELL': '/bin/bash', 01:36:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m0vTTwjAvC/Listeners', 01:36:49 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 01:36:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:49 INFO - 'USER': 'cltbld', 01:36:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:49 INFO - 'XPC_FLAGS': '0x0', 01:36:49 INFO - 'XPC_SERVICE_NAME': '0', 01:36:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:49 INFO - Downloading/unpacking jsonschema==2.5.1 01:36:49 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 01:36:49 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 01:36:49 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 01:36:49 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 01:36:49 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 01:36:49 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 01:36:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:36:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:52 INFO - Downloading functools32-3.2.3-2.tar.gz 01:36:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:36:52 INFO - warning: no files found matching '*.txt' 01:36:52 INFO - no previously-included directories found matching 'build' 01:36:52 INFO - no previously-included directories found matching 'dist' 01:36:52 INFO - no previously-included directories found matching '.git*' 01:36:52 INFO - Installing collected packages: jsonschema, functools32 01:36:52 INFO - Running setup.py install for functools32 01:36:53 INFO - warning: no files found matching '*.txt' 01:36:53 INFO - no previously-included directories found matching 'build' 01:36:53 INFO - no previously-included directories found matching 'dist' 01:36:53 INFO - no previously-included directories found matching '.git*' 01:36:53 INFO - Successfully installed jsonschema functools32 01:36:53 INFO - Cleaning up... 01:36:53 INFO - Return code: 0 01:36:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:36:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:53 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 0x105a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff000c6c550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105879d30>, '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 0x1057a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10586ebe8>, '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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 01:36:53 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 01:36:53 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 01:36:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F0U8wSSBcT/Render', 01:36:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:53 INFO - 'HOME': '/Users/cltbld', 01:36:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:53 INFO - 'LOGNAME': 'cltbld', 01:36:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:53 INFO - 'MOZ_NO_REMOTE': '1', 01:36:53 INFO - 'NO_EM_RESTART': '1', 01:36:53 INFO - 'PAGER': '/bin/cat', 01:36:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:53 INFO - 'PWD': '/builds/slave/test', 01:36:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:53 INFO - 'SHELL': '/bin/bash', 01:36:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m0vTTwjAvC/Listeners', 01:36:53 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 01:36:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:53 INFO - 'USER': 'cltbld', 01:36:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:53 INFO - 'XPC_FLAGS': '0x0', 01:36:53 INFO - 'XPC_SERVICE_NAME': '0', 01:36:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:36:53 INFO - Cleaning up... 01:36:53 INFO - Return code: 0 01:36:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:36:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:53 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 0x105a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff000c6c550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105879d30>, '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 0x1057a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10586ebe8>, '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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 01:36:53 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 01:36:53 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 01:36:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F0U8wSSBcT/Render', 01:36:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:53 INFO - 'HOME': '/Users/cltbld', 01:36:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:53 INFO - 'LOGNAME': 'cltbld', 01:36:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:53 INFO - 'MOZ_NO_REMOTE': '1', 01:36:53 INFO - 'NO_EM_RESTART': '1', 01:36:53 INFO - 'PAGER': '/bin/cat', 01:36:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:53 INFO - 'PWD': '/builds/slave/test', 01:36:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:53 INFO - 'SHELL': '/bin/bash', 01:36:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m0vTTwjAvC/Listeners', 01:36:53 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 01:36:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:53 INFO - 'USER': 'cltbld', 01:36:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:53 INFO - 'XPC_FLAGS': '0x0', 01:36:53 INFO - 'XPC_SERVICE_NAME': '0', 01:36:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:53 INFO - Downloading/unpacking blobuploader==1.2.4 01:36:53 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 01:36:53 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 01:36:53 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 01:36:53 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 01:36:53 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 01:36:53 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 01:36:56 INFO - Downloading blobuploader-1.2.4.tar.gz 01:36:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:36:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:36:56 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 01:36:56 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 01:36:56 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 01:36:56 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 01:36:56 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 01:36:56 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 01:36:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:36:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:36:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:57 INFO - Downloading docopt-0.6.1.tar.gz 01:36:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:36:57 INFO - Installing collected packages: blobuploader, requests, docopt 01:36:57 INFO - Running setup.py install for blobuploader 01:36:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:36:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:36:57 INFO - Running setup.py install for requests 01:36:58 INFO - Running setup.py install for docopt 01:36:58 INFO - Successfully installed blobuploader requests docopt 01:36:58 INFO - Cleaning up... 01:36:58 INFO - Return code: 0 01:36:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:36: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')]} 01:36:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36: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')]} 01:36:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:58 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 0x105a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff000c6c550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105879d30>, '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 0x1057a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10586ebe8>, '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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 01:36:58 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 01:36:58 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 01:36:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F0U8wSSBcT/Render', 01:36:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:58 INFO - 'HOME': '/Users/cltbld', 01:36:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:58 INFO - 'LOGNAME': 'cltbld', 01:36:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:58 INFO - 'MOZ_NO_REMOTE': '1', 01:36:58 INFO - 'NO_EM_RESTART': '1', 01:36:58 INFO - 'PAGER': '/bin/cat', 01:36:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:58 INFO - 'PWD': '/builds/slave/test', 01:36:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:58 INFO - 'SHELL': '/bin/bash', 01:36:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m0vTTwjAvC/Listeners', 01:36:58 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 01:36:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:58 INFO - 'USER': 'cltbld', 01:36:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:58 INFO - 'XPC_FLAGS': '0x0', 01:36:58 INFO - 'XPC_SERVICE_NAME': '0', 01:36:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:36:58 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-i__feu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:36:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:36:58 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-xp8hEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:36:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:36:58 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-y5ixUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:36:59 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-5tGt37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:36:59 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-c7wDP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:36:59 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-FBq22g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:36:59 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-hhUiSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:36:59 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-ESJEne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:36:59 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-0fbk6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:36:59 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-WI57OQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:36:59 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-1ZrVdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:36:59 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-ycYVAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:36:59 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-WcIIFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:37:00 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-mtjIQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:00 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-mrtTiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:37:00 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-jxu_Sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:37:00 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-AyFvsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:37:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:37:00 INFO - Running setup.py install for manifestparser 01:37:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:37:00 INFO - Running setup.py install for mozcrash 01:37:00 INFO - Running setup.py install for mozdebug 01:37:00 INFO - Running setup.py install for mozdevice 01:37:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:37:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:37:01 INFO - Running setup.py install for mozfile 01:37:01 INFO - Running setup.py install for mozhttpd 01:37:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:37:01 INFO - Running setup.py install for mozinfo 01:37:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:37:01 INFO - Running setup.py install for mozInstall 01:37:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:37:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:37:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:37:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:37:01 INFO - Running setup.py install for mozleak 01:37:01 INFO - Running setup.py install for mozlog 01:37:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:37:01 INFO - Running setup.py install for moznetwork 01:37:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:37:02 INFO - Running setup.py install for mozprocess 01:37:02 INFO - Running setup.py install for mozprofile 01:37:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:37:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:37:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:37:02 INFO - Running setup.py install for mozrunner 01:37:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:37:02 INFO - Running setup.py install for mozscreenshot 01:37:02 INFO - Running setup.py install for moztest 01:37:02 INFO - Running setup.py install for mozversion 01:37:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:37:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:37:03 INFO - Cleaning up... 01:37:03 INFO - Return code: 0 01:37:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:37:03 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')]} 01:37:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:03 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')]} 01:37:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:03 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 0x105a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff000c6c550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105879d30>, '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 0x1057a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10586ebe8>, '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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 01:37:03 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 01:37:03 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 01:37:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F0U8wSSBcT/Render', 01:37:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:03 INFO - 'HOME': '/Users/cltbld', 01:37:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:03 INFO - 'LOGNAME': 'cltbld', 01:37:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:03 INFO - 'MOZ_NO_REMOTE': '1', 01:37:03 INFO - 'NO_EM_RESTART': '1', 01:37:03 INFO - 'PAGER': '/bin/cat', 01:37:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:03 INFO - 'PWD': '/builds/slave/test', 01:37:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:03 INFO - 'SHELL': '/bin/bash', 01:37:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m0vTTwjAvC/Listeners', 01:37:03 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 01:37:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:03 INFO - 'USER': 'cltbld', 01:37:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:03 INFO - 'XPC_FLAGS': '0x0', 01:37:03 INFO - 'XPC_SERVICE_NAME': '0', 01:37:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:37:03 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-y6EeqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:37:03 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)) 01:37:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:37:03 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-wnQYmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:37:03 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)) 01:37:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:37:03 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-p_rEn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:37:03 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)) 01:37:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:37:03 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-56w_R_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:37:03 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)) 01:37:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:37:03 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-7S0JN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:37:03 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)) 01:37:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:37:03 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-0FDJ8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:37:04 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)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:37:04 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-ySSe8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:37:04 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)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:37:04 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-gauAuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:37:04 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)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:37:04 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-mOeZ_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:37:04 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)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:37:04 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-a9tuja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:37:04 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)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:37:04 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-n7Yuv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:37:04 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)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:37:04 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-R5rL07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:37:04 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-9dlPFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:37:04 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)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:37:04 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-jt5Xi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:37:04 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)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:04 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-tXYnxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:04 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)) 01:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:37:04 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Mm5ys3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:37:05 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)) 01:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:37:05 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-rT0vnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:37:05 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)) 01:37:05 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)) 01:37:05 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)) 01:37:05 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)) 01:37:05 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)) 01:37:05 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)) 01:37:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:07 INFO - Downloading blessings-1.6.tar.gz 01:37:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:37:07 INFO - Installing collected packages: blessings 01:37:07 INFO - Running setup.py install for blessings 01:37:08 INFO - Successfully installed blessings 01:37:08 INFO - Cleaning up... 01:37:08 INFO - Return code: 0 01:37:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:37:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:37:08 INFO - Reading from file tmpfile_stdout 01:37:08 INFO - Current package versions: 01:37:08 INFO - blessings == 1.6 01:37:08 INFO - blobuploader == 1.2.4 01:37:08 INFO - docopt == 0.6.1 01:37:08 INFO - functools32 == 3.2.3-2 01:37:08 INFO - jsonschema == 2.5.1 01:37:08 INFO - manifestparser == 1.1 01:37:08 INFO - mozInstall == 1.12 01:37:08 INFO - mozcrash == 1.0 01:37:08 INFO - mozdebug == 0.1 01:37:08 INFO - mozdevice == 0.49 01:37:08 INFO - mozfile == 1.2 01:37:08 INFO - mozhttpd == 0.7 01:37:08 INFO - mozinfo == 0.9 01:37:08 INFO - mozleak == 0.1 01:37:08 INFO - mozlog == 3.4 01:37:08 INFO - moznetwork == 0.27 01:37:08 INFO - mozprocess == 0.25 01:37:08 INFO - mozprofile == 0.28 01:37:08 INFO - mozrunner == 6.13 01:37:08 INFO - mozscreenshot == 0.1 01:37:08 INFO - mozsystemmonitor == 0.3 01:37:08 INFO - moztest == 0.8 01:37:08 INFO - mozversion == 1.4 01:37:08 INFO - psutil == 3.1.1 01:37:08 INFO - requests == 1.2.3 01:37:08 INFO - wsgiref == 0.1.2 01:37:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:37: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')]} 01:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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')]} 01:37:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:08 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 0x105a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff000c6c550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105879d30>, '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 0x1057a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10586ebe8>, '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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 01:37:08 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 01:37:08 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 01:37:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F0U8wSSBcT/Render', 01:37:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:08 INFO - 'HOME': '/Users/cltbld', 01:37:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:08 INFO - 'LOGNAME': 'cltbld', 01:37:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:08 INFO - 'MOZ_NO_REMOTE': '1', 01:37:08 INFO - 'NO_EM_RESTART': '1', 01:37:08 INFO - 'PAGER': '/bin/cat', 01:37:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:08 INFO - 'PWD': '/builds/slave/test', 01:37:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:08 INFO - 'SHELL': '/bin/bash', 01:37:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m0vTTwjAvC/Listeners', 01:37:08 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 01:37:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:08 INFO - 'USER': 'cltbld', 01:37:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:08 INFO - 'XPC_FLAGS': '0x0', 01:37:08 INFO - 'XPC_SERVICE_NAME': '0', 01:37:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:08 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)) 01:37:08 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)) 01:37:08 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)) 01:37:08 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)) 01:37:08 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)) 01:37:08 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)) 01:37:08 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)) 01:37:08 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)) 01:37:08 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)) 01:37:08 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)) 01:37:08 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)) 01:37:08 INFO - Cleaning up... 01:37:08 INFO - Return code: 0 01:37:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:37: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')]} 01:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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')]} 01:37:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105a9bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a9e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff000c6c550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105879d30>, '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 0x1057a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10586ebe8>, '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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 01:37: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', 'jsonschema'] in /builds/slave/test/build 01:37: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 jsonschema 01:37:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F0U8wSSBcT/Render', 01:37:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:08 INFO - 'HOME': '/Users/cltbld', 01:37:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:08 INFO - 'LOGNAME': 'cltbld', 01:37:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:08 INFO - 'MOZ_NO_REMOTE': '1', 01:37:08 INFO - 'NO_EM_RESTART': '1', 01:37:08 INFO - 'PAGER': '/bin/cat', 01:37:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:08 INFO - 'PWD': '/builds/slave/test', 01:37:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:08 INFO - 'SHELL': '/bin/bash', 01:37:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m0vTTwjAvC/Listeners', 01:37:08 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 01:37:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:08 INFO - 'USER': 'cltbld', 01:37:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:08 INFO - 'XPC_FLAGS': '0x0', 01:37:08 INFO - 'XPC_SERVICE_NAME': '0', 01:37:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:37:08 INFO - Cleaning up... 01:37:08 INFO - Return code: 0 01:37:08 INFO - Running post-action listener: _resource_record_post_action 01:37:08 INFO - Running post-action listener: _start_resource_monitoring 01:37:08 INFO - Starting resource monitoring. 01:37:08 INFO - [mozharness: 2017-04-24 08:37:08.970390Z] Finished create-virtualenv step (success) 01:37:08 INFO - [mozharness: 2017-04-24 08:37:08.971389Z] Running install step. 01:37:08 INFO - Running pre-action listener: _resource_record_pre_action 01:37:08 INFO - Running main action method: install 01:37:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:37:09 INFO - Reading from file tmpfile_stdout 01:37:09 INFO - Detecting whether we're running mozinstall >=1.0... 01:37:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:37:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:37:09 INFO - Reading from file tmpfile_stdout 01:37:09 INFO - Output received: 01:37:09 INFO - Usage: mozinstall [options] installer 01:37:09 INFO - Options: 01:37:09 INFO - -h, --help show this help message and exit 01:37:09 INFO - -d DEST, --destination=DEST 01:37:09 INFO - Directory to install application into. [default: 01:37:09 INFO - "/builds/slave/test"] 01:37:09 INFO - --app=APP Application being installed. [default: firefox] 01:37:09 INFO - mkdir: /builds/slave/test/build/application 01:37:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:37:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 01:37:29 INFO - Reading from file tmpfile_stdout 01:37:29 INFO - Output received: 01:37:29 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:37:29 INFO - Running post-action listener: _resource_record_post_action 01:37:29 INFO - [mozharness: 2017-04-24 08:37:29.314273Z] Finished install step (success) 01:37:29 INFO - [mozharness: 2017-04-24 08:37:29.314405Z] Running run-tests step. 01:37:29 INFO - Running pre-action listener: _resource_record_pre_action 01:37:29 INFO - Running main action method: run_tests 01:37:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:37:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:37:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:37:29 INFO - 2017-04-24 01:37:29.327 screenresolution[1627:7569] starting screenresolution argv=screenresolution get 01:37:29 INFO - 2017-04-24 01:37:29.329 screenresolution[1627:7569] Display 0: 1600x1200x32@60 01:37:29 INFO - 2017-04-24 01:37:29.334 screenresolution[1628:7572] starting screenresolution argv=screenresolution list 01:37:29 INFO - Available Modes on Display 0 01:37:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:37:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:37:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:37:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:37:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:37:29 INFO - Intel Iris: 01:37:29 INFO - Chipset Model: Intel Iris 01:37:29 INFO - Type: GPU 01:37:29 INFO - Bus: Built-In 01:37:29 INFO - VRAM (Dynamic, Max): 1536 MB 01:37:29 INFO - Vendor: Intel (0x8086) 01:37:29 INFO - Device ID: 0x0a2e 01:37:29 INFO - Revision ID: 0x0009 01:37:29 INFO - Displays: 01:37:29 INFO - Display: 01:37:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:37:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:37:29 INFO - Display Serial Number: 1600x1200 60 01:37:29 INFO - Main Display: Yes 01:37:29 INFO - Mirror: Off 01:37:29 INFO - Online: Yes 01:37:29 INFO - Rotation: Supported 01:37:29 INFO - Return code: 0 01:37:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:37:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:37:29 INFO - Python 2.7.3 01:37:29 INFO - Return code: 0 01:37:29 INFO - grabbing minidump binary from tooltool 01:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:29 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 0x105879d30>, '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 0x1057a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10586ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:37:29 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 01:37:29 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 01:37:29 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 01:37:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:37:29 INFO - Return code: 0 01:37:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:37:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:37:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:37:29 INFO - ENV: RUST_BACKTRACE is now 1 01:37:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:37:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:37:29 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/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--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 01:37:29 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/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0320 --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 01:37:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F0U8wSSBcT/Render', 01:37:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:29 INFO - 'HOME': '/Users/cltbld', 01:37:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:29 INFO - 'LOGNAME': 'cltbld', 01:37:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:37:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:29 INFO - 'MOZ_NO_REMOTE': '1', 01:37:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:37:29 INFO - 'NO_EM_RESTART': '1', 01:37:29 INFO - 'PAGER': '/bin/cat', 01:37:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:29 INFO - 'PWD': '/builds/slave/test', 01:37:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:37:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:29 INFO - 'RUST_BACKTRACE': '1', 01:37:29 INFO - 'SHELL': '/bin/bash', 01:37:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m0vTTwjAvC/Listeners', 01:37:29 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 01:37:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:29 INFO - 'USER': 'cltbld', 01:37:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:29 INFO - 'XPC_FLAGS': '0x0', 01:37:29 INFO - 'XPC_SERVICE_NAME': '0', 01:37:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:29 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/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--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 01:37:29 INFO - mozversion application_buildid: 20170424004007 01:37:29 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:37:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:37:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:37:29 INFO - mozversion application_name: Firefox 01:37:29 INFO - mozversion application_remotingname: firefox-dev 01:37:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:37:29 INFO - mozversion application_vendor: Mozilla 01:37:29 INFO - mozversion application_version: 54.0a2 01:37:29 INFO - mozversion platform_buildid: 20170424004007 01:37:29 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:37:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:37:29 INFO - mozversion platform_version: 54.0a2 01:37:29 INFO - using testdate: 1493023049 01:37:29 INFO - actual date: 1493023049 01:37:29 INFO - starting webserver on 'localhost:49244' 01:37:29 INFO - SUITE-START | Running 2 tests 01:37:29 INFO - TEST-START | dromaeo_css 01:37:29 INFO - operating with platform_type : mac_ 01:37:29 INFO - Initialising browser for dromaeo_css test... 01:37:29 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmptw83yk/profile http://localhost:49244/getInfo.html) 01:37:32 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 01:37:32 INFO - PROCESS | 1638 | colorDepth:24 01:37:32 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 01:37:32 INFO - PROCESS | 1638 | __metrics 01:37:32 INFO - PROCESS | 1638 | 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" 01:37:32 INFO - PROCESS | 1638 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:37:33 INFO - TEST-INFO | 1638: exit 0 01:37:33 INFO - Browser initialized. 01:37:33 INFO - Running cycle 1/1 for dromaeo_css test... 01:37:33 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmptw83yk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:37:45 INFO - PROCESS | 1641 | RSS: Main: 214835200 01:37:45 INFO - PROCESS | 1641 | 01:40:49 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 01:40:51 INFO - PROCESS | 1641 | RSS: Main: 407474176 01:40:51 INFO - PROCESS | 1641 | 01:42:35 INFO - PROCESS | 1641 | ************************* 01:42:35 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 01:42:35 INFO - PROCESS | 1641 | 01:42:35 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:42:35 INFO - PROCESS | 1641 | Full stack: 01:42:35 INFO - PROCESS | 1641 | ************************* 01:42:35 INFO - PROCESS | 1641 | ************************* 01:42:35 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 01:42:35 INFO - PROCESS | 1641 | 01:42:35 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:42:35 INFO - PROCESS | 1641 | Full stack: 01:42:35 INFO - PROCESS | 1641 | ************************* 01:42:35 INFO - PROCESS | 1641 | ************************* 01:42:35 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 01:42:35 INFO - PROCESS | 1641 | 01:42:35 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:42:35 INFO - PROCESS | 1641 | Full stack: 01:42:35 INFO - PROCESS | 1641 | ************************* 01:42:35 INFO - PROCESS | 1641 | ************************* 01:42:35 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 01:42:35 INFO - PROCESS | 1641 | 01:42:35 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:42:35 INFO - PROCESS | 1641 | Full stack: 01:42:35 INFO - PROCESS | 1641 | ************************* 01:43:54 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 01:43:55 INFO - PROCESS | 1641 | RSS: Main: 350662656 01:43:55 INFO - PROCESS | 1641 | 01:46:57 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 01:46:58 INFO - PROCESS | 1641 | RSS: Main: 337289216 01:46:58 INFO - PROCESS | 1641 | 01:50:00 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 01:50:01 INFO - PROCESS | 1641 | RSS: Main: 335376384 01:50:01 INFO - PROCESS | 1641 | 01:53:05 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 01:53:05 INFO - PROCESS | 1641 | RSS: Main: 322834432 01:53:05 INFO - PROCESS | 1641 | 01:56:09 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 01:56:09 INFO - PROCESS | 1641 | RSS: Main: 356306944 01:56:09 INFO - PROCESS | 1641 | 01:56:09 INFO - PROCESS | 1641 | __start_tp_report 01:56:09 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 01:56:09 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 01:56:09 INFO - PROCESS | 1641 | |i|pagename|runs| 01:56:09 INFO - PROCESS | 1641 | |0;dojo.html;3232.6928941750525;3407.284470261245;3428.6057103433095;3434.3474739799453;3468.8092154931483;5900.409959004108;5914.645121292726;5924.792632257871;5927.673977931212;5928.9703551482235;5897.20387747654;5959.642421454705;5963.16515687803;5979.641221526712;6000.699965001754;5348.786048558063;5359.410464848864;5393.81121660742;5435;5449.754761035743;5251.343582052244;5257.1851363038595;5274.7626356814035;5465.9726701366435;5505.476979686943;5361.544268737162;5370.275012873251;5403.729813509329;5430.59270554708;5441.673499590018;5411.729413529347;5412.512873841353;5427.891442171134;5430.049741295297;5434.646747961409;5192.506711862367;5298.523132918017;5354.759035843396;5358.812441564565;5423.159410291411;5755.856103597402;5767.88464230713;5775.971120144414;5788.855278618026;5796.942030579725;6226.844328891769;6236.937630623726;6258.499320054385;6293.244810622708;6311.747530098791;6374.266959299694;6401.487880969511;6418.19772528434;6419.743210271584;6435.8391040223905;6062.545309101835;6111.786087486917;6125.663088530129;6134.539909506805;6138.324784273771;6200.627962322275;6520.217573891164;6527.8694426111215;6530.412262896362;6537.771178008747;5842.1236814477315;6217.471514921192;6233.2208473940755;6258.092576576462;6296.401841825019;6387.105805187278;6392.0731493934045;6404.935950640527;6406.839829004312;6424.1327420798525;6254.967930291542;6282.465990390777;6311.810645680637;6319.115323854664;6323.36766323364;5139.820106296336;5249.501297376743;6228;6256.843578910472;6295.370462953668;4409.911801764011;5124.103281925648;5125.974370128126;5130.7178105204575;5164.122099243138;4585.885352866205;4594.035252596926;4595.770211489412;4601.332806743004;4618.769061546909;5772.3938986406665;5778.306603207558;5854.414558544111;6006.399360064046;6023.066424704178;6276.278228003838;6333.113364129025;6346.4605508532295;6353;6423.839404014843;5945;6013.759449621966;6015.909761353583;6016.815947705931;6038.184845045863;5455.399906010336;5511;5649.915251271234;5652.547796176296;5684.971575142098;6348.206474190633;6412.807615771534;6421.646809425526;6425.710843011982;6489.967550162219;6390.968045159744;6419;6424.068510065921;6445.16212892321;6447.742090316522;5632.957902787997;6562.704678289393;6598.538102332983;6625.436837868642;6627;6153.876922461615;6297.338779428094;6316.178896743394;6329.7468101275435;6338.619682819046;6184.288806787097;6196.977498712842;6226.968865155645;6250.312465628868;6303.46420554258;6204.162438070803;6248.343923887926;6254.718537665907;6268.4671802897265;6269.404406581368;6256.311805701278;6273.874522509615;6274.654893980693;6276.497880169667;6286.968565157145;5964.075568286922;5997.010493268736;6127.953177891068;6144.846378840565;6147.893379191788;6124.38756124384;6145.586515101462;6219.751209951733;6223.222097237845;6262.718177682106;6009.06859436788;6117.766507567455;6145.187169785014;6166.630002199882;6198.81403557894 01:56:09 INFO - PROCESS | 1641 | |1;ext.html;663.422822144734;813.528153670871;872.6683860133148;882.8366752150848;886.0165216609566;40107.59892401079;41062.794686026566;42898;42962.14075718484;43021.92445188868;42120.78939605306;42218.57781422185;42385.7880710596;42443.57556424435;42469.57530424695;7328.8167795805;7503.962480187592;7723.806904827382;9396.718098457057;9404.38871473352;11082;11291.266067705614;11458.598949036785;11460.942695286512;11467.770644587103;11313.038391736713;11344.14918881083;11356.772864542705;11357.886421135807;11365.261258018203;7403.814904627374;7583.696652133908;11276.887231127706;11289.266197697165;11299.661010169708;10799.676009719642;11073.667789966314;11197.720056998558;11198.944005280004;11223.214374993753;39586.60413395858;39719.60280397188;39760.602393975976;39788.20423591512;39931.8003409984;3829.6744776694013;3833.9424908626393;3834.2331533693373;3860.1314704191614;3864.130570621616;22621.434464138365;24674.889629966696;25402.475851448973;25690.614640780215;26183.73816261832;2943.411317736461;3016.75865930725;3018.773591980588;3033.59046528718;3151.0546835949353;362282;363102;364738;364741;364947;44887.326690099675;44920;45114.54885451169;45182;45521.317180242324;46925;46945;47017;47036.29445558319;47098.293525597146;12284.525856897051;12801.93599031999;13702.794458083137;13731.794023089662;13745.24401157926;11959.641210763692;11964.282143071443;11971.46128424223;11973.521059157536;11979.28124312544;11059;11744.70638234051;11763.188443183291;11842.052635789116;11872.703182420508;5527.834164975057;5580.13507906267;5580.330360356783;5583.972080139654;5619.522340601095;1138.2828817844704;1143.3997151495532;1157.5543415784985;1169.502961241469;1215.489494412349;25869;27361.86319068392;27578.310542236606;28143.044980277187;28321.008764693135;1224.4612370557104;1245.651217659057;1255.4978008796554;1292.4894666606638;1306.915050521713;10591.57633694659;11747.47136378865;14067.743523200119;14242.85757142436;14533.85466145325;476399;479442;479752.20247797074;480448;481099;56748;57303.71348143233;57343;57452;57465.425345746844;1438.101186758276;1438.3024233247077;1485.071830106162;1488.6873756511254;1494.0736743219275;23984.760152398252;30380.24049398783;31354.21614459657;31462.370752585277;31493.685063149074;49354.50645493499;55702.72148639231;55912.44087559235;55989;56317;461906;462599;464819.6759016183;465068;465241;30768.538471923388;32613.021609351756;32867;33430.49854252237;33448.498272525445;44272.114557708024;45020.54979450163;45298.867528312054;46037.76981115073;46109.769451152526;30643.080707578807;30743.077707668806;30886.382272353978;31511;31833.681663183073;30431.239219019702;30449.695503045572;30577.789332120698;31356.843215783774;31459.685403145675;35425;36747.63252367442;37021.62978370182;37109.81445092757;37286.44070338893;24482.755172448047;30723.078307650805;30950.226244343172;31319.277439741032;32189.517157242193;23182.072717091127;24303.75696243015;24328.75671243336;25000.499989999735;25103.623445647965 01:56:09 INFO - PROCESS | 1641 | |2;jquery.html;387.8138493523111;412.0584464498621;424.4079509084826;432.9112531930954;447.9395281636978;13189.208647481159;14611;20676.172953081896;21022.264220752277;21292.041858116383;21587.244446444376;21968.791716455587;22158.446038849037;22290.88854555725;22419.887900560476;6508.088867558534;7038.577685338896;7328.230535793745;7477.9252207477775;7502.174760776337;7109.253528379523;7260.673269702849;7369.77890663281;7422.331990120888;7502.512336698097;7449.925500745005;7470.2903224193615;7494.063242094739;7504.512206706574;7507.624618769067;7086.114235720535;7383.224761400056;7464.589447580381;7464.6640901159035;7470.327670509626;7053.753118640823;7263.636818159071;7269.89095163573;7317.341439270438;7324.926750732478;25418.36454088644;25775.097871574406;26109.738902611112;26149;26385.868070659715;2846.2599724071893;2908.0984894682715;2933;2959.156640357488;2975.5090410082526;14538.781918271234;15889.443869464629;16122.274497647633;16290.837091629051;16508.83491165097;1950.2004178286977;2027.3715148304072;2027.929022484206;2040.6326861165132;2044.9182032718672;114535;136200;145135.37161571046;146275;146561.53438465478;19010.04949752507;19084.33204837824;19309.4207173785;19555.706664400044;20006.799932000784;29683.554746678816;30096.699033009827;30156.944506942153;30203.640836161987;30275.243118922204;9615.375000624937;9745.025497450199;9957;10025.147862431775;10061.245406594535;8255.587220639065;8269.710560130487;8275.131111233355;8427.241548260687;8444.873326900102;8849.20357167858;9137.862932056025;9233.307501937381;9253.306002049738;9259.76850578741;3770.377887648507;3850.9397472352157;3851.5763266040713;3854.9229015420096;3856.421536769451;1006.7785087280787;1019.1133713669155;1030.294248439821;1034.9327293725885;1048.7325731938306;21339;21874;21886.12455501762;21912.89043554772;21952.780472195394;1050.669039252633;1060.188955449082;1075.8547596074586;1076.3488089705615;1087.9510422031028;10565.841512377321;11420.029297509805;11475.942620287013;11478.196526243082;11484.138689598314;629631;636451;639089;644014;649123;115674.42162789132;137916;141980;148273.77707290393;154949;1132.750794825137;1150.1488898215262;1170.8946194842506;1172.5192671004847;1217.7199244173712;18806.247750089842;19305.903470482557;19987.800121998593;20494.86904197101;20686.482837928572;66385;67129.66435167793;67245.66377168083;67330.66334668295;68861;72902.63548682223;91226;91754;91967;94031;11180.832287515856;11569.016633585903;11637.069034477052;11717.648470545897;12014.219075760047;11172.720681983015;13075.869241307466;13168.868311316764;13290.40193191328;13508.729825403241;11116.388598627153;11338.498318569396;11455.541778328774;11531.365774882126;11626.651200463999;8569.700060498102;10537.89462105369;10761.515731791931;10884.346939183675;10914.23600347968;11443.542258309575;11491.597794077501;11873.525058997544;11975.700607484883;12041.698957526132;11568;11624.244424112405;11733.413329333329;11955.701107472383;11996.040316774812;11212.943935280271;11252.099832013582;11476.655700328669;11568.190226684052;11579.652610421701 01:56:09 INFO - PROCESS | 1641 | |3;mootools.html;1435.5262763288115;1502.1587910769977;1531.946381876634;1555.5566663500902;1557.6573153906136;10200.489975501223;10709.518071686773;10727.892721072787;10733.516991735381;10739.892601074007;10429.739256518591;10924.726881827959;11226.438678066104;11249;11276.267042642243;2323.0591610397814;2387.9283621491295;2390.8685022323766;2391.868447235401;2395.700537432821;2283.178055899875;2342.508073304608;2353.8587684738886;2360.1385494294545;2361.8582885026867;2297.816174706028;2355.9646605300936;2356.528694261146;2360.0559776089617;2370.7510711375317;1976.7447670729048;2292.071710957065;2305.953880922389;2334.3230463165746;2345.343303874909;2336.9649455258186;2339.204670412068;2344.4256157241584;2344.824138189643;2346.0381243690176;11045.668629941114;11144.108471322275;11147.665570032912;11151.776964460665;11157.330560166416;5459.890802183934;5618.438156184389;5626.368353177573;5631.493165615073;5670;3092.134202423325;3169.683031696835;3344.8327583620967;3361.3613413451367;3364.444866597033;1023.4729114506035;1023.9948800255951;1054.2988912373262;1065.387402243713;1072.5066469424075;12474.251544907334;15405.306761195772;15481.922590386976;15500.302486388027;15508;4506.909861802746;4692.272697731857;4772.618190544748;4781.2111001685025;4783.210770222944;11634.243774154822;11955.52177912891;12725.045621578234;12742.426590803436;12785.936070319589;5736.13957906309;5739.569532285095;5742.655440673573;5745.368009518949;5748.223989761413;4926.679765815211;5006.224035274538;5069.340985671839;5097.770600322994;5176.275321454999;3705.7405981581032;3785.6971442284553;3787.981060094737;3834.9808250958567;3846.480725102131;824.3199360527589;979.3438396274513;981.9950900245595;986.9654562090292;992.5483904823288;929.1219797291494;937.9718608441758;960.9807803843884;964.71058682395;1000.6897861662762;5588.357338905997;6408.423241908251;6444;6465.094886715863;6505.51208659343;816.432579357341;900.4417261297906;901.6213190460022;921.2629896083107;940.9529523523934;3621.456781482746;4100.528439229527;4129.69027322952;4171.833126674899;4399;11059.557617695364;13391;13459.394327255297;14000.43998240059;14006.299685015709;11910.404479775976;12328;13646.590602281947;13906.51327203543;13908.304584770722;7823.604911783269;8852.734417967471;8872.24585910192;10138.18894488454;10159.949200253952;5135.589152867837;6659.067730517634;6774.695138718671;6801.081853949653;6922.723091076301;9894.257930655374;11873.762524749629;11927;12155.939220303842;12199.451024703732;10672.57309707603;12137.939310303393;12186.817197742219;12302;12394.75210495767;8358.874616880874;8379.916200838157;8462.534560599226;8471.830563388821;8493.405461617627;10164.390136591532;10201.336913100624;10290.536925838505;10306.536205870603;10321.587136514456;8422.536760478233;8597.226249637562;8657.696980605648;8663.176998185163;8696.826063478822;8387.161283871565;8413.747587572383;8446.366522510714;8461.873071904049;8468.153184681483;7537.54774713519;7742;7745;7755.3407960324;7764.06831180262;5035.899282014412;5807.709614519342;5822.3304320003745;5995.790147344822;6036.9698151508965;4366.519682834822;5173.1722924331925;5897.734601942837;5909.5863289569315;6103.877922441615 01:56:09 INFO - PROCESS | 1641 | |4;prototype.html;363.53649097400813;379.3702453926482;382.82390100553744;391.71600589572563;392.56817500749173;4813.759312034402;5021.924671129931;5101.801076746961;5102.897942041157;5170.974145129278;4373.584509471595;5131.948680513194;5177.508136727014;5181.300524429201;5182.2226666000115;3797.620237976208;4059.817308221137;4088.4276201331836;4107.960109518949;4167.895802604929;3705.962940370602;4210.810513526883;4224.514180869193;4236.131593023439;4327.372530983005;3750.9812450937843;3763.962360376402;3950.696270230817;4243.193793179302;4258.872233832974;4080.9795951020055;4170.845787942292;4304.354346847967;4309.547497512763;4312.137572485515;3422.709069729076;3462.532558104674;3826.349520581508;3893.610638936112;3951.8419263229434;4177.227212965611;4227.4292970448905;4805.903881922377;4988.900221995576;4989.629925785259;3971.5829837867186;4673.369095172142;4691.929621055687;4868.099401610713;4904.449686163211;4360.934585981213;5164.354455693038;5392.838214853521;5485.807996720096;5486.31421072366;3671.76133551321;4125.814338354781;4301.870943871658;4329.502107257643;4348.978255108705;4237.792229214659;4973.428055773632;5203.881165549387;5430.70131142787;5753.849230153986;3658.70730341572;3733.607971163043;3776.169242766587;4185.916281674349;4331.578053337827;3844.6539811417106;4129.483814523184;4193.559676234013;4460.375547423363;4473.932891006638;4198.790060496963;4347.7173983690955;4470.714025191482;4793.616510679207;4888.753367891165;4895.090914543331;4982.501749825061;5358.785648574013;5513.696746678971;5538.972305138529;1380.4607862233538;1500.3398504657916;1504.893902981308;1512.8789696824228;1513.9394424223128;1001.7145113642576;1046.895310468947;1099.5601759296185;1126.189143816451;1157.8610566731877;4524.6380289576755;4914.606831453475;4932.8520144395725;4934.432540257844;4949.356583644103;5572.804951826666;5625.46558076982;5684.317881854204;5684.971575142181;5686.232358631614;4384.6711496637245;4439.356293337512;4669.019505903798;4852.95147048525;4970.726610036482;2401.375642333006;2470.789982851478;2488.9128880489097;2602.375429896849;2604.6223297621736;5140.55582759552;5653.830385088454;5673.375928647845;5696.914546281809;5700.572457065737;4883.974365383309;4925.1873440881855;5426.2674538936735;5653.265075540154;5669.489745922887;4501.549845015537;5124.925684081164;5469.288992430958;5666.688332141772;5809.808989157213;4478.57453541913;4756.857294281177;5283.762230699551;5423.457654234545;5668.914966275427;5166.114368255635;5389.676619402849;5459.317585301837;5643.887122257614;5692.946804840952;5285.537972627935;5463.754131064032;5501.972490137524;5507.091329930517;5842.685395786084;4138.710290279651;4213.768242746556;4545.477270113979;4911.887888208104;4936.456989731055;4842.612590992782;5172.25002374646;5265.60507961912;5563.304586926634;5594.552435805207;4475.63493134584;4512.481064677604;5303.655262407931;5330.78676852937;5360.946390536201;4414.426124603781;5333.546648534918;5337.466253374631;5356.866078348073;5382.30030096085;4803;5086.4486331669195;5274.540850220003;5290.470952904679;5357.089294819812;4656.091002688881;4909.189983652657;5244.868878278074;5371.301730774974;5430.728463576885;4498.662600304925;4936.456989731055;5065.417476990193;5249.921251181159;5265.657732247391 01:56:09 INFO - PROCESS | 1641 | |5;yui.html;710.2506855267691;723.9022731931187;765.2768134113262;819.3813670678637;832.7335252719129;11873.584424545143;15658.84341156588;15658.921705391485;15699.529014129566;15713.764293535605;11767.823482647746;14304.78542821856;14318.069325493836;14428;14483.5654930352;905.1853332001218;909.4725059465527;921.6497730862263;922.8431166701677;930.7812664023949;903.7695387676133;920.9861852072224;922.5110691333571;922.6586163119655;922.9723108306762;984.1585444444968;985.1626117799884;986.3243678080502;986.8075725233582;988.6045581767279;973.8782652168479;973.9513024348754;976.3263348289693;977.6480467031898;977.7555611097225;724.3046675191822;724.90213821134;725.2928394815013;725.365305357812;727.0439372225507;15500.767488487681;15774.605634859106;15831.762523562156;16151.434699785568;16388.508344749676;13343.86656133436;13760.65598360039;14089.718205635932;14092.788608170886;14123.435062597484;11179.217454778169;11575.131865110066;11649;11685.948264656221;11791.823122653168;3510.8946731598094;3609.8375573099274;3727.683146932514;3728.776273423576;3817.2365526894732;87236;109005.9999999992;116845;118734;122698;10278.434686092187;10377.429241391796;10765.192610554239;10773.83839242412;10847.403392813314;17697.646047078986;22225.444363890707;22284.88857555702;22319.888400557895;22349.776502235094;7452.180260171377;7539.886901696479;7640.274173953466;7887.092984306763;7889.053313602404;4262.318029115327;4279.186954478639;4393.560643935645;4433.6009759121835;4534.0478499515475;3227.257730721947;3484.3728128937037;3523.4010218262956;3531.6644918732677;3532.0816587687386;624.1324558863183;629.3926361061598;631.5736877607688;652.8498445357594;655.6361219523116;663.9966800165968;694.5137405951268;695.8016965164903;704.7181127549021;708.7590219325351;8794.42836215644;9915.504224788876;10360.326578772356;10430.739231519121;10529.84205236922;686.7767975408012;691.1395312835451;691.752638797782;692.7159864455549;694.4305669351083;4664.720116793003;4982.451930287665;4987.600991920638;4989.126902791997;4999.42506611737;83731;84285.57857210797;91304.71738206598;104959.4752026235;110355.34466983001;47467.85989876271;78793;81168;82769.17230827735;83574;64631.67684161549;70204.24489387704;71518.2848171522;72179.27820721726;73735.63132184304;11006.669799906016;11042.889571104186;11125.44372781374;11239.494222759831;11325.603603873826;40195.59804402036;45327.77336113298;46727.766361167975;48886.0222795535;50772;58595;63417.36582634299;64410;64511.67744161249;64551;9606.807863842823;10581.62964296246;12046.939765301116;12290.938545307574;12292.815607765711;13574.660633484242;13811;13932.791008134684;14668.413263469769;14673.70652586921;13594.86405135936;13599.59201223965;13711.725765484834;13859.099158554662;14065.437382504586;12479.18648905814;13218;13606.537819136292;13632.179904972681;13672.248026358646;12748;13459.596212113653;13514.256715880721;13600.52398166099;13699.972502062186;9128.908710912805;9253.861192082259;9812.362196457207;9877;10153.64462243818;9004.639814407612;9952.3816661919;10345.751277345285;10651.414172220293;11377.829332559853 01:56:09 INFO - PROCESS | 1641 | __end_tp_report 01:56:09 INFO - PROCESS | 1641 | __start_cc_report 01:56:09 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,2301 01:56:09 INFO - PROCESS | 1641 | __end_cc_report 01:56:09 INFO - PROCESS | 1641 | __startTimestamp1493024169399__endTimestamp 01:56:09 INFO - PROCESS | 1641 | 01:56:09 INFO - PROCESS | 1641 | ------- Summary: start ------- 01:56:09 INFO - PROCESS | 1641 | Number of tests: 6 01:56:09 INFO - PROCESS | 1641 | 01:56:09 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5879.50 Median:6127.95 stddev:649.68 (10.6%) stddev-sans-first:617.60 01:56:09 INFO - PROCESS | 1641 | Values: 3232.7 3407.3 3428.6 3434.3 3468.8 5900.4 5914.6 5924.8 5927.7 5929.0 5897.2 5959.6 5963.2 5979.6 6000.7 5348.8 5359.4 5393.8 5435.0 5449.8 5251.3 5257.2 5274.8 5466.0 5505.5 5361.5 5370.3 5403.7 5430.6 5441.7 5411.7 5412.5 5427.9 5430.0 5434.6 5192.5 5298.5 5354.8 5358.8 5423.2 5755.9 5767.9 5776.0 5788.9 5796.9 6226.8 6236.9 6258.5 6293.2 6311.7 6374.3 6401.5 6418.2 6419.7 6435.8 6062.5 6111.8 6125.7 6134.5 6138.3 6200.6 6520.2 6527.9 6530.4 6537.8 5842.1 6217.5 6233.2 6258.1 6296.4 6387.1 6392.1 6404.9 6406.8 6424.1 6255.0 6282.5 6311.8 6319.1 6323.4 5139.8 5249.5 6228.0 6256.8 6295.4 4409.9 5124.1 5126.0 5130.7 5164.1 4585.9 4594.0 4595.8 4601.3 4618.8 5772.4 5778.3 5854.4 6006.4 6023.1 6276.3 6333.1 6346.5 6353.0 6423.8 5945.0 6013.8 6015.9 6016.8 6038.2 5455.4 5511.0 5649.9 5652.5 5685.0 6348.2 6412.8 6421.6 6425.7 6490.0 6391.0 6419.0 6424.1 6445.2 6447.7 5633.0 6562.7 6598.5 6625.4 6627.0 6153.9 6297.3 6316.2 6329.7 6338.6 6184.3 6197.0 6227.0 6250.3 6303.5 6204.2 6248.3 6254.7 6268.5 6269.4 6256.3 6273.9 6274.7 6276.5 6287.0 5964.1 5997.0 6128.0 6144.8 6147.9 6124.4 6145.6 6219.8 6223.2 6262.7 6009.1 6117.8 6145.2 6166.6 6198.8 01:56:09 INFO - PROCESS | 1641 | 01:56:09 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:57430.12 Median:17331.03 stddev:116498.37 (672.2%) stddev-sans-first:116747.21 01:56:09 INFO - PROCESS | 1641 | Values: 663.4 813.5 872.7 882.8 886.0 40107.6 41062.8 42898.0 42962.1 43021.9 42120.8 42218.6 42385.8 42443.6 42469.6 7328.8 7504.0 7723.8 9396.7 9404.4 11082.0 11291.3 11458.6 11460.9 11467.8 11313.0 11344.1 11356.8 11357.9 11365.3 7403.8 7583.7 11276.9 11289.3 11299.7 10799.7 11073.7 11197.7 11198.9 11223.2 39586.6 39719.6 39760.6 39788.2 39931.8 3829.7 3833.9 3834.2 3860.1 3864.1 22621.4 24674.9 25402.5 25690.6 26183.7 2943.4 3016.8 3018.8 3033.6 3151.1 362282.0 363102.0 364738.0 364741.0 364947.0 44887.3 44920.0 45114.5 45182.0 45521.3 46925.0 46945.0 47017.0 47036.3 47098.3 12284.5 12801.9 13702.8 13731.8 13745.2 11959.6 11964.3 11971.5 11973.5 11979.3 11059.0 11744.7 11763.2 11842.1 11872.7 5527.8 5580.1 5580.3 5584.0 5619.5 1138.3 1143.4 1157.6 1169.5 1215.5 25869.0 27361.9 27578.3 28143.0 28321.0 1224.5 1245.7 1255.5 1292.5 1306.9 10591.6 11747.5 14067.7 14242.9 14533.9 476399.0 479442.0 479752.2 480448.0 481099.0 56748.0 57303.7 57343.0 57452.0 57465.4 1438.1 1438.3 1485.1 1488.7 1494.1 23984.8 30380.2 31354.2 31462.4 31493.7 49354.5 55702.7 55912.4 55989.0 56317.0 461906.0 462599.0 464819.7 465068.0 465241.0 30768.5 32613.0 32867.0 33430.5 33448.5 44272.1 45020.5 45298.9 46037.8 46109.8 30643.1 30743.1 30886.4 31511.0 31833.7 30431.2 30449.7 30577.8 31356.8 31459.7 35425.0 36747.6 37021.6 37109.8 37286.4 24482.8 30723.1 30950.2 31319.3 32189.5 23182.1 24303.8 24328.8 25000.5 25103.6 01:56:09 INFO - PROCESS | 1641 | 01:56:09 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:39306.41 Median:21315.52 stddev:107064.53 (502.3%) stddev-sans-first:107325.00 01:56:09 INFO - PROCESS | 1641 | Values: 387.8 412.1 424.4 432.9 447.9 13189.2 14611.0 20676.2 21022.3 21292.0 21587.2 21968.8 22158.4 22290.9 22419.9 6508.1 7038.6 7328.2 7477.9 7502.2 7109.3 7260.7 7369.8 7422.3 7502.5 7449.9 7470.3 7494.1 7504.5 7507.6 7086.1 7383.2 7464.6 7464.7 7470.3 7053.8 7263.6 7269.9 7317.3 7324.9 25418.4 25775.1 26109.7 26149.0 26385.9 2846.3 2908.1 2933.0 2959.2 2975.5 14538.8 15889.4 16122.3 16290.8 16508.8 1950.2 2027.4 2027.9 2040.6 2044.9 114535.0 136200.0 145135.4 146275.0 146561.5 19010.0 19084.3 19309.4 19555.7 20006.8 29683.6 30096.7 30156.9 30203.6 30275.2 9615.4 9745.0 9957.0 10025.1 10061.2 8255.6 8269.7 8275.1 8427.2 8444.9 8849.2 9137.9 9233.3 9253.3 9259.8 3770.4 3850.9 3851.6 3854.9 3856.4 1006.8 1019.1 1030.3 1034.9 1048.7 21339.0 21874.0 21886.1 21912.9 21952.8 1050.7 1060.2 1075.9 1076.3 1088.0 10565.8 11420.0 11475.9 11478.2 11484.1 629631.0 636451.0 639089.0 644014.0 649123.0 115674.4 137916.0 141980.0 148273.8 154949.0 1132.8 1150.1 1170.9 1172.5 1217.7 18806.2 19305.9 19987.8 20494.9 20686.5 66385.0 67129.7 67245.7 67330.7 68861.0 72902.6 91226.0 91754.0 91967.0 94031.0 11180.8 11569.0 11637.1 11717.6 12014.2 11172.7 13075.9 13168.9 13290.4 13508.7 11116.4 11338.5 11455.5 11531.4 11626.7 8569.7 10537.9 10761.5 10884.3 10914.2 11443.5 11491.6 11873.5 11975.7 12041.7 11568.0 11624.2 11733.4 11955.7 11996.0 11212.9 11252.1 11476.7 11568.2 11579.7 01:56:09 INFO - PROCESS | 1641 | 01:56:09 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:6548.64 Median:3362.90 stddev:4146.88 (123.3%) stddev-sans-first:4140.72 01:56:09 INFO - PROCESS | 1641 | Values: 1435.5 1502.2 1531.9 1555.6 1557.7 10200.5 10709.5 10727.9 10733.5 10739.9 10429.7 10924.7 11226.4 11249.0 11276.3 2323.1 2387.9 2390.9 2391.9 2395.7 2283.2 2342.5 2353.9 2360.1 2361.9 2297.8 2356.0 2356.5 2360.1 2370.8 1976.7 2292.1 2306.0 2334.3 2345.3 2337.0 2339.2 2344.4 2344.8 2346.0 11045.7 11144.1 11147.7 11151.8 11157.3 5459.9 5618.4 5626.4 5631.5 5670.0 3092.1 3169.7 3344.8 3361.4 3364.4 1023.5 1024.0 1054.3 1065.4 1072.5 12474.3 15405.3 15481.9 15500.3 15508.0 4506.9 4692.3 4772.6 4781.2 4783.2 11634.2 11955.5 12725.0 12742.4 12785.9 5736.1 5739.6 5742.7 5745.4 5748.2 4926.7 5006.2 5069.3 5097.8 5176.3 3705.7 3785.7 3788.0 3835.0 3846.5 824.3 979.3 982.0 987.0 992.5 929.1 938.0 961.0 964.7 1000.7 5588.4 6408.4 6444.0 6465.1 6505.5 816.4 900.4 901.6 921.3 941.0 3621.5 4100.5 4129.7 4171.8 4399.0 11059.6 13391.0 13459.4 14000.4 14006.3 11910.4 12328.0 13646.6 13906.5 13908.3 7823.6 8852.7 8872.2 10138.2 10159.9 5135.6 6659.1 6774.7 6801.1 6922.7 9894.3 11873.8 11927.0 12155.9 12199.5 10672.6 12137.9 12186.8 12302.0 12394.8 8358.9 8379.9 8462.5 8471.8 8493.4 10164.4 10201.3 10290.5 10306.5 10321.6 8422.5 8597.2 8657.7 8663.2 8696.8 8387.2 8413.7 8446.4 8461.9 8468.2 7537.5 7742.0 7745.0 7755.3 7764.1 5035.9 5807.7 5822.3 5995.8 6037.0 4366.5 5173.2 5897.7 5909.6 6103.9 01:56:09 INFO - PROCESS | 1641 | 01:56:09 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4446.51 Median:4847.78 stddev:1259.87 (26.0%) stddev-sans-first:1225.56 01:56:09 INFO - PROCESS | 1641 | Values: 363.5 379.4 382.8 391.7 392.6 4813.8 5021.9 5101.8 5102.9 5171.0 4373.6 5131.9 5177.5 5181.3 5182.2 3797.6 4059.8 4088.4 4108.0 4167.9 3706.0 4210.8 4224.5 4236.1 4327.4 3751.0 3764.0 3950.7 4243.2 4258.9 4081.0 4170.8 4304.4 4309.5 4312.1 3422.7 3462.5 3826.3 3893.6 3951.8 4177.2 4227.4 4805.9 4988.9 4989.6 3971.6 4673.4 4691.9 4868.1 4904.4 4360.9 5164.4 5392.8 5485.8 5486.3 3671.8 4125.8 4301.9 4329.5 4349.0 4237.8 4973.4 5203.9 5430.7 5753.8 3658.7 3733.6 3776.2 4185.9 4331.6 3844.7 4129.5 4193.6 4460.4 4473.9 4198.8 4347.7 4470.7 4793.6 4888.8 4895.1 4982.5 5358.8 5513.7 5539.0 1380.5 1500.3 1504.9 1512.9 1513.9 1001.7 1046.9 1099.6 1126.2 1157.9 4524.6 4914.6 4932.9 4934.4 4949.4 5572.8 5625.5 5684.3 5685.0 5686.2 4384.7 4439.4 4669.0 4853.0 4970.7 2401.4 2470.8 2488.9 2602.4 2604.6 5140.6 5653.8 5673.4 5696.9 5700.6 4884.0 4925.2 5426.3 5653.3 5669.5 4501.5 5124.9 5469.3 5666.7 5809.8 4478.6 4756.9 5283.8 5423.5 5668.9 5166.1 5389.7 5459.3 5643.9 5692.9 5285.5 5463.8 5502.0 5507.1 5842.7 4138.7 4213.8 4545.5 4911.9 4936.5 4842.6 5172.3 5265.6 5563.3 5594.6 4475.6 4512.5 5303.7 5330.8 5360.9 4414.4 5333.5 5337.5 5356.9 5382.3 4803.0 5086.4 5274.5 5290.5 5357.1 4656.1 4909.2 5244.9 5371.3 5430.7 4498.7 4936.5 5065.4 5249.9 5265.7 01:56:09 INFO - PROCESS | 1641 | 01:56:09 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19476.91 Median:4413.58 stddev:27886.77 (631.8%) stddev-sans-first:27929.40 01:56:09 INFO - PROCESS | 1641 | Values: 710.3 723.9 765.3 819.4 832.7 11873.6 15658.8 15658.9 15699.5 15713.8 11767.8 14304.8 14318.1 14428.0 14483.6 905.2 909.5 921.6 922.8 930.8 903.8 921.0 922.5 922.7 923.0 984.2 985.2 986.3 986.8 988.6 973.9 974.0 976.3 977.6 977.8 724.3 724.9 725.3 725.4 727.0 15500.8 15774.6 15831.8 16151.4 16388.5 13343.9 13760.7 14089.7 14092.8 14123.4 11179.2 11575.1 11649.0 11685.9 11791.8 3510.9 3609.8 3727.7 3728.8 3817.2 87236.0 109006.0 116845.0 118734.0 122698.0 10278.4 10377.4 10765.2 10773.8 10847.4 17697.6 22225.4 22284.9 22319.9 22349.8 7452.2 7539.9 7640.3 7887.1 7889.1 4262.3 4279.2 4393.6 4433.6 4534.0 3227.3 3484.4 3523.4 3531.7 3532.1 624.1 629.4 631.6 652.8 655.6 664.0 694.5 695.8 704.7 708.8 8794.4 9915.5 10360.3 10430.7 10529.8 686.8 691.1 691.8 692.7 694.4 4664.7 4982.5 4987.6 4989.1 4999.4 83731.0 84285.6 91304.7 104959.5 110355.3 47467.9 78793.0 81168.0 82769.2 83574.0 64631.7 70204.2 71518.3 72179.3 73735.6 11006.7 11042.9 11125.4 11239.5 11325.6 40195.6 45327.8 46727.8 48886.0 50772.0 58595.0 63417.4 64410.0 64511.7 64551.0 9606.8 10581.6 12046.9 12290.9 12292.8 13574.7 13811.0 13932.8 14668.4 14673.7 13594.9 13599.6 13711.7 13859.1 14065.4 12479.2 13218.0 13606.5 13632.2 13672.2 12748.0 13459.6 13514.3 13600.5 13700.0 9128.9 9253.9 9812.4 9877.0 10153.6 9004.6 9952.4 10345.8 10651.4 11377.8 01:56:09 INFO - PROCESS | 1641 | -------- Summary: end -------- 01:56:09 INFO - PROCESS | 1641 | 01:56:10 INFO - TEST-INFO | 1641: exit 0 01:56:10 INFO - TEST-OK | dromaeo_css | took 1120178ms 01:56:10 INFO - TEST-START | kraken 01:56:10 INFO - operating with platform_type : mac_ 01:56:10 INFO - Initialising browser for kraken test... 01:56:10 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpVKAbUu/profile http://localhost:49244/getInfo.html) 01:56:12 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 01:56:12 INFO - PROCESS | 1651 | colorDepth:24 01:56:12 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 01:56:12 INFO - PROCESS | 1651 | __metrics 01:56:12 INFO - PROCESS | 1651 | 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" 01:56:12 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:56:13 INFO - TEST-INFO | 1651: exit 0 01:56:13 INFO - Browser initialized. 01:56:13 INFO - Running cycle 1/1 for kraken test... 01:56:13 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpVKAbUu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:56:25 INFO - PROCESS | 1653 | RSS: Main: 214818816 01:56:25 INFO - PROCESS | 1653 | 01:56:28 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 01:56:29 INFO - PROCESS | 1653 | RSS: Main: 268144640 01:56:29 INFO - PROCESS | 1653 | 01:56:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 01:56:32 INFO - PROCESS | 1653 | RSS: Main: 279224320 01:56:32 INFO - PROCESS | 1653 | 01:56:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 01:56:36 INFO - PROCESS | 1653 | RSS: Main: 377167872 01:56:36 INFO - PROCESS | 1653 | 01:56:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 01:56:38 INFO - PROCESS | 1653 | RSS: Main: 278368256 01:56:38 INFO - PROCESS | 1653 | 01:56:40 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:56:41 INFO - PROCESS | 1653 | RSS: Main: 280932352 01:56:41 INFO - PROCESS | 1653 | 01:56:44 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 01:56:44 INFO - PROCESS | 1653 | RSS: Main: 320032768 01:56:44 INFO - PROCESS | 1653 | 01:56:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 01:56:48 INFO - PROCESS | 1653 | RSS: Main: 322445312 01:56:48 INFO - PROCESS | 1653 | 01:56:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 01:56:52 INFO - PROCESS | 1653 | RSS: Main: 317698048 01:56:52 INFO - PROCESS | 1653 | 01:56:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:56:54 INFO - PROCESS | 1653 | RSS: Main: 297000960 01:56:54 INFO - PROCESS | 1653 | 01:56:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:56:56 INFO - PROCESS | 1653 | RSS: Main: 300843008 01:56:56 INFO - PROCESS | 1653 | 01:56:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:56:59 INFO - PROCESS | 1653 | RSS: Main: 303538176 01:56:59 INFO - PROCESS | 1653 | 01:57:02 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:57:03 INFO - PROCESS | 1653 | RSS: Main: 318390272 01:57:03 INFO - PROCESS | 1653 | 01:57:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:57:06 INFO - PROCESS | 1653 | RSS: Main: 311894016 01:57:06 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 01:57:08 INFO - PROCESS | 1653 | RSS: Main: 356278272 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | __start_tp_report 01:57:08 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 01:57:08 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 01:57:08 INFO - PROCESS | 1653 | |i|pagename|runs| 01:57:08 INFO - PROCESS | 1653 | |0;ai-astar;116;114;115;114;114;115;114;114;114;114 01:57:08 INFO - PROCESS | 1653 | |1;audio-beat-detection;138;109;107;140;105;109;106;110;131;114 01:57:08 INFO - PROCESS | 1653 | |2;audio-dft;183;184;184;184;181;184;183;185;183;182 01:57:08 INFO - PROCESS | 1653 | |3;audio-fft;82;60;61;92;62;61;59;61;77;60 01:57:08 INFO - PROCESS | 1653 | |4;audio-oscillator;87;70;87;70;69;68;81;68;69;83 01:57:08 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;88;87;86;86;87;86;87;87;87;86 01:57:08 INFO - PROCESS | 1653 | |6;imaging-darkroom;94;93;93;95;93;93;94;94;93;93 01:57:08 INFO - PROCESS | 1653 | |7;imaging-desaturate;70;67;130;136;135;83;85;83;86;85 01:57:08 INFO - PROCESS | 1653 | |8;json-parse-financial;60;62;63;62;66;63;64;63;64;63 01:57:08 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;53;62;67;66;60;56;58;57;57;58 01:57:08 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;82;91;103;102;102;82;86;82;85;86 01:57:08 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;140;136;147;126;144;137;149;141;182;136 01:57:08 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;166;165;163;162;159;218;205;165;165;163 01:57:08 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;65;64;65;61;65;64;65;63;62;63 01:57:08 INFO - PROCESS | 1653 | __end_tp_report 01:57:08 INFO - PROCESS | 1653 | __start_cc_report 01:57:08 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,2098 01:57:08 INFO - PROCESS | 1653 | __end_cc_report 01:57:08 INFO - PROCESS | 1653 | __startTimestamp1493024228920__endTimestamp 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | ------- Summary: start ------- 01:57:08 INFO - PROCESS | 1653 | Number of tests: 14 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:114.40 Median:114.00 stddev:0.70 (0.6%) stddev-sans-first:0.44 01:57:08 INFO - PROCESS | 1653 | Values: 116.0 114.0 115.0 114.0 114.0 115.0 114.0 114.0 114.0 114.0 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:116.90 Median:112.00 stddev:13.81 (12.3%) stddev-sans-first:12.36 01:57:08 INFO - PROCESS | 1653 | Values: 138.0 109.0 107.0 140.0 105.0 109.0 106.0 110.0 131.0 114.0 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:1.16 (0.6%) stddev-sans-first:1.22 01:57:08 INFO - PROCESS | 1653 | Values: 183.0 184.0 184.0 184.0 181.0 184.0 183.0 185.0 183.0 182.0 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:67.50 Median:61.50 stddev:11.75 (19.1%) stddev-sans-first:11.23 01:57:08 INFO - PROCESS | 1653 | Values: 82.0 60.0 61.0 92.0 62.0 61.0 59.0 61.0 77.0 60.0 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:75.50 stddev:8.22 (10.9%) stddev-sans-first:7.52 01:57:08 INFO - PROCESS | 1653 | Values: 87.0 70.0 87.0 70.0 69.0 68.0 81.0 68.0 69.0 83.0 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.00 stddev:0.67 (0.8%) stddev-sans-first:0.53 01:57:08 INFO - PROCESS | 1653 | Values: 88.0 87.0 86.0 86.0 87.0 86.0 87.0 87.0 87.0 86.0 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:93.50 stddev:0.71 (0.8%) stddev-sans-first:0.73 01:57:08 INFO - PROCESS | 1653 | Values: 94.0 93.0 93.0 95.0 93.0 93.0 94.0 94.0 93.0 93.0 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:83.00 stddev:26.82 (32.3%) stddev-sans-first:26.75 01:57:08 INFO - PROCESS | 1653 | Values: 70.0 67.0 130.0 136.0 135.0 83.0 85.0 83.0 86.0 85.0 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:63.00 Median:63.00 stddev:1.56 (2.5%) stddev-sans-first:1.22 01:57:08 INFO - PROCESS | 1653 | Values: 60.0 62.0 63.0 62.0 66.0 63.0 64.0 63.0 64.0 63.0 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.00 stddev:4.43 (7.5%) stddev-sans-first:4.04 01:57:08 INFO - PROCESS | 1653 | Values: 53.0 62.0 67.0 66.0 60.0 56.0 58.0 57.0 57.0 58.0 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:83.50 stddev:8.86 (10.6%) stddev-sans-first:8.90 01:57:08 INFO - PROCESS | 1653 | Values: 82.0 91.0 103.0 102.0 102.0 82.0 86.0 82.0 85.0 86.0 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:143.80 Median:142.50 stddev:14.92 (10.5%) stddev-sans-first:15.76 01:57:08 INFO - PROCESS | 1653 | Values: 140.0 136.0 147.0 126.0 144.0 137.0 149.0 141.0 182.0 136.0 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:165.00 stddev:20.57 (12.5%) stddev-sans-first:21.65 01:57:08 INFO - PROCESS | 1653 | Values: 166.0 165.0 163.0 162.0 159.0 218.0 205.0 165.0 165.0 163.0 01:57:08 INFO - PROCESS | 1653 | 01:57:08 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:64.50 stddev:1.42 (2.2%) stddev-sans-first:1.42 01:57:08 INFO - PROCESS | 1653 | Values: 65.0 64.0 65.0 61.0 65.0 64.0 65.0 63.0 62.0 63.0 01:57:08 INFO - PROCESS | 1653 | -------- Summary: end -------- 01:57:08 INFO - PROCESS | 1653 | 01:57:09 INFO - TEST-INFO | 1653: exit 0 01:57:09 INFO - TEST-OK | kraken | took 59877ms 01:57:09 INFO - SUITE-END | took 1180s 01:57:10 INFO - Completed test suite (00:19:40) 01:57:10 INFO - javascript benchmark 01:57:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7602.799356161291, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3232.6928941750525, 3407.284470261245, 3428.6057103433095, 3434.3474739799453, 3468.8092154931483, 5900.409959004108, 5914.645121292726, 5924.792632257871, 5927.673977931212, 5928.9703551482235, 5897.20387747654, 5959.642421454705, 5963.16515687803, 5979.641221526712, 6000.699965001754, 5348.786048558063, 5359.410464848864, 5393.81121660742, 5435.0, 5449.754761035743, 5251.343582052244, 5257.1851363038595, 5274.7626356814035, 5465.9726701366435, 5505.476979686943, 5361.544268737162, 5370.275012873251, 5403.729813509329, 5430.59270554708, 5441.673499590018, 5411.729413529347, 5412.512873841353, 5427.891442171134, 5430.049741295297, 5434.646747961409, 5192.506711862367, 5298.523132918017, 5354.759035843396, 5358.812441564565, 5423.159410291411, 5755.856103597402, 5767.88464230713, 5775.971120144414, 5788.855278618026, 5796.942030579725, 6226.844328891769, 6236.937630623726, 6258.499320054385, 6293.244810622708, 6311.747530098791, 6374.266959299694, 6401.487880969511, 6418.19772528434, 6419.743210271584, 6435.8391040223905, 6062.545309101835, 6111.786087486917, 6125.663088530129, 6134.539909506805, 6138.324784273771, 6200.627962322275, 6520.217573891164, 6527.8694426111215, 6530.412262896362, 6537.771178008747, 5842.1236814477315, 6217.471514921192, 6233.2208473940755, 6258.092576576462, 6296.401841825019, 6387.105805187278, 6392.0731493934045, 6404.935950640527, 6406.839829004312, 6424.1327420798525, 6254.967930291542, 6282.465990390777, 6311.810645680637, 6319.115323854664, 6323.36766323364, 5139.820106296336, 5249.501297376743, 6228.0, 6256.843578910472, 6295.370462953668, 4409.911801764011, 5124.103281925648, 5125.974370128126, 5130.7178105204575, 5164.122099243138, 4585.885352866205, 4594.035252596926, 4595.770211489412, 4601.332806743004, 4618.769061546909, 5772.3938986406665, 5778.306603207558, 5854.414558544111, 6006.399360064046, 6023.066424704178, 6276.278228003838, 6333.113364129025, 6346.4605508532295, 6353.0, 6423.839404014843, 5945.0, 6013.759449621966, 6015.909761353583, 6016.815947705931, 6038.184845045863, 5455.399906010336, 5511.0, 5649.915251271234, 5652.547796176296, 5684.971575142098, 6348.206474190633, 6412.807615771534, 6421.646809425526, 6425.710843011982, 6489.967550162219, 6390.968045159744, 6419.0, 6424.068510065921, 6445.16212892321, 6447.742090316522, 5632.957902787997, 6562.704678289393, 6598.538102332983, 6625.436837868642, 6627.0, 6153.876922461615, 6297.338779428094, 6316.178896743394, 6329.7468101275435, 6338.619682819046, 6184.288806787097, 6196.977498712842, 6226.968865155645, 6250.312465628868, 6303.46420554258, 6204.162438070803, 6248.343923887926, 6254.718537665907, 6268.4671802897265, 6269.404406581368, 6256.311805701278, 6273.874522509615, 6274.654893980693, 6276.497880169667, 6286.968565157145, 5964.075568286922, 5997.010493268736, 6127.953177891068, 6144.846378840565, 6147.893379191788, 6124.38756124384, 6145.586515101462, 6219.751209951733, 6223.222097237845, 6262.718177682106, 6009.06859436788, 6117.766507567455, 6145.187169785014, 6166.630002199882, 6198.81403557894], "value": 5838.199772786356, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.422822144734, 813.528153670871, 872.6683860133148, 882.8366752150848, 886.0165216609566, 40107.59892401079, 41062.794686026566, 42898.0, 42962.14075718484, 43021.92445188868, 42120.78939605306, 42218.57781422185, 42385.7880710596, 42443.57556424435, 42469.57530424695, 7328.8167795805, 7503.962480187592, 7723.806904827382, 9396.718098457057, 9404.38871473352, 11082.0, 11291.266067705614, 11458.598949036785, 11460.942695286512, 11467.770644587103, 11313.038391736713, 11344.14918881083, 11356.772864542705, 11357.886421135807, 11365.261258018203, 7403.814904627374, 7583.696652133908, 11276.887231127706, 11289.266197697165, 11299.661010169708, 10799.676009719642, 11073.667789966314, 11197.720056998558, 11198.944005280004, 11223.214374993753, 39586.60413395858, 39719.60280397188, 39760.602393975976, 39788.20423591512, 39931.8003409984, 3829.6744776694013, 3833.9424908626393, 3834.2331533693373, 3860.1314704191614, 3864.130570621616, 22621.434464138365, 24674.889629966696, 25402.475851448973, 25690.614640780215, 26183.73816261832, 2943.411317736461, 3016.75865930725, 3018.773591980588, 3033.59046528718, 3151.0546835949353, 362282.0, 363102.0, 364738.0, 364741.0, 364947.0, 44887.326690099675, 44920.0, 45114.54885451169, 45182.0, 45521.317180242324, 46925.0, 46945.0, 47017.0, 47036.29445558319, 47098.293525597146, 12284.525856897051, 12801.93599031999, 13702.794458083137, 13731.794023089662, 13745.24401157926, 11959.641210763692, 11964.282143071443, 11971.46128424223, 11973.521059157536, 11979.28124312544, 11059.0, 11744.70638234051, 11763.188443183291, 11842.052635789116, 11872.703182420508, 5527.834164975057, 5580.13507906267, 5580.330360356783, 5583.972080139654, 5619.522340601095, 1138.2828817844704, 1143.3997151495532, 1157.5543415784985, 1169.502961241469, 1215.489494412349, 25869.0, 27361.86319068392, 27578.310542236606, 28143.044980277187, 28321.008764693135, 1224.4612370557104, 1245.651217659057, 1255.4978008796554, 1292.4894666606638, 1306.915050521713, 10591.57633694659, 11747.47136378865, 14067.743523200119, 14242.85757142436, 14533.85466145325, 476399.0, 479442.0, 479752.20247797074, 480448.0, 481099.0, 56748.0, 57303.71348143233, 57343.0, 57452.0, 57465.425345746844, 1438.101186758276, 1438.3024233247077, 1485.071830106162, 1488.6873756511254, 1494.0736743219275, 23984.760152398252, 30380.24049398783, 31354.21614459657, 31462.370752585277, 31493.685063149074, 49354.50645493499, 55702.72148639231, 55912.44087559235, 55989.0, 56317.0, 461906.0, 462599.0, 464819.6759016183, 465068.0, 465241.0, 30768.538471923388, 32613.021609351756, 32867.0, 33430.49854252237, 33448.498272525445, 44272.114557708024, 45020.54979450163, 45298.867528312054, 46037.76981115073, 46109.769451152526, 30643.080707578807, 30743.077707668806, 30886.382272353978, 31511.0, 31833.681663183073, 30431.239219019702, 30449.695503045572, 30577.789332120698, 31356.843215783774, 31459.685403145675, 35425.0, 36747.63252367442, 37021.62978370182, 37109.81445092757, 37286.44070338893, 24482.755172448047, 30723.078307650805, 30950.226244343172, 31319.277439741032, 32189.517157242193, 23182.072717091127, 24303.75696243015, 24328.75671243336, 25000.499989999735, 25103.623445647965], "value": 18932.458120693565, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.8138493523111, 412.0584464498621, 424.4079509084826, 432.9112531930954, 447.9395281636978, 13189.208647481159, 14611.0, 20676.172953081896, 21022.264220752277, 21292.041858116383, 21587.244446444376, 21968.791716455587, 22158.446038849037, 22290.88854555725, 22419.887900560476, 6508.088867558534, 7038.577685338896, 7328.230535793745, 7477.9252207477775, 7502.174760776337, 7109.253528379523, 7260.673269702849, 7369.77890663281, 7422.331990120888, 7502.512336698097, 7449.925500745005, 7470.2903224193615, 7494.063242094739, 7504.512206706574, 7507.624618769067, 7086.114235720535, 7383.224761400056, 7464.589447580381, 7464.6640901159035, 7470.327670509626, 7053.753118640823, 7263.636818159071, 7269.89095163573, 7317.341439270438, 7324.926750732478, 25418.36454088644, 25775.097871574406, 26109.738902611112, 26149.0, 26385.868070659715, 2846.2599724071893, 2908.0984894682715, 2933.0, 2959.156640357488, 2975.5090410082526, 14538.781918271234, 15889.443869464629, 16122.274497647633, 16290.837091629051, 16508.83491165097, 1950.2004178286977, 2027.3715148304072, 2027.929022484206, 2040.6326861165132, 2044.9182032718672, 114535.0, 136200.0, 145135.37161571046, 146275.0, 146561.53438465478, 19010.04949752507, 19084.33204837824, 19309.4207173785, 19555.706664400044, 20006.799932000784, 29683.554746678816, 30096.699033009827, 30156.944506942153, 30203.640836161987, 30275.243118922204, 9615.375000624937, 9745.025497450199, 9957.0, 10025.147862431775, 10061.245406594535, 8255.587220639065, 8269.710560130487, 8275.131111233355, 8427.241548260687, 8444.873326900102, 8849.20357167858, 9137.862932056025, 9233.307501937381, 9253.306002049738, 9259.76850578741, 3770.377887648507, 3850.9397472352157, 3851.5763266040713, 3854.9229015420096, 3856.421536769451, 1006.7785087280787, 1019.1133713669155, 1030.294248439821, 1034.9327293725885, 1048.7325731938306, 21339.0, 21874.0, 21886.12455501762, 21912.89043554772, 21952.780472195394, 1050.669039252633, 1060.188955449082, 1075.8547596074586, 1076.3488089705615, 1087.9510422031028, 10565.841512377321, 11420.029297509805, 11475.942620287013, 11478.196526243082, 11484.138689598314, 629631.0, 636451.0, 639089.0, 644014.0, 649123.0, 115674.42162789132, 137916.0, 141980.0, 148273.77707290393, 154949.0, 1132.750794825137, 1150.1488898215262, 1170.8946194842506, 1172.5192671004847, 1217.7199244173712, 18806.247750089842, 19305.903470482557, 19987.800121998593, 20494.86904197101, 20686.482837928572, 66385.0, 67129.66435167793, 67245.66377168083, 67330.66334668295, 68861.0, 72902.63548682223, 91226.0, 91754.0, 91967.0, 94031.0, 11180.832287515856, 11569.016633585903, 11637.069034477052, 11717.648470545897, 12014.219075760047, 11172.720681983015, 13075.869241307466, 13168.868311316764, 13290.40193191328, 13508.729825403241, 11116.388598627153, 11338.498318569396, 11455.541778328774, 11531.365774882126, 11626.651200463999, 8569.700060498102, 10537.89462105369, 10761.515731791931, 10884.346939183675, 10914.23600347968, 11443.542258309575, 11491.597794077501, 11873.525058997544, 11975.700607484883, 12041.698957526132, 11568.0, 11624.244424112405, 11733.413329333329, 11955.701107472383, 11996.040316774812, 11212.943935280271, 11252.099832013582, 11476.655700328669, 11568.190226684052, 11579.652610421701], "value": 11565.259122479325, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1435.5262763288115, 1502.1587910769977, 1531.946381876634, 1555.5566663500902, 1557.6573153906136, 10200.489975501223, 10709.518071686773, 10727.892721072787, 10733.516991735381, 10739.892601074007, 10429.739256518591, 10924.726881827959, 11226.438678066104, 11249.0, 11276.267042642243, 2323.0591610397814, 2387.9283621491295, 2390.8685022323766, 2391.868447235401, 2395.700537432821, 2283.178055899875, 2342.508073304608, 2353.8587684738886, 2360.1385494294545, 2361.8582885026867, 2297.816174706028, 2355.9646605300936, 2356.528694261146, 2360.0559776089617, 2370.7510711375317, 1976.7447670729048, 2292.071710957065, 2305.953880922389, 2334.3230463165746, 2345.343303874909, 2336.9649455258186, 2339.204670412068, 2344.4256157241584, 2344.824138189643, 2346.0381243690176, 11045.668629941114, 11144.108471322275, 11147.665570032912, 11151.776964460665, 11157.330560166416, 5459.890802183934, 5618.438156184389, 5626.368353177573, 5631.493165615073, 5670.0, 3092.134202423325, 3169.683031696835, 3344.8327583620967, 3361.3613413451367, 3364.444866597033, 1023.4729114506035, 1023.9948800255951, 1054.2988912373262, 1065.387402243713, 1072.5066469424075, 12474.251544907334, 15405.306761195772, 15481.922590386976, 15500.302486388027, 15508.0, 4506.909861802746, 4692.272697731857, 4772.618190544748, 4781.2111001685025, 4783.210770222944, 11634.243774154822, 11955.52177912891, 12725.045621578234, 12742.426590803436, 12785.936070319589, 5736.13957906309, 5739.569532285095, 5742.655440673573, 5745.368009518949, 5748.223989761413, 4926.679765815211, 5006.224035274538, 5069.340985671839, 5097.770600322994, 5176.275321454999, 3705.7405981581032, 3785.6971442284553, 3787.981060094737, 3834.9808250958567, 3846.480725102131, 824.3199360527589, 979.3438396274513, 981.9950900245595, 986.9654562090292, 992.5483904823288, 929.1219797291494, 937.9718608441758, 960.9807803843884, 964.71058682395, 1000.6897861662762, 5588.357338905997, 6408.423241908251, 6444.0, 6465.094886715863, 6505.51208659343, 816.432579357341, 900.4417261297906, 901.6213190460022, 921.2629896083107, 940.9529523523934, 3621.456781482746, 4100.528439229527, 4129.69027322952, 4171.833126674899, 4399.0, 11059.557617695364, 13391.0, 13459.394327255297, 14000.43998240059, 14006.299685015709, 11910.404479775976, 12328.0, 13646.590602281947, 13906.51327203543, 13908.304584770722, 7823.604911783269, 8852.734417967471, 8872.24585910192, 10138.18894488454, 10159.949200253952, 5135.589152867837, 6659.067730517634, 6774.695138718671, 6801.081853949653, 6922.723091076301, 9894.257930655374, 11873.762524749629, 11927.0, 12155.939220303842, 12199.451024703732, 10672.57309707603, 12137.939310303393, 12186.817197742219, 12302.0, 12394.75210495767, 8358.874616880874, 8379.916200838157, 8462.534560599226, 8471.830563388821, 8493.405461617627, 10164.390136591532, 10201.336913100624, 10290.536925838505, 10306.536205870603, 10321.587136514456, 8422.536760478233, 8597.226249637562, 8657.696980605648, 8663.176998185163, 8696.826063478822, 8387.161283871565, 8413.747587572383, 8446.366522510714, 8461.873071904049, 8468.153184681483, 7537.54774713519, 7742.0, 7745.0, 7755.3407960324, 7764.06831180262, 5035.899282014412, 5807.709614519342, 5822.3304320003745, 5995.790147344822, 6036.9698151508965, 4366.519682834822, 5173.1722924331925, 5897.734601942837, 5909.5863289569315, 6103.877922441615], "value": 4965.977330668553, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.53649097400813, 379.3702453926482, 382.82390100553744, 391.71600589572563, 392.56817500749173, 4813.759312034402, 5021.924671129931, 5101.801076746961, 5102.897942041157, 5170.974145129278, 4373.584509471595, 5131.948680513194, 5177.508136727014, 5181.300524429201, 5182.2226666000115, 3797.620237976208, 4059.817308221137, 4088.4276201331836, 4107.960109518949, 4167.895802604929, 3705.962940370602, 4210.810513526883, 4224.514180869193, 4236.131593023439, 4327.372530983005, 3750.9812450937843, 3763.962360376402, 3950.696270230817, 4243.193793179302, 4258.872233832974, 4080.9795951020055, 4170.845787942292, 4304.354346847967, 4309.547497512763, 4312.137572485515, 3422.709069729076, 3462.532558104674, 3826.349520581508, 3893.610638936112, 3951.8419263229434, 4177.227212965611, 4227.4292970448905, 4805.903881922377, 4988.900221995576, 4989.629925785259, 3971.5829837867186, 4673.369095172142, 4691.929621055687, 4868.099401610713, 4904.449686163211, 4360.934585981213, 5164.354455693038, 5392.838214853521, 5485.807996720096, 5486.31421072366, 3671.76133551321, 4125.814338354781, 4301.870943871658, 4329.502107257643, 4348.978255108705, 4237.792229214659, 4973.428055773632, 5203.881165549387, 5430.70131142787, 5753.849230153986, 3658.70730341572, 3733.607971163043, 3776.169242766587, 4185.916281674349, 4331.578053337827, 3844.6539811417106, 4129.483814523184, 4193.559676234013, 4460.375547423363, 4473.932891006638, 4198.790060496963, 4347.7173983690955, 4470.714025191482, 4793.616510679207, 4888.753367891165, 4895.090914543331, 4982.501749825061, 5358.785648574013, 5513.696746678971, 5538.972305138529, 1380.4607862233538, 1500.3398504657916, 1504.893902981308, 1512.8789696824228, 1513.9394424223128, 1001.7145113642576, 1046.895310468947, 1099.5601759296185, 1126.189143816451, 1157.8610566731877, 4524.6380289576755, 4914.606831453475, 4932.8520144395725, 4934.432540257844, 4949.356583644103, 5572.804951826666, 5625.46558076982, 5684.317881854204, 5684.971575142181, 5686.232358631614, 4384.6711496637245, 4439.356293337512, 4669.019505903798, 4852.95147048525, 4970.726610036482, 2401.375642333006, 2470.789982851478, 2488.9128880489097, 2602.375429896849, 2604.6223297621736, 5140.55582759552, 5653.830385088454, 5673.375928647845, 5696.914546281809, 5700.572457065737, 4883.974365383309, 4925.1873440881855, 5426.2674538936735, 5653.265075540154, 5669.489745922887, 4501.549845015537, 5124.925684081164, 5469.288992430958, 5666.688332141772, 5809.808989157213, 4478.57453541913, 4756.857294281177, 5283.762230699551, 5423.457654234545, 5668.914966275427, 5166.114368255635, 5389.676619402849, 5459.317585301837, 5643.887122257614, 5692.946804840952, 5285.537972627935, 5463.754131064032, 5501.972490137524, 5507.091329930517, 5842.685395786084, 4138.710290279651, 4213.768242746556, 4545.477270113979, 4911.887888208104, 4936.456989731055, 4842.612590992782, 5172.25002374646, 5265.60507961912, 5563.304586926634, 5594.552435805207, 4475.63493134584, 4512.481064677604, 5303.655262407931, 5330.78676852937, 5360.946390536201, 4414.426124603781, 5333.546648534918, 5337.466253374631, 5356.866078348073, 5382.30030096085, 4803.0, 5086.4486331669195, 5274.540850220003, 5290.470952904679, 5357.089294819812, 4656.091002688881, 4909.189983652657, 5244.868878278074, 5371.301730774974, 5430.728463576885, 4498.662600304925, 4936.456989731055, 5065.417476990193, 5249.921251181159, 5265.657732247391], "value": 4082.5666041161935, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.2506855267691, 723.9022731931187, 765.2768134113262, 819.3813670678637, 832.7335252719129, 11873.584424545143, 15658.84341156588, 15658.921705391485, 15699.529014129566, 15713.764293535605, 11767.823482647746, 14304.78542821856, 14318.069325493836, 14428.0, 14483.5654930352, 905.1853332001218, 909.4725059465527, 921.6497730862263, 922.8431166701677, 930.7812664023949, 903.7695387676133, 920.9861852072224, 922.5110691333571, 922.6586163119655, 922.9723108306762, 984.1585444444968, 985.1626117799884, 986.3243678080502, 986.8075725233582, 988.6045581767279, 973.8782652168479, 973.9513024348754, 976.3263348289693, 977.6480467031898, 977.7555611097225, 724.3046675191822, 724.90213821134, 725.2928394815013, 725.365305357812, 727.0439372225507, 15500.767488487681, 15774.605634859106, 15831.762523562156, 16151.434699785568, 16388.508344749676, 13343.86656133436, 13760.65598360039, 14089.718205635932, 14092.788608170886, 14123.435062597484, 11179.217454778169, 11575.131865110066, 11649.0, 11685.948264656221, 11791.823122653168, 3510.8946731598094, 3609.8375573099274, 3727.683146932514, 3728.776273423576, 3817.2365526894732, 87236.0, 109005.9999999992, 116845.0, 118734.0, 122698.0, 10278.434686092187, 10377.429241391796, 10765.192610554239, 10773.83839242412, 10847.403392813314, 17697.646047078986, 22225.444363890707, 22284.88857555702, 22319.888400557895, 22349.776502235094, 7452.180260171377, 7539.886901696479, 7640.274173953466, 7887.092984306763, 7889.053313602404, 4262.318029115327, 4279.186954478639, 4393.560643935645, 4433.6009759121835, 4534.0478499515475, 3227.257730721947, 3484.3728128937037, 3523.4010218262956, 3531.6644918732677, 3532.0816587687386, 624.1324558863183, 629.3926361061598, 631.5736877607688, 652.8498445357594, 655.6361219523116, 663.9966800165968, 694.5137405951268, 695.8016965164903, 704.7181127549021, 708.7590219325351, 8794.42836215644, 9915.504224788876, 10360.326578772356, 10430.739231519121, 10529.84205236922, 686.7767975408012, 691.1395312835451, 691.752638797782, 692.7159864455549, 694.4305669351083, 4664.720116793003, 4982.451930287665, 4987.600991920638, 4989.126902791997, 4999.42506611737, 83731.0, 84285.57857210797, 91304.71738206598, 104959.4752026235, 110355.34466983001, 47467.85989876271, 78793.0, 81168.0, 82769.17230827735, 83574.0, 64631.67684161549, 70204.24489387704, 71518.2848171522, 72179.27820721726, 73735.63132184304, 11006.669799906016, 11042.889571104186, 11125.44372781374, 11239.494222759831, 11325.603603873826, 40195.59804402036, 45327.77336113298, 46727.766361167975, 48886.0222795535, 50772.0, 58595.0, 63417.36582634299, 64410.0, 64511.67744161249, 64551.0, 9606.807863842823, 10581.62964296246, 12046.939765301116, 12290.938545307574, 12292.815607765711, 13574.660633484242, 13811.0, 13932.791008134684, 14668.413263469769, 14673.70652586921, 13594.86405135936, 13599.59201223965, 13711.725765484834, 13859.099158554662, 14065.437382504586, 12479.18648905814, 13218.0, 13606.537819136292, 13632.179904972681, 13672.248026358646, 12748.0, 13459.596212113653, 13514.256715880721, 13600.52398166099, 13699.972502062186, 9128.908710912805, 9253.861192082259, 9812.362196457207, 9877.0, 10153.64462243818, 9004.639814407612, 9952.3816661919, 10345.751277345285, 10651.414172220293, 11377.829332559853], "value": 7451.051966033478, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1426.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 109.0, 107.0, 140.0, 105.0, 109.0, 106.0, 110.0, 131.0, 114.0], "value": 116.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 184.0, 184.0, 181.0, 184.0, 183.0, 185.0, 183.0, 182.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 61.0, 92.0, 62.0, 61.0, 59.0, 61.0, 77.0, 60.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 87.0, 70.0, 69.0, 68.0, 81.0, 68.0, 69.0, 83.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 86.0, 86.0, 87.0, 86.0, 87.0, 87.0, 87.0, 86.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 95.0, 93.0, 93.0, 94.0, 94.0, 93.0, 93.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 130.0, 136.0, 135.0, 83.0, 85.0, 83.0, 86.0, 85.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 63.0, 62.0, 66.0, 63.0, 64.0, 63.0, 64.0, 63.0], "value": 63.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 62.0, 67.0, 66.0, 60.0, 56.0, 58.0, 57.0, 57.0, 58.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 91.0, 103.0, 102.0, 102.0, 82.0, 86.0, 82.0, 85.0, 86.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 136.0, 147.0, 126.0, 144.0, 137.0, 149.0, 141.0, 182.0, 136.0], "value": 143.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 163.0, 162.0, 159.0, 218.0, 205.0, 165.0, 165.0, 163.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 61.0, 65.0, 64.0, 65.0, 63.0, 62.0, 63.0], "value": 63.7, "unit": "score"}]}]} 01:57:10 INFO - Return code: 0 01:57:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:57:10 ERROR - # TBPL SUCCESS # 01:57:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:57:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:57:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:57:10 INFO - 2017-04-24 01:57:10.259 screenresolution[1657:10169] starting screenresolution argv=screenresolution get 01:57:10 INFO - 2017-04-24 01:57:10.261 screenresolution[1657:10169] Display 0: 1600x1200x32@60 01:57:10 INFO - 2017-04-24 01:57:10.268 screenresolution[1658:10172] starting screenresolution argv=screenresolution list 01:57:10 INFO - Available Modes on Display 0 01:57:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:57:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:57:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:57:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:57:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:57:10 INFO - Intel Iris: 01:57:10 INFO - Chipset Model: Intel Iris 01:57:10 INFO - Type: GPU 01:57:10 INFO - Bus: Built-In 01:57:10 INFO - VRAM (Dynamic, Max): 1536 MB 01:57:10 INFO - Vendor: Intel (0x8086) 01:57:10 INFO - Device ID: 0x0a2e 01:57:10 INFO - Revision ID: 0x0009 01:57:10 INFO - Displays: 01:57:10 INFO - Display: 01:57:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:57:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:57:10 INFO - Display Serial Number: 1600x1200 60 01:57:10 INFO - Main Display: Yes 01:57:10 INFO - Mirror: Off 01:57:10 INFO - Online: Yes 01:57:10 INFO - Rotation: Supported 01:57:10 INFO - Return code: 0 01:57:10 INFO - Running post-action listener: _resource_record_post_action 01:57:10 INFO - [mozharness: 2017-04-24 08:57:10.517189Z] Finished run-tests step (success) 01:57:10 INFO - Running post-run listener: _resource_record_post_run 01:57:10 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 38216704; Write bytes: 509858304; Read time: 242; Write time: 1562 01:57:10 INFO - TinderboxPrint: CPU usage
53.8% 01:57:10 INFO - TinderboxPrint: I/O read bytes / time
38,216,704 / 242 01:57:10 INFO - TinderboxPrint: I/O write bytes / time
509,858,304 / 1,562 01:57:10 INFO - TinderboxPrint: CPU idle
2,222.3 (46.2%) 01:57:10 INFO - TinderboxPrint: CPU system
954.0 (19.9%) 01:57:10 INFO - TinderboxPrint: CPU user
1,629.4 (33.9%) 01:57:10 INFO - TinderboxPrint: Swap in / out
246,677,504 / 0 01:57:10 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205980160; Write bytes: 200632320; Read time: 15700; Write time: 419 01:57:10 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36115456; Write bytes: 298162688; Read time: 221; Write time: 1106 01:57:11 INFO - Running post-run listener: _upload_blobber_files 01:57:11 INFO - Blob upload gear active. 01:57:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:57:11 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 01:57:11 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'] 01:57:11 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 01:57:11 INFO - (blobuploader) - INFO - Open directory for files ... 01:57:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:57:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:57:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:57:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:57:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:57:12 INFO - (blobuploader) - INFO - Done attempting. 01:57:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:57:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:57:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:57:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:57:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:57:13 INFO - (blobuploader) - INFO - Done attempting. 01:57:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:57:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:57:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:57:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:57:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:57:14 INFO - (blobuploader) - INFO - Done attempting. 01:57:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:57:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:57:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:57:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:57:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:57:16 INFO - (blobuploader) - INFO - Done attempting. 01:57:16 INFO - (blobuploader) - INFO - Iteration through files over. 01:57:16 INFO - Return code: 0 01:57:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:57:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:57:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/28a70feeed94724b47f76dfb31f0946c69467b84e3bdfaefd38fbe9999b25b693b5266cd6b256a37f44135ebef54a709cf5d1f17faa1459eef9795ceef5d25a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3ea3977523894e12422ffe73f544e8d6c9156a315c2f6a26ad8da9cd3efa5ae42d1c18ca584560d319452d3dacdd84f1d9748d80a10e3cb28f3c08c5f24fc80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/642afd4d82bdd6780b2a62a9802726daf799b7c88b8892b9188cb3d03d5bde364b59e97fbfaed31727b2831d2aecf294827351883052b41a0bea44b4f871318c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/180fe085f3cc83123d26de8cd34cc403d3048d14f6ff4413965b19e1e94e4aa156dd575715ebe8890306e1ad4afadeecfc1200f6df1d3fb7bb88d1d6eefd5264"} 01:57:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:57:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:57:16 INFO - Contents: 01:57:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/28a70feeed94724b47f76dfb31f0946c69467b84e3bdfaefd38fbe9999b25b693b5266cd6b256a37f44135ebef54a709cf5d1f17faa1459eef9795ceef5d25a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3ea3977523894e12422ffe73f544e8d6c9156a315c2f6a26ad8da9cd3efa5ae42d1c18ca584560d319452d3dacdd84f1d9748d80a10e3cb28f3c08c5f24fc80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/642afd4d82bdd6780b2a62a9802726daf799b7c88b8892b9188cb3d03d5bde364b59e97fbfaed31727b2831d2aecf294827351883052b41a0bea44b4f871318c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/180fe085f3cc83123d26de8cd34cc403d3048d14f6ff4413965b19e1e94e4aa156dd575715ebe8890306e1ad4afadeecfc1200f6df1d3fb7bb88d1d6eefd5264"} 01:57:16 INFO - Running post-run listener: copy_logs_to_upload_dir 01:57:16 INFO - Copying logs to upload dir... 01:57:16 INFO - mkdir: /builds/slave/test/build/upload/logs 01:57:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.402793 ========= master_lag: 4.77 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-04-24 01:57:21.764267) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-24 01:57:21.767658) ========= 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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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/28a70feeed94724b47f76dfb31f0946c69467b84e3bdfaefd38fbe9999b25b693b5266cd6b256a37f44135ebef54a709cf5d1f17faa1459eef9795ceef5d25a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3ea3977523894e12422ffe73f544e8d6c9156a315c2f6a26ad8da9cd3efa5ae42d1c18ca584560d319452d3dacdd84f1d9748d80a10e3cb28f3c08c5f24fc80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/642afd4d82bdd6780b2a62a9802726daf799b7c88b8892b9188cb3d03d5bde364b59e97fbfaed31727b2831d2aecf294827351883052b41a0bea44b4f871318c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/180fe085f3cc83123d26de8cd34cc403d3048d14f6ff4413965b19e1e94e4aa156dd575715ebe8890306e1ad4afadeecfc1200f6df1d3fb7bb88d1d6eefd5264"} build_url:https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010873 build_url: 'https://queue.taskcluster.net/v1/task/UGRBOTMsRnOs4IvRdptTGA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/28a70feeed94724b47f76dfb31f0946c69467b84e3bdfaefd38fbe9999b25b693b5266cd6b256a37f44135ebef54a709cf5d1f17faa1459eef9795ceef5d25a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3ea3977523894e12422ffe73f544e8d6c9156a315c2f6a26ad8da9cd3efa5ae42d1c18ca584560d319452d3dacdd84f1d9748d80a10e3cb28f3c08c5f24fc80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/642afd4d82bdd6780b2a62a9802726daf799b7c88b8892b9188cb3d03d5bde364b59e97fbfaed31727b2831d2aecf294827351883052b41a0bea44b4f871318c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/180fe085f3cc83123d26de8cd34cc403d3048d14f6ff4413965b19e1e94e4aa156dd575715ebe8890306e1ad4afadeecfc1200f6df1d3fb7bb88d1d6eefd5264"}' ========= master_lag: 0.14 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-24 01:57:21.922145) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 01:57:21.922495) ========= 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.F0U8wSSBcT/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.m0vTTwjAvC/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.005047 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 01:57:21.951166) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-24 01:57:21.951487) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-24 01:57:21.953417) ========= ========= Total master_lag: 5.14 =========