builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0231 starttime: 1493800420.83 results: success (0) buildid: 20170503004005 builduid: 41a53a48985947738221400b3b39e2a1 revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 01:33:40.829757) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 01:33:40.869638) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 01:33:40.870104) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 01:33:40.950041) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 01:33:40.950307) ========= 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.qEAFYszRQV/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.G9djcpstYn/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-03 01:33:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.41M=0.002s 2017-05-03 01:33:41 (6.41 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.575108 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 01:33:41.551837) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 01:33:41.552149) ========= 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.qEAFYszRQV/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.G9djcpstYn/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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.031018 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 01:33:41.606946) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-03 01:33:41.607270) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qEAFYszRQV/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.G9djcpstYn/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-03 01:33:41,724 truncating revision to first 12 chars 2017-05-03 01:33:41,724 Setting DEBUG logging. 2017-05-03 01:33:41,724 attempt 1/10 2017-05-03 01:33:41,724 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-03 01:33:42,646 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.220367 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-03 01:33:42.856909) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 01:33:42.857184) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 01:33:42.857590) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 01:33:42.858065) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 01:33:43.085648) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2017-05-03 01:33:43.085924) ========= /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.qEAFYszRQV/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.G9djcpstYn/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 01:33:43 INFO - MultiFileLogger online at 20170503 01:33:43 in /builds/slave/test 01:33:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 01:33:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:33:43 INFO - {'append_to_log': False, 01:33:43 INFO - 'base_work_dir': '/builds/slave/test', 01:33:43 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:33:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:33:43 INFO - 'branch': 'Mozilla-Aurora', 01:33:43 INFO - 'buildbot_json_path': 'buildprops.json', 01:33:43 INFO - 'config_files': ('talos/mac_config.py',), 01:33:43 INFO - 'default_actions': ('clobber', 01:33:43 INFO - 'read-buildbot-config', 01:33:43 INFO - 'download-and-extract', 01:33:43 INFO - 'populate-webroot', 01:33:43 INFO - 'create-virtualenv', 01:33:43 INFO - 'install', 01:33:43 INFO - 'run-tests'), 01:33:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:33:43 INFO - 'download_minidump_stackwalk': True, 01:33:43 INFO - 'download_symbols': 'ondemand', 01:33:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:33:43 INFO - 'tooltool.py': '/tools/tooltool.py', 01:33:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:33:43 INFO - '/tools/misc-python/virtualenv.py')}, 01:33:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:33:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:33:43 INFO - 'gecko_profile': False, 01:33:43 INFO - 'gecko_profile_interval': 0, 01:33:43 INFO - 'installer_path': 'installer.exe', 01:33:43 INFO - 'log_level': 'info', 01:33:43 INFO - 'log_name': 'talos', 01:33:43 INFO - 'log_to_console': True, 01:33:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:33:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:33:43 INFO - 'opt_config_files': (), 01:33:43 INFO - 'pip_index': False, 01:33:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:33:43 INFO - 'cmd': ('bash', 01:33:43 INFO - '-c', 01:33:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:33:43 INFO - 'enabled': True, 01:33:43 INFO - 'halt_on_failure': False, 01:33:43 INFO - 'name': 'check_screen_resolution'},), 01:33:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:33:43 INFO - 'cmd': ('bash', 01:33:43 INFO - '-c', 01:33:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:33:43 INFO - 'enabled': True, 01:33:43 INFO - 'halt_on_failure': False, 01:33:43 INFO - 'name': 'check_screen_resolution'},), 01:33:43 INFO - 'run_cmd_checks_enabled': True, 01:33:43 INFO - 'suite': 'dromaeojs', 01:33:43 INFO - 'system_bits': '32', 01:33:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:33:43 INFO - 'title': 't-yosemite-r7-0231', 01:33:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:33:43 INFO - 'use_talos_json': True, 01:33:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:33:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:33:43 INFO - 'work_dir': 'build'} 01:33:43 INFO - [mozharness: 2017-05-03 08:33:43.321412Z] Running clobber step. 01:33:43 INFO - Running pre-action listener: _resource_record_pre_action 01:33:43 INFO - Running main action method: clobber 01:33:43 INFO - rmtree: /builds/slave/test/build 01:33:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:33:47 INFO - Running post-action listener: _resource_record_post_action 01:33:47 INFO - [mozharness: 2017-05-03 08:33:47.709557Z] Finished clobber step (success) 01:33:47 INFO - [mozharness: 2017-05-03 08:33:47.709718Z] Running read-buildbot-config step. 01:33:47 INFO - Running pre-action listener: _resource_record_pre_action 01:33:47 INFO - Running main action method: read_buildbot_config 01:33:47 INFO - Using buildbot properties: 01:33:47 INFO - { 01:33:47 INFO - "project": "", 01:33:47 INFO - "product": "firefox", 01:33:47 INFO - "script_repo_revision": "production", 01:33:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 01:33:47 INFO - "repository": "", 01:33:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 01:33:47 INFO - "buildid": "20170503004005", 01:33:47 INFO - "stage_platform": "macosx64", 01:33:47 INFO - "basedir": "/builds/slave/test", 01:33:47 INFO - "nightly_build": "True", 01:33:47 INFO - "buildnumber": 1, 01:33:47 INFO - "slavename": "t-yosemite-r7-0231", 01:33:47 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 01:33:47 INFO - "platform": "yosemite_r7", 01:33:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:33:47 INFO - "branch": "mozilla-aurora", 01:33:47 INFO - "repo_path": "releases/mozilla-aurora", 01:33:47 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 01:33:47 INFO - "pgo_build": "False", 01:33:47 INFO - "builduid": "41a53a48985947738221400b3b39e2a1", 01:33:47 INFO - "slavebuilddir": "test" 01:33:47 INFO - } 01:33:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 01:33:47 INFO - Running post-action listener: _resource_record_post_action 01:33:47 INFO - [mozharness: 2017-05-03 08:33:47.713024Z] Finished read-buildbot-config step (success) 01:33:47 INFO - [mozharness: 2017-05-03 08:33:47.713137Z] Running download-and-extract step. 01:33:47 INFO - Running pre-action listener: _resource_record_pre_action 01:33:47 INFO - Running main action method: download_and_extract 01:33:47 INFO - mkdir: /builds/slave/test/build/tests 01:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:47 INFO - trying https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 01:33:47 INFO - Downloading https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:33:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 01:33:48 INFO - Downloaded 1199 bytes. 01:33:48 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:33:48 INFO - Using the following test package requirements: 01:33:48 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:33:48 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:33:48 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 01:33:48 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:33:48 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 01:33:48 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:33:48 INFO - u'jsshell-mac.zip'], 01:33:48 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:33:48 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 01:33:48 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:33:48 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:33:48 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 01:33:48 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:33:48 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 01:33:48 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:33:48 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 01:33:48 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:33:48 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 01:33:48 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:33:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:33:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:33:48 INFO - Fetch https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:33:55 INFO - Expected file size: 51793703 01:33:55 INFO - Obtained file size: 51793703 01:34:12 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:34:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:34:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:34:12 INFO - Fetch https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:34:14 INFO - Expected file size: 51793703 01:34:14 INFO - Obtained file size: 51793703 01:34:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 01:34:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:34:29 INFO - Fetch https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 01:34:31 INFO - Expected file size: 14404424 01:34:31 INFO - Obtained file size: 14404424 01:34:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:31 INFO - trying https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:34:31 INFO - Downloading https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 01:34:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 01:34:33 INFO - Downloaded 74780971 bytes. 01:34:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:34:33 INFO - mkdir: /builds/slave/test/properties 01:34:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:34:33 INFO - Writing to file /builds/slave/test/properties/build_url 01:34:33 INFO - Contents: 01:34:33 INFO - build_url:https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:34:34 INFO - Running post-action listener: _resource_record_post_action 01:34:34 INFO - Running post-action listener: set_extra_try_arguments 01:34:34 INFO - [mozharness: 2017-05-03 08:34:34.721669Z] Finished download-and-extract step (success) 01:34:34 INFO - [mozharness: 2017-05-03 08:34:34.721761Z] Running populate-webroot step. 01:34:34 INFO - Running pre-action listener: _resource_record_pre_action 01:34:34 INFO - Running main action method: populate_webroot 01:34:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:34:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:34:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:34:34 INFO - '--tppagecycles', 01:34:34 INFO - '7'], 01:34:34 INFO - 'tests': ['tsvgm']}}, 01:34:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:34:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:34:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:34:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:34:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:34:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:34:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:34:34 INFO - 'talos_options': ['--disable-e10s'], 01:34:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:34:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:34:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:34:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:34:34 INFO - 'talos_options': ['--disable-e10s'], 01:34:34 INFO - 'tests': ['damp', 'tps']}, 01:34:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:34:34 INFO - 'tests': ['damp', 'tps']}, 01:34:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:34:34 INFO - 'tests': ['dromaeo_dom']}, 01:34:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:34:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:34:34 INFO - 'tests': ['basic_compositor_video']}, 01:34:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:34:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:34:34 INFO - 'tests': ['a11yr', 01:34:34 INFO - 'ts_paint', 01:34:34 INFO - 'tpaint', 01:34:34 INFO - 'sessionrestore', 01:34:34 INFO - 'sessionrestore_no_auto_restore', 01:34:34 INFO - 'tabpaint']}, 01:34:34 INFO - 'other-e10s': {'tests': ['a11yr', 01:34:34 INFO - 'ts_paint', 01:34:34 INFO - 'tpaint', 01:34:34 INFO - 'sessionrestore', 01:34:34 INFO - 'sessionrestore_no_auto_restore', 01:34:34 INFO - 'tabpaint']}, 01:34:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:34:34 INFO - 'ts_paint', 01:34:34 INFO - 'tpaint', 01:34:34 INFO - 'sessionrestore', 01:34:34 INFO - 'sessionrestore_no_auto_restore', 01:34:34 INFO - 'tabpaint']}, 01:34:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:34:34 INFO - 'ts_paint', 01:34:34 INFO - 'tpaint', 01:34:34 INFO - 'sessionrestore', 01:34:34 INFO - 'sessionrestore_no_auto_restore', 01:34:34 INFO - 'tabpaint']}, 01:34:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:34:34 INFO - 'tests': ['a11yr', 01:34:34 INFO - 'ts_paint', 01:34:34 INFO - 'tpaint', 01:34:34 INFO - 'sessionrestore', 01:34:34 INFO - 'sessionrestore_no_auto_restore', 01:34:34 INFO - 'tabpaint']}, 01:34:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:34:34 INFO - 'tests': ['a11yr', 01:34:34 INFO - 'ts_paint', 01:34:34 INFO - 'tpaint', 01:34:34 INFO - 'sessionrestore', 01:34:34 INFO - 'sessionrestore_no_auto_restore', 01:34:34 INFO - 'tabpaint']}, 01:34:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:34:34 INFO - 'tests': ['tsvgx', 01:34:34 INFO - 'tsvgr_opacity', 01:34:34 INFO - 'tart', 01:34:34 INFO - 'tscrollx', 01:34:34 INFO - 'cart', 01:34:34 INFO - 'tsvg_static']}, 01:34:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:34:34 INFO - 'tsvgr_opacity', 01:34:34 INFO - 'tart', 01:34:34 INFO - 'tscrollx', 01:34:34 INFO - 'cart', 01:34:34 INFO - 'tsvg_static']}, 01:34:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:34:34 INFO - 'talos_options': ['--disable-e10s'], 01:34:34 INFO - 'tests': ['tp5o']}, 01:34:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:34:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:34:34 INFO - 'talos_options': ['--disable-e10s', 01:34:34 INFO - '--xperf_path', 01:34:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:34:34 INFO - 'tests': ['tp5n']}, 01:34:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:34:34 INFO - 'talos_options': ['--xperf_path', 01:34:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:34:34 INFO - 'tests': ['tp5n']}}, 01:34:34 INFO - 'talos.zip': {'path': '', 01:34:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:34:34 INFO - Running post-action listener: _resource_record_post_action 01:34:34 INFO - [mozharness: 2017-05-03 08:34:34.740172Z] Finished populate-webroot step (success) 01:34:34 INFO - [mozharness: 2017-05-03 08:34:34.740311Z] Running create-virtualenv step. 01:34:34 INFO - Running pre-action listener: _resource_record_pre_action 01:34:34 INFO - Running main action method: create_virtualenv 01:34:34 INFO - Creating virtualenv /builds/slave/test/build/venv 01:34:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:34:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:34:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:34:34 INFO - Using real prefix '/tools/python27' 01:34:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:34:35 INFO - Installing distribute.............................................................................................................................................................................................done. 01:34:38 INFO - Installing pip.................done. 01:34:38 INFO - Return code: 0 01:34:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:34:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:34:38 INFO - Reading from file tmpfile_stdout 01:34:38 INFO - Output received: 01:34:38 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 01:34:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:34:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10858ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10858d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c8da9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108368d30>, '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 0x10828fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10835dbe8>, '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.qEAFYszRQV/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.G9djcpstYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:34:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:34:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:34:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qEAFYszRQV/Render', 01:34:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:38 INFO - 'HOME': '/Users/cltbld', 01:34:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:38 INFO - 'LOGNAME': 'cltbld', 01:34:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:38 INFO - 'MOZ_NO_REMOTE': '1', 01:34:38 INFO - 'NO_EM_RESTART': '1', 01:34:38 INFO - 'PAGER': '/bin/cat', 01:34:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:38 INFO - 'PWD': '/builds/slave/test', 01:34:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:38 INFO - 'SHELL': '/bin/bash', 01:34:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G9djcpstYn/Listeners', 01:34:38 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 01:34:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:38 INFO - 'USER': 'cltbld', 01:34:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:38 INFO - 'XPC_FLAGS': '0x0', 01:34:38 INFO - 'XPC_SERVICE_NAME': '0', 01:34:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:34:39 INFO - Cleaning up... 01:34:39 INFO - Return code: 0 01:34:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:34:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10858ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10858d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c8da9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108368d30>, '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 0x10828fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10835dbe8>, '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.qEAFYszRQV/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.G9djcpstYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:34:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:34:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:34:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qEAFYszRQV/Render', 01:34:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:39 INFO - 'HOME': '/Users/cltbld', 01:34:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:39 INFO - 'LOGNAME': 'cltbld', 01:34:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:39 INFO - 'MOZ_NO_REMOTE': '1', 01:34:39 INFO - 'NO_EM_RESTART': '1', 01:34:39 INFO - 'PAGER': '/bin/cat', 01:34:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:39 INFO - 'PWD': '/builds/slave/test', 01:34:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:39 INFO - 'SHELL': '/bin/bash', 01:34:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G9djcpstYn/Listeners', 01:34:39 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 01:34:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:39 INFO - 'USER': 'cltbld', 01:34:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:39 INFO - 'XPC_FLAGS': '0x0', 01:34:39 INFO - 'XPC_SERVICE_NAME': '0', 01:34:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:39 INFO - Downloading/unpacking psutil>=3.1.1 01:34:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:34:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:34:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:34:43 INFO - Installing collected packages: psutil 01:34:43 INFO - Running setup.py install for psutil 01:34:43 INFO - building 'psutil._psutil_osx' extension 01:34:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 01:34:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 01:34:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 01:34:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 01:34:44 INFO - building 'psutil._psutil_posix' extension 01:34:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 01:34:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:34:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:34:44 INFO - ^ 01:34:44 INFO - 1 warning generated. 01:34:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 01:34:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:34:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:34:44 INFO - Successfully installed psutil 01:34:44 INFO - Cleaning up... 01:34:44 INFO - Return code: 0 01:34:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:44 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 0x10858ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10858d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c8da9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108368d30>, '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 0x10828fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10835dbe8>, '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.qEAFYszRQV/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.G9djcpstYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:34:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:34:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:34:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qEAFYszRQV/Render', 01:34:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:44 INFO - 'HOME': '/Users/cltbld', 01:34:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:44 INFO - 'LOGNAME': 'cltbld', 01:34:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:44 INFO - 'MOZ_NO_REMOTE': '1', 01:34:44 INFO - 'NO_EM_RESTART': '1', 01:34:44 INFO - 'PAGER': '/bin/cat', 01:34:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:44 INFO - 'PWD': '/builds/slave/test', 01:34:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:44 INFO - 'SHELL': '/bin/bash', 01:34:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G9djcpstYn/Listeners', 01:34:44 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 01:34:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:44 INFO - 'USER': 'cltbld', 01:34:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:44 INFO - 'XPC_FLAGS': '0x0', 01:34:44 INFO - 'XPC_SERVICE_NAME': '0', 01:34:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:34:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:34:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:34:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:34:47 INFO - Installing collected packages: mozsystemmonitor 01:34:47 INFO - Running setup.py install for mozsystemmonitor 01:34:47 INFO - Successfully installed mozsystemmonitor 01:34:47 INFO - Cleaning up... 01:34:47 INFO - Return code: 0 01:34:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:34:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:47 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 0x10858ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10858d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c8da9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108368d30>, '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 0x10828fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10835dbe8>, '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.qEAFYszRQV/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.G9djcpstYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:34:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:34:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:34:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qEAFYszRQV/Render', 01:34:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:47 INFO - 'HOME': '/Users/cltbld', 01:34:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:47 INFO - 'LOGNAME': 'cltbld', 01:34:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:47 INFO - 'MOZ_NO_REMOTE': '1', 01:34:47 INFO - 'NO_EM_RESTART': '1', 01:34:47 INFO - 'PAGER': '/bin/cat', 01:34:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:47 INFO - 'PWD': '/builds/slave/test', 01:34:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:47 INFO - 'SHELL': '/bin/bash', 01:34:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G9djcpstYn/Listeners', 01:34:47 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 01:34:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:47 INFO - 'USER': 'cltbld', 01:34:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:47 INFO - 'XPC_FLAGS': '0x0', 01:34:47 INFO - 'XPC_SERVICE_NAME': '0', 01:34:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:48 INFO - Downloading/unpacking jsonschema==2.5.1 01:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:34:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:51 INFO - Downloading functools32-3.2.3-2.tar.gz 01:34:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:34:51 INFO - warning: no files found matching '*.txt' 01:34:51 INFO - no previously-included directories found matching 'build' 01:34:51 INFO - no previously-included directories found matching 'dist' 01:34:51 INFO - no previously-included directories found matching '.git*' 01:34:51 INFO - Installing collected packages: jsonschema, functools32 01:34:51 INFO - Running setup.py install for functools32 01:34:51 INFO - warning: no files found matching '*.txt' 01:34:51 INFO - no previously-included directories found matching 'build' 01:34:51 INFO - no previously-included directories found matching 'dist' 01:34:51 INFO - no previously-included directories found matching '.git*' 01:34:51 INFO - Successfully installed jsonschema functools32 01:34:51 INFO - Cleaning up... 01:34:51 INFO - Return code: 0 01:34:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:51 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 0x10858ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10858d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c8da9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108368d30>, '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 0x10828fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10835dbe8>, '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.qEAFYszRQV/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.G9djcpstYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:34:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:34:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:34:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qEAFYszRQV/Render', 01:34:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:51 INFO - 'HOME': '/Users/cltbld', 01:34:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:51 INFO - 'LOGNAME': 'cltbld', 01:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:51 INFO - 'MOZ_NO_REMOTE': '1', 01:34:51 INFO - 'NO_EM_RESTART': '1', 01:34:51 INFO - 'PAGER': '/bin/cat', 01:34:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:51 INFO - 'PWD': '/builds/slave/test', 01:34:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:51 INFO - 'SHELL': '/bin/bash', 01:34:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G9djcpstYn/Listeners', 01:34:51 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 01:34:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:51 INFO - 'USER': 'cltbld', 01:34:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:51 INFO - 'XPC_FLAGS': '0x0', 01:34:51 INFO - 'XPC_SERVICE_NAME': '0', 01:34:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:34:51 INFO - Cleaning up... 01:34:51 INFO - Return code: 0 01:34:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:51 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 0x10858ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10858d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c8da9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108368d30>, '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 0x10828fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10835dbe8>, '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.qEAFYszRQV/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.G9djcpstYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:34:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:34:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:34:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qEAFYszRQV/Render', 01:34:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:51 INFO - 'HOME': '/Users/cltbld', 01:34:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:51 INFO - 'LOGNAME': 'cltbld', 01:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:51 INFO - 'MOZ_NO_REMOTE': '1', 01:34:51 INFO - 'NO_EM_RESTART': '1', 01:34:51 INFO - 'PAGER': '/bin/cat', 01:34:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:51 INFO - 'PWD': '/builds/slave/test', 01:34:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:51 INFO - 'SHELL': '/bin/bash', 01:34:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G9djcpstYn/Listeners', 01:34:51 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 01:34:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:51 INFO - 'USER': 'cltbld', 01:34:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:51 INFO - 'XPC_FLAGS': '0x0', 01:34:51 INFO - 'XPC_SERVICE_NAME': '0', 01:34:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:52 INFO - Downloading/unpacking blobuploader==1.2.4 01:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:54 INFO - Downloading blobuploader-1.2.4.tar.gz 01:34:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:34:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:34:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:34:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:55 INFO - Downloading docopt-0.6.1.tar.gz 01:34:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:34:56 INFO - Installing collected packages: blobuploader, requests, docopt 01:34:56 INFO - Running setup.py install for blobuploader 01:34:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:34:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:34:56 INFO - Running setup.py install for requests 01:34:56 INFO - Running setup.py install for docopt 01:34:56 INFO - Successfully installed blobuploader requests docopt 01:34:56 INFO - Cleaning up... 01:34:57 INFO - Return code: 0 01:34:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:34:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:57 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 0x10858ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10858d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c8da9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108368d30>, '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 0x10828fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10835dbe8>, '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.qEAFYszRQV/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.G9djcpstYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:34:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:34:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:34:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qEAFYszRQV/Render', 01:34:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:57 INFO - 'HOME': '/Users/cltbld', 01:34:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:57 INFO - 'LOGNAME': 'cltbld', 01:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:57 INFO - 'MOZ_NO_REMOTE': '1', 01:34:57 INFO - 'NO_EM_RESTART': '1', 01:34:57 INFO - 'PAGER': '/bin/cat', 01:34:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:57 INFO - 'PWD': '/builds/slave/test', 01:34:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:57 INFO - 'SHELL': '/bin/bash', 01:34:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G9djcpstYn/Listeners', 01:34:57 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 01:34:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:57 INFO - 'USER': 'cltbld', 01:34:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:57 INFO - 'XPC_FLAGS': '0x0', 01:34:57 INFO - 'XPC_SERVICE_NAME': '0', 01:34:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:34:57 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-CZubh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:34:57 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-HE3ifQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:34:57 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Doz499-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:34:57 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-nKKZOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:34:57 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-kt_r_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:34:57 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-meHNCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:34:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:34:57 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-j3KQPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:34:58 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-7MqKOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:34:58 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-xOdZim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:34:58 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-oZcIKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:34:58 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-NYi0va-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:34:58 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-_DIobK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:34:58 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-9E5S02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:34:58 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-p9ir1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:34:58 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-e3m0MR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:34:58 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-bg4vPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:34:59 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-mKbHWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:34:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:34:59 INFO - Running setup.py install for manifestparser 01:34:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:34:59 INFO - Running setup.py install for mozcrash 01:34:59 INFO - Running setup.py install for mozdebug 01:34:59 INFO - Running setup.py install for mozdevice 01:34:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:34:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:34:59 INFO - Running setup.py install for mozfile 01:34:59 INFO - Running setup.py install for mozhttpd 01:35:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:35:00 INFO - Running setup.py install for mozinfo 01:35:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:35:00 INFO - Running setup.py install for mozInstall 01:35:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:35:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:35:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:35:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:35:00 INFO - Running setup.py install for mozleak 01:35:00 INFO - Running setup.py install for mozlog 01:35:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:35:00 INFO - Running setup.py install for moznetwork 01:35:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:35:00 INFO - Running setup.py install for mozprocess 01:35:00 INFO - Running setup.py install for mozprofile 01:35:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:35:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:35:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:35:01 INFO - Running setup.py install for mozrunner 01:35:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:35:01 INFO - Running setup.py install for mozscreenshot 01:35:01 INFO - Running setup.py install for moztest 01:35:01 INFO - Running setup.py install for mozversion 01:35:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:35:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:35:01 INFO - Cleaning up... 01:35:01 INFO - Return code: 0 01:35:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:35: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')]} 01:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35: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')]} 01:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:01 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 0x10858ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10858d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c8da9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108368d30>, '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 0x10828fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10835dbe8>, '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.qEAFYszRQV/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.G9djcpstYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:35:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:35:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:35:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qEAFYszRQV/Render', 01:35:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:01 INFO - 'HOME': '/Users/cltbld', 01:35:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:01 INFO - 'LOGNAME': 'cltbld', 01:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:01 INFO - 'MOZ_NO_REMOTE': '1', 01:35:01 INFO - 'NO_EM_RESTART': '1', 01:35:01 INFO - 'PAGER': '/bin/cat', 01:35:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:01 INFO - 'PWD': '/builds/slave/test', 01:35:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:01 INFO - 'SHELL': '/bin/bash', 01:35:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G9djcpstYn/Listeners', 01:35:01 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 01:35:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:01 INFO - 'USER': 'cltbld', 01:35:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:01 INFO - 'XPC_FLAGS': '0x0', 01:35:01 INFO - 'XPC_SERVICE_NAME': '0', 01:35:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:35:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-rig4hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:35:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:35:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-cw_ybX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:35:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:35:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-xmQFRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:35:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:35:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-LsyWWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:35:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:35:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-HWnSXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:35:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:35:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Q7LNxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:35:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:35:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-hGZTts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:35:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:35:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-aVqQ0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:35:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:35:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-08CpJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:35:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-odThSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:35:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-cfp_pG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:35:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-6PWoEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:35:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Rb8W4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:35:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-iBchyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:35:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-wAJ90Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:35:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-UfGz_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:35:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-aCASKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:35:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:06 INFO - Downloading blessings-1.6.tar.gz 01:35:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:35:06 INFO - Installing collected packages: blessings 01:35:06 INFO - Running setup.py install for blessings 01:35:06 INFO - Successfully installed blessings 01:35:06 INFO - Cleaning up... 01:35:06 INFO - Return code: 0 01:35:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:35:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:35:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:35:07 INFO - Reading from file tmpfile_stdout 01:35:07 INFO - Current package versions: 01:35:07 INFO - blessings == 1.6 01:35:07 INFO - blobuploader == 1.2.4 01:35:07 INFO - docopt == 0.6.1 01:35:07 INFO - functools32 == 3.2.3-2 01:35:07 INFO - jsonschema == 2.5.1 01:35:07 INFO - manifestparser == 1.1 01:35:07 INFO - mozInstall == 1.12 01:35:07 INFO - mozcrash == 1.0 01:35:07 INFO - mozdebug == 0.1 01:35:07 INFO - mozdevice == 0.49 01:35:07 INFO - mozfile == 1.2 01:35:07 INFO - mozhttpd == 0.7 01:35:07 INFO - mozinfo == 0.9 01:35:07 INFO - mozleak == 0.1 01:35:07 INFO - mozlog == 3.4 01:35:07 INFO - moznetwork == 0.27 01:35:07 INFO - mozprocess == 0.25 01:35:07 INFO - mozprofile == 0.28 01:35:07 INFO - mozrunner == 6.13 01:35:07 INFO - mozscreenshot == 0.1 01:35:07 INFO - mozsystemmonitor == 0.3 01:35:07 INFO - moztest == 0.8 01:35:07 INFO - mozversion == 1.4 01:35:07 INFO - psutil == 3.1.1 01:35:07 INFO - requests == 1.2.3 01:35:07 INFO - wsgiref == 0.1.2 01:35:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:07 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 0x10858ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10858d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c8da9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108368d30>, '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 0x10828fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10835dbe8>, '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.qEAFYszRQV/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.G9djcpstYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:35:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:35:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:35:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qEAFYszRQV/Render', 01:35:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:07 INFO - 'HOME': '/Users/cltbld', 01:35:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:07 INFO - 'LOGNAME': 'cltbld', 01:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:07 INFO - 'MOZ_NO_REMOTE': '1', 01:35:07 INFO - 'NO_EM_RESTART': '1', 01:35:07 INFO - 'PAGER': '/bin/cat', 01:35:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:07 INFO - 'PWD': '/builds/slave/test', 01:35:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:07 INFO - 'SHELL': '/bin/bash', 01:35:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G9djcpstYn/Listeners', 01:35:07 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 01:35:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:07 INFO - 'USER': 'cltbld', 01:35:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:07 INFO - 'XPC_FLAGS': '0x0', 01:35:07 INFO - 'XPC_SERVICE_NAME': '0', 01:35:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:35:07 INFO - Cleaning up... 01:35:07 INFO - Return code: 0 01:35:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:07 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 0x10858ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10858d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1c8da9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108368d30>, '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 0x10828fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10835dbe8>, '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.qEAFYszRQV/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.G9djcpstYn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:35:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:35:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:35:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qEAFYszRQV/Render', 01:35:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:07 INFO - 'HOME': '/Users/cltbld', 01:35:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:07 INFO - 'LOGNAME': 'cltbld', 01:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:07 INFO - 'MOZ_NO_REMOTE': '1', 01:35:07 INFO - 'NO_EM_RESTART': '1', 01:35:07 INFO - 'PAGER': '/bin/cat', 01:35:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:07 INFO - 'PWD': '/builds/slave/test', 01:35:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:07 INFO - 'SHELL': '/bin/bash', 01:35:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G9djcpstYn/Listeners', 01:35:07 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 01:35:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:07 INFO - 'USER': 'cltbld', 01:35:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:07 INFO - 'XPC_FLAGS': '0x0', 01:35:07 INFO - 'XPC_SERVICE_NAME': '0', 01:35:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:35:07 INFO - Cleaning up... 01:35:07 INFO - Return code: 0 01:35:07 INFO - Running post-action listener: _resource_record_post_action 01:35:07 INFO - Running post-action listener: _start_resource_monitoring 01:35:07 INFO - Starting resource monitoring. 01:35:07 INFO - [mozharness: 2017-05-03 08:35:07.780281Z] Finished create-virtualenv step (success) 01:35:07 INFO - [mozharness: 2017-05-03 08:35:07.781286Z] Running install step. 01:35:07 INFO - Running pre-action listener: _resource_record_pre_action 01:35:07 INFO - Running main action method: install 01:35:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:35:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:35:08 INFO - Reading from file tmpfile_stdout 01:35:08 INFO - Detecting whether we're running mozinstall >=1.0... 01:35:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:35:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:35:08 INFO - Reading from file tmpfile_stdout 01:35:08 INFO - Output received: 01:35:08 INFO - Usage: mozinstall [options] installer 01:35:08 INFO - Options: 01:35:08 INFO - -h, --help show this help message and exit 01:35:08 INFO - -d DEST, --destination=DEST 01:35:08 INFO - Directory to install application into. [default: 01:35:08 INFO - "/builds/slave/test"] 01:35:08 INFO - --app=APP Application being installed. [default: firefox] 01:35:08 INFO - mkdir: /builds/slave/test/build/application 01:35:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:35:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 01:35:28 INFO - Reading from file tmpfile_stdout 01:35:28 INFO - Output received: 01:35:28 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:35:28 INFO - Running post-action listener: _resource_record_post_action 01:35:28 INFO - [mozharness: 2017-05-03 08:35:28.377818Z] Finished install step (success) 01:35:28 INFO - [mozharness: 2017-05-03 08:35:28.377963Z] Running run-tests step. 01:35:28 INFO - Running pre-action listener: _resource_record_pre_action 01:35:28 INFO - Running main action method: run_tests 01:35:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:35:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:35:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:35:28 INFO - 2017-05-03 01:35:28.394 screenresolution[12241:312327] starting screenresolution argv=screenresolution get 01:35:28 INFO - 2017-05-03 01:35:28.397 screenresolution[12241:312327] Display 0: 1600x1200x32@60 01:35:28 INFO - 2017-05-03 01:35:28.403 screenresolution[12242:312330] starting screenresolution argv=screenresolution list 01:35:28 INFO - Available Modes on Display 0 01:35:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:35:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:35:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:35:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:35:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:35:28 INFO - Intel Iris: 01:35:28 INFO - Chipset Model: Intel Iris 01:35:28 INFO - Type: GPU 01:35:28 INFO - Bus: Built-In 01:35:28 INFO - VRAM (Dynamic, Max): 1536 MB 01:35:28 INFO - Vendor: Intel (0x8086) 01:35:28 INFO - Device ID: 0x0a2e 01:35:28 INFO - Revision ID: 0x0009 01:35:28 INFO - Displays: 01:35:28 INFO - Display: 01:35:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:35:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:35:28 INFO - Display Serial Number: 1600x1200 60 01:35:28 INFO - Main Display: Yes 01:35:28 INFO - Mirror: Off 01:35:28 INFO - Online: Yes 01:35:28 INFO - Rotation: Supported 01:35:28 INFO - Return code: 0 01:35:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:35:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:35:28 INFO - Python 2.7.3 01:35:28 INFO - Return code: 0 01:35:28 INFO - grabbing minidump binary from tooltool 01:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:28 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 0x108368d30>, '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 0x10828fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10835dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:35:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:35:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 01:35:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 01:35:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:35:28 INFO - Return code: 0 01:35:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:35:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:35:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:35:28 INFO - ENV: RUST_BACKTRACE is now 1 01:35:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:35:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:35:28 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/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0231', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:35:28 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/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0231 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:35:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qEAFYszRQV/Render', 01:35:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:28 INFO - 'HOME': '/Users/cltbld', 01:35:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:28 INFO - 'LOGNAME': 'cltbld', 01:35:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:35:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:35:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:28 INFO - 'MOZ_NO_REMOTE': '1', 01:35:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:35:28 INFO - 'NO_EM_RESTART': '1', 01:35:28 INFO - 'PAGER': '/bin/cat', 01:35:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:28 INFO - 'PWD': '/builds/slave/test', 01:35:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:35:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:28 INFO - 'RUST_BACKTRACE': '1', 01:35:28 INFO - 'SHELL': '/bin/bash', 01:35:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G9djcpstYn/Listeners', 01:35:28 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 01:35:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:28 INFO - 'USER': 'cltbld', 01:35:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:28 INFO - 'XPC_FLAGS': '0x0', 01:35:28 INFO - 'XPC_SERVICE_NAME': '0', 01:35:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:28 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/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0231', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:35:29 INFO - mozversion application_buildid: 20170503004005 01:35:29 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:35:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:35:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:35:29 INFO - mozversion application_name: Firefox 01:35:29 INFO - mozversion application_remotingname: firefox-dev 01:35:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:35:29 INFO - mozversion application_vendor: Mozilla 01:35:29 INFO - mozversion application_version: 54.0a2 01:35:29 INFO - mozversion platform_buildid: 20170503004005 01:35:29 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:35:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:35:29 INFO - mozversion platform_version: 54.0a2 01:35:29 INFO - using testdate: 1493800529 01:35:29 INFO - actual date: 1493800529 01:35:29 INFO - starting webserver on 'localhost:60097' 01:35:29 INFO - SUITE-START | Running 2 tests 01:35:29 INFO - TEST-START | dromaeo_css 01:35:29 INFO - operating with platform_type : mac_ 01:35:29 INFO - Initialising browser for dromaeo_css test... 01:35:29 INFO - TEST-INFO | started process 12252 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmpzcS45L/profile http://localhost:60097/getInfo.html) 01:35:31 INFO - PROCESS | 12252 | __metrics Screen width/height:1600/1200 01:35:31 INFO - PROCESS | 12252 | colorDepth:24 01:35:31 INFO - PROCESS | 12252 | Browser inner width/height: 1024/689 01:35:31 INFO - PROCESS | 12252 | __metrics 01:35:31 INFO - PROCESS | 12252 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:35:31 INFO - PROCESS | 12252 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:35:32 INFO - TEST-INFO | 12252: exit 0 01:35:32 INFO - Browser initialized. 01:35:32 INFO - Running cycle 1/1 for dromaeo_css test... 01:35:32 INFO - TEST-INFO | started process 12255 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmpzcS45L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:35:44 INFO - PROCESS | 12255 | RSS: Main: 215523328 01:35:44 INFO - PROCESS | 12255 | 01:38:48 INFO - PROCESS | 12255 | Cycle 1(1): loaded http://localhost:60097/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60097/tests/dromaeo/cssquery-ext.html) 01:38:50 INFO - PROCESS | 12255 | RSS: Main: 389246976 01:38:50 INFO - PROCESS | 12255 | 01:40:34 INFO - PROCESS | 12255 | ************************* 01:40:34 INFO - PROCESS | 12255 | A coding exception was thrown and uncaught in a Task. 01:40:34 INFO - PROCESS | 12255 | 01:40:34 ERROR - PROCESS | 12255 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:40:34 INFO - PROCESS | 12255 | Full stack: 01:40:34 INFO - PROCESS | 12255 | ************************* 01:40:34 INFO - PROCESS | 12255 | ************************* 01:40:34 INFO - PROCESS | 12255 | A coding exception was thrown and uncaught in a Task. 01:40:34 INFO - PROCESS | 12255 | 01:40:34 ERROR - PROCESS | 12255 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:40:34 INFO - PROCESS | 12255 | Full stack: 01:40:34 INFO - PROCESS | 12255 | ************************* 01:40:34 INFO - PROCESS | 12255 | ************************* 01:40:34 INFO - PROCESS | 12255 | A coding exception was thrown and uncaught in a Task. 01:40:34 INFO - PROCESS | 12255 | 01:40:34 ERROR - PROCESS | 12255 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:40:34 INFO - PROCESS | 12255 | Full stack: 01:40:34 INFO - PROCESS | 12255 | ************************* 01:40:34 INFO - PROCESS | 12255 | ************************* 01:40:34 INFO - PROCESS | 12255 | A coding exception was thrown and uncaught in a Task. 01:40:34 INFO - PROCESS | 12255 | 01:40:34 ERROR - PROCESS | 12255 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:40:34 INFO - PROCESS | 12255 | Full stack: 01:40:34 INFO - PROCESS | 12255 | ************************* 01:41:53 INFO - PROCESS | 12255 | Cycle 1(1): loaded http://localhost:60097/tests/dromaeo/cssquery-ext.html (next: http://localhost:60097/tests/dromaeo/cssquery-jquery.html) 01:41:54 INFO - PROCESS | 12255 | RSS: Main: 349577216 01:41:54 INFO - PROCESS | 12255 | 01:44:57 INFO - PROCESS | 12255 | Cycle 1(1): loaded http://localhost:60097/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60097/tests/dromaeo/cssquery-mootools.html) 01:44:57 INFO - PROCESS | 12255 | RSS: Main: 339210240 01:44:57 INFO - PROCESS | 12255 | 01:48:00 INFO - PROCESS | 12255 | Cycle 1(1): loaded http://localhost:60097/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60097/tests/dromaeo/cssquery-prototype.html) 01:48:00 INFO - PROCESS | 12255 | RSS: Main: 336809984 01:48:00 INFO - PROCESS | 12255 | 01:51:04 INFO - PROCESS | 12255 | Cycle 1(1): loaded http://localhost:60097/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60097/tests/dromaeo/cssquery-yui.html) 01:51:05 INFO - PROCESS | 12255 | RSS: Main: 329572352 01:51:05 INFO - PROCESS | 12255 | 01:54:08 INFO - PROCESS | 12255 | Cycle 1(1): loaded http://localhost:60097/tests/dromaeo/cssquery-yui.html (next: http://localhost:60097/tests/dromaeo/cssquery-dojo.html) 01:54:09 INFO - PROCESS | 12255 | RSS: Main: 411205632 01:54:09 INFO - PROCESS | 12255 | 01:54:09 INFO - PROCESS | 12255 | __start_tp_report 01:54:09 INFO - PROCESS | 12255 | _x_x_mozilla_page_load 01:54:09 INFO - PROCESS | 12255 | _x_x_mozilla_page_load_details 01:54:09 INFO - PROCESS | 12255 | |i|pagename|runs| 01:54:09 INFO - PROCESS | 12255 | |0;dojo.html;3332.7833690810094;3360.411927912615;3363.1423986883347;3372.0895358253274;3411.3347897160047;5819.621724587899;5825.766969321233;5827.679477628729;5856.590038697287;5923.348431672518;5864.560157988147;5927.347991720907;5939.316978547468;5954.79158229461;5964.910526342108;5273.9736301318535;5306.416294207634;5322;5384.946150538503;5388.946110538904;5229.424763276036;5239.659422137568;5264.368275806909;5321.600879934001;5410.513053825155;5353.197020446944;5377.354717433914;5382.2195781611645;5391.406945236021;5406.297181366436;4449.977750111245;5382.138857782736;5397.892042159174;5398.163284690879;5420.539254163361;5140.074786538422;5194.428612852583;5239.056969745444;5305.575553955674;5334.706591137486;5733.569982251348;5827.388124246935;5876.2067120938555;5912.881742365129;5937.940620593782;6256.436920677115;6329.208848893888;6329.272133704637;6330.27201871786;6339.429451349401;6371.553991220617;6382.712777924958;6390.9360906391275;6398.29618741938;6402.93597064028;5752.741126649311;5881.764729410818;5910.8817823643285;5911.438413350725;5914.408559144051;5351.277577526984;6255.343188965184;6265.937340626627;6269.498440124779;6272.968635156795;6220.782272620527;6251.687415629201;6260.655663938527;6302.527310451735;6305.180326557518;6350.174477318012;6369.235691717024;6373.330800265998;6375.011873159668;6380.330065343165;6173.783917562864;6224.37756224374;6295.9926411773895;6312.242530896322;6338.968305158444;6252.218472690914;6265.843353916189;6269.498440124779;6271.4669253114;6317.905231421532;5082.822101226439;5123.308353372322;5123.974380128076;5126.179811230185;5150.639455238173;4558.635309175258;4563.178627846953;4573.108243892409;4589.541045895384;4612.5156858530045;5203.479652034842;5670.7731690732735;5779.393163717749;5817.38917413674;5984.910226346608;6315.621062736251;6318.147050148263;6384.648844313514;6396.744130234832;6405.3594640535575;5993.220881285492;5997.670128142994;5999.970000149971;6005.369436209223;6043.425874541911;5451.018816612968;5596.384397716248;5603.607747457639;5620.63465874725;5637.746301416446;5468.56251499887;6385.201849768871;6400.327965563549;6453.41919227253;6535.738570457129;6354.968225158845;6402.391772781578;6403.007533832078;6406.711697973509;6407.935920640921;6521.087047813215;6559.376859198369;6581.539292249497;6591;6601.042848786995;5730.197772311796;6121.209103132208;6248.0940263660605;6310.400511951358;6335.524835637253;6319.210098737658;6323.747050117944;6329.272133704683;6336.17629708135;6337.809865704036;5434.347878254634;5969.552283578836;6139.277491147187;6155.1075094112;6266.122742815918;6065.181200538047;6150.138980542817;6158.538109641705;6161.075838624242;6161.44546990773;6109.9816581254145;6114.785982490592;6119.488547144922;6121.960940613755;6125.724342404692;6054.455099041107;6109.11417844419;6293.465055470337;6309.526785491014;6309.968450157903;5961.552883533665;5967.9701601491715;5968.910466343095;5970;5980.9401905980385 01:54:09 INFO - PROCESS | 12255 | |1;ext.html;667.362668651438;741.8367959049008;782.5734974438931;808.3048578222729;879.5514287713272;27344.86327568362;38430.23139537207;38776.418353724715;38935.41596876049;38975.41536876942;32569.51145732816;39330.4100438493;39497.40753888694;39757.40363894544;39773.60226397735;7208.062951816257;9071.410358326704;9196.540172991357;9203.125703058198;9206.815863682723;7279.818004549902;10926.125909927227;11102.444877756121;11109.666709998713;11113;10976.506057227443;11005.229633925628;11014.06380457658;11049.558017679323;11051.226414151013;7162.4628152888745;10926.945365273203;10964.945175274073;10972.506237219344;10995.395253261067;7217.3865221456235;7265.673044713;7269.709211631528;7272.672729727175;10480.266381353309;38553;38738.41892371617;38793;38796.41805372922;38816.80591597052;3780.3384407728536;3797.5063241778666;3835;3859.4210868369687;3949.5852935441653;26281.605775913376;26339.73660263392;27232.046878359164;28188.718112818813;28201.717982820115;2688.8117831751783;3049.9390012199856;3110.626724793039;3118.3919135768533;3156.0531840448;323696;326538;326952;327655;328192;50578.241326380135;50730.49269507331;50760;50888.49111508838;50902.74548627233;52782;53056.469435305924;53056.734716326944;53133.734331328866;53135;14321.498747543787;14798.704025919422;14800.333984970703;14808;14835.925820371045;11005.559777608805;11198.496067676973;12792.2964236966;12807.93596032014;12834.422450989729;10316.793664126675;11596.362200078907;11742.236754610924;11843.170978031456;11980.221285616408;5157.4584668610005;5159.7678104485385;5163.096458119814;5167.354080739908;5178.093833579109;896.7668406214431;916.8441364967971;921.7142685767434;944.6127087894068;960.6061514778908;24603.753962460145;26734.46531069368;27078.86460567724;27105.457890842074;27688.30779230495;978.8091322192247;1093.420487141816;1105.1435137768294;1122.4443900269255;1125.0886781706843;14644.853551464563;14689.77965330521;14793.556193314218;14888.47890323855;14911.70176596462;313600;378829;379384;379864;380261;55409;55587.444125559035;55693.164602531;55753.72123139358;55822;1385.6189547874415;1437.3100911562299;1437.4896911596234;1441.4954765831874;1441.509886638527;26737;27181.1845644631;27818.304542386602;27884.721152788214;28003.579946300415;45881;45992.310115347624;46112.308315375965;46156.07687846291;46361.304580430646;393703;393895;394217;395170;395948;30290;30770.846145769126;30961.845190773904;30969.225769355948;30974.38051239008;42323.7883810579;42452.150956981306;42884.78557607192;43022.569774303105;43088.56911430971;22752.431189220402;28186.577201341584;28965.13104606865;29821.70178298276;30040.849795751754;26613.570438891376;28779.42441151207;29406;29825.70174298229;29849;34598.82700586481;34901.65098348984;35790.28419431545;35807.46288805618;35868.64131358758;29220.7077929218;29394;30137.397252055274;30206;30452.543211852284;22261.220857269916;23278.534429311658;23280.185193518144;23507.41231469227;23604.29187124389 01:54:09 INFO - PROCESS | 12255 | |2;jquery.html;385.79167249685173;410.35779005855835;413.9958600413995;424.5287730619013;445.9063596644705;12941.676458088552;14827.258637068144;20826.166953321866;21408.57182856342;21584.244551440705;21364;21510.462238444048;21718.674219886714;22302.442438938993;23112.07551697934;6525.3800888915475;6601.438877695402;6741.966290168542;6823.624700641462;6838.897416538756;4127.834886604533;4306.246406878784;6702.597844129344;6797.422219111381;6813.11429514165;3248.61016677999;3330.8508564545314;5778.624389414675;6120.8982383171015;6723.798286051427;7351.926480735177;7576.848463030764;7719.382449404035;7792.688292468294;7797.805054873616;7471;7493.550386976744;7494.325510704009;7506.699732010768;7509.662065207079;24371;25505;26127.477450450886;26265.212043638723;26487.47025059488;2761.4615150045747;2849;2870.3398218409884;2896.6379202599674;2899.4780939430893;15387.923060384739;15648.4523041693;16013.279402426919;16346.10096444695;16695.332186712516;1995;2007.3993557973383;2023.1705000949687;2046.703228031942;2059.5468996820678;125063;125629.99999999908;125917;143117;151494;14089.436422542984;16416.671666566603;18286.542836429195;19737.506562336057;20000.49998750043;31954;32065.67934320627;32160.517592236134;32185.35629287448;32242.677573223966;10113.089821916063;10170.847437288447;10264.743381415376;10369.118624916966;10509.894901051044;8639.395242333103;8652.264557512555;8666.480011199348;8845.823083538422;8849.867251991225;7220;9224.953875230582;9251.583678734472;9264.536773161315;9344.626214951462;3809.904752381213;3850.383938569815;3884.475595794588;3895;3902.2780785554196;813.032491335309;863.9438436501607;891.3003292415423;905.9275257979345;928.8467402878584;15717.842821571867;17429.38997135094;20268.29060982888;20859.331253499684;21046.894765526074;913.3332667153015;949.6960972488876;977.7262366537238;979.6669132494986;985.7979114281699;8760.693375731818;10928.562857485771;10959.342439453658;11051.72370690739;11241.437928103562;686047.569762148;688064;699144;699513.0146095626;717675;121073.37492694455;131255;140732;152335;157814;1220.3044264768996;1239.9938000310121;1255.7739606870668;1263.3746295583744;1270.8586032535209;18801.905990470506;19100.522486937938;19142.46168759465;20625.58748825045;20706.378808635764;45590;62245;62348.44128896632;64360;65414.34585654273;81250;81443;82034;84166.73749893635;84581;11094.223404361617;11469.025132863466;11731.00286475625;11803.645890623295;11962.58130965412;12729.618111456672;12931.741365172456;13285.269310188032;13333.933330333286;13409.798853017408;11054.723631909266;11171.776564468502;11298.435078246219;11349.886501134883;11442.713932151762;8633.69782057625;8812.51531165792;10470.371777693363;10781.407022613832;10796.298240614335;11000.504977276201;11439.656810295704;11440.141989350666;11572.305661660328;11645.359505227294;11511.71723016423;11566.768664626828;11648.76702465929;11791.469383877575;11808.94095529517;11217.158713096713;11234.539509863665;11451.59919402817;11459.541618335506;11594.76810463803 01:54:09 INFO - PROCESS | 12255 | |3;mootools.html;1524.5578782153177;1552;1577.5425126713253;1580.6364536156677;1597.8561929426348;10443.42561159136;10934.179936504754;11011.394373309466;11016.889831101696;11019.449027548611;10517;10747.408892510908;10840.078593319577;10924.836127458095;10946.616868409606;2236.3514580771557;2267.852589581672;2268.0587556164173;2271.5116250006245;2272.284230467406;2194.714687090676;2260.42359198405;2288.439332363565;2294.5181511882524;2297.517521320525;2242.1816037146486;2244.7755224477587;2269.0583407885706;2277.7152855893014;2291.5531471363092;2208.6686996950584;2269.3872654383226;2269.6595510673283;2271.477560161164;2278.9316320510247;2267.2744721689064;2276.408133885185;2284.0635339510886;2284.383216531527;2286.1998300594823;11076.94768996944;11164;11168.944155279172;11173.162012849069;11194.44027798615;5234.18870075139;5406.702631355274;5421.430749771296;5432.728363581805;5453.945460545423;2905.2010697058267;2973.836438995854;3095.90712278632;3145.1979745165067;3151.8266495342746;1072.5388083124253;1082.6210826210843;1104.6630777612825;1116.4975760907544;1120.3669926491589;13068.215907045607;15173.544793656209;15193.620159495991;15383.846161538353;15387.38450461969;4108.137291168888;4273.188094262143;4340.891477713082;4347.760873151944;4375.387445757597;11452.255603385753;11459.541618335174;11460.197786155057;11473;11483;5332.9466705333225;5373.328333958255;5384.892302153935;5390.622656414092;5392.083345831218;4699.2481203007355;4741.478437371887;4798.424189097331;4905.9264111038365;4919.114559379274;2980.612520372381;3508.4386498160684;3560.608333083359;3598.4242521196484;3611.7471776975885;893.8659201119884;899.4872922434281;901.0133903375793;904.3759805734154;904.9502277374808;776.8301950519303;869.2698133567833;896.1352295035322;944.2493217891792;950.6007476859736;6239.251289845248;6305.5901366411035;6485.935140648628;6500.057491663682;6555.6066636002;876.5529579914289;923.5936188077225;938.9859152112724;960.4285450157146;968.7723385004518;3394.2702319001532;4024.7383920045104;4030.778307193074;4064.349704047338;4215.620594146541;12846.935765321301;13573.04988650785;13598.980076494303;13613.11514751538;13657.180569165881;12788.744225115446;13324.333783310796;13469.393877275546;13491.460341586422;13557;9812.558434870449;10015.599376024877;10102.747431314128;10110.39337639744;10118.949405253074;6473.352664733489;6713.1944166700305;6718.496112791462;6817.420519255918;6995.3004699531675;11836.526538938691;11984.221025633306;12128.272303661808;12188.57339993096;12196.878031219574;11988.76022479563;12048.216865903689;12287.078469114673;12333.568325108577;12365.629031129081;9234.492102934402;9262.9536852318;9396.624135034523;9427.387219830689;9438.00900905395;10138.442385668564;10349.534270957674;10357.9482102589;10366.170706343324;10379.481025948824;8481.194286542768;8560.743177704679;8764.079771623889;8878.978917424578;8917.866232006396;8605.139486051345;8640.956795215983;8655.394122411371;8698.391112622056;8746.65013399457;7748.147703752696;7800.960995194988;7927.881081783893;7932.286094251545;7949.046114466301;5769.711514424346;5810.738516766671;5883;5995.46040856325;6018.277806663228;5896.941030589811;6018.037114061729;6087.543434242361;6159.692015399301;6227.593672105418 01:54:09 INFO - PROCESS | 12255 | |4;prototype.html;330.7349388588648;365.37885594489376;370.9666130048295;372.13664298826717;385.94596756454104;4824.5416685414875;4824.745566152799;4888;4919.163742163832;4927.063857867003;4534.34252033455;4606.654500912437;4674.579287864091;4746.691465054778;4793.472718001017;3449.9482507762277;3918.157596116834;3934.7212156049254;3942.9014274643077;4039.4950631171105;3495.440729483283;3566.7681600696;3892.3188442022533;4142.958570414287;4276.893077673052;4147.191297696951;4182.560831112735;4196.81114349855;4199.6220340169375;4219.282721937263;3729.191342199045;3875.61243875613;3894.123822140024;3985.103351745863;4099.3031184698375;3529.32942740881;3743.3262012837677;3792.6207379262128;3923.901902452433;3930.2925473414775;4535.274356103007;4657.650676199265;4711.151992641323;4712.952870471286;4801.111794318027;3748.7750734955716;4410.448693913261;4450.287953927356;4819.590334821544;4896.045271172123;4594.471635761896;4806.423229212517;5118.7696553654805;5155.9742201288755;5187.922181167285;3569.7501174917766;4147.543770185277;4154.756431526972;4177.582241775798;4187.602177793105;4583.129205450954;4728.929066064012;5036.874078148039;5162.948370516285;5187.455317191679;4176.9582304177175;4219.493660760667;4224;4252.766097864647;4312.059573192076;3591.7665351752053;3927.815283825323;3936.4292177634657;4274.722143060753;4276.444062271947;3688.7971161585833;4327.221100201931;4458.888527786831;4519.864404067883;4579.4584005876395;4640;4908.288298196741;4993.550580447704;5066.746662666926;5179.119549676564;1318.5627665844277;1347.4408120629828;1414.504923276845;1472.6023973527087;1475.9631009224856;976.1848856205006;1004.2167109654481;1044.3159730376615;1109.944502774858;1134.6822889590928;4245.426867372927;4437.267850804581;4718.0328032753205;4727.338172655831;4785.588251465826;4842.297866809293;5088.821891233789;5293.338332708411;5321.206366657536;5358.839234822962;4070.470838790938;4123.752574845519;4722.763861806896;4762.714237145782;4866.440359358719;2411.3127758589067;2444.6455263986622;2471.270975062361;2519.3449703076967;2817.2815931937225;5103.923441148386;5355.946440535623;5530.695811730394;5573;5611.6352437091455;4407.691461597721;4704.882377940579;5040.294358789773;5540.625593174615;5542.5565954723525;5164.870878228149;5182.611304152204;5503.871706300199;5561.830885954562;5623.606347555714;4663.673542851968;5185.870353241199;5321.866953326198;5337.653052551572;5444.972775136099;4261.80669412565;4627.93058104122;4949.901001980013;5002.924956125734;5372.543333816513;4567.954320456753;5129.486801393672;5431.592630552804;5467.2663668165915;5539.639923404966;3554.7333949954373;3986.0832008638754;3990.705344426908;4013.097053163015;4299.720518166309;4443.600265916226;4810.927836082532;5404.270423492778;5407.540359069523;5416.674999500043;4242.300020496584;5011.774470148779;5013.510206324069;5187.688738675692;5197.532222100029;5068.619853511075;5113.002964421902;5128.00007996889;5158.194631878812;5211.374635043819;4364.454443194601;4479.17135329965;4887.731174785421;5053.469385714447;5085.92371114441;4584.977075114603;4738.881527961828;4807.687500312609;5128.256402821645;5146.4338922717725;4717.481077081449;5082.695038297714;5138.229265610188;5154.1445079772;5167.176289545634 01:54:09 INFO - PROCESS | 12255 | |5;yui.html;742.6769355330429;761.4631684662313;782.4757412533602;821.8233079887823;849.4393700157896;15053.322600482978;15979.201039948015;16024.919875400621;16033.759493607606;16062.518124456285;12092.818607720892;16637.66724665506;17272.654546909056;17331.046792426412;17351.652966940652;862.4351050062219;935.8128374325128;967.274544091931;968.2883080935542;969.8739163908682;926.4580220570974;945.3571571331491;953.1516949914582;954.265215783846;955.3408148377632;985.9112679858812;996.2876543271559;996.5565323431076;999.0558921818885;1001.8096561653299;993.5280741647659;993.7565296502403;994.5673631970044;995.6863587969802;996.2976101848179;738.4018944654794;739.0503203383627;739.7447880481226;740.4150720930512;740.6926125657845;15389.692206155925;15725.685486290324;15789.526314210592;16224.513264602081;16226;13706.52027179054;14110.435582576685;14125;14131.222782746945;14137.434502619883;11310.660680179608;11744.354060526668;11788.29270243788;11822.113341499336;12040.698982525419;3665.21197942441;3698.7595806272775;3698.981505092457;3795.6963442924775;3830.5020347354944;102647.48676256646;104171;104565.47717261441;105179.99999999923;105275.94724052738;10140.645077422254;10349;10644.361338319726;10753.193510486744;11115.110791136689;21908;22136.778632213794;22296.66555001676;22349.55300893973;22371;7667.0416197975255;7727.961360193275;7759.689612415553;7835.843283134305;7952.920470795334;4214.915701685949;4249.553796851348;4287.742735435884;4354.477462704495;4512.729236245836;3246.3020450603235;3280.2947366316357;3304.3556506481486;3324.5013248012506;3332.5334453176574;511.03021869939687;608.7990962982294;618.6473709985265;619.294004834489;619.3527763487167;695.9512834101564;697.5117417807555;711.5481669140133;721.6103304215769;721.797896588956;10262.948685256526;10289.43408112546;10289.74275643115;10436.218412584723;10535.473226338652;583.9007368747323;662.493192707579;697.6454466176654;713.1549114299468;725.9075091986526;4333.306670932699;4857.951420485821;4949.925751113737;5055.064813009605;5065.898682026413;98593.50703246437;100835.49582252042;100955.49522252342;101243;103697;71476;86377.27245455056;86549.56725216459;88532;90792;65461.345386546476;66854.66572667105;67104.66447667731;67197.66401168158;67350.66324668346;9565.282603804604;9673.051073589832;9678.717453188474;9696.0788725071;9735.805283894424;46118;46125.30812037889;48910;50740;51651;65861;67061.66469167623;67167.66416167888;68191.3180868185;68530;12502.624921252376;12614.684632883884;12777.16948398351;12951.093423460645;13330.600081997556;14208.28958552089;14290.785638215642;14330.713385732019;14828.925855370655;15415.229238538253;11635.88364116348;12014;12210.267383956636;12235.510579576718;12513.874861251272;12807.231566106064;13339.866601333862;13368.331583420595;13375.331233438485;13397.464101435833;13434.731305374033;13563.728725425633;13621.25083120438;13840.930795346361;14010.485253559433;9752.414855108716;9900.455474948947;10313.484325783831;10365.481725913825;10679.626213082505;10618.469076545987;11144;11168.720781980515;11278.605248816251;11375.715607109889 01:54:09 INFO - PROCESS | 12255 | __end_tp_report 01:54:09 INFO - PROCESS | 12255 | __start_cc_report 01:54:09 INFO - PROCESS | 12255 | _x_x_mozilla_cycle_collect,1947 01:54:09 INFO - PROCESS | 12255 | __end_cc_report 01:54:09 INFO - PROCESS | 12255 | __startTimestamp1493801649049__endTimestamp 01:54:09 INFO - PROCESS | 12255 | 01:54:09 INFO - PROCESS | 12255 | ------- Summary: start ------- 01:54:09 INFO - PROCESS | 12255 | Number of tests: 6 01:54:09 INFO - PROCESS | 12255 | 01:54:09 INFO - PROCESS | 12255 | [#0] dojo.html Cycles:165 Average:5842.10 Median:5999.97 stddev:652.04 (10.9%) stddev-sans-first:623.62 01:54:09 INFO - PROCESS | 12255 | Values: 3332.8 3360.4 3363.1 3372.1 3411.3 5819.6 5825.8 5827.7 5856.6 5923.3 5864.6 5927.3 5939.3 5954.8 5964.9 5274.0 5306.4 5322.0 5384.9 5388.9 5229.4 5239.7 5264.4 5321.6 5410.5 5353.2 5377.4 5382.2 5391.4 5406.3 4450.0 5382.1 5397.9 5398.2 5420.5 5140.1 5194.4 5239.1 5305.6 5334.7 5733.6 5827.4 5876.2 5912.9 5937.9 6256.4 6329.2 6329.3 6330.3 6339.4 6371.6 6382.7 6390.9 6398.3 6402.9 5752.7 5881.8 5910.9 5911.4 5914.4 5351.3 6255.3 6265.9 6269.5 6273.0 6220.8 6251.7 6260.7 6302.5 6305.2 6350.2 6369.2 6373.3 6375.0 6380.3 6173.8 6224.4 6296.0 6312.2 6339.0 6252.2 6265.8 6269.5 6271.5 6317.9 5082.8 5123.3 5124.0 5126.2 5150.6 4558.6 4563.2 4573.1 4589.5 4612.5 5203.5 5670.8 5779.4 5817.4 5984.9 6315.6 6318.1 6384.6 6396.7 6405.4 5993.2 5997.7 6000.0 6005.4 6043.4 5451.0 5596.4 5603.6 5620.6 5637.7 5468.6 6385.2 6400.3 6453.4 6535.7 6355.0 6402.4 6403.0 6406.7 6407.9 6521.1 6559.4 6581.5 6591.0 6601.0 5730.2 6121.2 6248.1 6310.4 6335.5 6319.2 6323.7 6329.3 6336.2 6337.8 5434.3 5969.6 6139.3 6155.1 6266.1 6065.2 6150.1 6158.5 6161.1 6161.4 6110.0 6114.8 6119.5 6122.0 6125.7 6054.5 6109.1 6293.5 6309.5 6310.0 5961.6 5968.0 5968.9 5970.0 5980.9 01:54:09 INFO - PROCESS | 12255 | 01:54:09 INFO - PROCESS | 12255 | [#1] ext.html Cycles:180 Average:50678.13 Median:178132.76 stddev:96057.92 (53.9%) stddev-sans-first:96254.00 01:54:09 INFO - PROCESS | 12255 | Values: 667.4 741.8 782.6 808.3 879.6 27344.9 38430.2 38776.4 38935.4 38975.4 32569.5 39330.4 39497.4 39757.4 39773.6 7208.1 9071.4 9196.5 9203.1 9206.8 7279.8 10926.1 11102.4 11109.7 11113.0 10976.5 11005.2 11014.1 11049.6 11051.2 7162.5 10926.9 10964.9 10972.5 10995.4 7217.4 7265.7 7269.7 7272.7 10480.3 38553.0 38738.4 38793.0 38796.4 38816.8 3780.3 3797.5 3835.0 3859.4 3949.6 26281.6 26339.7 27232.0 28188.7 28201.7 2688.8 3049.9 3110.6 3118.4 3156.1 323696.0 326538.0 326952.0 327655.0 328192.0 50578.2 50730.5 50760.0 50888.5 50902.7 52782.0 53056.5 53056.7 53133.7 53135.0 14321.5 14798.7 14800.3 14808.0 14835.9 11005.6 11198.5 12792.3 12807.9 12834.4 10316.8 11596.4 11742.2 11843.2 11980.2 5157.5 5159.8 5163.1 5167.4 5178.1 896.8 916.8 921.7 944.6 960.6 24603.8 26734.5 27078.9 27105.5 27688.3 978.8 1093.4 1105.1 1122.4 1125.1 14644.9 14689.8 14793.6 14888.5 14911.7 313600.0 378829.0 379384.0 379864.0 380261.0 55409.0 55587.4 55693.2 55753.7 55822.0 1385.6 1437.3 1437.5 1441.5 1441.5 26737.0 27181.2 27818.3 27884.7 28003.6 45881.0 45992.3 46112.3 46156.1 46361.3 393703.0 393895.0 394217.0 395170.0 395948.0 30290.0 30770.8 30961.8 30969.2 30974.4 42323.8 42452.2 42884.8 43022.6 43088.6 22752.4 28186.6 28965.1 29821.7 30040.8 26613.6 28779.4 29406.0 29825.7 29849.0 34598.8 34901.7 35790.3 35807.5 35868.6 29220.7 29394.0 30137.4 30206.0 30452.5 22261.2 23278.5 23280.2 23507.4 23604.3 01:54:09 INFO - PROCESS | 12255 | 01:54:09 INFO - PROCESS | 12255 | [#2] jquery.html Cycles:180 Average:40279.71 Median:22707.26 stddev:116160.42 (511.6%) stddev-sans-first:116447.66 01:54:09 INFO - PROCESS | 12255 | Values: 385.8 410.4 414.0 424.5 445.9 12941.7 14827.3 20826.2 21408.6 21584.2 21364.0 21510.5 21718.7 22302.4 23112.1 6525.4 6601.4 6742.0 6823.6 6838.9 4127.8 4306.2 6702.6 6797.4 6813.1 3248.6 3330.9 5778.6 6120.9 6723.8 7351.9 7576.8 7719.4 7792.7 7797.8 7471.0 7493.6 7494.3 7506.7 7509.7 24371.0 25505.0 26127.5 26265.2 26487.5 2761.5 2849.0 2870.3 2896.6 2899.5 15387.9 15648.5 16013.3 16346.1 16695.3 1995.0 2007.4 2023.2 2046.7 2059.5 125063.0 125630.0 125917.0 143117.0 151494.0 14089.4 16416.7 18286.5 19737.5 20000.5 31954.0 32065.7 32160.5 32185.4 32242.7 10113.1 10170.8 10264.7 10369.1 10509.9 8639.4 8652.3 8666.5 8845.8 8849.9 7220.0 9225.0 9251.6 9264.5 9344.6 3809.9 3850.4 3884.5 3895.0 3902.3 813.0 863.9 891.3 905.9 928.8 15717.8 17429.4 20268.3 20859.3 21046.9 913.3 949.7 977.7 979.7 985.8 8760.7 10928.6 10959.3 11051.7 11241.4 686047.6 688064.0 699144.0 699513.0 717675.0 121073.4 131255.0 140732.0 152335.0 157814.0 1220.3 1240.0 1255.8 1263.4 1270.9 18801.9 19100.5 19142.5 20625.6 20706.4 45590.0 62245.0 62348.4 64360.0 65414.3 81250.0 81443.0 82034.0 84166.7 84581.0 11094.2 11469.0 11731.0 11803.6 11962.6 12729.6 12931.7 13285.3 13333.9 13409.8 11054.7 11171.8 11298.4 11349.9 11442.7 8633.7 8812.5 10470.4 10781.4 10796.3 11000.5 11439.7 11440.1 11572.3 11645.4 11511.7 11566.8 11648.8 11791.5 11808.9 11217.2 11234.5 11451.6 11459.5 11594.8 01:54:09 INFO - PROCESS | 12255 | 01:54:09 INFO - PROCESS | 12255 | [#3] mootools.html Cycles:180 Average:6592.71 Median:2977.22 stddev:4212.88 (141.5%) stddev-sans-first:4207.49 01:54:09 INFO - PROCESS | 12255 | Values: 1524.6 1552.0 1577.5 1580.6 1597.9 10443.4 10934.2 11011.4 11016.9 11019.4 10517.0 10747.4 10840.1 10924.8 10946.6 2236.4 2267.9 2268.1 2271.5 2272.3 2194.7 2260.4 2288.4 2294.5 2297.5 2242.2 2244.8 2269.1 2277.7 2291.6 2208.7 2269.4 2269.7 2271.5 2278.9 2267.3 2276.4 2284.1 2284.4 2286.2 11076.9 11164.0 11168.9 11173.2 11194.4 5234.2 5406.7 5421.4 5432.7 5453.9 2905.2 2973.8 3095.9 3145.2 3151.8 1072.5 1082.6 1104.7 1116.5 1120.4 13068.2 15173.5 15193.6 15383.8 15387.4 4108.1 4273.2 4340.9 4347.8 4375.4 11452.3 11459.5 11460.2 11473.0 11483.0 5332.9 5373.3 5384.9 5390.6 5392.1 4699.2 4741.5 4798.4 4905.9 4919.1 2980.6 3508.4 3560.6 3598.4 3611.7 893.9 899.5 901.0 904.4 905.0 776.8 869.3 896.1 944.2 950.6 6239.3 6305.6 6485.9 6500.1 6555.6 876.6 923.6 939.0 960.4 968.8 3394.3 4024.7 4030.8 4064.3 4215.6 12846.9 13573.0 13599.0 13613.1 13657.2 12788.7 13324.3 13469.4 13491.5 13557.0 9812.6 10015.6 10102.7 10110.4 10118.9 6473.4 6713.2 6718.5 6817.4 6995.3 11836.5 11984.2 12128.3 12188.6 12196.9 11988.8 12048.2 12287.1 12333.6 12365.6 9234.5 9263.0 9396.6 9427.4 9438.0 10138.4 10349.5 10357.9 10366.2 10379.5 8481.2 8560.7 8764.1 8879.0 8917.9 8605.1 8641.0 8655.4 8698.4 8746.7 7748.1 7801.0 7927.9 7932.3 7949.0 5769.7 5810.7 5883.0 5995.5 6018.3 5896.9 6018.0 6087.5 6159.7 6227.6 01:54:09 INFO - PROCESS | 12255 | 01:54:09 INFO - PROCESS | 12255 | [#4] prototype.html Cycles:180 Average:4289.14 Median:4648.83 stddev:1198.18 (25.8%) stddev-sans-first:1164.13 01:54:09 INFO - PROCESS | 12255 | Values: 330.7 365.4 371.0 372.1 385.9 4824.5 4824.7 4888.0 4919.2 4927.1 4534.3 4606.7 4674.6 4746.7 4793.5 3449.9 3918.2 3934.7 3942.9 4039.5 3495.4 3566.8 3892.3 4143.0 4276.9 4147.2 4182.6 4196.8 4199.6 4219.3 3729.2 3875.6 3894.1 3985.1 4099.3 3529.3 3743.3 3792.6 3923.9 3930.3 4535.3 4657.7 4711.2 4713.0 4801.1 3748.8 4410.4 4450.3 4819.6 4896.0 4594.5 4806.4 5118.8 5156.0 5187.9 3569.8 4147.5 4154.8 4177.6 4187.6 4583.1 4728.9 5036.9 5162.9 5187.5 4177.0 4219.5 4224.0 4252.8 4312.1 3591.8 3927.8 3936.4 4274.7 4276.4 3688.8 4327.2 4458.9 4519.9 4579.5 4640.0 4908.3 4993.6 5066.7 5179.1 1318.6 1347.4 1414.5 1472.6 1476.0 976.2 1004.2 1044.3 1109.9 1134.7 4245.4 4437.3 4718.0 4727.3 4785.6 4842.3 5088.8 5293.3 5321.2 5358.8 4070.5 4123.8 4722.8 4762.7 4866.4 2411.3 2444.6 2471.3 2519.3 2817.3 5103.9 5355.9 5530.7 5573.0 5611.6 4407.7 4704.9 5040.3 5540.6 5542.6 5164.9 5182.6 5503.9 5561.8 5623.6 4663.7 5185.9 5321.9 5337.7 5445.0 4261.8 4627.9 4949.9 5002.9 5372.5 4568.0 5129.5 5431.6 5467.3 5539.6 3554.7 3986.1 3990.7 4013.1 4299.7 4443.6 4810.9 5404.3 5407.5 5416.7 4242.3 5011.8 5013.5 5187.7 5197.5 5068.6 5113.0 5128.0 5158.2 5211.4 4364.5 4479.2 4887.7 5053.5 5085.9 4585.0 4738.9 4807.7 5128.3 5146.4 4717.5 5082.7 5138.2 5154.1 5167.2 01:54:09 INFO - PROCESS | 12255 | 01:54:09 INFO - PROCESS | 12255 | [#5] yui.html Cycles:180 Average:19965.16 Median:3747.34 stddev:28228.57 (753.3%) stddev-sans-first:28270.86 01:54:09 INFO - PROCESS | 12255 | Values: 742.7 761.5 782.5 821.8 849.4 15053.3 15979.2 16024.9 16033.8 16062.5 12092.8 16637.7 17272.7 17331.0 17351.7 862.4 935.8 967.3 968.3 969.9 926.5 945.4 953.2 954.3 955.3 985.9 996.3 996.6 999.1 1001.8 993.5 993.8 994.6 995.7 996.3 738.4 739.1 739.7 740.4 740.7 15389.7 15725.7 15789.5 16224.5 16226.0 13706.5 14110.4 14125.0 14131.2 14137.4 11310.7 11744.4 11788.3 11822.1 12040.7 3665.2 3698.8 3699.0 3795.7 3830.5 102647.5 104171.0 104565.5 105180.0 105275.9 10140.6 10349.0 10644.4 10753.2 11115.1 21908.0 22136.8 22296.7 22349.6 22371.0 7667.0 7728.0 7759.7 7835.8 7952.9 4214.9 4249.6 4287.7 4354.5 4512.7 3246.3 3280.3 3304.4 3324.5 3332.5 511.0 608.8 618.6 619.3 619.4 696.0 697.5 711.5 721.6 721.8 10262.9 10289.4 10289.7 10436.2 10535.5 583.9 662.5 697.6 713.2 725.9 4333.3 4858.0 4949.9 5055.1 5065.9 98593.5 100835.5 100955.5 101243.0 103697.0 71476.0 86377.3 86549.6 88532.0 90792.0 65461.3 66854.7 67104.7 67197.7 67350.7 9565.3 9673.1 9678.7 9696.1 9735.8 46118.0 46125.3 48910.0 50740.0 51651.0 65861.0 67061.7 67167.7 68191.3 68530.0 12502.6 12614.7 12777.2 12951.1 13330.6 14208.3 14290.8 14330.7 14828.9 15415.2 11635.9 12014.0 12210.3 12235.5 12513.9 12807.2 13339.9 13368.3 13375.3 13397.5 13434.7 13563.7 13621.3 13840.9 14010.5 9752.4 9900.5 10313.5 10365.5 10679.6 10618.5 11144.0 11168.7 11278.6 11375.7 01:54:09 INFO - PROCESS | 12255 | -------- Summary: end -------- 01:54:09 INFO - PROCESS | 12255 | 01:54:09 INFO - TEST-INFO | 12255: exit 0 01:54:09 INFO - TEST-OK | dromaeo_css | took 1120798ms 01:54:09 INFO - TEST-START | kraken 01:54:09 INFO - operating with platform_type : mac_ 01:54:09 INFO - Initialising browser for kraken test... 01:54:09 INFO - TEST-INFO | started process 12349 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmp88dCan/profile http://localhost:60097/getInfo.html) 01:54:12 INFO - PROCESS | 12349 | __metrics Screen width/height:1600/1200 01:54:12 INFO - PROCESS | 12349 | colorDepth:24 01:54:12 INFO - PROCESS | 12349 | Browser inner width/height: 1024/689 01:54:12 INFO - PROCESS | 12349 | __metrics 01:54:12 INFO - PROCESS | 12349 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:54:12 INFO - PROCESS | 12349 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:54:13 INFO - TEST-INFO | 12349: exit 0 01:54:13 INFO - Browser initialized. 01:54:13 INFO - Running cycle 1/1 for kraken test... 01:54:13 INFO - TEST-INFO | started process 12351 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmp88dCan/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:54:25 INFO - PROCESS | 12351 | RSS: Main: 210952192 01:54:25 INFO - PROCESS | 12351 | 01:54:28 INFO - PROCESS | 12351 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60097/tests/kraken/driver.html?testName=audio-beat-detection) 01:54:29 INFO - PROCESS | 12351 | RSS: Main: 265371648 01:54:29 INFO - PROCESS | 12351 | 01:54:31 INFO - PROCESS | 12351 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60097/tests/kraken/driver.html?testName=audio-dft) 01:54:32 INFO - PROCESS | 12351 | RSS: Main: 279351296 01:54:32 INFO - PROCESS | 12351 | 01:54:35 INFO - PROCESS | 12351 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60097/tests/kraken/driver.html?testName=audio-fft) 01:54:36 INFO - PROCESS | 12351 | RSS: Main: 375537664 01:54:36 INFO - PROCESS | 12351 | 01:54:38 INFO - PROCESS | 12351 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60097/tests/kraken/driver.html?testName=audio-oscillator) 01:54:38 INFO - PROCESS | 12351 | RSS: Main: 279166976 01:54:38 INFO - PROCESS | 12351 | 01:54:40 INFO - PROCESS | 12351 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60097/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:54:41 INFO - PROCESS | 12351 | RSS: Main: 280440832 01:54:41 INFO - PROCESS | 12351 | 01:54:44 INFO - PROCESS | 12351 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60097/tests/kraken/driver.html?testName=imaging-darkroom) 01:54:45 INFO - PROCESS | 12351 | RSS: Main: 320815104 01:54:45 INFO - PROCESS | 12351 | 01:54:48 INFO - PROCESS | 12351 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60097/tests/kraken/driver.html?testName=imaging-desaturate) 01:54:48 INFO - PROCESS | 12351 | RSS: Main: 321204224 01:54:48 INFO - PROCESS | 12351 | 01:54:51 INFO - PROCESS | 12351 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60097/tests/kraken/driver.html?testName=json-parse-financial) 01:54:52 INFO - PROCESS | 12351 | RSS: Main: 317591552 01:54:52 INFO - PROCESS | 12351 | 01:54:53 INFO - PROCESS | 12351 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60097/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:54:54 INFO - PROCESS | 12351 | RSS: Main: 295886848 01:54:54 INFO - PROCESS | 12351 | 01:54:56 INFO - PROCESS | 12351 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:54:57 INFO - PROCESS | 12351 | RSS: Main: 301740032 01:54:57 INFO - PROCESS | 12351 | 01:54:59 INFO - PROCESS | 12351 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:55:00 INFO - PROCESS | 12351 | RSS: Main: 303865856 01:55:00 INFO - PROCESS | 12351 | 01:55:02 INFO - PROCESS | 12351 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:55:03 INFO - PROCESS | 12351 | RSS: Main: 318087168 01:55:03 INFO - PROCESS | 12351 | 01:55:06 INFO - PROCESS | 12351 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:55:07 INFO - PROCESS | 12351 | RSS: Main: 311660544 01:55:07 INFO - PROCESS | 12351 | 01:55:08 INFO - PROCESS | 12351 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60097/tests/kraken/driver.html?testName=ai-astar) 01:55:09 INFO - PROCESS | 12351 | RSS: Main: 356024320 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | __start_tp_report 01:55:09 INFO - PROCESS | 12351 | _x_x_mozilla_page_load 01:55:09 INFO - PROCESS | 12351 | _x_x_mozilla_page_load_details 01:55:09 INFO - PROCESS | 12351 | |i|pagename|runs| 01:55:09 INFO - PROCESS | 12351 | |0;ai-astar;106;116;116;115;116;116;116;114;116;116 01:55:09 INFO - PROCESS | 12351 | |1;audio-beat-detection;146;109;119;176;108;109;108;109;130;110 01:55:09 INFO - PROCESS | 12351 | |2;audio-dft;186;185;184;187;184;185;185;185;185;195 01:55:09 INFO - PROCESS | 12351 | |3;audio-fft;83;62;59;95;64;60;61;61;78;61 01:55:09 INFO - PROCESS | 12351 | |4;audio-oscillator;91;92;119;100;69;68;82;69;68;84 01:55:09 INFO - PROCESS | 12351 | |5;imaging-gaussian-blur;87;87;86;87;87;86;87;87;86;90 01:55:09 INFO - PROCESS | 12351 | |6;imaging-darkroom;93;94;94;95;94;94;94;95;94;102 01:55:09 INFO - PROCESS | 12351 | |7;imaging-desaturate;86;83;85;83;86;101;137;109;86;84 01:55:09 INFO - PROCESS | 12351 | |8;json-parse-financial;61;61;59;60;64;64;64;64;64;65 01:55:09 INFO - PROCESS | 12351 | |9;json-stringify-tinderbox;65;66;64;55;55;59;60;86;85;86 01:55:09 INFO - PROCESS | 12351 | |10;stanford-crypto-aes;83;85;88;87;83;82;80;80;82;86 01:55:09 INFO - PROCESS | 12351 | |11;stanford-crypto-ccm;145;127;138;142;125;139;135;129;201;178 01:55:09 INFO - PROCESS | 12351 | |12;stanford-crypto-pbkdf2;163;164;165;164;169;162;165;166;163;164 01:55:09 INFO - PROCESS | 12351 | |13;stanford-crypto-sha256-iterative;62;70;64;65;63;63;64;64;64;62 01:55:09 INFO - PROCESS | 12351 | __end_tp_report 01:55:09 INFO - PROCESS | 12351 | __start_cc_report 01:55:09 INFO - PROCESS | 12351 | _x_x_mozilla_cycle_collect,2160 01:55:09 INFO - PROCESS | 12351 | __end_cc_report 01:55:09 INFO - PROCESS | 12351 | __startTimestamp1493801709220__endTimestamp 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | ------- Summary: start ------- 01:55:09 INFO - PROCESS | 12351 | Number of tests: 14 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | [#0] ai-astar Cycles:10 Average:114.70 Median:116.00 stddev:3.13 (2.7%) stddev-sans-first:0.71 01:55:09 INFO - PROCESS | 12351 | Values: 106.0 116.0 116.0 115.0 116.0 116.0 116.0 114.0 116.0 116.0 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | [#1] audio-beat-detection Cycles:10 Average:122.40 Median:114.50 stddev:22.62 (19.8%) stddev-sans-first:22.33 01:55:09 INFO - PROCESS | 12351 | Values: 146.0 109.0 119.0 176.0 108.0 109.0 108.0 109.0 130.0 110.0 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | [#2] audio-dft Cycles:10 Average:186.10 Median:185.00 stddev:3.25 (1.8%) stddev-sans-first:3.44 01:55:09 INFO - PROCESS | 12351 | Values: 186.0 185.0 184.0 187.0 184.0 185.0 185.0 185.0 185.0 195.0 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | [#3] audio-fft Cycles:10 Average:68.40 Median:63.00 stddev:12.46 (19.8%) stddev-sans-first:12.04 01:55:09 INFO - PROCESS | 12351 | Values: 83.0 62.0 59.0 95.0 64.0 60.0 61.0 61.0 78.0 61.0 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | [#4] audio-oscillator Cycles:10 Average:84.20 Median:75.50 stddev:16.86 (22.3%) stddev-sans-first:17.71 01:55:09 INFO - PROCESS | 12351 | Values: 91.0 92.0 119.0 100.0 69.0 68.0 82.0 69.0 68.0 84.0 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:87.00 stddev:1.15 (1.3%) stddev-sans-first:1.22 01:55:09 INFO - PROCESS | 12351 | Values: 87.0 87.0 86.0 87.0 87.0 86.0 87.0 87.0 86.0 90.0 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | [#6] imaging-darkroom Cycles:10 Average:94.90 Median:94.00 stddev:2.56 (2.7%) stddev-sans-first:2.62 01:55:09 INFO - PROCESS | 12351 | Values: 93.0 94.0 94.0 95.0 94.0 94.0 94.0 95.0 94.0 102.0 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:84.50 stddev:17.44 (20.6%) stddev-sans-first:18.26 01:55:09 INFO - PROCESS | 12351 | Values: 86.0 83.0 85.0 83.0 86.0 101.0 137.0 109.0 86.0 84.0 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:64.00 stddev:2.12 (3.3%) stddev-sans-first:2.17 01:55:09 INFO - PROCESS | 12351 | Values: 61.0 61.0 59.0 60.0 64.0 64.0 64.0 64.0 64.0 65.0 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:65.50 stddev:12.69 (19.4%) stddev-sans-first:13.41 01:55:09 INFO - PROCESS | 12351 | Values: 65.0 66.0 64.0 55.0 55.0 59.0 60.0 86.0 85.0 86.0 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:2.80 (3.3%) stddev-sans-first:2.96 01:55:09 INFO - PROCESS | 12351 | Values: 83.0 85.0 88.0 87.0 83.0 82.0 80.0 80.0 82.0 86.0 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | [#11] stanford-crypto-ccm Cycles:10 Average:145.90 Median:140.50 stddev:24.47 (17.4%) stddev-sans-first:25.96 01:55:09 INFO - PROCESS | 12351 | Values: 145.0 127.0 138.0 142.0 125.0 139.0 135.0 129.0 201.0 178.0 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.50 Median:164.50 stddev:1.96 (1.2%) stddev-sans-first:2.00 01:55:09 INFO - PROCESS | 12351 | Values: 163.0 164.0 165.0 164.0 169.0 162.0 165.0 166.0 163.0 164.0 01:55:09 INFO - PROCESS | 12351 | 01:55:09 INFO - PROCESS | 12351 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.00 stddev:2.28 (3.6%) stddev-sans-first:2.29 01:55:09 INFO - PROCESS | 12351 | Values: 62.0 70.0 64.0 65.0 63.0 63.0 64.0 64.0 64.0 62.0 01:55:09 INFO - PROCESS | 12351 | -------- Summary: end -------- 01:55:09 INFO - PROCESS | 12351 | 01:55:10 INFO - TEST-INFO | 12351: exit 0 01:55:10 INFO - TEST-OK | kraken | took 60376ms 01:55:10 INFO - SUITE-END | took 1181s 01:55:10 INFO - Completed test suite (00:19:41) 01:55:10 INFO - javascript benchmark 01:55:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7458.49940663222, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3332.7833690810094, 3360.411927912615, 3363.1423986883347, 3372.0895358253274, 3411.3347897160047, 5819.621724587899, 5825.766969321233, 5827.679477628729, 5856.590038697287, 5923.348431672518, 5864.560157988147, 5927.347991720907, 5939.316978547468, 5954.79158229461, 5964.910526342108, 5273.9736301318535, 5306.416294207634, 5322.0, 5384.946150538503, 5388.946110538904, 5229.424763276036, 5239.659422137568, 5264.368275806909, 5321.600879934001, 5410.513053825155, 5353.197020446944, 5377.354717433914, 5382.2195781611645, 5391.406945236021, 5406.297181366436, 4449.977750111245, 5382.138857782736, 5397.892042159174, 5398.163284690879, 5420.539254163361, 5140.074786538422, 5194.428612852583, 5239.056969745444, 5305.575553955674, 5334.706591137486, 5733.569982251348, 5827.388124246935, 5876.2067120938555, 5912.881742365129, 5937.940620593782, 6256.436920677115, 6329.208848893888, 6329.272133704637, 6330.27201871786, 6339.429451349401, 6371.553991220617, 6382.712777924958, 6390.9360906391275, 6398.29618741938, 6402.93597064028, 5752.741126649311, 5881.764729410818, 5910.8817823643285, 5911.438413350725, 5914.408559144051, 5351.277577526984, 6255.343188965184, 6265.937340626627, 6269.498440124779, 6272.968635156795, 6220.782272620527, 6251.687415629201, 6260.655663938527, 6302.527310451735, 6305.180326557518, 6350.174477318012, 6369.235691717024, 6373.330800265998, 6375.011873159668, 6380.330065343165, 6173.783917562864, 6224.37756224374, 6295.9926411773895, 6312.242530896322, 6338.968305158444, 6252.218472690914, 6265.843353916189, 6269.498440124779, 6271.4669253114, 6317.905231421532, 5082.822101226439, 5123.308353372322, 5123.974380128076, 5126.179811230185, 5150.639455238173, 4558.635309175258, 4563.178627846953, 4573.108243892409, 4589.541045895384, 4612.5156858530045, 5203.479652034842, 5670.7731690732735, 5779.393163717749, 5817.38917413674, 5984.910226346608, 6315.621062736251, 6318.147050148263, 6384.648844313514, 6396.744130234832, 6405.3594640535575, 5993.220881285492, 5997.670128142994, 5999.970000149971, 6005.369436209223, 6043.425874541911, 5451.018816612968, 5596.384397716248, 5603.607747457639, 5620.63465874725, 5637.746301416446, 5468.56251499887, 6385.201849768871, 6400.327965563549, 6453.41919227253, 6535.738570457129, 6354.968225158845, 6402.391772781578, 6403.007533832078, 6406.711697973509, 6407.935920640921, 6521.087047813215, 6559.376859198369, 6581.539292249497, 6591.0, 6601.042848786995, 5730.197772311796, 6121.209103132208, 6248.0940263660605, 6310.400511951358, 6335.524835637253, 6319.210098737658, 6323.747050117944, 6329.272133704683, 6336.17629708135, 6337.809865704036, 5434.347878254634, 5969.552283578836, 6139.277491147187, 6155.1075094112, 6266.122742815918, 6065.181200538047, 6150.138980542817, 6158.538109641705, 6161.075838624242, 6161.44546990773, 6109.9816581254145, 6114.785982490592, 6119.488547144922, 6121.960940613755, 6125.724342404692, 6054.455099041107, 6109.11417844419, 6293.465055470337, 6309.526785491014, 6309.968450157903, 5961.552883533665, 5967.9701601491715, 5968.910466343095, 5970.0, 5980.9401905980385], "value": 5800.582868395693, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.362668651438, 741.8367959049008, 782.5734974438931, 808.3048578222729, 879.5514287713272, 27344.86327568362, 38430.23139537207, 38776.418353724715, 38935.41596876049, 38975.41536876942, 32569.51145732816, 39330.4100438493, 39497.40753888694, 39757.40363894544, 39773.60226397735, 7208.062951816257, 9071.410358326704, 9196.540172991357, 9203.125703058198, 9206.815863682723, 7279.818004549902, 10926.125909927227, 11102.444877756121, 11109.666709998713, 11113.0, 10976.506057227443, 11005.229633925628, 11014.06380457658, 11049.558017679323, 11051.226414151013, 7162.4628152888745, 10926.945365273203, 10964.945175274073, 10972.506237219344, 10995.395253261067, 7217.3865221456235, 7265.673044713, 7269.709211631528, 7272.672729727175, 10480.266381353309, 38553.0, 38738.41892371617, 38793.0, 38796.41805372922, 38816.80591597052, 3780.3384407728536, 3797.5063241778666, 3835.0, 3859.4210868369687, 3949.5852935441653, 26281.605775913376, 26339.73660263392, 27232.046878359164, 28188.718112818813, 28201.717982820115, 2688.8117831751783, 3049.9390012199856, 3110.626724793039, 3118.3919135768533, 3156.0531840448, 323696.0, 326538.0, 326952.0, 327655.0, 328192.0, 50578.241326380135, 50730.49269507331, 50760.0, 50888.49111508838, 50902.74548627233, 52782.0, 53056.469435305924, 53056.734716326944, 53133.734331328866, 53135.0, 14321.498747543787, 14798.704025919422, 14800.333984970703, 14808.0, 14835.925820371045, 11005.559777608805, 11198.496067676973, 12792.2964236966, 12807.93596032014, 12834.422450989729, 10316.793664126675, 11596.362200078907, 11742.236754610924, 11843.170978031456, 11980.221285616408, 5157.4584668610005, 5159.7678104485385, 5163.096458119814, 5167.354080739908, 5178.093833579109, 896.7668406214431, 916.8441364967971, 921.7142685767434, 944.6127087894068, 960.6061514778908, 24603.753962460145, 26734.46531069368, 27078.86460567724, 27105.457890842074, 27688.30779230495, 978.8091322192247, 1093.420487141816, 1105.1435137768294, 1122.4443900269255, 1125.0886781706843, 14644.853551464563, 14689.77965330521, 14793.556193314218, 14888.47890323855, 14911.70176596462, 313600.0, 378829.0, 379384.0, 379864.0, 380261.0, 55409.0, 55587.444125559035, 55693.164602531, 55753.72123139358, 55822.0, 1385.6189547874415, 1437.3100911562299, 1437.4896911596234, 1441.4954765831874, 1441.509886638527, 26737.0, 27181.1845644631, 27818.304542386602, 27884.721152788214, 28003.579946300415, 45881.0, 45992.310115347624, 46112.308315375965, 46156.07687846291, 46361.304580430646, 393703.0, 393895.0, 394217.0, 395170.0, 395948.0, 30290.0, 30770.846145769126, 30961.845190773904, 30969.225769355948, 30974.38051239008, 42323.7883810579, 42452.150956981306, 42884.78557607192, 43022.569774303105, 43088.56911430971, 22752.431189220402, 28186.577201341584, 28965.13104606865, 29821.70178298276, 30040.849795751754, 26613.570438891376, 28779.42441151207, 29406.0, 29825.70174298229, 29849.0, 34598.82700586481, 34901.65098348984, 35790.28419431545, 35807.46288805618, 35868.64131358758, 29220.7077929218, 29394.0, 30137.397252055274, 30206.0, 30452.543211852284, 22261.220857269916, 23278.534429311658, 23280.185193518144, 23507.41231469227, 23604.29187124389], "value": 17986.102973460063, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.79167249685173, 410.35779005855835, 413.9958600413995, 424.5287730619013, 445.9063596644705, 12941.676458088552, 14827.258637068144, 20826.166953321866, 21408.57182856342, 21584.244551440705, 21364.0, 21510.462238444048, 21718.674219886714, 22302.442438938993, 23112.07551697934, 6525.3800888915475, 6601.438877695402, 6741.966290168542, 6823.624700641462, 6838.897416538756, 4127.834886604533, 4306.246406878784, 6702.597844129344, 6797.422219111381, 6813.11429514165, 3248.61016677999, 3330.8508564545314, 5778.624389414675, 6120.8982383171015, 6723.798286051427, 7351.926480735177, 7576.848463030764, 7719.382449404035, 7792.688292468294, 7797.805054873616, 7471.0, 7493.550386976744, 7494.325510704009, 7506.699732010768, 7509.662065207079, 24371.0, 25505.0, 26127.477450450886, 26265.212043638723, 26487.47025059488, 2761.4615150045747, 2849.0, 2870.3398218409884, 2896.6379202599674, 2899.4780939430893, 15387.923060384739, 15648.4523041693, 16013.279402426919, 16346.10096444695, 16695.332186712516, 1995.0, 2007.3993557973383, 2023.1705000949687, 2046.703228031942, 2059.5468996820678, 125063.0, 125629.99999999908, 125917.0, 143117.0, 151494.0, 14089.436422542984, 16416.671666566603, 18286.542836429195, 19737.506562336057, 20000.49998750043, 31954.0, 32065.67934320627, 32160.517592236134, 32185.35629287448, 32242.677573223966, 10113.089821916063, 10170.847437288447, 10264.743381415376, 10369.118624916966, 10509.894901051044, 8639.395242333103, 8652.264557512555, 8666.480011199348, 8845.823083538422, 8849.867251991225, 7220.0, 9224.953875230582, 9251.583678734472, 9264.536773161315, 9344.626214951462, 3809.904752381213, 3850.383938569815, 3884.475595794588, 3895.0, 3902.2780785554196, 813.032491335309, 863.9438436501607, 891.3003292415423, 905.9275257979345, 928.8467402878584, 15717.842821571867, 17429.38997135094, 20268.29060982888, 20859.331253499684, 21046.894765526074, 913.3332667153015, 949.6960972488876, 977.7262366537238, 979.6669132494986, 985.7979114281699, 8760.693375731818, 10928.562857485771, 10959.342439453658, 11051.72370690739, 11241.437928103562, 686047.569762148, 688064.0, 699144.0, 699513.0146095626, 717675.0, 121073.37492694455, 131255.0, 140732.0, 152335.0, 157814.0, 1220.3044264768996, 1239.9938000310121, 1255.7739606870668, 1263.3746295583744, 1270.8586032535209, 18801.905990470506, 19100.522486937938, 19142.46168759465, 20625.58748825045, 20706.378808635764, 45590.0, 62245.0, 62348.44128896632, 64360.0, 65414.34585654273, 81250.0, 81443.0, 82034.0, 84166.73749893635, 84581.0, 11094.223404361617, 11469.025132863466, 11731.00286475625, 11803.645890623295, 11962.58130965412, 12729.618111456672, 12931.741365172456, 13285.269310188032, 13333.933330333286, 13409.798853017408, 11054.723631909266, 11171.776564468502, 11298.435078246219, 11349.886501134883, 11442.713932151762, 8633.69782057625, 8812.51531165792, 10470.371777693363, 10781.407022613832, 10796.298240614335, 11000.504977276201, 11439.656810295704, 11440.141989350666, 11572.305661660328, 11645.359505227294, 11511.71723016423, 11566.768664626828, 11648.76702465929, 11791.469383877575, 11808.94095529517, 11217.158713096713, 11234.539509863665, 11451.59919402817, 11459.541618335506, 11594.76810463803], "value": 11181.274891229157, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1524.5578782153177, 1552.0, 1577.5425126713253, 1580.6364536156677, 1597.8561929426348, 10443.42561159136, 10934.179936504754, 11011.394373309466, 11016.889831101696, 11019.449027548611, 10517.0, 10747.408892510908, 10840.078593319577, 10924.836127458095, 10946.616868409606, 2236.3514580771557, 2267.852589581672, 2268.0587556164173, 2271.5116250006245, 2272.284230467406, 2194.714687090676, 2260.42359198405, 2288.439332363565, 2294.5181511882524, 2297.517521320525, 2242.1816037146486, 2244.7755224477587, 2269.0583407885706, 2277.7152855893014, 2291.5531471363092, 2208.6686996950584, 2269.3872654383226, 2269.6595510673283, 2271.477560161164, 2278.9316320510247, 2267.2744721689064, 2276.408133885185, 2284.0635339510886, 2284.383216531527, 2286.1998300594823, 11076.94768996944, 11164.0, 11168.944155279172, 11173.162012849069, 11194.44027798615, 5234.18870075139, 5406.702631355274, 5421.430749771296, 5432.728363581805, 5453.945460545423, 2905.2010697058267, 2973.836438995854, 3095.90712278632, 3145.1979745165067, 3151.8266495342746, 1072.5388083124253, 1082.6210826210843, 1104.6630777612825, 1116.4975760907544, 1120.3669926491589, 13068.215907045607, 15173.544793656209, 15193.620159495991, 15383.846161538353, 15387.38450461969, 4108.137291168888, 4273.188094262143, 4340.891477713082, 4347.760873151944, 4375.387445757597, 11452.255603385753, 11459.541618335174, 11460.197786155057, 11473.0, 11483.0, 5332.9466705333225, 5373.328333958255, 5384.892302153935, 5390.622656414092, 5392.083345831218, 4699.2481203007355, 4741.478437371887, 4798.424189097331, 4905.9264111038365, 4919.114559379274, 2980.612520372381, 3508.4386498160684, 3560.608333083359, 3598.4242521196484, 3611.7471776975885, 893.8659201119884, 899.4872922434281, 901.0133903375793, 904.3759805734154, 904.9502277374808, 776.8301950519303, 869.2698133567833, 896.1352295035322, 944.2493217891792, 950.6007476859736, 6239.251289845248, 6305.5901366411035, 6485.935140648628, 6500.057491663682, 6555.6066636002, 876.5529579914289, 923.5936188077225, 938.9859152112724, 960.4285450157146, 968.7723385004518, 3394.2702319001532, 4024.7383920045104, 4030.778307193074, 4064.349704047338, 4215.620594146541, 12846.935765321301, 13573.04988650785, 13598.980076494303, 13613.11514751538, 13657.180569165881, 12788.744225115446, 13324.333783310796, 13469.393877275546, 13491.460341586422, 13557.0, 9812.558434870449, 10015.599376024877, 10102.747431314128, 10110.39337639744, 10118.949405253074, 6473.352664733489, 6713.1944166700305, 6718.496112791462, 6817.420519255918, 6995.3004699531675, 11836.526538938691, 11984.221025633306, 12128.272303661808, 12188.57339993096, 12196.878031219574, 11988.76022479563, 12048.216865903689, 12287.078469114673, 12333.568325108577, 12365.629031129081, 9234.492102934402, 9262.9536852318, 9396.624135034523, 9427.387219830689, 9438.00900905395, 10138.442385668564, 10349.534270957674, 10357.9482102589, 10366.170706343324, 10379.481025948824, 8481.194286542768, 8560.743177704679, 8764.079771623889, 8878.978917424578, 8917.866232006396, 8605.139486051345, 8640.956795215983, 8655.394122411371, 8698.391112622056, 8746.65013399457, 7748.147703752696, 7800.960995194988, 7927.881081783893, 7932.286094251545, 7949.046114466301, 5769.711514424346, 5810.738516766671, 5883.0, 5995.46040856325, 6018.277806663228, 5896.941030589811, 6018.037114061729, 6087.543434242361, 6159.692015399301, 6227.593672105418], "value": 4949.677287355652, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.7349388588648, 365.37885594489376, 370.9666130048295, 372.13664298826717, 385.94596756454104, 4824.5416685414875, 4824.745566152799, 4888.0, 4919.163742163832, 4927.063857867003, 4534.34252033455, 4606.654500912437, 4674.579287864091, 4746.691465054778, 4793.472718001017, 3449.9482507762277, 3918.157596116834, 3934.7212156049254, 3942.9014274643077, 4039.4950631171105, 3495.440729483283, 3566.7681600696, 3892.3188442022533, 4142.958570414287, 4276.893077673052, 4147.191297696951, 4182.560831112735, 4196.81114349855, 4199.6220340169375, 4219.282721937263, 3729.191342199045, 3875.61243875613, 3894.123822140024, 3985.103351745863, 4099.3031184698375, 3529.32942740881, 3743.3262012837677, 3792.6207379262128, 3923.901902452433, 3930.2925473414775, 4535.274356103007, 4657.650676199265, 4711.151992641323, 4712.952870471286, 4801.111794318027, 3748.7750734955716, 4410.448693913261, 4450.287953927356, 4819.590334821544, 4896.045271172123, 4594.471635761896, 4806.423229212517, 5118.7696553654805, 5155.9742201288755, 5187.922181167285, 3569.7501174917766, 4147.543770185277, 4154.756431526972, 4177.582241775798, 4187.602177793105, 4583.129205450954, 4728.929066064012, 5036.874078148039, 5162.948370516285, 5187.455317191679, 4176.9582304177175, 4219.493660760667, 4224.0, 4252.766097864647, 4312.059573192076, 3591.7665351752053, 3927.815283825323, 3936.4292177634657, 4274.722143060753, 4276.444062271947, 3688.7971161585833, 4327.221100201931, 4458.888527786831, 4519.864404067883, 4579.4584005876395, 4640.0, 4908.288298196741, 4993.550580447704, 5066.746662666926, 5179.119549676564, 1318.5627665844277, 1347.4408120629828, 1414.504923276845, 1472.6023973527087, 1475.9631009224856, 976.1848856205006, 1004.2167109654481, 1044.3159730376615, 1109.944502774858, 1134.6822889590928, 4245.426867372927, 4437.267850804581, 4718.0328032753205, 4727.338172655831, 4785.588251465826, 4842.297866809293, 5088.821891233789, 5293.338332708411, 5321.206366657536, 5358.839234822962, 4070.470838790938, 4123.752574845519, 4722.763861806896, 4762.714237145782, 4866.440359358719, 2411.3127758589067, 2444.6455263986622, 2471.270975062361, 2519.3449703076967, 2817.2815931937225, 5103.923441148386, 5355.946440535623, 5530.695811730394, 5573.0, 5611.6352437091455, 4407.691461597721, 4704.882377940579, 5040.294358789773, 5540.625593174615, 5542.5565954723525, 5164.870878228149, 5182.611304152204, 5503.871706300199, 5561.830885954562, 5623.606347555714, 4663.673542851968, 5185.870353241199, 5321.866953326198, 5337.653052551572, 5444.972775136099, 4261.80669412565, 4627.93058104122, 4949.901001980013, 5002.924956125734, 5372.543333816513, 4567.954320456753, 5129.486801393672, 5431.592630552804, 5467.2663668165915, 5539.639923404966, 3554.7333949954373, 3986.0832008638754, 3990.705344426908, 4013.097053163015, 4299.720518166309, 4443.600265916226, 4810.927836082532, 5404.270423492778, 5407.540359069523, 5416.674999500043, 4242.300020496584, 5011.774470148779, 5013.510206324069, 5187.688738675692, 5197.532222100029, 5068.619853511075, 5113.002964421902, 5128.00007996889, 5158.194631878812, 5211.374635043819, 4364.454443194601, 4479.17135329965, 4887.731174785421, 5053.469385714447, 5085.92371114441, 4584.977075114603, 4738.881527961828, 4807.687500312609, 5128.256402821645, 5146.4338922717725, 4717.481077081449, 5082.695038297714, 5138.229265610188, 5154.1445079772, 5167.176289545634], "value": 3940.5512101122827, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.6769355330429, 761.4631684662313, 782.4757412533602, 821.8233079887823, 849.4393700157896, 15053.322600482978, 15979.201039948015, 16024.919875400621, 16033.759493607606, 16062.518124456285, 12092.818607720892, 16637.66724665506, 17272.654546909056, 17331.046792426412, 17351.652966940652, 862.4351050062219, 935.8128374325128, 967.274544091931, 968.2883080935542, 969.8739163908682, 926.4580220570974, 945.3571571331491, 953.1516949914582, 954.265215783846, 955.3408148377632, 985.9112679858812, 996.2876543271559, 996.5565323431076, 999.0558921818885, 1001.8096561653299, 993.5280741647659, 993.7565296502403, 994.5673631970044, 995.6863587969802, 996.2976101848179, 738.4018944654794, 739.0503203383627, 739.7447880481226, 740.4150720930512, 740.6926125657845, 15389.692206155925, 15725.685486290324, 15789.526314210592, 16224.513264602081, 16226.0, 13706.52027179054, 14110.435582576685, 14125.0, 14131.222782746945, 14137.434502619883, 11310.660680179608, 11744.354060526668, 11788.29270243788, 11822.113341499336, 12040.698982525419, 3665.21197942441, 3698.7595806272775, 3698.981505092457, 3795.6963442924775, 3830.5020347354944, 102647.48676256646, 104171.0, 104565.47717261441, 105179.99999999923, 105275.94724052738, 10140.645077422254, 10349.0, 10644.361338319726, 10753.193510486744, 11115.110791136689, 21908.0, 22136.778632213794, 22296.66555001676, 22349.55300893973, 22371.0, 7667.0416197975255, 7727.961360193275, 7759.689612415553, 7835.843283134305, 7952.920470795334, 4214.915701685949, 4249.553796851348, 4287.742735435884, 4354.477462704495, 4512.729236245836, 3246.3020450603235, 3280.2947366316357, 3304.3556506481486, 3324.5013248012506, 3332.5334453176574, 511.03021869939687, 608.7990962982294, 618.6473709985265, 619.294004834489, 619.3527763487167, 695.9512834101564, 697.5117417807555, 711.5481669140133, 721.6103304215769, 721.797896588956, 10262.948685256526, 10289.43408112546, 10289.74275643115, 10436.218412584723, 10535.473226338652, 583.9007368747323, 662.493192707579, 697.6454466176654, 713.1549114299468, 725.9075091986526, 4333.306670932699, 4857.951420485821, 4949.925751113737, 5055.064813009605, 5065.898682026413, 98593.50703246437, 100835.49582252042, 100955.49522252342, 101243.0, 103697.0, 71476.0, 86377.27245455056, 86549.56725216459, 88532.0, 90792.0, 65461.345386546476, 66854.66572667105, 67104.66447667731, 67197.66401168158, 67350.66324668346, 9565.282603804604, 9673.051073589832, 9678.717453188474, 9696.0788725071, 9735.805283894424, 46118.0, 46125.30812037889, 48910.0, 50740.0, 51651.0, 65861.0, 67061.66469167623, 67167.66416167888, 68191.3180868185, 68530.0, 12502.624921252376, 12614.684632883884, 12777.16948398351, 12951.093423460645, 13330.600081997556, 14208.28958552089, 14290.785638215642, 14330.713385732019, 14828.925855370655, 15415.229238538253, 11635.88364116348, 12014.0, 12210.267383956636, 12235.510579576718, 12513.874861251272, 12807.231566106064, 13339.866601333862, 13368.331583420595, 13375.331233438485, 13397.464101435833, 13434.731305374033, 13563.728725425633, 13621.25083120438, 13840.930795346361, 14010.485253559433, 9752.414855108716, 9900.455474948947, 10313.484325783831, 10365.481725913825, 10679.626213082505, 10618.469076545987, 11144.0, 11168.720781980515, 11278.605248816251, 11375.715607109889], "value": 7565.384836891328, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1440.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 116.0, 115.0, 116.0, 116.0, 116.0, 114.0, 116.0, 116.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 109.0, 119.0, 176.0, 108.0, 109.0, 108.0, 109.0, 130.0, 110.0], "value": 122.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 184.0, 187.0, 184.0, 185.0, 185.0, 185.0, 185.0, 195.0], "value": 186.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 62.0, 59.0, 95.0, 64.0, 60.0, 61.0, 61.0, 78.0, 61.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 119.0, 100.0, 69.0, 68.0, 82.0, 69.0, 68.0, 84.0], "value": 84.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 87.0, 87.0, 86.0, 87.0, 87.0, 86.0, 90.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 102.0], "value": 94.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 85.0, 83.0, 86.0, 101.0, 137.0, 109.0, 86.0, 84.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 59.0, 60.0, 64.0, 64.0, 64.0, 64.0, 64.0, 65.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 64.0, 55.0, 55.0, 59.0, 60.0, 86.0, 85.0, 86.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 88.0, 87.0, 83.0, 82.0, 80.0, 80.0, 82.0, 86.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 127.0, 138.0, 142.0, 125.0, 139.0, 135.0, 129.0, 201.0, 178.0], "value": 145.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 165.0, 164.0, 169.0, 162.0, 165.0, 166.0, 163.0, 164.0], "value": 164.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 70.0, 64.0, 65.0, 63.0, 63.0, 64.0, 64.0, 64.0, 62.0], "value": 64.1, "unit": "score"}]}]} 01:55:10 INFO - Return code: 0 01:55:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:55:10 ERROR - # TBPL SUCCESS # 01:55:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:55:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:55:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:55:10 INFO - 2017-05-03 01:55:10.544 screenresolution[12355:314562] starting screenresolution argv=screenresolution get 01:55:10 INFO - 2017-05-03 01:55:10.546 screenresolution[12355:314562] Display 0: 1600x1200x32@60 01:55:10 INFO - 2017-05-03 01:55:10.553 screenresolution[12356:314565] starting screenresolution argv=screenresolution list 01:55:10 INFO - Available Modes on Display 0 01:55:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:55:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:55:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:55:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:55:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:55:10 INFO - Intel Iris: 01:55:10 INFO - Chipset Model: Intel Iris 01:55:10 INFO - Type: GPU 01:55:10 INFO - Bus: Built-In 01:55:10 INFO - VRAM (Dynamic, Max): 1536 MB 01:55:10 INFO - Vendor: Intel (0x8086) 01:55:10 INFO - Device ID: 0x0a2e 01:55:10 INFO - Revision ID: 0x0009 01:55:10 INFO - Displays: 01:55:10 INFO - Display: 01:55:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:55:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:55:10 INFO - Display Serial Number: 1600x1200 60 01:55:10 INFO - Main Display: Yes 01:55:10 INFO - Mirror: Off 01:55:10 INFO - Online: Yes 01:55:10 INFO - Rotation: Supported 01:55:10 INFO - Return code: 0 01:55:10 INFO - Running post-action listener: _resource_record_post_action 01:55:10 INFO - [mozharness: 2017-05-03 08:55:10.795502Z] Finished run-tests step (success) 01:55:10 INFO - Running post-run listener: _resource_record_post_run 01:55:10 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 51437056; Write bytes: 513754112; Read time: 686; Write time: 1582 01:55:10 INFO - TinderboxPrint: CPU usage
53.9% 01:55:10 INFO - TinderboxPrint: I/O read bytes / time
51,437,056 / 686 01:55:10 INFO - TinderboxPrint: I/O write bytes / time
513,754,112 / 1,582 01:55:10 INFO - TinderboxPrint: CPU idle
2,216.9 (46.1%) 01:55:10 INFO - TinderboxPrint: CPU system
960.1 (20.0%) 01:55:10 INFO - TinderboxPrint: CPU user
1,632.6 (33.9%) 01:55:10 INFO - TinderboxPrint: Swap in / out
257,466,368 / 0 01:55:10 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201867264; Write bytes: 199344128; Read time: 15638; Write time: 421 01:55:10 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 49009152; Write bytes: 303625216; Read time: 659; Write time: 1127 01:55:11 INFO - Running post-run listener: _upload_blobber_files 01:55:11 INFO - Blob upload gear active. 01:55:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:55:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 01:55:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:55:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:55:11 INFO - (blobuploader) - INFO - Open directory for files ... 01:55:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:55:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:55:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:55:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:55:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:55:12 INFO - (blobuploader) - INFO - Done attempting. 01:55:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:55:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:55:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:55:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:55:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:55:13 INFO - (blobuploader) - INFO - Done attempting. 01:55:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:55:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:55:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:55:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:55:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:55:14 INFO - (blobuploader) - INFO - Done attempting. 01:55:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:55:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:55:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:55:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:55:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:55:17 INFO - (blobuploader) - INFO - Done attempting. 01:55:17 INFO - (blobuploader) - INFO - Iteration through files over. 01:55:17 INFO - Return code: 0 01:55:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:55:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:55:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3eed4ed2023b61299ddd654a9b84ddc1c4f2976978ecd5ee9cedb7bf1aa5eacefa3d3c67652e79fea15da49b3c411a20a90cc99bcdfebd3737495cccd521a46a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cfbe07cb35bf11671973ffdcc88531a29ffa8a3895f607ddbd99e5f82707dc57b66ace73f2916b0e8c70cddbf6334ad87432025cf374301df3c23bfba20540ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e693fbecf377985ef2da80b2f54198588e24178951cfc9545a1f202ef55fbc0d72c0f5942b68a2c3961c0505cb22144bc56eff05b4bca7b6d0fb4099de6bc647", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/03f9d162947b52d99bb201f0c5426a56411e2a613e6e5591df0be8c9bf332b7381b3b140e342b14bbb8b0b13eeb5032ee46851cd101b9939fa69b47a1b1185f4"} 01:55:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:55:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:55:17 INFO - Contents: 01:55:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3eed4ed2023b61299ddd654a9b84ddc1c4f2976978ecd5ee9cedb7bf1aa5eacefa3d3c67652e79fea15da49b3c411a20a90cc99bcdfebd3737495cccd521a46a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cfbe07cb35bf11671973ffdcc88531a29ffa8a3895f607ddbd99e5f82707dc57b66ace73f2916b0e8c70cddbf6334ad87432025cf374301df3c23bfba20540ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e693fbecf377985ef2da80b2f54198588e24178951cfc9545a1f202ef55fbc0d72c0f5942b68a2c3961c0505cb22144bc56eff05b4bca7b6d0fb4099de6bc647", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/03f9d162947b52d99bb201f0c5426a56411e2a613e6e5591df0be8c9bf332b7381b3b140e342b14bbb8b0b13eeb5032ee46851cd101b9939fa69b47a1b1185f4"} 01:55:17 INFO - Running post-run listener: copy_logs_to_upload_dir 01:55:17 INFO - Copying logs to upload dir... 01:55:17 INFO - mkdir: /builds/slave/test/build/upload/logs 01:55:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1294.850818 ========= master_lag: -0.19 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2017-05-03 01:55:17.750228) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 01:55:17.753872) ========= 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.qEAFYszRQV/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.G9djcpstYn/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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/3eed4ed2023b61299ddd654a9b84ddc1c4f2976978ecd5ee9cedb7bf1aa5eacefa3d3c67652e79fea15da49b3c411a20a90cc99bcdfebd3737495cccd521a46a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cfbe07cb35bf11671973ffdcc88531a29ffa8a3895f607ddbd99e5f82707dc57b66ace73f2916b0e8c70cddbf6334ad87432025cf374301df3c23bfba20540ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e693fbecf377985ef2da80b2f54198588e24178951cfc9545a1f202ef55fbc0d72c0f5942b68a2c3961c0505cb22144bc56eff05b4bca7b6d0fb4099de6bc647", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/03f9d162947b52d99bb201f0c5426a56411e2a613e6e5591df0be8c9bf332b7381b3b140e342b14bbb8b0b13eeb5032ee46851cd101b9939fa69b47a1b1185f4"} build_url:https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011235 build_url: 'https://queue.taskcluster.net/v1/task/EXl5vQQzQQmywgN0Ev2e2Q/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/3eed4ed2023b61299ddd654a9b84ddc1c4f2976978ecd5ee9cedb7bf1aa5eacefa3d3c67652e79fea15da49b3c411a20a90cc99bcdfebd3737495cccd521a46a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cfbe07cb35bf11671973ffdcc88531a29ffa8a3895f607ddbd99e5f82707dc57b66ace73f2916b0e8c70cddbf6334ad87432025cf374301df3c23bfba20540ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e693fbecf377985ef2da80b2f54198588e24178951cfc9545a1f202ef55fbc0d72c0f5942b68a2c3961c0505cb22144bc56eff05b4bca7b6d0fb4099de6bc647", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/03f9d162947b52d99bb201f0c5426a56411e2a613e6e5591df0be8c9bf332b7381b3b140e342b14bbb8b0b13eeb5032ee46851cd101b9939fa69b47a1b1185f4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 01:55:17.788989) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 01:55:17.789324) ========= 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.qEAFYszRQV/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.G9djcpstYn/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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.005225 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 01:55:17.838327) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 01:55:17.838658) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 01:55:17.838980) ========= ========= Total master_lag: -0.04 =========