builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0351 starttime: 1491587002.27 results: success (0) buildid: 20170407090522 builduid: 398a9b5f86af4d1fb7e63d31172a30f9 revision: 2e7b7b293fdc1444430855ffb1b3d88925c9d5ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 10:43:22.270262) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 10:43:22.270748) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 10:43:22.271031) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 10:43:22.297884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:43:22.298265) ========= 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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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-07 10:43:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 23.7M=0s 2017-04-07 10:43:22 (23.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107283 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:43:22.433884) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:43:22.434224) ========= 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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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.031186 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 10:43:22.487550) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-07 10:43:22.487866) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2e7b7b293fdc1444430855ffb1b3d88925c9d5ad --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 2e7b7b293fdc1444430855ffb1b3d88925c9d5ad --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.e3pOwUiVoe/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.6PRpwU5sw4/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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-07 10:43:22,599 truncating revision to first 12 chars 2017-04-07 10:43:22,599 Setting DEBUG logging. 2017-04-07 10:43:22,599 attempt 1/10 2017-04-07 10:43:22,599 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2e7b7b293fdc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-07 10:43:25,181 unpacking tar archive at: mozilla-aurora-2e7b7b293fdc/testing/mozharness/ program finished with exit code 0 elapsedTime=2.855565 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-07 10:43:25.367801) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 10:43:25.368184) ========= script_repo_revision: 2e7b7b293fdc1444430855ffb1b3d88925c9d5ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 10:43:25.368660) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 10:43:25.369065) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 10:43:25.389350) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-07 10:43:25.389703) ========= /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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:43:25 INFO - MultiFileLogger online at 20170407 10:43:25 in /builds/slave/test 10:43:25 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 10:43:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:43:25 INFO - {'append_to_log': False, 10:43:25 INFO - 'base_work_dir': '/builds/slave/test', 10:43:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:43:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:43:25 INFO - 'branch': 'Mozilla-Aurora', 10:43:25 INFO - 'buildbot_json_path': 'buildprops.json', 10:43:25 INFO - 'config_files': ('talos/mac_config.py',), 10:43:25 INFO - 'default_actions': ('clobber', 10:43:25 INFO - 'read-buildbot-config', 10:43:25 INFO - 'download-and-extract', 10:43:25 INFO - 'populate-webroot', 10:43:25 INFO - 'create-virtualenv', 10:43:25 INFO - 'install', 10:43:25 INFO - 'run-tests'), 10:43:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:43:25 INFO - 'download_minidump_stackwalk': True, 10:43:25 INFO - 'download_symbols': 'ondemand', 10:43:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:43:25 INFO - 'tooltool.py': '/tools/tooltool.py', 10:43:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:43:25 INFO - '/tools/misc-python/virtualenv.py')}, 10:43:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:43:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:43:25 INFO - 'gecko_profile': False, 10:43:25 INFO - 'gecko_profile_interval': 0, 10:43:25 INFO - 'installer_path': 'installer.exe', 10:43:25 INFO - 'log_level': 'info', 10:43:25 INFO - 'log_name': 'talos', 10:43:25 INFO - 'log_to_console': True, 10:43:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:43:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:43:25 INFO - 'opt_config_files': (), 10:43:25 INFO - 'pip_index': False, 10:43:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:43:25 INFO - 'cmd': ('bash', 10:43:25 INFO - '-c', 10:43:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:43:25 INFO - 'enabled': True, 10:43:25 INFO - 'halt_on_failure': False, 10:43:25 INFO - 'name': 'check_screen_resolution'},), 10:43:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:43:25 INFO - 'cmd': ('bash', 10:43:25 INFO - '-c', 10:43:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:43:25 INFO - 'enabled': True, 10:43:25 INFO - 'halt_on_failure': False, 10:43:25 INFO - 'name': 'check_screen_resolution'},), 10:43:25 INFO - 'run_cmd_checks_enabled': True, 10:43:25 INFO - 'suite': 'dromaeojs', 10:43:25 INFO - 'system_bits': '32', 10:43:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:43:25 INFO - 'title': 't-yosemite-r7-0351', 10:43:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:43:25 INFO - 'use_talos_json': True, 10:43:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:43:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:43:25 INFO - 'work_dir': 'build'} 10:43:25 INFO - [mozharness: 2017-04-07 17:43:25.604482Z] Running clobber step. 10:43:25 INFO - Running pre-action listener: _resource_record_pre_action 10:43:25 INFO - Running main action method: clobber 10:43:25 INFO - rmtree: /builds/slave/test/build 10:43:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:43:27 INFO - Running post-action listener: _resource_record_post_action 10:43:27 INFO - [mozharness: 2017-04-07 17:43:27.982528Z] Finished clobber step (success) 10:43:27 INFO - [mozharness: 2017-04-07 17:43:27.982656Z] Running read-buildbot-config step. 10:43:27 INFO - Running pre-action listener: _resource_record_pre_action 10:43:27 INFO - Running main action method: read_buildbot_config 10:43:27 INFO - Using buildbot properties: 10:43:27 INFO - { 10:43:27 INFO - "project": "", 10:43:27 INFO - "product": "firefox", 10:43:27 INFO - "script_repo_revision": "production", 10:43:27 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:43:27 INFO - "repository": "", 10:43:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:43:27 INFO - "buildid": "20170407090522", 10:43:27 INFO - "pgo_build": "False", 10:43:27 INFO - "basedir": "/builds/slave/test", 10:43:27 INFO - "buildnumber": 13, 10:43:27 INFO - "slavename": "t-yosemite-r7-0351", 10:43:27 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:43:27 INFO - "platform": "yosemite_r7", 10:43:27 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:43:27 INFO - "branch": "mozilla-aurora", 10:43:27 INFO - "repo_path": "releases/mozilla-aurora", 10:43:27 INFO - "revision": "2e7b7b293fdc1444430855ffb1b3d88925c9d5ad", 10:43:27 INFO - "stage_platform": "macosx64", 10:43:27 INFO - "builduid": "398a9b5f86af4d1fb7e63d31172a30f9", 10:43:27 INFO - "slavebuilddir": "test" 10:43:27 INFO - } 10:43:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 10:43:27 INFO - Running post-action listener: _resource_record_post_action 10:43:27 INFO - [mozharness: 2017-04-07 17:43:27.985954Z] Finished read-buildbot-config step (success) 10:43:27 INFO - [mozharness: 2017-04-07 17:43:27.986087Z] Running download-and-extract step. 10:43:27 INFO - Running pre-action listener: _resource_record_pre_action 10:43:27 INFO - Running main action method: download_and_extract 10:43:27 INFO - mkdir: /builds/slave/test/build/tests 10:43:27 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')]} 10:43:27 INFO - trying https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 10:43:27 INFO - Downloading https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/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 10:43:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/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 10:43:29 INFO - Downloaded 1199 bytes. 10:43:29 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 10:43:29 INFO - Using the following test package requirements: 10:43:29 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 10:43:29 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:43:29 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 10:43:29 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:43:29 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 10:43:29 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:43:29 INFO - u'jsshell-mac.zip'], 10:43:29 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:43:29 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 10:43:29 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 10:43:29 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:43:29 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 10:43:29 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:43:29 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 10:43:29 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:43:29 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 10:43:29 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:43:29 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 10:43:29 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:43:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 10:43:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:43:29 INFO - Fetch https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 10:43:30 INFO - Expected file size: 51790708 10:43:30 INFO - Obtained file size: 51790708 10:43:46 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 10:43:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 10:43:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:43:46 INFO - Fetch https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 10:43:48 INFO - Expected file size: 51790708 10:43:48 INFO - Obtained file size: 51790708 10:44:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 10:44:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:44:03 INFO - Fetch https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 10:44:05 INFO - Expected file size: 14404424 10:44:05 INFO - Obtained file size: 14404424 10:44:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:05 INFO - trying https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 10:44:05 INFO - Downloading https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 10:44:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/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 10:44:07 INFO - Downloaded 74677344 bytes. 10:44:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 10:44:07 INFO - mkdir: /builds/slave/test/properties 10:44:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:44:07 INFO - Writing to file /builds/slave/test/properties/build_url 10:44:07 INFO - Contents: 10:44:07 INFO - build_url:https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 10:44:08 INFO - Running post-action listener: _resource_record_post_action 10:44:08 INFO - Running post-action listener: set_extra_try_arguments 10:44:08 INFO - [mozharness: 2017-04-07 17:44:08.180186Z] Finished download-and-extract step (success) 10:44:08 INFO - [mozharness: 2017-04-07 17:44:08.180309Z] Running populate-webroot step. 10:44:08 INFO - Running pre-action listener: _resource_record_pre_action 10:44:08 INFO - Running main action method: populate_webroot 10:44:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:44:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:44:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:44:08 INFO - '--tppagecycles', 10:44:08 INFO - '7'], 10:44:08 INFO - 'tests': ['tsvgm']}}, 10:44:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:44:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:44:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:44:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:44:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:44:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:44:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:44:08 INFO - 'talos_options': ['--disable-e10s'], 10:44:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:44:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:44:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:44:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:44:08 INFO - 'talos_options': ['--disable-e10s'], 10:44:08 INFO - 'tests': ['damp', 'tps']}, 10:44:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:44:08 INFO - 'tests': ['damp', 'tps']}, 10:44:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:44:08 INFO - 'tests': ['dromaeo_dom']}, 10:44:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:44:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:44:08 INFO - 'tests': ['basic_compositor_video']}, 10:44:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:44:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:44:08 INFO - 'tests': ['a11yr', 10:44:08 INFO - 'ts_paint', 10:44:08 INFO - 'tpaint', 10:44:08 INFO - 'sessionrestore', 10:44:08 INFO - 'sessionrestore_no_auto_restore', 10:44:08 INFO - 'tabpaint']}, 10:44:08 INFO - 'other-e10s': {'tests': ['a11yr', 10:44:08 INFO - 'ts_paint', 10:44:08 INFO - 'tpaint', 10:44:08 INFO - 'sessionrestore', 10:44:08 INFO - 'sessionrestore_no_auto_restore', 10:44:08 INFO - 'tabpaint']}, 10:44:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:44:08 INFO - 'ts_paint', 10:44:08 INFO - 'tpaint', 10:44:08 INFO - 'sessionrestore', 10:44:08 INFO - 'sessionrestore_no_auto_restore', 10:44:08 INFO - 'tabpaint']}, 10:44:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:44:08 INFO - 'ts_paint', 10:44:08 INFO - 'tpaint', 10:44:08 INFO - 'sessionrestore', 10:44:08 INFO - 'sessionrestore_no_auto_restore', 10:44:08 INFO - 'tabpaint']}, 10:44:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:44:08 INFO - 'tests': ['a11yr', 10:44:08 INFO - 'ts_paint', 10:44:08 INFO - 'tpaint', 10:44:08 INFO - 'sessionrestore', 10:44:08 INFO - 'sessionrestore_no_auto_restore', 10:44:08 INFO - 'tabpaint']}, 10:44:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:44:08 INFO - 'tests': ['a11yr', 10:44:08 INFO - 'ts_paint', 10:44:08 INFO - 'tpaint', 10:44:08 INFO - 'sessionrestore', 10:44:08 INFO - 'sessionrestore_no_auto_restore', 10:44:08 INFO - 'tabpaint']}, 10:44:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:44:08 INFO - 'tests': ['tsvgx', 10:44:08 INFO - 'tsvgr_opacity', 10:44:08 INFO - 'tart', 10:44:08 INFO - 'tscrollx', 10:44:08 INFO - 'cart', 10:44:08 INFO - 'tsvg_static']}, 10:44:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:44:08 INFO - 'tsvgr_opacity', 10:44:08 INFO - 'tart', 10:44:08 INFO - 'tscrollx', 10:44:08 INFO - 'cart', 10:44:08 INFO - 'tsvg_static']}, 10:44:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:44:08 INFO - 'talos_options': ['--disable-e10s'], 10:44:08 INFO - 'tests': ['tp5o']}, 10:44:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:44:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:44:08 INFO - 'talos_options': ['--disable-e10s', 10:44:08 INFO - '--xperf_path', 10:44:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:44:08 INFO - 'tests': ['tp5n']}, 10:44:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:44:08 INFO - 'talos_options': ['--xperf_path', 10:44:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:44:08 INFO - 'tests': ['tp5n']}}, 10:44:08 INFO - 'talos.zip': {'path': '', 10:44:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:44:08 INFO - Running post-action listener: _resource_record_post_action 10:44:08 INFO - [mozharness: 2017-04-07 17:44:08.198773Z] Finished populate-webroot step (success) 10:44:08 INFO - [mozharness: 2017-04-07 17:44:08.198862Z] Running create-virtualenv step. 10:44:08 INFO - Running pre-action listener: _resource_record_pre_action 10:44:08 INFO - Running main action method: create_virtualenv 10:44:08 INFO - Creating virtualenv /builds/slave/test/build/venv 10:44:08 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 10:44:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:44:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:44:08 INFO - Using real prefix '/tools/python27' 10:44:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:44:09 INFO - Installing distribute.............................................................................................................................................................................................done. 10:44:12 INFO - Installing pip.................done. 10:44:12 INFO - Return code: 0 10:44:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:44:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:44:12 INFO - Reading from file tmpfile_stdout 10:44:12 INFO - Output received: 10:44:12 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) 10:44:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:44:12 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')]} 10:44:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:12 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')]} 10:44:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:12 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 0x106e26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09d64bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c04d30>, '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 0x106b2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bf9be8>, '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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:44:12 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 10:44:12 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 10:44:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e3pOwUiVoe/Render', 10:44:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:12 INFO - 'HOME': '/Users/cltbld', 10:44:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:12 INFO - 'LOGNAME': 'cltbld', 10:44:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:12 INFO - 'MOZ_NO_REMOTE': '1', 10:44:12 INFO - 'NO_EM_RESTART': '1', 10:44:12 INFO - 'PAGER': '/bin/cat', 10:44:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:12 INFO - 'PWD': '/builds/slave/test', 10:44:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:12 INFO - 'SHELL': '/bin/bash', 10:44:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PRpwU5sw4/Listeners', 10:44:12 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:44:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:12 INFO - 'USER': 'cltbld', 10:44:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:12 INFO - 'XPC_FLAGS': '0x0', 10:44:12 INFO - 'XPC_SERVICE_NAME': '0', 10:44:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:12 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 10:44:12 INFO - Cleaning up... 10:44:12 INFO - Return code: 0 10:44:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:44:12 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')]} 10:44:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:12 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')]} 10:44:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:12 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 0x106e26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09d64bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c04d30>, '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 0x106b2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bf9be8>, '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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:44:12 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 10:44:12 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 10:44:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e3pOwUiVoe/Render', 10:44:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:12 INFO - 'HOME': '/Users/cltbld', 10:44:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:12 INFO - 'LOGNAME': 'cltbld', 10:44:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:12 INFO - 'MOZ_NO_REMOTE': '1', 10:44:12 INFO - 'NO_EM_RESTART': '1', 10:44:12 INFO - 'PAGER': '/bin/cat', 10:44:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:12 INFO - 'PWD': '/builds/slave/test', 10:44:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:12 INFO - 'SHELL': '/bin/bash', 10:44:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PRpwU5sw4/Listeners', 10:44:12 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:44:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:12 INFO - 'USER': 'cltbld', 10:44:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:12 INFO - 'XPC_FLAGS': '0x0', 10:44:12 INFO - 'XPC_SERVICE_NAME': '0', 10:44:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:12 INFO - Downloading/unpacking psutil>=3.1.1 10:44:12 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 10:44:12 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 10:44:12 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 10:44:12 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 10:44:12 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 10:44:12 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 10:44:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:44:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:44:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:44:16 INFO - Installing collected packages: psutil 10:44:16 INFO - Running setup.py install for psutil 10:44:16 INFO - building 'psutil._psutil_osx' extension 10:44:16 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 10:44:16 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 10:44:17 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 10:44:17 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 10:44:17 INFO - building 'psutil._psutil_posix' extension 10:44:17 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 10:44:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:44:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:44:17 INFO - ^ 10:44:17 INFO - 1 warning generated. 10:44:17 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 10:44:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:44:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:44:17 INFO - Successfully installed psutil 10:44:17 INFO - Cleaning up... 10:44:17 INFO - Return code: 0 10:44:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:44:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106e26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09d64bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c04d30>, '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 0x106b2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bf9be8>, '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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:44:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:44:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:44:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e3pOwUiVoe/Render', 10:44:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:18 INFO - 'HOME': '/Users/cltbld', 10:44:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:18 INFO - 'LOGNAME': 'cltbld', 10:44:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:18 INFO - 'MOZ_NO_REMOTE': '1', 10:44:18 INFO - 'NO_EM_RESTART': '1', 10:44:18 INFO - 'PAGER': '/bin/cat', 10:44:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:18 INFO - 'PWD': '/builds/slave/test', 10:44:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:18 INFO - 'SHELL': '/bin/bash', 10:44:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PRpwU5sw4/Listeners', 10:44:18 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:44:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:18 INFO - 'USER': 'cltbld', 10:44:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:18 INFO - 'XPC_FLAGS': '0x0', 10:44:18 INFO - 'XPC_SERVICE_NAME': '0', 10:44:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:44:18 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 10:44:18 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 10:44:18 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 10:44:18 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 10:44:18 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 10:44:18 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 10:44:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:44:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:44:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:44:21 INFO - Installing collected packages: mozsystemmonitor 10:44:21 INFO - Running setup.py install for mozsystemmonitor 10:44:21 INFO - Successfully installed mozsystemmonitor 10:44:21 INFO - Cleaning up... 10:44:21 INFO - Return code: 0 10:44:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:44:21 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')]} 10:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:21 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')]} 10:44:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:21 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 0x106e26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09d64bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c04d30>, '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 0x106b2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bf9be8>, '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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:44:21 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 10:44:21 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 10:44:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e3pOwUiVoe/Render', 10:44:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:21 INFO - 'HOME': '/Users/cltbld', 10:44:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:21 INFO - 'LOGNAME': 'cltbld', 10:44:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:21 INFO - 'MOZ_NO_REMOTE': '1', 10:44:21 INFO - 'NO_EM_RESTART': '1', 10:44:21 INFO - 'PAGER': '/bin/cat', 10:44:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:21 INFO - 'PWD': '/builds/slave/test', 10:44:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:21 INFO - 'SHELL': '/bin/bash', 10:44:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PRpwU5sw4/Listeners', 10:44:21 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:44:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:21 INFO - 'USER': 'cltbld', 10:44:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:21 INFO - 'XPC_FLAGS': '0x0', 10:44:21 INFO - 'XPC_SERVICE_NAME': '0', 10:44:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:22 INFO - Downloading/unpacking jsonschema==2.5.1 10:44:22 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 10:44:22 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 10:44:22 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 10:44:22 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 10:44:22 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 10:44:22 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 10:44:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:44:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:25 INFO - Downloading functools32-3.2.3-2.tar.gz 10:44:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:44:25 INFO - warning: no files found matching '*.txt' 10:44:25 INFO - no previously-included directories found matching 'build' 10:44:25 INFO - no previously-included directories found matching 'dist' 10:44:25 INFO - no previously-included directories found matching '.git*' 10:44:25 INFO - Installing collected packages: jsonschema, functools32 10:44:25 INFO - Running setup.py install for functools32 10:44:25 INFO - warning: no files found matching '*.txt' 10:44:25 INFO - no previously-included directories found matching 'build' 10:44:25 INFO - no previously-included directories found matching 'dist' 10:44:25 INFO - no previously-included directories found matching '.git*' 10:44:25 INFO - Successfully installed jsonschema functools32 10:44:25 INFO - Cleaning up... 10:44:25 INFO - Return code: 0 10:44:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:44:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:25 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 0x106e26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09d64bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c04d30>, '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 0x106b2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bf9be8>, '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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:44:25 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 10:44:25 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 10:44:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e3pOwUiVoe/Render', 10:44:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:25 INFO - 'HOME': '/Users/cltbld', 10:44:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:25 INFO - 'LOGNAME': 'cltbld', 10:44:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:25 INFO - 'MOZ_NO_REMOTE': '1', 10:44:25 INFO - 'NO_EM_RESTART': '1', 10:44:25 INFO - 'PAGER': '/bin/cat', 10:44:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:25 INFO - 'PWD': '/builds/slave/test', 10:44:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:25 INFO - 'SHELL': '/bin/bash', 10:44:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PRpwU5sw4/Listeners', 10:44:25 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:44:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:25 INFO - 'USER': 'cltbld', 10:44:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:25 INFO - 'XPC_FLAGS': '0x0', 10:44:25 INFO - 'XPC_SERVICE_NAME': '0', 10:44:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:44:25 INFO - Cleaning up... 10:44:25 INFO - Return code: 0 10:44:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:44:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:25 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 0x106e26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09d64bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c04d30>, '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 0x106b2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bf9be8>, '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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:44:25 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 10:44:25 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 10:44:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e3pOwUiVoe/Render', 10:44:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:25 INFO - 'HOME': '/Users/cltbld', 10:44:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:25 INFO - 'LOGNAME': 'cltbld', 10:44:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:25 INFO - 'MOZ_NO_REMOTE': '1', 10:44:25 INFO - 'NO_EM_RESTART': '1', 10:44:25 INFO - 'PAGER': '/bin/cat', 10:44:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:25 INFO - 'PWD': '/builds/slave/test', 10:44:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:25 INFO - 'SHELL': '/bin/bash', 10:44:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PRpwU5sw4/Listeners', 10:44:25 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:44:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:25 INFO - 'USER': 'cltbld', 10:44:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:25 INFO - 'XPC_FLAGS': '0x0', 10:44:25 INFO - 'XPC_SERVICE_NAME': '0', 10:44:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:25 INFO - Downloading/unpacking blobuploader==1.2.4 10:44:25 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 10:44:25 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 10:44:25 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 10:44:25 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 10:44:25 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 10:44:25 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 10:44:28 INFO - Downloading blobuploader-1.2.4.tar.gz 10:44:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:44:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:44:28 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 10:44:28 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 10:44:28 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 10:44:28 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 10:44:28 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 10:44:28 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 10:44:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:44:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:44:29 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 10:44:29 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 10:44:29 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 10:44:29 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 10:44:29 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 10:44:29 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 10:44:29 INFO - Downloading docopt-0.6.1.tar.gz 10:44:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:44:29 INFO - Installing collected packages: blobuploader, requests, docopt 10:44:29 INFO - Running setup.py install for blobuploader 10:44:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:44:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:44:30 INFO - Running setup.py install for requests 10:44:30 INFO - Running setup.py install for docopt 10:44:30 INFO - Successfully installed blobuploader requests docopt 10:44:30 INFO - Cleaning up... 10:44:30 INFO - Return code: 0 10:44:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:30 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 0x106e26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09d64bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c04d30>, '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 0x106b2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bf9be8>, '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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:44:30 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 10:44:30 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 10:44:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e3pOwUiVoe/Render', 10:44:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:30 INFO - 'HOME': '/Users/cltbld', 10:44:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:30 INFO - 'LOGNAME': 'cltbld', 10:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:30 INFO - 'MOZ_NO_REMOTE': '1', 10:44:30 INFO - 'NO_EM_RESTART': '1', 10:44:30 INFO - 'PAGER': '/bin/cat', 10:44:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:30 INFO - 'PWD': '/builds/slave/test', 10:44:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:30 INFO - 'SHELL': '/bin/bash', 10:44:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PRpwU5sw4/Listeners', 10:44:30 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:44:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:30 INFO - 'USER': 'cltbld', 10:44:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:30 INFO - 'XPC_FLAGS': '0x0', 10:44:30 INFO - 'XPC_SERVICE_NAME': '0', 10:44:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:44:31 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-ONcFTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:44:31 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-iCKWle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:44:31 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-sCZYYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:44:31 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Pti29k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:44:31 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-AOutjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:44:31 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-kyGM9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:44:31 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-puXA_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:44:31 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-zUfHKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:44:31 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-5uQxWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:44:31 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-mTZZ_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:44:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-KLbrMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:44:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Sww04q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:44:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-qamNTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:44:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-zDuQsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:44:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-z2yEkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:44:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Nu0tT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:44:32 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-SalqvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:44:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:44:32 INFO - Running setup.py install for manifestparser 10:44:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:44:32 INFO - Running setup.py install for mozcrash 10:44:33 INFO - Running setup.py install for mozdebug 10:44:33 INFO - Running setup.py install for mozdevice 10:44:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:44:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:44:33 INFO - Running setup.py install for mozfile 10:44:33 INFO - Running setup.py install for mozhttpd 10:44:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:44:33 INFO - Running setup.py install for mozinfo 10:44:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:44:33 INFO - Running setup.py install for mozInstall 10:44:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:44:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:44:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:44:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:44:33 INFO - Running setup.py install for mozleak 10:44:34 INFO - Running setup.py install for mozlog 10:44:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:44:34 INFO - Running setup.py install for moznetwork 10:44:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:44:34 INFO - Running setup.py install for mozprocess 10:44:34 INFO - Running setup.py install for mozprofile 10:44:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:44:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:44:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:44:34 INFO - Running setup.py install for mozrunner 10:44:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:44:34 INFO - Running setup.py install for mozscreenshot 10:44:35 INFO - Running setup.py install for moztest 10:44:35 INFO - Running setup.py install for mozversion 10:44:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:44:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:44:35 INFO - Cleaning up... 10:44:35 INFO - Return code: 0 10:44:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:35 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 0x106e26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09d64bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c04d30>, '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 0x106b2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bf9be8>, '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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:44:35 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 10:44:35 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 10:44:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e3pOwUiVoe/Render', 10:44:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:35 INFO - 'HOME': '/Users/cltbld', 10:44:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:35 INFO - 'LOGNAME': 'cltbld', 10:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:35 INFO - 'MOZ_NO_REMOTE': '1', 10:44:35 INFO - 'NO_EM_RESTART': '1', 10:44:35 INFO - 'PAGER': '/bin/cat', 10:44:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:35 INFO - 'PWD': '/builds/slave/test', 10:44:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:35 INFO - 'SHELL': '/bin/bash', 10:44:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PRpwU5sw4/Listeners', 10:44:35 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:44:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:35 INFO - 'USER': 'cltbld', 10:44:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:35 INFO - 'XPC_FLAGS': '0x0', 10:44:35 INFO - 'XPC_SERVICE_NAME': '0', 10:44:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:44:35 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-J7Ovxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:44:35 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)) 10:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:44:35 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-MzCX5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:44:35 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)) 10:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:44:35 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-brvFXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:44:35 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)) 10:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:44:35 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-W25EeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:44:36 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)) 10:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:44:36 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-K46020-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:44:36 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)) 10:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:44:36 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-9ic29g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:44:36 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)) 10:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:44:36 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-3Lrkgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:44:36 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)) 10:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:44:36 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-9si_fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:44:36 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)) 10:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:44:36 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-xrcqSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:44:36 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)) 10:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:44:36 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-WvYqWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:44:36 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)) 10:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:44:36 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-ZMV2Fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:44:36 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)) 10:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:44:36 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-yU6jQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:44:36 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)) 10:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:44:36 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-mLYKkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:44:37 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)) 10:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:44:37 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-7qoFjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:44:37 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)) 10:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:44:37 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-p6NcwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:44:37 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)) 10:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:44:37 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-lscG4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:44:37 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)) 10:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:44:37 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-fsmT5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:44:37 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)) 10:44:37 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)) 10:44:37 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)) 10:44:37 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)) 10:44:37 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)) 10:44:37 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)) 10:44:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:44:37 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 10:44:37 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 10:44:37 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 10:44:37 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 10:44:37 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 10:44:37 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 10:44:40 INFO - Downloading blessings-1.6.tar.gz 10:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:44:40 INFO - Installing collected packages: blessings 10:44:40 INFO - Running setup.py install for blessings 10:44:40 INFO - Successfully installed blessings 10:44:40 INFO - Cleaning up... 10:44:40 INFO - Return code: 0 10:44:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:44:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:44:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:44:40 INFO - Reading from file tmpfile_stdout 10:44:40 INFO - Current package versions: 10:44:40 INFO - blessings == 1.6 10:44:40 INFO - blobuploader == 1.2.4 10:44:40 INFO - docopt == 0.6.1 10:44:40 INFO - functools32 == 3.2.3-2 10:44:40 INFO - jsonschema == 2.5.1 10:44:40 INFO - manifestparser == 1.1 10:44:40 INFO - mozInstall == 1.12 10:44:40 INFO - mozcrash == 1.0 10:44:40 INFO - mozdebug == 0.1 10:44:40 INFO - mozdevice == 0.49 10:44:40 INFO - mozfile == 1.2 10:44:40 INFO - mozhttpd == 0.7 10:44:40 INFO - mozinfo == 0.9 10:44:40 INFO - mozleak == 0.1 10:44:40 INFO - mozlog == 3.4 10:44:40 INFO - moznetwork == 0.27 10:44:40 INFO - mozprocess == 0.25 10:44:40 INFO - mozprofile == 0.28 10:44:40 INFO - mozrunner == 6.13 10:44:40 INFO - mozscreenshot == 0.1 10:44:40 INFO - mozsystemmonitor == 0.3 10:44:40 INFO - moztest == 0.8 10:44:40 INFO - mozversion == 1.4 10:44:40 INFO - psutil == 3.1.1 10:44:40 INFO - requests == 1.2.3 10:44:40 INFO - wsgiref == 0.1.2 10:44:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:44: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')]} 10:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44: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')]} 10:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:40 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 0x106e26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09d64bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c04d30>, '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 0x106b2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bf9be8>, '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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:44:40 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 10:44:40 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 10:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e3pOwUiVoe/Render', 10:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:40 INFO - 'HOME': '/Users/cltbld', 10:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:40 INFO - 'LOGNAME': 'cltbld', 10:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:40 INFO - 'MOZ_NO_REMOTE': '1', 10:44:40 INFO - 'NO_EM_RESTART': '1', 10:44:40 INFO - 'PAGER': '/bin/cat', 10:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:40 INFO - 'PWD': '/builds/slave/test', 10:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:40 INFO - 'SHELL': '/bin/bash', 10:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PRpwU5sw4/Listeners', 10:44:40 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:40 INFO - 'USER': 'cltbld', 10:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:40 INFO - 'XPC_FLAGS': '0x0', 10:44:40 INFO - 'XPC_SERVICE_NAME': '0', 10:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:40 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)) 10:44:40 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)) 10:44:40 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)) 10:44:40 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)) 10:44:40 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)) 10:44:40 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)) 10:44:40 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)) 10:44:40 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)) 10:44:40 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)) 10:44:40 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)) 10:44:40 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)) 10:44:40 INFO - Cleaning up... 10:44:41 INFO - Return code: 0 10:44:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:44:41 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')]} 10:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:41 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')]} 10:44:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:41 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 0x106e26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09d64bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c04d30>, '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 0x106b2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bf9be8>, '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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:44:41 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 10:44:41 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 10:44:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e3pOwUiVoe/Render', 10:44:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:41 INFO - 'HOME': '/Users/cltbld', 10:44:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:41 INFO - 'LOGNAME': 'cltbld', 10:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:41 INFO - 'MOZ_NO_REMOTE': '1', 10:44:41 INFO - 'NO_EM_RESTART': '1', 10:44:41 INFO - 'PAGER': '/bin/cat', 10:44:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:41 INFO - 'PWD': '/builds/slave/test', 10:44:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:41 INFO - 'SHELL': '/bin/bash', 10:44:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PRpwU5sw4/Listeners', 10:44:41 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:44:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:41 INFO - 'USER': 'cltbld', 10:44:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:41 INFO - 'XPC_FLAGS': '0x0', 10:44:41 INFO - 'XPC_SERVICE_NAME': '0', 10:44:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:44:41 INFO - Cleaning up... 10:44:41 INFO - Return code: 0 10:44:41 INFO - Running post-action listener: _resource_record_post_action 10:44:41 INFO - Running post-action listener: _start_resource_monitoring 10:44:41 INFO - Starting resource monitoring. 10:44:41 INFO - [mozharness: 2017-04-07 17:44:41.305081Z] Finished create-virtualenv step (success) 10:44:41 INFO - [mozharness: 2017-04-07 17:44:41.306011Z] Running install step. 10:44:41 INFO - Running pre-action listener: _resource_record_pre_action 10:44:41 INFO - Running main action method: install 10:44:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:44:41 INFO - Reading from file tmpfile_stdout 10:44:41 INFO - Detecting whether we're running mozinstall >=1.0... 10:44:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:44:41 INFO - Reading from file tmpfile_stdout 10:44:41 INFO - Output received: 10:44:41 INFO - Usage: mozinstall [options] installer 10:44:41 INFO - Options: 10:44:41 INFO - -h, --help show this help message and exit 10:44:41 INFO - -d DEST, --destination=DEST 10:44:41 INFO - Directory to install application into. [default: 10:44:41 INFO - "/builds/slave/test"] 10:44:41 INFO - --app=APP Application being installed. [default: firefox] 10:44:41 INFO - mkdir: /builds/slave/test/build/application 10:44:41 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'] 10:44:41 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 10:45:01 INFO - Reading from file tmpfile_stdout 10:45:01 INFO - Output received: 10:45:01 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:45:01 INFO - Running post-action listener: _resource_record_post_action 10:45:01 INFO - [mozharness: 2017-04-07 17:45:01.697823Z] Finished install step (success) 10:45:01 INFO - [mozharness: 2017-04-07 17:45:01.697985Z] Running run-tests step. 10:45:01 INFO - Running pre-action listener: _resource_record_pre_action 10:45:01 INFO - Running main action method: run_tests 10:45:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:45:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:45:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:45:01 INFO - 2017-04-07 10:45:01.712 screenresolution[1626:7543] starting screenresolution argv=screenresolution get 10:45:01 INFO - 2017-04-07 10:45:01.715 screenresolution[1626:7543] Display 0: 1600x1200x32@60 10:45:01 INFO - 2017-04-07 10:45:01.721 screenresolution[1627:7546] starting screenresolution argv=screenresolution list 10:45:01 INFO - Available Modes on Display 0 10:45:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:45:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:45:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:45:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:45:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:45:01 INFO - Intel Iris: 10:45:01 INFO - Chipset Model: Intel Iris 10:45:01 INFO - Type: GPU 10:45:01 INFO - Bus: Built-In 10:45:01 INFO - VRAM (Dynamic, Max): 1536 MB 10:45:01 INFO - Vendor: Intel (0x8086) 10:45:01 INFO - Device ID: 0x0a2e 10:45:01 INFO - Revision ID: 0x0009 10:45:01 INFO - Displays: 10:45:01 INFO - Display: 10:45:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:45:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:45:01 INFO - Display Serial Number: 1600x1200 60 10:45:01 INFO - Main Display: Yes 10:45:01 INFO - Mirror: Off 10:45:01 INFO - Online: Yes 10:45:01 INFO - Rotation: Supported 10:45:01 INFO - Return code: 0 10:45:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:45:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:45:01 INFO - Python 2.7.3 10:45:01 INFO - Return code: 0 10:45:01 INFO - grabbing minidump binary from tooltool 10:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:01 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 0x106c04d30>, '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 0x106b2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bf9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:45:01 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 10:45:01 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 10:45:01 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 10:45:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:45:02 INFO - Return code: 0 10:45:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:45:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:45:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:45:02 INFO - ENV: RUST_BACKTRACE is now 1 10:45:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:45:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:45:02 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/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0351', '--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 10:45:02 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/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0351 --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 10:45:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e3pOwUiVoe/Render', 10:45:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:02 INFO - 'HOME': '/Users/cltbld', 10:45:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:02 INFO - 'LOGNAME': 'cltbld', 10:45:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:45:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:02 INFO - 'MOZ_NO_REMOTE': '1', 10:45:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:45:02 INFO - 'NO_EM_RESTART': '1', 10:45:02 INFO - 'PAGER': '/bin/cat', 10:45:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:02 INFO - 'PWD': '/builds/slave/test', 10:45:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:45:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:02 INFO - 'RUST_BACKTRACE': '1', 10:45:02 INFO - 'SHELL': '/bin/bash', 10:45:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PRpwU5sw4/Listeners', 10:45:02 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:45:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:02 INFO - 'USER': 'cltbld', 10:45:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:02 INFO - 'XPC_FLAGS': '0x0', 10:45:02 INFO - 'XPC_SERVICE_NAME': '0', 10:45:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:02 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/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0351', '--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 10:45:02 INFO - mozversion application_buildid: 20170407090522 10:45:02 INFO - mozversion application_changeset: 2e7b7b293fdc1444430855ffb1b3d88925c9d5ad 10:45:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:45:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:45:02 INFO - mozversion application_name: Firefox 10:45:02 INFO - mozversion application_remotingname: firefox-dev 10:45:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:45:02 INFO - mozversion application_vendor: Mozilla 10:45:02 INFO - mozversion application_version: 54.0a2 10:45:02 INFO - mozversion platform_buildid: 20170407090522 10:45:02 INFO - mozversion platform_changeset: 2e7b7b293fdc1444430855ffb1b3d88925c9d5ad 10:45:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:45:02 INFO - mozversion platform_version: 54.0a2 10:45:02 INFO - using testdate: 1491587102 10:45:02 INFO - actual date: 1491587102 10:45:02 INFO - starting webserver on 'localhost:49244' 10:45:02 INFO - SUITE-START | Running 2 tests 10:45:02 INFO - TEST-START | dromaeo_css 10:45:02 INFO - operating with platform_type : mac_ 10:45:02 INFO - Initialising browser for dromaeo_css test... 10:45:02 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpfDfm3K/profile http://localhost:49244/getInfo.html) 10:45:04 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 10:45:04 INFO - PROCESS | 1637 | colorDepth:24 10:45:04 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 10:45:04 INFO - PROCESS | 1637 | __metrics 10:45:04 INFO - PROCESS | 1637 | 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" 10:45:04 INFO - PROCESS | 1637 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:45:05 INFO - TEST-INFO | 1637: exit 0 10:45:05 INFO - Browser initialized. 10:45:05 INFO - Running cycle 1/1 for dromaeo_css test... 10:45:05 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpfDfm3K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:45:17 INFO - PROCESS | 1639 | RSS: Main: 214634496 10:45:17 INFO - PROCESS | 1639 | 10:48:22 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 10:48:24 INFO - PROCESS | 1639 | RSS: Main: 407900160 10:48:24 INFO - PROCESS | 1639 | 10:50:07 INFO - PROCESS | 1639 | ************************* 10:50:07 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 10:50:07 INFO - PROCESS | 1639 | 10:50:07 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:50:07 INFO - PROCESS | 1639 | Full stack: 10:50:07 INFO - PROCESS | 1639 | ************************* 10:50:07 INFO - PROCESS | 1639 | ************************* 10:50:07 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 10:50:07 INFO - PROCESS | 1639 | 10:50:07 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:50:07 INFO - PROCESS | 1639 | Full stack: 10:50:07 INFO - PROCESS | 1639 | ************************* 10:50:07 INFO - PROCESS | 1639 | ************************* 10:50:07 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 10:50:07 INFO - PROCESS | 1639 | 10:50:07 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:50:07 INFO - PROCESS | 1639 | Full stack: 10:50:07 INFO - PROCESS | 1639 | ************************* 10:50:07 INFO - PROCESS | 1639 | ************************* 10:50:07 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 10:50:07 INFO - PROCESS | 1639 | 10:50:07 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:50:07 INFO - PROCESS | 1639 | Full stack: 10:50:07 INFO - PROCESS | 1639 | ************************* 10:51:27 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 10:51:28 INFO - PROCESS | 1639 | RSS: Main: 354955264 10:51:28 INFO - PROCESS | 1639 | 10:54:30 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 10:54:31 INFO - PROCESS | 1639 | RSS: Main: 340992000 10:54:31 INFO - PROCESS | 1639 | 10:57:33 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 10:57:34 INFO - PROCESS | 1639 | RSS: Main: 345415680 10:57:34 INFO - PROCESS | 1639 | 11:00:38 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 11:00:39 INFO - PROCESS | 1639 | RSS: Main: 325943296 11:00:39 INFO - PROCESS | 1639 | 11:03:42 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 11:03:42 INFO - PROCESS | 1639 | RSS: Main: 481533952 11:03:42 INFO - PROCESS | 1639 | 11:03:42 INFO - PROCESS | 1639 | __start_tp_report 11:03:42 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 11:03:42 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 11:03:42 INFO - PROCESS | 1639 | |i|pagename|runs| 11:03:42 INFO - PROCESS | 1639 | |0;dojo.html;3288.1943923738686;3412.476930959522;3417.8721022062723;3424.982875085625;3480.390931586972;5981.4018598140165;6006.729697163627;6009.459148676624;6036.305824830146;6057.697115144236;4847.951520484794;6008.369121242272;6009.969950150254;6015.458608725214;6020.879582408349;5378.811741589046;5396.163594642837;5424.267723857268;5469.097598896177;5507.201455788908;5313.73431328434;5334.413214546396;5365.865853353678;5429.945700543003;5551.472610102034;5505.944940550603;5518.503334699876;5543.362513310981;5551.3893471718075;5577.470140336682;5480.314960629901;5504.944950550524;5513.117901135799;5514.779408823642;5532.917006244909;5353.732313384354;5417;5445.128779395317;5452.100403433429;5464.098423760075;5779.913301300484;5812.970935145339;5822.767089316422;5827.737751801179;5850.707464626794;6326.49388048955;6332.208473940757;6335.11308416822;6358.205224346957;6369.872602547969;6276.184096067527;6379.840503987438;6402.295747467775;6414.326495717929;6432.195975503062;5868.149118377812;6156.199694039746;6159.753609855646;6165.506759459233;6169.444749972524;5452.563794896399;6363.649999250086;6365.649889256136;6486;6581;6378.936210637927;6390.4248617624635;6395.16862807832;6395.584287021329;6404.967975160188;6387.2335319761;6429.453496452854;6446.194225721785;6452.0967064610995;6475.449586785176;5289.889123284076;6250.124982502453;6262.874742505124;6315.147455093595;6363.427291543783;6121.877562448815;6356.809295721136;6358.427741503195;6366.331535188831;6367.554271200971;5155.304033955443;5159.58723302135;5163;5165.819196328111;5175.922361164586;4502.347159661831;4527.84152554659;4535.7732113394195;4581.496035436099;4582.725036497821;6017.157597936292;6033.78881739137;6041.426064523863;6069.666168360783;6075.48358389533;6392.904106438407;6419.935800642027;6447.3552644735155;6459.644719540376;6460.676966151674;5772.249607550992;6045.516358691294;6056.636601803906;6066.848328791815;6071.0286354184;5588.804391846266;5592.916106258409;5658.632188907708;5702.942970570241;5736.4550367715;6648.734050638114;6694.129763130762;6694.698738556874;6711.4966377520905;6724.092247546519;6424.421802037839;6478.222613286436;6478.740850365933;6482.70827812759;6495.2855185928565;6347.5556711029785;6413.903791443039;6555.868882622416;6607.2071351438135;6698.129243198353;6333.050042493663;6367.649779262185;6383.202099737533;6383.616982981036;6393.93606063952;6202.100695399232;6284.905726414199;6316.6841657916;6330.3669633036325;6416.839579010562;6184.969075154595;6277.466415354746;6283;6293.527985401205;6295.905561416674;6261.9373806263175;6311.463525600376;6319.24169099711;6329.683515824282;6335.714892829926;4888.315635810918;6277.246730392382;6278.748850045947;6281.780137695158;6287.528435367457;6173.506119510339;6228.159198508325;6262;6273.749050037947;6291.433770960635;6230.563860529719;6292.150559674568;6295.716692748929;6301.180846489927;6303 11:03:42 INFO - PROCESS | 1639 | |1;ext.html;789.4355535791908;810.3031393002019;870.7300736771601;887.7913690282786;889.1997202517729;38624.806875965616;39638.80180599097;39669.214885330155;39773.403398949005;39774;40091.997700057516;40143.79928100356;40209.79895100528;40248.798756006254;40327.39508907369;7312.8171795705;9179.632814687422;9309.301802364816;9334.113259240361;9352.766180845465;7362.889556656654;10939.507722152483;11193.216474846764;11235;11245.212835101505;11062.004419602234;11096.722581935435;11107.83338249927;11109.611163609276;11156.330620162775;6256.499480041586;11027.338359698444;11071.391073490955;11074.557017719282;11083.334999900031;7369.963150184269;7416.147143078561;7420.406367490642;7422.072240969879;10671.253012289142;31713.682863171536;38443.42334864979;38620.42069368934;38742.61257387418;38772.642957496355;3806.847726090953;3818.6372294631965;3827.7797980502287;3830.731848770587;3838.9616103838885;26707.46585068288;26736.866315668492;26967.595486067723;28219.57670634942;28356.858215708788;3049.6886338874274;3061.280599059219;3072.4776787946103;3211.8394080295893;3284.178955261185;350774;350938;351024;351732;353360;51809.48190518122;51892.74053629783;51998.48001520012;52016;52059.73970130125;53178;53339;53445.7327713359;53490.66273343198;53597.052162131455;14530.05554638945;14659.193744343951;14671.193084380462;14671.853281467262;14685.77971330431;12646.30445325516;12648;12654.936725316498;12659.810102848465;12677.429515671816;10690.305130166515;12417.937910310391;12425.19236249659;12479.812802807965;12530.874691252971;5240.405999030428;5284.392294886137;5294.629375943647;5310.60170487215;5324.227986941949;966.8735922650175;967.4920666649924;975.9508528332071;983.2723784399636;990.3958585262984;24612.630810537856;26989.730102699115;28029.018984335857;28224.625183096654;28364.85817570899;1070.0690399352613;1075.8224892892586;1108.501174471475;1110.3227031510771;1111.6109361723331;13084.28036458004;13654.112482688593;13797.724045519033;13847.723045539033;13873.930630346986;419089;423067;436793;437385;439055;47461.76269118701;47544;47636.28545571819;47665.808354790715;47687;1384.6469150366793;1420.8366037905566;1422.9715405691827;1449.2463918762396;1450.2821103553597;22337.664935025663;22490.437739056655;25274.873625631753;26229;26291.07981220648;38493.61506385012;38505;38527.229455410175;38538.80730596329;38697.41953870638;443122;445595;446674;447352;447598;28712;29420.55869162007;29430.411391771617;29467.55798662979;29548.261293467836;36621.901342959754;37673.86978390653;37675;37753.62246377501;37897.052573685876;25552;26476;27106.864465677547;27518;27651.308717281423;27018.784154713478;27275.59086613742;27309;27369.58945615857;27456.176314710592;30567.694323056483;31068.68931310658;31353.90261340842;31563.684363156994;31767.364652706354;26856.462870742085;26883.731162688906;27377.45245095047;27856.860715696293;28026.719732802412;16758.575521080846;19436.70844937299;20146.79853201449;21071.57856842824;21998.670019949393 11:03:42 INFO - PROCESS | 1639 | |2;jquery.html;381.3935842011202;424.79680552802245;427.98716038518836;431.3099041533547;442.57954942804344;15928;20503.384898453052;21612.783872161275;21825.38095428551;21879.12483500658;20726.792732072674;21346.786532134673;21621;21830.672539911913;22403.32790016298;4761.761911904408;5741.799037033705;6421.7752378666755;6526.836829079263;6578.835529111787;4187.874363769091;4420.20436321462;4459.487158976711;6657.167854018247;6701.664916754143;7185.497015208937;7516.774496765106;7587.81030474237;7618.5428874267445;7629.084509858824;6291.748330066792;7455.776326710207;7592.316691497736;7596.08846938371;7610.1628820829665;7144.857102857965;7238.167610724781;7307.669316755349;7341.706331746724;7373;24704.505909881882;25639.846206920545;25744.356391090187;25855.0950716724;25896.352591185183;2376.4415362389823;2852.8145670531558;2855.0863723608454;2878.736917080504;2914.5191043477803;15223.923880380638;16077.678446431122;16162.515124546282;16377.017378957182;16642.16789160549;2015.7813146443093;2105.4420578546697;2106.2101711858054;2106.3575609439113;2108.9156433742787;132649;154992;162178;163094;176849.99999999872;20169.29407470761;20370.49073773138;20622.896885515776;20635;20693;31763;32230;32357.51463728046;32411;32523.674763252537;10117;10273.74315642115;10498.265121441425;10511.526981285708;10631.308964917256;8614.310855131574;8634.6977855776;8672.739817805475;8804.8679269811;8817.426867253607;7088.574685518783;8384.455010424303;9373.343865929455;9414.924037098712;9555.474448905237;2769.3630464993166;3593.586737525166;3864.401017842239;4032.6370626643743;4051.493563304587;866.2377108144792;974.7465658928588;995.5370752600076;1011.1960991012163;1052.6789329254498;20624.27815026467;20680.862552559454;20746.585068298853;20783.58432831335;20826.47933801667;1001.1790331927749;1005.5123265216364;1037.5486663301488;1040.422565476168;1040.9947950260353;8381.664733410717;10191.337563058378;10794.92530919842;10815.945920270347;10864.728381790519;737766;744410;762027;768237;775043;157177;158236;161352.93235338185;162601;166320.16839915965;1078.8921107889148;1270.5426046623209;1324.331212737542;1328.2030781531198;1335.706144648176;19180;19216.61566768685;19699.40901772949;19744.605107897474;19787.604247914674;49035.754821225666;59906.60373585182;63057;64382.67808660927;66551.66724166348;87716;88072;88428;90894.54552727194;90938;11238.66284011481;11355.602553910574;11392.43037848121;11601.419929003347;11737.647870563897;12208.267503949792;12283.877161228273;12298.754024919273;12527.56153534622;12622.242665440102;9040.864387034067;9222.769430764285;10818.29681070728;10932.672019839418;11008.174386920853;8625.180607842245;10091.091801737879;10130.746731331776;10231.43727095017;10320.43237621938;10896.57714009655;11065.557377705123;11144.387058711849;11215.71960700989;11275.830862537232;11133.443327833738;11194.496247669038;11469.942650287028;11621.418929053683;11623.76752464929;5864.179014937827;9971.40171589707;10574.048335649828;10977.28647637901;11122.61070862516 11:03:42 INFO - PROCESS | 1639 | |3;mootools.html;1444.5449683349743;1533.2563706602289;1533.8926275160736;1561.4769052367456;1565.9373625054998;9710.465924374157;10772.946135269323;11285;11297.096232301417;11331.43342832858;10751.946240268808;11214.66356009319;11277.661670149888;11302.717432064203;11326.773464530705;2375.6911601491784;2421.406755344943;2423.9151629692965;2426.3570153909227;2428.720697119836;2366.089055713554;2420.9152679656218;2450.6691596634405;2456.349067497115;2458.815588830836;2121.2575598540466;2368.7749663781915;2427.1626288930293;2430.149447693302;2463.6304554316816;2344.191254017361;2399.892004859777;2412.5657381671203;2417.9274621761374;2431.2341612392215;2392.9042838286446;2410.08416801616;2415.311636183697;2415.830891837572;2417.5648383291;10941.671749847517;11181.273217240854;11183;11209.327440353605;11210.943945280304;5713;5902.439268269508;5936.317323507766;5954.523638108941;5959.284885813687;3602.2435288589427;3625.619309972468;3660.231351416179;3694.5751238607636;3742.4947632069593;1149.7987852125846;1157.2304417562395;1191.356667399603;1198.7003249187703;1206.6440400081997;13898.861011389858;14233.359498822474;14495.27523623825;15471.226438678133;15724.292406841605;4307.676924230633;4422.071365013319;4450.577195166454;4454.821807127743;4455.866324010285;11559.075273978062;11586.420678966018;11606.419679016015;11607.071434285237;11641.6507504775;4388.868333949987;5080.949190508048;5504.834854954358;5693.658380497183;5720.599558030981;5089.00764350948;5258.6581872178185;5260.947810437928;5334.039872822929;5344.305240318734;3782.6028267031566;3864.458975743342;3866.304065268279;3869.1487872667967;3888.5333759948985;1008.729001458168;1009.0817356205818;1019.8776146862424;1028.3058935218846;1029.0327092532996;889.2308153447221;920.963161473547;926.8980412154658;939.7368736753583;974.3666616699061;5621.575393550074;6527.379898909596;6537.280899101095;6683.2648408675;6831.897521537181;817.91217680486;971.7473456901115;983.695054533097;987.0721521769514;988.7577543501889;3718.3678774608384;3941.8423263069158;4103.610157035077;4227.852025179165;4248.681348898845;13019.544315949086;13656.385462654205;13662.31688415595;13672.316384180951;13702.588922332347;13169.802452963213;13466.192028478323;13593.98045146618;13596.184228946096;13626.591202263948;9944.751381215383;10234.744131396774;10244.897551024394;10280.434576098387;10285.794284114276;5376.516113549799;6767.323267673193;6791.45668346541;6903.6203008835;6966.024756534191;10571.735706607396;11805.940970295094;12330;12382;12412.255264684149;11010.229283950366;11439.656810295704;11867.347295898808;12071;12321.137520373846;9002.90997090047;9354.251659867059;9400.435973841591;9400.576974036048;9460.621575136918;9991.850122248026;10252.282340236112;10296.691099267035;10301.536430860779;10302.33034852732;8799.516026618341;8886.155815197546;8938.24024957886;8985.19133277982;9110.635574576943;8627.784305392415;8660;8685.392022558613;8735.868961965703;8777.648894044421;7855.60721963888;8031.678732850621;8034.87947680797;8048;8060.992375953006;5784.4504772046585;5857.677827719516;5858.384869588632;5983.401659833982;6054.243219597551;5660.745266463102;5727.770889164553;5860.531157507475;5918.17145599625;6045.365236650088 11:03:42 INFO - PROCESS | 1639 | |4;prototype.html;350.90700964244473;371.45581722776126;388.39604415134465;391.60643553229016;403.41504818013885;4574.428196475441;4833.323334733132;5302.284191634129;5339.919901201481;5353.62524623277;4487.842925497608;4996.526024822677;5022.547970682638;5026.924596131052;5102.897942041157;3931.8230679619487;4210.71573170183;4399.208142534342;4503.864884053483;4511.075229577945;3906.921861562767;4013.2776100301935;4252.8953863679535;4455.665825063117;4471.071448201434;3581.8209089545417;3889.2804831106337;4207.663386929054;4222.746635201897;4265.893352666193;3687.59436461989;3798.4872042274214;4074.6129117733767;4275.315949448089;4275.5296917339065;3848.730588858781;3864.130570621616;3864.7294689371756;3882.6893848492055;3883.941740873889;4247.704450142705;4634.629229661653;4739.142379061455;5001.249812528114;5116.437191908887;4229.631406412388;4565.61192298655;4682.859514214579;4965.354503914468;4970.950290497085;4928.630352723524;5063.873403164914;5115.948840511622;5488.69812160331;5497.0695952393835;3888.572257051722;4365.43249377582;4392.275274579691;4404.55954404557;4419.4254746883025;5088.6946783193125;5155.5617772489;5478.397376288605;5655.1799989001365;5713.171785028792;3843.942340864889;4192.392103145027;4487.259602165672;4502.797374118172;4553.544645535486;4031.3549832026733;4370.759608221515;4646.7444290564345;4647.767611619473;4738.407699037621;4041.5450437842355;4280.828766849291;4791.275140949256;4991.376077990251;5083.771230294646;4476.6418686505;4878.706872860805;5495.807646732345;5547.66855954568;5680.2794169818835;1370.9862901371057;1481.8959874893274;1483.6142602923317;1488.2318886870412;1507.7964474795763;1079.4063265204106;1108.8613923259593;1110.5502271580024;1179.731788327534;1193.886580774825;4503.662225333113;4559.401141349465;4916.345173606284;5124.666896651705;5149.9742501288;5083.949160508421;5545.279113715272;5654.483754729106;5687.374388817227;5711.942880571224;4305.225059489322;4774.641901857374;4920.212765957501;4953.563466594647;4977.701337919736;2551.553478141318;2650.575907854734;2861.7424431801237;2951;2974.1114392360573;5444.141825541622;5482.862928426821;5507.972460137754;5534.1975413564805;5946.256717910262;5177.896442071137;5229.921551176735;5457.317835270591;5872.530197584183;5872.858020958766;5249.501297376743;5401.4868587484125;5688.516476099412;5973.372795856372;5999.460048595647;4986.401631804207;5526.530244929226;5664.320281566238;5740.827775166752;5747.971260143672;4614.723116613014;5237.450067742832;5262.6843289177705;5532.30846144232;5550.972245138749;5121.590272778094;5158.845234642967;5582.911332290165;5831.017454065638;5891.852703682443;3780.886573402692;4083.77539235333;4350.608445239944;4505.684602077823;4545.8181672734045;4993.152533562605;5228.294180285765;5622.8313150605545;5690.77236910519;5697.7151142443545;4585.908281834411;5338.279332290091;5365.409804921534;5388.380336261223;5399.352077750695;4617.53824617549;4726.480087190337;4923.704577725348;5425.267588875451;5530.2625200526;5046.798128074836;5108.719020453911;5299.947000529945;5382.811601593926;5390.784368625211;5171.431142574239;5520.66875987442;5524.309461317335;5543.667379957215;5584.441555844383;4658.8120029392285;4825.806967721252;5289.497497737709;5403.405625381126;5447.727613619382 11:03:42 INFO - PROCESS | 1639 | |5;yui.html;714.5998240985045;737.9963100184501;769.3537428560008;826.3513142183385;839.5004972041633;15174.772378414318;16131.75802362964;16161.595960100974;16193.271302791372;16239.106849123293;12329.876701232984;14732.852671473282;14765.483208087719;14767.926160369183;14937.178455184987;945.078548415293;948.3409030723667;961.4663861556833;965.8985806490323;966.4298064142155;953.2564599612278;964.4984608003833;969.8545218217254;970.3595626886256;974.7319487141023;1003.9849402258972;1005.6832097889178;1007.7631756537207;1008.6116845014685;1010.5301035018715;1000.8198524265629;1002.3334482569082;1003.362864580989;1004.4224570871742;1004.9849252261222;740.3447948565558;741.1550832051465;742.8365759532949;743.8995735575683;743.9962800185964;15668.373265069384;15774.605634859221;15903.920480397639;16466.83533164665;16753.246103925354;11146.331220126818;12071.637850864488;12109.878901211052;12258.754824903452;12541.059420543404;11540.24988375753;11566.248193867372;11818.645440636794;12017.93991030048;12262.38688065602;3644.8542058317635;3720.4419337099653;3742.2328422673286;3880.7477513961785;3887.105965627893;118982;119075;120134.39932800368;121921.39039304835;122627;10952.507137178845;10991.505382257817;11054.778904421866;11097.278676886139;11105.611303604335;22663;23016.65475017876;23375.83120843951;23551.764482354956;23581.410464738517;7682.095407312781;7697.769066928001;7726.304632583095;7813.413993950477;7835;4302;4315.331123675835;4409.470863496337;4472.329150627432;4577.7340216593;3521.1373213562842;3586.65500437336;3598.0824889653495;3608.4767708682093;3614.1145419372424;615.5075939248583;624.1075262374757;645.3610925184034;650.3658932540735;655.7672026430644;669.3667451418523;691.1533371619726;696.916370035599;704.6828926982878;713.4399496395305;9938.850917236246;10513.84229236562;10530.684079477627;10659.093977011886;10713.357198567955;678.5657179405184;716.5808002318598;727.5634619228421;735.2830989785024;744.0736283327184;4856;4974.602031837444;5031.169856973558;5035.748212589356;5071.46749591295;97917;99278.5036074815;101080;101626;102278;51890.74054629703;53582.73208633932;60765.69617151886;74410;90933.54533227424;50254;76649;77481.61259193781;78180.60909695415;78639;10512.789744205225;10795;10969.341839489656;11178.776424471627;11257.605983790529;50178.74910625423;50327.74836125796;50671.74664126656;50961.47115586538;51380.74309628428;62095.50777452707;62427.375726243976;63466.36533634605;64185.35814641794;64394.678026611444;12330.19853709506;12479.875201248235;12527;12647.683807904876;13081.476740930257;14312.642183945485;14457.710845783236;14530.709385812435;14553.272336382926;15308.23458827032;13176.60470185896;13681.247531385869;13895.722085558435;14006.789898151332;14078.577642670736;13316.46734130624;13893.58319250424;14014.439422423397;14032.859671403156;14206.928965355108;12796.296203708513;13392.665183370495;13533.86466135326;13686.726265474834;13709.383077761326;9761.707148785548;10064.29549931527;10257.692269231935;10368.015038571324;10407.323523970917;9578.425294482353;11094.889051109385;11293.604723834625;11341.943290283496;11352.318860868374 11:03:42 INFO - PROCESS | 1639 | __end_tp_report 11:03:42 INFO - PROCESS | 1639 | __start_cc_report 11:03:42 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,2186 11:03:42 INFO - PROCESS | 1639 | __end_cc_report 11:03:42 INFO - PROCESS | 1639 | __startTimestamp1491588222728__endTimestamp 11:03:42 INFO - PROCESS | 1639 | 11:03:42 INFO - PROCESS | 1639 | ------- Summary: start ------- 11:03:42 INFO - PROCESS | 1639 | Number of tests: 6 11:03:42 INFO - PROCESS | 1639 | 11:03:42 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5939.07 Median:6228.16 stddev:665.92 (10.7%) stddev-sans-first:634.66 11:03:42 INFO - PROCESS | 1639 | Values: 3288.2 3412.5 3417.9 3425.0 3480.4 5981.4 6006.7 6009.5 6036.3 6057.7 4848.0 6008.4 6010.0 6015.5 6020.9 5378.8 5396.2 5424.3 5469.1 5507.2 5313.7 5334.4 5365.9 5429.9 5551.5 5505.9 5518.5 5543.4 5551.4 5577.5 5480.3 5504.9 5513.1 5514.8 5532.9 5353.7 5417.0 5445.1 5452.1 5464.1 5779.9 5813.0 5822.8 5827.7 5850.7 6326.5 6332.2 6335.1 6358.2 6369.9 6276.2 6379.8 6402.3 6414.3 6432.2 5868.1 6156.2 6159.8 6165.5 6169.4 5452.6 6363.6 6365.6 6486.0 6581.0 6378.9 6390.4 6395.2 6395.6 6405.0 6387.2 6429.5 6446.2 6452.1 6475.4 5289.9 6250.1 6262.9 6315.1 6363.4 6121.9 6356.8 6358.4 6366.3 6367.6 5155.3 5159.6 5163.0 5165.8 5175.9 4502.3 4527.8 4535.8 4581.5 4582.7 6017.2 6033.8 6041.4 6069.7 6075.5 6392.9 6419.9 6447.4 6459.6 6460.7 5772.2 6045.5 6056.6 6066.8 6071.0 5588.8 5592.9 5658.6 5702.9 5736.5 6648.7 6694.1 6694.7 6711.5 6724.1 6424.4 6478.2 6478.7 6482.7 6495.3 6347.6 6413.9 6555.9 6607.2 6698.1 6333.1 6367.6 6383.2 6383.6 6393.9 6202.1 6284.9 6316.7 6330.4 6416.8 6185.0 6277.5 6283.0 6293.5 6295.9 6261.9 6311.5 6319.2 6329.7 6335.7 4888.3 6277.2 6278.7 6281.8 6287.5 6173.5 6228.2 6262.0 6273.7 6291.4 6230.6 6292.2 6295.7 6301.2 6303.0 11:03:42 INFO - PROCESS | 1639 | 11:03:42 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:53725.26 Median:31638.68 stddev:109296.20 (345.5%) stddev-sans-first:109530.54 11:03:42 INFO - PROCESS | 1639 | Values: 789.4 810.3 870.7 887.8 889.2 38624.8 39638.8 39669.2 39773.4 39774.0 40092.0 40143.8 40209.8 40248.8 40327.4 7312.8 9179.6 9309.3 9334.1 9352.8 7362.9 10939.5 11193.2 11235.0 11245.2 11062.0 11096.7 11107.8 11109.6 11156.3 6256.5 11027.3 11071.4 11074.6 11083.3 7370.0 7416.1 7420.4 7422.1 10671.3 31713.7 38443.4 38620.4 38742.6 38772.6 3806.8 3818.6 3827.8 3830.7 3839.0 26707.5 26736.9 26967.6 28219.6 28356.9 3049.7 3061.3 3072.5 3211.8 3284.2 350774.0 350938.0 351024.0 351732.0 353360.0 51809.5 51892.7 51998.5 52016.0 52059.7 53178.0 53339.0 53445.7 53490.7 53597.1 14530.1 14659.2 14671.2 14671.9 14685.8 12646.3 12648.0 12654.9 12659.8 12677.4 10690.3 12417.9 12425.2 12479.8 12530.9 5240.4 5284.4 5294.6 5310.6 5324.2 966.9 967.5 976.0 983.3 990.4 24612.6 26989.7 28029.0 28224.6 28364.9 1070.1 1075.8 1108.5 1110.3 1111.6 13084.3 13654.1 13797.7 13847.7 13873.9 419089.0 423067.0 436793.0 437385.0 439055.0 47461.8 47544.0 47636.3 47665.8 47687.0 1384.6 1420.8 1423.0 1449.2 1450.3 22337.7 22490.4 25274.9 26229.0 26291.1 38493.6 38505.0 38527.2 38538.8 38697.4 443122.0 445595.0 446674.0 447352.0 447598.0 28712.0 29420.6 29430.4 29467.6 29548.3 36621.9 37673.9 37675.0 37753.6 37897.1 25552.0 26476.0 27106.9 27518.0 27651.3 27018.8 27275.6 27309.0 27369.6 27456.2 30567.7 31068.7 31353.9 31563.7 31767.4 26856.5 26883.7 27377.5 27856.9 28026.7 16758.6 19436.7 20146.8 21071.6 21998.7 11:03:42 INFO - PROCESS | 1639 | 11:03:42 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:43412.85 Median:21616.89 stddev:126677.97 (586.0%) stddev-sans-first:126992.13 11:03:42 INFO - PROCESS | 1639 | Values: 381.4 424.8 428.0 431.3 442.6 15928.0 20503.4 21612.8 21825.4 21879.1 20726.8 21346.8 21621.0 21830.7 22403.3 4761.8 5741.8 6421.8 6526.8 6578.8 4187.9 4420.2 4459.5 6657.2 6701.7 7185.5 7516.8 7587.8 7618.5 7629.1 6291.7 7455.8 7592.3 7596.1 7610.2 7144.9 7238.2 7307.7 7341.7 7373.0 24704.5 25639.8 25744.4 25855.1 25896.4 2376.4 2852.8 2855.1 2878.7 2914.5 15223.9 16077.7 16162.5 16377.0 16642.2 2015.8 2105.4 2106.2 2106.4 2108.9 132649.0 154992.0 162178.0 163094.0 176850.0 20169.3 20370.5 20622.9 20635.0 20693.0 31763.0 32230.0 32357.5 32411.0 32523.7 10117.0 10273.7 10498.3 10511.5 10631.3 8614.3 8634.7 8672.7 8804.9 8817.4 7088.6 8384.5 9373.3 9414.9 9555.5 2769.4 3593.6 3864.4 4032.6 4051.5 866.2 974.7 995.5 1011.2 1052.7 20624.3 20680.9 20746.6 20783.6 20826.5 1001.2 1005.5 1037.5 1040.4 1041.0 8381.7 10191.3 10794.9 10815.9 10864.7 737766.0 744410.0 762027.0 768237.0 775043.0 157177.0 158236.0 161352.9 162601.0 166320.2 1078.9 1270.5 1324.3 1328.2 1335.7 19180.0 19216.6 19699.4 19744.6 19787.6 49035.8 59906.6 63057.0 64382.7 66551.7 87716.0 88072.0 88428.0 90894.5 90938.0 11238.7 11355.6 11392.4 11601.4 11737.6 12208.3 12283.9 12298.8 12527.6 12622.2 9040.9 9222.8 10818.3 10932.7 11008.2 8625.2 10091.1 10130.7 10231.4 10320.4 10896.6 11065.6 11144.4 11215.7 11275.8 11133.4 11194.5 11469.9 11621.4 11623.8 5864.2 9971.4 10574.0 10977.3 11122.6 11:03:42 INFO - PROCESS | 1639 | 11:03:42 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:6678.62 Median:2461.22 stddev:4163.55 (169.2%) stddev-sans-first:4156.65 11:03:42 INFO - PROCESS | 1639 | Values: 1444.5 1533.3 1533.9 1561.5 1565.9 9710.5 10772.9 11285.0 11297.1 11331.4 10751.9 11214.7 11277.7 11302.7 11326.8 2375.7 2421.4 2423.9 2426.4 2428.7 2366.1 2420.9 2450.7 2456.3 2458.8 2121.3 2368.8 2427.2 2430.1 2463.6 2344.2 2399.9 2412.6 2417.9 2431.2 2392.9 2410.1 2415.3 2415.8 2417.6 10941.7 11181.3 11183.0 11209.3 11210.9 5713.0 5902.4 5936.3 5954.5 5959.3 3602.2 3625.6 3660.2 3694.6 3742.5 1149.8 1157.2 1191.4 1198.7 1206.6 13898.9 14233.4 14495.3 15471.2 15724.3 4307.7 4422.1 4450.6 4454.8 4455.9 11559.1 11586.4 11606.4 11607.1 11641.7 4388.9 5080.9 5504.8 5693.7 5720.6 5089.0 5258.7 5260.9 5334.0 5344.3 3782.6 3864.5 3866.3 3869.1 3888.5 1008.7 1009.1 1019.9 1028.3 1029.0 889.2 921.0 926.9 939.7 974.4 5621.6 6527.4 6537.3 6683.3 6831.9 817.9 971.7 983.7 987.1 988.8 3718.4 3941.8 4103.6 4227.9 4248.7 13019.5 13656.4 13662.3 13672.3 13702.6 13169.8 13466.2 13594.0 13596.2 13626.6 9944.8 10234.7 10244.9 10280.4 10285.8 5376.5 6767.3 6791.5 6903.6 6966.0 10571.7 11805.9 12330.0 12382.0 12412.3 11010.2 11439.7 11867.3 12071.0 12321.1 9002.9 9354.3 9400.4 9400.6 9460.6 9991.9 10252.3 10296.7 10301.5 10302.3 8799.5 8886.2 8938.2 8985.2 9110.6 8627.8 8660.0 8685.4 8735.9 8777.6 7855.6 8031.7 8034.9 8048.0 8061.0 5784.5 5857.7 5858.4 5983.4 6054.2 5660.7 5727.8 5860.5 5918.2 6045.4 11:03:42 INFO - PROCESS | 1639 | 11:03:42 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4535.93 Median:4921.96 stddev:1267.84 (25.8%) stddev-sans-first:1231.87 11:03:42 INFO - PROCESS | 1639 | Values: 350.9 371.5 388.4 391.6 403.4 4574.4 4833.3 5302.3 5339.9 5353.6 4487.8 4996.5 5022.5 5026.9 5102.9 3931.8 4210.7 4399.2 4503.9 4511.1 3906.9 4013.3 4252.9 4455.7 4471.1 3581.8 3889.3 4207.7 4222.7 4265.9 3687.6 3798.5 4074.6 4275.3 4275.5 3848.7 3864.1 3864.7 3882.7 3883.9 4247.7 4634.6 4739.1 5001.2 5116.4 4229.6 4565.6 4682.9 4965.4 4971.0 4928.6 5063.9 5115.9 5488.7 5497.1 3888.6 4365.4 4392.3 4404.6 4419.4 5088.7 5155.6 5478.4 5655.2 5713.2 3843.9 4192.4 4487.3 4502.8 4553.5 4031.4 4370.8 4646.7 4647.8 4738.4 4041.5 4280.8 4791.3 4991.4 5083.8 4476.6 4878.7 5495.8 5547.7 5680.3 1371.0 1481.9 1483.6 1488.2 1507.8 1079.4 1108.9 1110.6 1179.7 1193.9 4503.7 4559.4 4916.3 5124.7 5150.0 5083.9 5545.3 5654.5 5687.4 5711.9 4305.2 4774.6 4920.2 4953.6 4977.7 2551.6 2650.6 2861.7 2951.0 2974.1 5444.1 5482.9 5508.0 5534.2 5946.3 5177.9 5229.9 5457.3 5872.5 5872.9 5249.5 5401.5 5688.5 5973.4 5999.5 4986.4 5526.5 5664.3 5740.8 5748.0 4614.7 5237.5 5262.7 5532.3 5551.0 5121.6 5158.8 5582.9 5831.0 5891.9 3780.9 4083.8 4350.6 4505.7 4545.8 4993.2 5228.3 5622.8 5690.8 5697.7 4585.9 5338.3 5365.4 5388.4 5399.4 4617.5 4726.5 4923.7 5425.3 5530.3 5046.8 5108.7 5299.9 5382.8 5390.8 5171.4 5520.7 5524.3 5543.7 5584.4 4658.8 4825.8 5289.5 5403.4 5447.7 11:03:42 INFO - PROCESS | 1639 | 11:03:42 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20027.26 Median:22839.83 stddev:28906.24 (126.6%) stddev-sans-first:28950.96 11:03:42 INFO - PROCESS | 1639 | Values: 714.6 738.0 769.4 826.4 839.5 15174.8 16131.8 16161.6 16193.3 16239.1 12329.9 14732.9 14765.5 14767.9 14937.2 945.1 948.3 961.5 965.9 966.4 953.3 964.5 969.9 970.4 974.7 1004.0 1005.7 1007.8 1008.6 1010.5 1000.8 1002.3 1003.4 1004.4 1005.0 740.3 741.2 742.8 743.9 744.0 15668.4 15774.6 15903.9 16466.8 16753.2 11146.3 12071.6 12109.9 12258.8 12541.1 11540.2 11566.2 11818.6 12017.9 12262.4 3644.9 3720.4 3742.2 3880.7 3887.1 118982.0 119075.0 120134.4 121921.4 122627.0 10952.5 10991.5 11054.8 11097.3 11105.6 22663.0 23016.7 23375.8 23551.8 23581.4 7682.1 7697.8 7726.3 7813.4 7835.0 4302.0 4315.3 4409.5 4472.3 4577.7 3521.1 3586.7 3598.1 3608.5 3614.1 615.5 624.1 645.4 650.4 655.8 669.4 691.2 696.9 704.7 713.4 9938.9 10513.8 10530.7 10659.1 10713.4 678.6 716.6 727.6 735.3 744.1 4856.0 4974.6 5031.2 5035.7 5071.5 97917.0 99278.5 101080.0 101626.0 102278.0 51890.7 53582.7 60765.7 74410.0 90933.5 50254.0 76649.0 77481.6 78180.6 78639.0 10512.8 10795.0 10969.3 11178.8 11257.6 50178.7 50327.7 50671.7 50961.5 51380.7 62095.5 62427.4 63466.4 64185.4 64394.7 12330.2 12479.9 12527.0 12647.7 13081.5 14312.6 14457.7 14530.7 14553.3 15308.2 13176.6 13681.2 13895.7 14006.8 14078.6 13316.5 13893.6 14014.4 14032.9 14206.9 12796.3 13392.7 13533.9 13686.7 13709.4 9761.7 10064.3 10257.7 10368.0 10407.3 9578.4 11094.9 11293.6 11341.9 11352.3 11:03:42 INFO - PROCESS | 1639 | -------- Summary: end -------- 11:03:42 INFO - PROCESS | 1639 | 11:03:43 INFO - TEST-INFO | 1639: exit 0 11:03:43 INFO - TEST-OK | dromaeo_css | took 1121126ms 11:03:43 INFO - TEST-START | kraken 11:03:43 INFO - operating with platform_type : mac_ 11:03:43 INFO - Initialising browser for kraken test... 11:03:43 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpzH5ynb/profile http://localhost:49244/getInfo.html) 11:03:45 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 11:03:45 INFO - PROCESS | 1650 | colorDepth:24 11:03:45 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 11:03:45 INFO - PROCESS | 1650 | __metrics 11:03:45 INFO - PROCESS | 1650 | 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" 11:03:45 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:03:46 INFO - TEST-INFO | 1650: exit 0 11:03:46 INFO - Browser initialized. 11:03:46 INFO - Running cycle 1/1 for kraken test... 11:03:46 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpzH5ynb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:03:58 INFO - PROCESS | 1651 | RSS: Main: 210984960 11:03:58 INFO - PROCESS | 1651 | 11:04:01 INFO - PROCESS | 1651 | 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) 11:04:02 INFO - PROCESS | 1651 | RSS: Main: 264380416 11:04:02 INFO - PROCESS | 1651 | 11:04:04 INFO - PROCESS | 1651 | 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) 11:04:05 INFO - PROCESS | 1651 | RSS: Main: 277774336 11:04:05 INFO - PROCESS | 1651 | 11:04:08 INFO - PROCESS | 1651 | 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) 11:04:09 INFO - PROCESS | 1651 | RSS: Main: 374013952 11:04:09 INFO - PROCESS | 1651 | 11:04:11 INFO - PROCESS | 1651 | 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) 11:04:11 INFO - PROCESS | 1651 | RSS: Main: 277843968 11:04:11 INFO - PROCESS | 1651 | 11:04:14 INFO - PROCESS | 1651 | 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) 11:04:14 INFO - PROCESS | 1651 | RSS: Main: 279486464 11:04:14 INFO - PROCESS | 1651 | 11:04:17 INFO - PROCESS | 1651 | 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) 11:04:18 INFO - PROCESS | 1651 | RSS: Main: 320172032 11:04:18 INFO - PROCESS | 1651 | 11:04:21 INFO - PROCESS | 1651 | 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) 11:04:21 INFO - PROCESS | 1651 | RSS: Main: 320626688 11:04:21 INFO - PROCESS | 1651 | 11:04:24 INFO - PROCESS | 1651 | 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) 11:04:25 INFO - PROCESS | 1651 | RSS: Main: 316092416 11:04:25 INFO - PROCESS | 1651 | 11:04:27 INFO - PROCESS | 1651 | 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) 11:04:27 INFO - PROCESS | 1651 | RSS: Main: 296022016 11:04:27 INFO - PROCESS | 1651 | 11:04:29 INFO - PROCESS | 1651 | 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) 11:04:30 INFO - PROCESS | 1651 | RSS: Main: 300728320 11:04:30 INFO - PROCESS | 1651 | 11:04:32 INFO - PROCESS | 1651 | 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) 11:04:33 INFO - PROCESS | 1651 | RSS: Main: 303677440 11:04:33 INFO - PROCESS | 1651 | 11:04:35 INFO - PROCESS | 1651 | 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) 11:04:36 INFO - PROCESS | 1651 | RSS: Main: 318791680 11:04:36 INFO - PROCESS | 1651 | 11:04:39 INFO - PROCESS | 1651 | 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) 11:04:40 INFO - PROCESS | 1651 | RSS: Main: 311652352 11:04:40 INFO - PROCESS | 1651 | 11:04:41 INFO - PROCESS | 1651 | 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) 11:04:42 INFO - PROCESS | 1651 | RSS: Main: 356605952 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | __start_tp_report 11:04:42 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 11:04:42 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 11:04:42 INFO - PROCESS | 1651 | |i|pagename|runs| 11:04:42 INFO - PROCESS | 1651 | |0;ai-astar;103;116;115;115;115;116;115;115;114;115 11:04:42 INFO - PROCESS | 1651 | |1;audio-beat-detection;143;109;110;144;108;110;110;107;130;109 11:04:42 INFO - PROCESS | 1651 | |2;audio-dft;183;184;183;183;182;184;183;185;183;184 11:04:42 INFO - PROCESS | 1651 | |3;audio-fft;81;62;61;91;61;60;60;60;78;61 11:04:42 INFO - PROCESS | 1651 | |4;audio-oscillator;118;99;107;69;69;68;82;70;70;106 11:04:42 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;87;87;88;87;88;87;88;87;87;87 11:04:42 INFO - PROCESS | 1651 | |6;imaging-darkroom;96;95;96;95;94;95;95;96;94;95 11:04:42 INFO - PROCESS | 1651 | |7;imaging-desaturate;85;86;85;85;87;86;86;85;84;85 11:04:42 INFO - PROCESS | 1651 | |8;json-parse-financial;62;60;61;63;63;62;63;63;62;64 11:04:42 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;54;60;64;63;58;57;57;56;57;58 11:04:42 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;95;100;86;86;82;86;83;80;92;99 11:04:42 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;141;139;146;137;139;145;141;150;184;139 11:04:42 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;168;165;167;145;212;216;182;162;163;164 11:04:42 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;66;65;65;65;64;65;65;62;64;64 11:04:42 INFO - PROCESS | 1651 | __end_tp_report 11:04:42 INFO - PROCESS | 1651 | __start_cc_report 11:04:42 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2213 11:04:42 INFO - PROCESS | 1651 | __end_cc_report 11:04:42 INFO - PROCESS | 1651 | __startTimestamp1491588282230__endTimestamp 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | ------- Summary: start ------- 11:04:42 INFO - PROCESS | 1651 | Number of tests: 14 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:3.87 (3.4%) stddev-sans-first:0.60 11:04:42 INFO - PROCESS | 1651 | Values: 103.0 116.0 115.0 115.0 115.0 116.0 115.0 115.0 114.0 115.0 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:118.00 Median:110.00 stddev:14.98 (13.6%) stddev-sans-first:12.87 11:04:42 INFO - PROCESS | 1651 | Values: 143.0 109.0 110.0 144.0 108.0 110.0 110.0 107.0 130.0 109.0 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:183.40 Median:183.50 stddev:0.84 (0.5%) stddev-sans-first:0.88 11:04:42 INFO - PROCESS | 1651 | Values: 183.0 184.0 183.0 183.0 182.0 184.0 183.0 185.0 183.0 184.0 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:67.50 Median:61.50 stddev:11.40 (18.5%) stddev-sans-first:11.00 11:04:42 INFO - PROCESS | 1651 | Values: 81.0 62.0 61.0 91.0 61.0 60.0 60.0 60.0 78.0 61.0 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:85.80 Median:69.50 stddev:19.62 (28.2%) stddev-sans-first:17.00 11:04:42 INFO - PROCESS | 1651 | Values: 118.0 99.0 107.0 69.0 69.0 68.0 82.0 70.0 70.0 106.0 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:87.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 11:04:42 INFO - PROCESS | 1651 | Values: 87.0 87.0 88.0 87.0 88.0 87.0 88.0 87.0 87.0 87.0 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 11:04:42 INFO - PROCESS | 1651 | Values: 96.0 95.0 96.0 95.0 94.0 95.0 95.0 96.0 94.0 95.0 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:85.50 stddev:0.84 (1.0%) stddev-sans-first:0.88 11:04:42 INFO - PROCESS | 1651 | Values: 85.0 86.0 85.0 85.0 87.0 86.0 86.0 85.0 84.0 85.0 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:63.00 stddev:1.16 (1.8%) stddev-sans-first:1.22 11:04:42 INFO - PROCESS | 1651 | Values: 62.0 60.0 61.0 63.0 63.0 62.0 63.0 63.0 62.0 64.0 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:58.40 Median:58.00 stddev:3.10 (5.3%) stddev-sans-first:2.85 11:04:42 INFO - PROCESS | 1651 | Values: 54.0 60.0 64.0 63.0 58.0 57.0 57.0 56.0 57.0 58.0 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:86.00 stddev:7.14 (8.3%) stddev-sans-first:7.22 11:04:42 INFO - PROCESS | 1651 | Values: 95.0 100.0 86.0 86.0 82.0 86.0 83.0 80.0 92.0 99.0 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:143.00 stddev:13.90 (9.7%) stddev-sans-first:14.62 11:04:42 INFO - PROCESS | 1651 | Values: 141.0 139.0 146.0 137.0 139.0 145.0 141.0 150.0 184.0 139.0 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.40 Median:167.50 stddev:22.71 (13.6%) stddev-sans-first:23.97 11:04:42 INFO - PROCESS | 1651 | Values: 168.0 165.0 167.0 145.0 212.0 216.0 182.0 162.0 163.0 164.0 11:04:42 INFO - PROCESS | 1651 | 11:04:42 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:65.00 stddev:1.08 (1.7%) stddev-sans-first:1.00 11:04:42 INFO - PROCESS | 1651 | Values: 66.0 65.0 65.0 65.0 64.0 65.0 65.0 62.0 64.0 64.0 11:04:42 INFO - PROCESS | 1651 | -------- Summary: end -------- 11:04:42 INFO - PROCESS | 1651 | 11:04:43 INFO - TEST-INFO | 1651: exit 0 11:04:43 INFO - TEST-OK | kraken | took 59727ms 11:04:43 INFO - SUITE-END | took 1180s 11:04:43 INFO - Completed test suite (00:19:41) 11:04:43 INFO - javascript benchmark 11:04:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7607.658268290475, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3288.1943923738686, 3412.476930959522, 3417.8721022062723, 3424.982875085625, 3480.390931586972, 5981.4018598140165, 6006.729697163627, 6009.459148676624, 6036.305824830146, 6057.697115144236, 4847.951520484794, 6008.369121242272, 6009.969950150254, 6015.458608725214, 6020.879582408349, 5378.811741589046, 5396.163594642837, 5424.267723857268, 5469.097598896177, 5507.201455788908, 5313.73431328434, 5334.413214546396, 5365.865853353678, 5429.945700543003, 5551.472610102034, 5505.944940550603, 5518.503334699876, 5543.362513310981, 5551.3893471718075, 5577.470140336682, 5480.314960629901, 5504.944950550524, 5513.117901135799, 5514.779408823642, 5532.917006244909, 5353.732313384354, 5417.0, 5445.128779395317, 5452.100403433429, 5464.098423760075, 5779.913301300484, 5812.970935145339, 5822.767089316422, 5827.737751801179, 5850.707464626794, 6326.49388048955, 6332.208473940757, 6335.11308416822, 6358.205224346957, 6369.872602547969, 6276.184096067527, 6379.840503987438, 6402.295747467775, 6414.326495717929, 6432.195975503062, 5868.149118377812, 6156.199694039746, 6159.753609855646, 6165.506759459233, 6169.444749972524, 5452.563794896399, 6363.649999250086, 6365.649889256136, 6486.0, 6581.0, 6378.936210637927, 6390.4248617624635, 6395.16862807832, 6395.584287021329, 6404.967975160188, 6387.2335319761, 6429.453496452854, 6446.194225721785, 6452.0967064610995, 6475.449586785176, 5289.889123284076, 6250.124982502453, 6262.874742505124, 6315.147455093595, 6363.427291543783, 6121.877562448815, 6356.809295721136, 6358.427741503195, 6366.331535188831, 6367.554271200971, 5155.304033955443, 5159.58723302135, 5163.0, 5165.819196328111, 5175.922361164586, 4502.347159661831, 4527.84152554659, 4535.7732113394195, 4581.496035436099, 4582.725036497821, 6017.157597936292, 6033.78881739137, 6041.426064523863, 6069.666168360783, 6075.48358389533, 6392.904106438407, 6419.935800642027, 6447.3552644735155, 6459.644719540376, 6460.676966151674, 5772.249607550992, 6045.516358691294, 6056.636601803906, 6066.848328791815, 6071.0286354184, 5588.804391846266, 5592.916106258409, 5658.632188907708, 5702.942970570241, 5736.4550367715, 6648.734050638114, 6694.129763130762, 6694.698738556874, 6711.4966377520905, 6724.092247546519, 6424.421802037839, 6478.222613286436, 6478.740850365933, 6482.70827812759, 6495.2855185928565, 6347.5556711029785, 6413.903791443039, 6555.868882622416, 6607.2071351438135, 6698.129243198353, 6333.050042493663, 6367.649779262185, 6383.202099737533, 6383.616982981036, 6393.93606063952, 6202.100695399232, 6284.905726414199, 6316.6841657916, 6330.3669633036325, 6416.839579010562, 6184.969075154595, 6277.466415354746, 6283.0, 6293.527985401205, 6295.905561416674, 6261.9373806263175, 6311.463525600376, 6319.24169099711, 6329.683515824282, 6335.714892829926, 4888.315635810918, 6277.246730392382, 6278.748850045947, 6281.780137695158, 6287.528435367457, 6173.506119510339, 6228.159198508325, 6262.0, 6273.749050037947, 6291.433770960635, 6230.563860529719, 6292.150559674568, 6295.716692748929, 6301.180846489927, 6303.0], "value": 5896.766519798777, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [789.4355535791908, 810.3031393002019, 870.7300736771601, 887.7913690282786, 889.1997202517729, 38624.806875965616, 39638.80180599097, 39669.214885330155, 39773.403398949005, 39774.0, 40091.997700057516, 40143.79928100356, 40209.79895100528, 40248.798756006254, 40327.39508907369, 7312.8171795705, 9179.632814687422, 9309.301802364816, 9334.113259240361, 9352.766180845465, 7362.889556656654, 10939.507722152483, 11193.216474846764, 11235.0, 11245.212835101505, 11062.004419602234, 11096.722581935435, 11107.83338249927, 11109.611163609276, 11156.330620162775, 6256.499480041586, 11027.338359698444, 11071.391073490955, 11074.557017719282, 11083.334999900031, 7369.963150184269, 7416.147143078561, 7420.406367490642, 7422.072240969879, 10671.253012289142, 31713.682863171536, 38443.42334864979, 38620.42069368934, 38742.61257387418, 38772.642957496355, 3806.847726090953, 3818.6372294631965, 3827.7797980502287, 3830.731848770587, 3838.9616103838885, 26707.46585068288, 26736.866315668492, 26967.595486067723, 28219.57670634942, 28356.858215708788, 3049.6886338874274, 3061.280599059219, 3072.4776787946103, 3211.8394080295893, 3284.178955261185, 350774.0, 350938.0, 351024.0, 351732.0, 353360.0, 51809.48190518122, 51892.74053629783, 51998.48001520012, 52016.0, 52059.73970130125, 53178.0, 53339.0, 53445.7327713359, 53490.66273343198, 53597.052162131455, 14530.05554638945, 14659.193744343951, 14671.193084380462, 14671.853281467262, 14685.77971330431, 12646.30445325516, 12648.0, 12654.936725316498, 12659.810102848465, 12677.429515671816, 10690.305130166515, 12417.937910310391, 12425.19236249659, 12479.812802807965, 12530.874691252971, 5240.405999030428, 5284.392294886137, 5294.629375943647, 5310.60170487215, 5324.227986941949, 966.8735922650175, 967.4920666649924, 975.9508528332071, 983.2723784399636, 990.3958585262984, 24612.630810537856, 26989.730102699115, 28029.018984335857, 28224.625183096654, 28364.85817570899, 1070.0690399352613, 1075.8224892892586, 1108.501174471475, 1110.3227031510771, 1111.6109361723331, 13084.28036458004, 13654.112482688593, 13797.724045519033, 13847.723045539033, 13873.930630346986, 419089.0, 423067.0, 436793.0, 437385.0, 439055.0, 47461.76269118701, 47544.0, 47636.28545571819, 47665.808354790715, 47687.0, 1384.6469150366793, 1420.8366037905566, 1422.9715405691827, 1449.2463918762396, 1450.2821103553597, 22337.664935025663, 22490.437739056655, 25274.873625631753, 26229.0, 26291.07981220648, 38493.61506385012, 38505.0, 38527.229455410175, 38538.80730596329, 38697.41953870638, 443122.0, 445595.0, 446674.0, 447352.0, 447598.0, 28712.0, 29420.55869162007, 29430.411391771617, 29467.55798662979, 29548.261293467836, 36621.901342959754, 37673.86978390653, 37675.0, 37753.62246377501, 37897.052573685876, 25552.0, 26476.0, 27106.864465677547, 27518.0, 27651.308717281423, 27018.784154713478, 27275.59086613742, 27309.0, 27369.58945615857, 27456.176314710592, 30567.694323056483, 31068.68931310658, 31353.90261340842, 31563.684363156994, 31767.364652706354, 26856.462870742085, 26883.731162688906, 27377.45245095047, 27856.860715696293, 28026.719732802412, 16758.575521080846, 19436.70844937299, 20146.79853201449, 21071.57856842824, 21998.670019949393], "value": 17822.231903825188, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.3935842011202, 424.79680552802245, 427.98716038518836, 431.3099041533547, 442.57954942804344, 15928.0, 20503.384898453052, 21612.783872161275, 21825.38095428551, 21879.12483500658, 20726.792732072674, 21346.786532134673, 21621.0, 21830.672539911913, 22403.32790016298, 4761.761911904408, 5741.799037033705, 6421.7752378666755, 6526.836829079263, 6578.835529111787, 4187.874363769091, 4420.20436321462, 4459.487158976711, 6657.167854018247, 6701.664916754143, 7185.497015208937, 7516.774496765106, 7587.81030474237, 7618.5428874267445, 7629.084509858824, 6291.748330066792, 7455.776326710207, 7592.316691497736, 7596.08846938371, 7610.1628820829665, 7144.857102857965, 7238.167610724781, 7307.669316755349, 7341.706331746724, 7373.0, 24704.505909881882, 25639.846206920545, 25744.356391090187, 25855.0950716724, 25896.352591185183, 2376.4415362389823, 2852.8145670531558, 2855.0863723608454, 2878.736917080504, 2914.5191043477803, 15223.923880380638, 16077.678446431122, 16162.515124546282, 16377.017378957182, 16642.16789160549, 2015.7813146443093, 2105.4420578546697, 2106.2101711858054, 2106.3575609439113, 2108.9156433742787, 132649.0, 154992.0, 162178.0, 163094.0, 176849.99999999872, 20169.29407470761, 20370.49073773138, 20622.896885515776, 20635.0, 20693.0, 31763.0, 32230.0, 32357.51463728046, 32411.0, 32523.674763252537, 10117.0, 10273.74315642115, 10498.265121441425, 10511.526981285708, 10631.308964917256, 8614.310855131574, 8634.6977855776, 8672.739817805475, 8804.8679269811, 8817.426867253607, 7088.574685518783, 8384.455010424303, 9373.343865929455, 9414.924037098712, 9555.474448905237, 2769.3630464993166, 3593.586737525166, 3864.401017842239, 4032.6370626643743, 4051.493563304587, 866.2377108144792, 974.7465658928588, 995.5370752600076, 1011.1960991012163, 1052.6789329254498, 20624.27815026467, 20680.862552559454, 20746.585068298853, 20783.58432831335, 20826.47933801667, 1001.1790331927749, 1005.5123265216364, 1037.5486663301488, 1040.422565476168, 1040.9947950260353, 8381.664733410717, 10191.337563058378, 10794.92530919842, 10815.945920270347, 10864.728381790519, 737766.0, 744410.0, 762027.0, 768237.0, 775043.0, 157177.0, 158236.0, 161352.93235338185, 162601.0, 166320.16839915965, 1078.8921107889148, 1270.5426046623209, 1324.331212737542, 1328.2030781531198, 1335.706144648176, 19180.0, 19216.61566768685, 19699.40901772949, 19744.605107897474, 19787.604247914674, 49035.754821225666, 59906.60373585182, 63057.0, 64382.67808660927, 66551.66724166348, 87716.0, 88072.0, 88428.0, 90894.54552727194, 90938.0, 11238.66284011481, 11355.602553910574, 11392.43037848121, 11601.419929003347, 11737.647870563897, 12208.267503949792, 12283.877161228273, 12298.754024919273, 12527.56153534622, 12622.242665440102, 9040.864387034067, 9222.769430764285, 10818.29681070728, 10932.672019839418, 11008.174386920853, 8625.180607842245, 10091.091801737879, 10130.746731331776, 10231.43727095017, 10320.43237621938, 10896.57714009655, 11065.557377705123, 11144.387058711849, 11215.71960700989, 11275.830862537232, 11133.443327833738, 11194.496247669038, 11469.942650287028, 11621.418929053683, 11623.76752464929, 5864.179014937827, 9971.40171589707, 10574.048335649828, 10977.28647637901, 11122.61070862516], "value": 11379.261456179516, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1444.5449683349743, 1533.2563706602289, 1533.8926275160736, 1561.4769052367456, 1565.9373625054998, 9710.465924374157, 10772.946135269323, 11285.0, 11297.096232301417, 11331.43342832858, 10751.946240268808, 11214.66356009319, 11277.661670149888, 11302.717432064203, 11326.773464530705, 2375.6911601491784, 2421.406755344943, 2423.9151629692965, 2426.3570153909227, 2428.720697119836, 2366.089055713554, 2420.9152679656218, 2450.6691596634405, 2456.349067497115, 2458.815588830836, 2121.2575598540466, 2368.7749663781915, 2427.1626288930293, 2430.149447693302, 2463.6304554316816, 2344.191254017361, 2399.892004859777, 2412.5657381671203, 2417.9274621761374, 2431.2341612392215, 2392.9042838286446, 2410.08416801616, 2415.311636183697, 2415.830891837572, 2417.5648383291, 10941.671749847517, 11181.273217240854, 11183.0, 11209.327440353605, 11210.943945280304, 5713.0, 5902.439268269508, 5936.317323507766, 5954.523638108941, 5959.284885813687, 3602.2435288589427, 3625.619309972468, 3660.231351416179, 3694.5751238607636, 3742.4947632069593, 1149.7987852125846, 1157.2304417562395, 1191.356667399603, 1198.7003249187703, 1206.6440400081997, 13898.861011389858, 14233.359498822474, 14495.27523623825, 15471.226438678133, 15724.292406841605, 4307.676924230633, 4422.071365013319, 4450.577195166454, 4454.821807127743, 4455.866324010285, 11559.075273978062, 11586.420678966018, 11606.419679016015, 11607.071434285237, 11641.6507504775, 4388.868333949987, 5080.949190508048, 5504.834854954358, 5693.658380497183, 5720.599558030981, 5089.00764350948, 5258.6581872178185, 5260.947810437928, 5334.039872822929, 5344.305240318734, 3782.6028267031566, 3864.458975743342, 3866.304065268279, 3869.1487872667967, 3888.5333759948985, 1008.729001458168, 1009.0817356205818, 1019.8776146862424, 1028.3058935218846, 1029.0327092532996, 889.2308153447221, 920.963161473547, 926.8980412154658, 939.7368736753583, 974.3666616699061, 5621.575393550074, 6527.379898909596, 6537.280899101095, 6683.2648408675, 6831.897521537181, 817.91217680486, 971.7473456901115, 983.695054533097, 987.0721521769514, 988.7577543501889, 3718.3678774608384, 3941.8423263069158, 4103.610157035077, 4227.852025179165, 4248.681348898845, 13019.544315949086, 13656.385462654205, 13662.31688415595, 13672.316384180951, 13702.588922332347, 13169.802452963213, 13466.192028478323, 13593.98045146618, 13596.184228946096, 13626.591202263948, 9944.751381215383, 10234.744131396774, 10244.897551024394, 10280.434576098387, 10285.794284114276, 5376.516113549799, 6767.323267673193, 6791.45668346541, 6903.6203008835, 6966.024756534191, 10571.735706607396, 11805.940970295094, 12330.0, 12382.0, 12412.255264684149, 11010.229283950366, 11439.656810295704, 11867.347295898808, 12071.0, 12321.137520373846, 9002.90997090047, 9354.251659867059, 9400.435973841591, 9400.576974036048, 9460.621575136918, 9991.850122248026, 10252.282340236112, 10296.691099267035, 10301.536430860779, 10302.33034852732, 8799.516026618341, 8886.155815197546, 8938.24024957886, 8985.19133277982, 9110.635574576943, 8627.784305392415, 8660.0, 8685.392022558613, 8735.868961965703, 8777.648894044421, 7855.60721963888, 8031.678732850621, 8034.87947680797, 8048.0, 8060.992375953006, 5784.4504772046585, 5857.677827719516, 5858.384869588632, 5983.401659833982, 6054.243219597551, 5660.745266463102, 5727.770889164553, 5860.531157507475, 5918.17145599625, 6045.365236650088], "value": 5093.120257931168, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.90700964244473, 371.45581722776126, 388.39604415134465, 391.60643553229016, 403.41504818013885, 4574.428196475441, 4833.323334733132, 5302.284191634129, 5339.919901201481, 5353.62524623277, 4487.842925497608, 4996.526024822677, 5022.547970682638, 5026.924596131052, 5102.897942041157, 3931.8230679619487, 4210.71573170183, 4399.208142534342, 4503.864884053483, 4511.075229577945, 3906.921861562767, 4013.2776100301935, 4252.8953863679535, 4455.665825063117, 4471.071448201434, 3581.8209089545417, 3889.2804831106337, 4207.663386929054, 4222.746635201897, 4265.893352666193, 3687.59436461989, 3798.4872042274214, 4074.6129117733767, 4275.315949448089, 4275.5296917339065, 3848.730588858781, 3864.130570621616, 3864.7294689371756, 3882.6893848492055, 3883.941740873889, 4247.704450142705, 4634.629229661653, 4739.142379061455, 5001.249812528114, 5116.437191908887, 4229.631406412388, 4565.61192298655, 4682.859514214579, 4965.354503914468, 4970.950290497085, 4928.630352723524, 5063.873403164914, 5115.948840511622, 5488.69812160331, 5497.0695952393835, 3888.572257051722, 4365.43249377582, 4392.275274579691, 4404.55954404557, 4419.4254746883025, 5088.6946783193125, 5155.5617772489, 5478.397376288605, 5655.1799989001365, 5713.171785028792, 3843.942340864889, 4192.392103145027, 4487.259602165672, 4502.797374118172, 4553.544645535486, 4031.3549832026733, 4370.759608221515, 4646.7444290564345, 4647.767611619473, 4738.407699037621, 4041.5450437842355, 4280.828766849291, 4791.275140949256, 4991.376077990251, 5083.771230294646, 4476.6418686505, 4878.706872860805, 5495.807646732345, 5547.66855954568, 5680.2794169818835, 1370.9862901371057, 1481.8959874893274, 1483.6142602923317, 1488.2318886870412, 1507.7964474795763, 1079.4063265204106, 1108.8613923259593, 1110.5502271580024, 1179.731788327534, 1193.886580774825, 4503.662225333113, 4559.401141349465, 4916.345173606284, 5124.666896651705, 5149.9742501288, 5083.949160508421, 5545.279113715272, 5654.483754729106, 5687.374388817227, 5711.942880571224, 4305.225059489322, 4774.641901857374, 4920.212765957501, 4953.563466594647, 4977.701337919736, 2551.553478141318, 2650.575907854734, 2861.7424431801237, 2951.0, 2974.1114392360573, 5444.141825541622, 5482.862928426821, 5507.972460137754, 5534.1975413564805, 5946.256717910262, 5177.896442071137, 5229.921551176735, 5457.317835270591, 5872.530197584183, 5872.858020958766, 5249.501297376743, 5401.4868587484125, 5688.516476099412, 5973.372795856372, 5999.460048595647, 4986.401631804207, 5526.530244929226, 5664.320281566238, 5740.827775166752, 5747.971260143672, 4614.723116613014, 5237.450067742832, 5262.6843289177705, 5532.30846144232, 5550.972245138749, 5121.590272778094, 5158.845234642967, 5582.911332290165, 5831.017454065638, 5891.852703682443, 3780.886573402692, 4083.77539235333, 4350.608445239944, 4505.684602077823, 4545.8181672734045, 4993.152533562605, 5228.294180285765, 5622.8313150605545, 5690.77236910519, 5697.7151142443545, 4585.908281834411, 5338.279332290091, 5365.409804921534, 5388.380336261223, 5399.352077750695, 4617.53824617549, 4726.480087190337, 4923.704577725348, 5425.267588875451, 5530.2625200526, 5046.798128074836, 5108.719020453911, 5299.947000529945, 5382.811601593926, 5390.784368625211, 5171.431142574239, 5520.66875987442, 5524.309461317335, 5543.667379957215, 5584.441555844383, 4658.8120029392285, 4825.806967721252, 5289.497497737709, 5403.405625381126, 5447.727613619382], "value": 4167.541570803453, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.5998240985045, 737.9963100184501, 769.3537428560008, 826.3513142183385, 839.5004972041633, 15174.772378414318, 16131.75802362964, 16161.595960100974, 16193.271302791372, 16239.106849123293, 12329.876701232984, 14732.852671473282, 14765.483208087719, 14767.926160369183, 14937.178455184987, 945.078548415293, 948.3409030723667, 961.4663861556833, 965.8985806490323, 966.4298064142155, 953.2564599612278, 964.4984608003833, 969.8545218217254, 970.3595626886256, 974.7319487141023, 1003.9849402258972, 1005.6832097889178, 1007.7631756537207, 1008.6116845014685, 1010.5301035018715, 1000.8198524265629, 1002.3334482569082, 1003.362864580989, 1004.4224570871742, 1004.9849252261222, 740.3447948565558, 741.1550832051465, 742.8365759532949, 743.8995735575683, 743.9962800185964, 15668.373265069384, 15774.605634859221, 15903.920480397639, 16466.83533164665, 16753.246103925354, 11146.331220126818, 12071.637850864488, 12109.878901211052, 12258.754824903452, 12541.059420543404, 11540.24988375753, 11566.248193867372, 11818.645440636794, 12017.93991030048, 12262.38688065602, 3644.8542058317635, 3720.4419337099653, 3742.2328422673286, 3880.7477513961785, 3887.105965627893, 118982.0, 119075.0, 120134.39932800368, 121921.39039304835, 122627.0, 10952.507137178845, 10991.505382257817, 11054.778904421866, 11097.278676886139, 11105.611303604335, 22663.0, 23016.65475017876, 23375.83120843951, 23551.764482354956, 23581.410464738517, 7682.095407312781, 7697.769066928001, 7726.304632583095, 7813.413993950477, 7835.0, 4302.0, 4315.331123675835, 4409.470863496337, 4472.329150627432, 4577.7340216593, 3521.1373213562842, 3586.65500437336, 3598.0824889653495, 3608.4767708682093, 3614.1145419372424, 615.5075939248583, 624.1075262374757, 645.3610925184034, 650.3658932540735, 655.7672026430644, 669.3667451418523, 691.1533371619726, 696.916370035599, 704.6828926982878, 713.4399496395305, 9938.850917236246, 10513.84229236562, 10530.684079477627, 10659.093977011886, 10713.357198567955, 678.5657179405184, 716.5808002318598, 727.5634619228421, 735.2830989785024, 744.0736283327184, 4856.0, 4974.602031837444, 5031.169856973558, 5035.748212589356, 5071.46749591295, 97917.0, 99278.5036074815, 101080.0, 101626.0, 102278.0, 51890.74054629703, 53582.73208633932, 60765.69617151886, 74410.0, 90933.54533227424, 50254.0, 76649.0, 77481.61259193781, 78180.60909695415, 78639.0, 10512.789744205225, 10795.0, 10969.341839489656, 11178.776424471627, 11257.605983790529, 50178.74910625423, 50327.74836125796, 50671.74664126656, 50961.47115586538, 51380.74309628428, 62095.50777452707, 62427.375726243976, 63466.36533634605, 64185.35814641794, 64394.678026611444, 12330.19853709506, 12479.875201248235, 12527.0, 12647.683807904876, 13081.476740930257, 14312.642183945485, 14457.710845783236, 14530.709385812435, 14553.272336382926, 15308.23458827032, 13176.60470185896, 13681.247531385869, 13895.722085558435, 14006.789898151332, 14078.577642670736, 13316.46734130624, 13893.58319250424, 14014.439422423397, 14032.859671403156, 14206.928965355108, 12796.296203708513, 13392.665183370495, 13533.86466135326, 13686.726265474834, 13709.383077761326, 9761.707148785548, 10064.29549931527, 10257.692269231935, 10368.015038571324, 10407.323523970917, 9578.425294482353, 11094.889051109385, 11293.604723834625, 11341.943290283496, 11352.318860868374], "value": 7636.810332080822, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1431.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 116.0, 115.0, 115.0, 115.0, 116.0, 115.0, 115.0, 114.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 110.0, 144.0, 108.0, 110.0, 110.0, 107.0, 130.0, 109.0], "value": 118.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 183.0, 183.0, 182.0, 184.0, 183.0, 185.0, 183.0, 184.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 62.0, 61.0, 91.0, 61.0, 60.0, 60.0, 60.0, 78.0, 61.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 99.0, 107.0, 69.0, 69.0, 68.0, 82.0, 70.0, 70.0, 106.0], "value": 85.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 88.0, 87.0, 88.0, 87.0, 88.0, 87.0, 87.0, 87.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 96.0, 94.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 85.0, 87.0, 86.0, 86.0, 85.0, 84.0, 85.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 61.0, 63.0, 63.0, 62.0, 63.0, 63.0, 62.0, 64.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 60.0, 64.0, 63.0, 58.0, 57.0, 57.0, 56.0, 57.0, 58.0], "value": 58.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 100.0, 86.0, 86.0, 82.0, 86.0, 83.0, 80.0, 92.0, 99.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 146.0, 137.0, 139.0, 145.0, 141.0, 150.0, 184.0, 139.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 165.0, 167.0, 145.0, 212.0, 216.0, 182.0, 162.0, 163.0, 164.0], "value": 174.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 64.0, 65.0, 65.0, 62.0, 64.0, 64.0], "value": 64.5, "unit": "score"}]}]} 11:04:43 INFO - Return code: 0 11:04:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:04:43 ERROR - # TBPL SUCCESS # 11:04:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:04:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:04:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:04:43 INFO - 2017-04-07 11:04:43.588 screenresolution[1655:10016] starting screenresolution argv=screenresolution get 11:04:43 INFO - 2017-04-07 11:04:43.591 screenresolution[1655:10016] Display 0: 1600x1200x32@60 11:04:43 INFO - 2017-04-07 11:04:43.597 screenresolution[1656:10019] starting screenresolution argv=screenresolution list 11:04:43 INFO - Available Modes on Display 0 11:04:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:04:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:04:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:04:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:04:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:04:43 INFO - Intel Iris: 11:04:43 INFO - Chipset Model: Intel Iris 11:04:43 INFO - Type: GPU 11:04:43 INFO - Bus: Built-In 11:04:43 INFO - VRAM (Dynamic, Max): 1536 MB 11:04:43 INFO - Vendor: Intel (0x8086) 11:04:43 INFO - Device ID: 0x0a2e 11:04:43 INFO - Revision ID: 0x0009 11:04:43 INFO - Displays: 11:04:43 INFO - Display: 11:04:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:04:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:04:43 INFO - Display Serial Number: 1600x1200 60 11:04:43 INFO - Main Display: Yes 11:04:43 INFO - Mirror: Off 11:04:43 INFO - Online: Yes 11:04:43 INFO - Rotation: Supported 11:04:43 INFO - Return code: 0 11:04:43 INFO - Running post-action listener: _resource_record_post_action 11:04:43 INFO - [mozharness: 2017-04-07 18:04:43.840461Z] Finished run-tests step (success) 11:04:43 INFO - Running post-run listener: _resource_record_post_run 11:04:43 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 38532096; Write bytes: 510935040; Read time: 242; Write time: 1607 11:04:43 INFO - TinderboxPrint: CPU usage
53.8% 11:04:43 INFO - TinderboxPrint: I/O read bytes / time
38,532,096 / 242 11:04:43 INFO - TinderboxPrint: I/O write bytes / time
510,935,040 / 1,607 11:04:43 INFO - TinderboxPrint: CPU idle
2,220.2 (46.2%) 11:04:43 INFO - TinderboxPrint: CPU system
958.6 (19.9%) 11:04:43 INFO - TinderboxPrint: CPU user
1,630.8 (33.9%) 11:04:43 INFO - TinderboxPrint: Swap in / out
246,382,592 / 0 11:04:43 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205941248; Write bytes: 201832448; Read time: 15774; Write time: 439 11:04:43 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 36467712; Write bytes: 298092544; Read time: 222; Write time: 1131 11:04:44 INFO - Running post-run listener: _upload_blobber_files 11:04:44 INFO - Blob upload gear active. 11:04:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:04:44 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 11:04:44 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'] 11:04:44 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 11:04:44 INFO - (blobuploader) - INFO - Open directory for files ... 11:04:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:04:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:04:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:04:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:04:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:04:45 INFO - (blobuploader) - INFO - Done attempting. 11:04:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:04:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:04:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:04:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:04:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:04:46 INFO - (blobuploader) - INFO - Done attempting. 11:04:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:04:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:04:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:04:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:04:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:04:47 INFO - (blobuploader) - INFO - Done attempting. 11:04:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:04:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:04:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:04:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:04:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:04:49 INFO - (blobuploader) - INFO - Done attempting. 11:04:49 INFO - (blobuploader) - INFO - Iteration through files over. 11:04:49 INFO - Return code: 0 11:04:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:04:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:04:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3ff44fc41db6e342a6347c8d44efa1f2557d442eeddb635774c785ec9a15bb96cc21c7a99968939a2d640ec52ef9bd56fa7732aca9bab069d7eb80609f52650", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edf119a4ac4c9dec8ff0b3710d46809a374b26427680f9d26a3041a7e4f913be104c3eab4987ed0bb5386927f916ae0f7a714574f50b228ef5304f40c48bafa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59b6d3c42c86bc2419a01ad543b97ad9f011ea4f21c89f0f4d6dcafcabbcab95e9e266786e3d6494508ae92bdfc5386a18abedbba9951809b90ca59c2ef274fd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b348411b28a941906e53f82962f0d0e0c14f78cf4040a982260a8dd592902f091437e923dbc9712cdbd530bd898323aa8c7d1e03df78ff3da7932bb57aa1609"} 11:04:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:04:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:04:49 INFO - Contents: 11:04:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3ff44fc41db6e342a6347c8d44efa1f2557d442eeddb635774c785ec9a15bb96cc21c7a99968939a2d640ec52ef9bd56fa7732aca9bab069d7eb80609f52650", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edf119a4ac4c9dec8ff0b3710d46809a374b26427680f9d26a3041a7e4f913be104c3eab4987ed0bb5386927f916ae0f7a714574f50b228ef5304f40c48bafa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59b6d3c42c86bc2419a01ad543b97ad9f011ea4f21c89f0f4d6dcafcabbcab95e9e266786e3d6494508ae92bdfc5386a18abedbba9951809b90ca59c2ef274fd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b348411b28a941906e53f82962f0d0e0c14f78cf4040a982260a8dd592902f091437e923dbc9712cdbd530bd898323aa8c7d1e03df78ff3da7932bb57aa1609"} 11:04:49 INFO - Running post-run listener: copy_logs_to_upload_dir 11:04:49 INFO - Copying logs to upload dir... 11:04:49 INFO - mkdir: /builds/slave/test/build/upload/logs 11:04:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.238815 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-07 11:04:49.683197) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 11:04:49.686899) ========= 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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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/d3ff44fc41db6e342a6347c8d44efa1f2557d442eeddb635774c785ec9a15bb96cc21c7a99968939a2d640ec52ef9bd56fa7732aca9bab069d7eb80609f52650", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edf119a4ac4c9dec8ff0b3710d46809a374b26427680f9d26a3041a7e4f913be104c3eab4987ed0bb5386927f916ae0f7a714574f50b228ef5304f40c48bafa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59b6d3c42c86bc2419a01ad543b97ad9f011ea4f21c89f0f4d6dcafcabbcab95e9e266786e3d6494508ae92bdfc5386a18abedbba9951809b90ca59c2ef274fd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b348411b28a941906e53f82962f0d0e0c14f78cf4040a982260a8dd592902f091437e923dbc9712cdbd530bd898323aa8c7d1e03df78ff3da7932bb57aa1609"} build_url:https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010789 build_url: 'https://queue.taskcluster.net/v1/task/CoiRBp-gQ8eW_qbIrs0bsQ/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/d3ff44fc41db6e342a6347c8d44efa1f2557d442eeddb635774c785ec9a15bb96cc21c7a99968939a2d640ec52ef9bd56fa7732aca9bab069d7eb80609f52650", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edf119a4ac4c9dec8ff0b3710d46809a374b26427680f9d26a3041a7e4f913be104c3eab4987ed0bb5386927f916ae0f7a714574f50b228ef5304f40c48bafa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59b6d3c42c86bc2419a01ad543b97ad9f011ea4f21c89f0f4d6dcafcabbcab95e9e266786e3d6494508ae92bdfc5386a18abedbba9951809b90ca59c2ef274fd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b348411b28a941906e53f82962f0d0e0c14f78cf4040a982260a8dd592902f091437e923dbc9712cdbd530bd898323aa8c7d1e03df78ff3da7932bb57aa1609"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 11:04:49.721704) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 11:04:49.722056) ========= 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.e3pOwUiVoe/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.6PRpwU5sw4/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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.005226 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 11:04:49.769953) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 11:04:49.770257) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 11:04:49.770579) ========= ========= Total master_lag: 0.20 =========