builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0324 starttime: 1492249309.99 results: success (0) buildid: 20170415004005 builduid: 55f99a4cbbfe40df8bf2eb2e2f58a678 revision: e53f7f1785ebedaf692d599d70e3a7a9e1bb5f4f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-15 02:41:49.988698) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-15 02:41:49.989254) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-15 02:41:49.989587) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-15 02:41:50.018472) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 02:41:50.018940) ========= 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.C56yZiuR98/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.HcMOJGFc1k/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-15 02:41:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2017-04-15 02:41:50 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.181791 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 02:41:50.225273) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 02:41:50.225642) ========= 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.C56yZiuR98/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.HcMOJGFc1k/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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.033118 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 02:41:50.317680) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-15 02:41:50.318067) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e53f7f1785ebedaf692d599d70e3a7a9e1bb5f4f --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 e53f7f1785ebedaf692d599d70e3a7a9e1bb5f4f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.C56yZiuR98/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.HcMOJGFc1k/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-15 02:41:50,426 truncating revision to first 12 chars 2017-04-15 02:41:50,426 Setting DEBUG logging. 2017-04-15 02:41:50,426 attempt 1/10 2017-04-15 02:41:50,426 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e53f7f1785eb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-15 02:41:51,509 unpacking tar archive at: mozilla-aurora-e53f7f1785eb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.365069 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-15 02:41:51.707846) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-15 02:41:51.708207) ========= script_repo_revision: e53f7f1785ebedaf692d599d70e3a7a9e1bb5f4f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-15 02:41:51.708621) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-15 02:41:51.708909) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-15 02:41:51.733582) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-15 02:41:51.733882) ========= /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.C56yZiuR98/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.HcMOJGFc1k/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 02:41:51 INFO - MultiFileLogger online at 20170415 02:41:51 in /builds/slave/test 02:41:51 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 02:41:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:41:51 INFO - {'append_to_log': False, 02:41:51 INFO - 'base_work_dir': '/builds/slave/test', 02:41:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:41:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:41:51 INFO - 'branch': 'Mozilla-Aurora', 02:41:51 INFO - 'buildbot_json_path': 'buildprops.json', 02:41:51 INFO - 'config_files': ('talos/mac_config.py',), 02:41:51 INFO - 'default_actions': ('clobber', 02:41:51 INFO - 'read-buildbot-config', 02:41:51 INFO - 'download-and-extract', 02:41:51 INFO - 'populate-webroot', 02:41:51 INFO - 'create-virtualenv', 02:41:51 INFO - 'install', 02:41:51 INFO - 'run-tests'), 02:41:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:41:51 INFO - 'download_minidump_stackwalk': True, 02:41:51 INFO - 'download_symbols': 'ondemand', 02:41:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:41:51 INFO - 'tooltool.py': '/tools/tooltool.py', 02:41:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:41:51 INFO - '/tools/misc-python/virtualenv.py')}, 02:41:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:41:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:41:51 INFO - 'gecko_profile': False, 02:41:51 INFO - 'gecko_profile_interval': 0, 02:41:51 INFO - 'installer_path': 'installer.exe', 02:41:51 INFO - 'log_level': 'info', 02:41:51 INFO - 'log_name': 'talos', 02:41:51 INFO - 'log_to_console': True, 02:41:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:41:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:41:51 INFO - 'opt_config_files': (), 02:41:51 INFO - 'pip_index': False, 02:41:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:41:51 INFO - 'cmd': ('bash', 02:41:51 INFO - '-c', 02:41:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:41:51 INFO - 'enabled': True, 02:41:51 INFO - 'halt_on_failure': False, 02:41:51 INFO - 'name': 'check_screen_resolution'},), 02:41:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:41:51 INFO - 'cmd': ('bash', 02:41:51 INFO - '-c', 02:41:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:41:51 INFO - 'enabled': True, 02:41:51 INFO - 'halt_on_failure': False, 02:41:51 INFO - 'name': 'check_screen_resolution'},), 02:41:51 INFO - 'run_cmd_checks_enabled': True, 02:41:51 INFO - 'suite': 'dromaeojs', 02:41:51 INFO - 'system_bits': '32', 02:41:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:41:51 INFO - 'title': 't-yosemite-r7-0324', 02:41:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:41:51 INFO - 'use_talos_json': True, 02:41:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:41:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:41:51 INFO - 'work_dir': 'build'} 02:41:51 INFO - [mozharness: 2017-04-15 09:41:51.950738Z] Running clobber step. 02:41:51 INFO - Running pre-action listener: _resource_record_pre_action 02:41:51 INFO - Running main action method: clobber 02:41:51 INFO - rmtree: /builds/slave/test/build 02:41:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:41:54 INFO - Running post-action listener: _resource_record_post_action 02:41:54 INFO - [mozharness: 2017-04-15 09:41:54.541973Z] Finished clobber step (success) 02:41:54 INFO - [mozharness: 2017-04-15 09:41:54.542106Z] Running read-buildbot-config step. 02:41:54 INFO - Running pre-action listener: _resource_record_pre_action 02:41:54 INFO - Running main action method: read_buildbot_config 02:41:54 INFO - Using buildbot properties: 02:41:54 INFO - { 02:41:54 INFO - "project": "", 02:41:54 INFO - "product": "firefox", 02:41:54 INFO - "script_repo_revision": "production", 02:41:54 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:41:54 INFO - "repository": "", 02:41:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:41:54 INFO - "buildid": "20170415004005", 02:41:54 INFO - "stage_platform": "macosx64", 02:41:54 INFO - "basedir": "/builds/slave/test", 02:41:54 INFO - "nightly_build": "True", 02:41:54 INFO - "buildnumber": 94, 02:41:54 INFO - "slavename": "t-yosemite-r7-0324", 02:41:54 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:41:54 INFO - "platform": "yosemite_r7", 02:41:54 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:41:54 INFO - "branch": "mozilla-aurora", 02:41:54 INFO - "repo_path": "releases/mozilla-aurora", 02:41:54 INFO - "revision": "e53f7f1785ebedaf692d599d70e3a7a9e1bb5f4f", 02:41:54 INFO - "pgo_build": "False", 02:41:54 INFO - "builduid": "55f99a4cbbfe40df8bf2eb2e2f58a678", 02:41:54 INFO - "slavebuilddir": "test" 02:41:54 INFO - } 02:41:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:41:54 INFO - Running post-action listener: _resource_record_post_action 02:41:54 INFO - [mozharness: 2017-04-15 09:41:54.545589Z] Finished read-buildbot-config step (success) 02:41:54 INFO - [mozharness: 2017-04-15 09:41:54.545734Z] Running download-and-extract step. 02:41:54 INFO - Running pre-action listener: _resource_record_pre_action 02:41:54 INFO - Running main action method: download_and_extract 02:41:54 INFO - mkdir: /builds/slave/test/build/tests 02:41:54 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')]} 02:41:54 INFO - trying https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:41:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/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 02:41:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/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 02:41:55 INFO - Downloaded 1199 bytes. 02:41:55 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:41:55 INFO - Using the following test package requirements: 02:41:55 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:41:55 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:41:55 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:41:55 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:41:55 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:41:55 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:41:55 INFO - u'jsshell-mac.zip'], 02:41:55 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:41:55 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:41:55 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:41:55 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:41:55 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:41:55 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:41:55 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:41:55 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:41:55 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:41:55 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:41:55 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:41:55 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:41:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:41:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:41:55 INFO - Fetch https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:41:57 INFO - Expected file size: 51791136 02:41:57 INFO - Obtained file size: 51791136 02:42:13 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 02:42:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:42:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:42:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:42:15 INFO - Expected file size: 51791136 02:42:15 INFO - Obtained file size: 51791136 02:42:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:42:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:42:29 INFO - Fetch https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:42:30 INFO - Expected file size: 14404424 02:42:30 INFO - Obtained file size: 14404424 02:42: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')]} 02:42:31 INFO - trying https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:42:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:42:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/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 02:42:33 INFO - Downloaded 74781694 bytes. 02:42:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:42:33 INFO - mkdir: /builds/slave/test/properties 02:42:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:42:33 INFO - Writing to file /builds/slave/test/properties/build_url 02:42:33 INFO - Contents: 02:42:33 INFO - build_url:https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:42:33 INFO - Running post-action listener: _resource_record_post_action 02:42:33 INFO - Running post-action listener: set_extra_try_arguments 02:42:33 INFO - [mozharness: 2017-04-15 09:42:33.955206Z] Finished download-and-extract step (success) 02:42:33 INFO - [mozharness: 2017-04-15 09:42:33.955355Z] Running populate-webroot step. 02:42:33 INFO - Running pre-action listener: _resource_record_pre_action 02:42:33 INFO - Running main action method: populate_webroot 02:42:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:42:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:42:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:42:33 INFO - '--tppagecycles', 02:42:33 INFO - '7'], 02:42:33 INFO - 'tests': ['tsvgm']}}, 02:42:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:42:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:42:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:42:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:42:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:42:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:42:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:42:33 INFO - 'talos_options': ['--disable-e10s'], 02:42:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:42:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:42:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:42:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:42:33 INFO - 'talos_options': ['--disable-e10s'], 02:42:33 INFO - 'tests': ['damp', 'tps']}, 02:42:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:42:33 INFO - 'tests': ['damp', 'tps']}, 02:42:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:42:33 INFO - 'tests': ['dromaeo_dom']}, 02:42:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:42:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:42:33 INFO - 'tests': ['basic_compositor_video']}, 02:42:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:42:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:42:33 INFO - 'tests': ['a11yr', 02:42:33 INFO - 'ts_paint', 02:42:33 INFO - 'tpaint', 02:42:33 INFO - 'sessionrestore', 02:42:33 INFO - 'sessionrestore_no_auto_restore', 02:42:33 INFO - 'tabpaint']}, 02:42:33 INFO - 'other-e10s': {'tests': ['a11yr', 02:42:33 INFO - 'ts_paint', 02:42:33 INFO - 'tpaint', 02:42:33 INFO - 'sessionrestore', 02:42:33 INFO - 'sessionrestore_no_auto_restore', 02:42:33 INFO - 'tabpaint']}, 02:42:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:42:33 INFO - 'ts_paint', 02:42:33 INFO - 'tpaint', 02:42:33 INFO - 'sessionrestore', 02:42:33 INFO - 'sessionrestore_no_auto_restore', 02:42:33 INFO - 'tabpaint']}, 02:42:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:42:33 INFO - 'ts_paint', 02:42:33 INFO - 'tpaint', 02:42:33 INFO - 'sessionrestore', 02:42:33 INFO - 'sessionrestore_no_auto_restore', 02:42:33 INFO - 'tabpaint']}, 02:42:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:42:33 INFO - 'tests': ['a11yr', 02:42:33 INFO - 'ts_paint', 02:42:33 INFO - 'tpaint', 02:42:33 INFO - 'sessionrestore', 02:42:33 INFO - 'sessionrestore_no_auto_restore', 02:42:33 INFO - 'tabpaint']}, 02:42:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:42:33 INFO - 'tests': ['a11yr', 02:42:33 INFO - 'ts_paint', 02:42:33 INFO - 'tpaint', 02:42:33 INFO - 'sessionrestore', 02:42:33 INFO - 'sessionrestore_no_auto_restore', 02:42:33 INFO - 'tabpaint']}, 02:42:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:42:33 INFO - 'tests': ['tsvgx', 02:42:33 INFO - 'tsvgr_opacity', 02:42:33 INFO - 'tart', 02:42:33 INFO - 'tscrollx', 02:42:33 INFO - 'cart', 02:42:33 INFO - 'tsvg_static']}, 02:42:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:42:33 INFO - 'tsvgr_opacity', 02:42:33 INFO - 'tart', 02:42:33 INFO - 'tscrollx', 02:42:33 INFO - 'cart', 02:42:33 INFO - 'tsvg_static']}, 02:42:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:42:33 INFO - 'talos_options': ['--disable-e10s'], 02:42:33 INFO - 'tests': ['tp5o']}, 02:42:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:42:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:42:33 INFO - 'talos_options': ['--disable-e10s', 02:42:33 INFO - '--xperf_path', 02:42:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:42:33 INFO - 'tests': ['tp5n']}, 02:42:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:42:33 INFO - 'talos_options': ['--xperf_path', 02:42:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:42:33 INFO - 'tests': ['tp5n']}}, 02:42:33 INFO - 'talos.zip': {'path': '', 02:42:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:42:33 INFO - Running post-action listener: _resource_record_post_action 02:42:33 INFO - [mozharness: 2017-04-15 09:42:33.973766Z] Finished populate-webroot step (success) 02:42:33 INFO - [mozharness: 2017-04-15 09:42:33.973892Z] Running create-virtualenv step. 02:42:33 INFO - Running pre-action listener: _resource_record_pre_action 02:42:33 INFO - Running main action method: create_virtualenv 02:42:33 INFO - Creating virtualenv /builds/slave/test/build/venv 02:42:33 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 02:42:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:42:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:42:34 INFO - Using real prefix '/tools/python27' 02:42:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:42:34 INFO - Installing distribute.............................................................................................................................................................................................done. 02:42:37 INFO - Installing pip.................done. 02:42:37 INFO - Return code: 0 02:42:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:42:37 INFO - Reading from file tmpfile_stdout 02:42:37 INFO - Output received: 02:42:37 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) 02:42:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:42:37 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')]} 02:42:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:37 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')]} 02:42:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103307b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10330a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894b562d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030e5d30>, '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 0x10300cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030dabe8>, '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.C56yZiuR98/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.HcMOJGFc1k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 02:42:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:42:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C56yZiuR98/Render', 02:42:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:37 INFO - 'HOME': '/Users/cltbld', 02:42:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:37 INFO - 'LOGNAME': 'cltbld', 02:42:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:37 INFO - 'MOZ_NO_REMOTE': '1', 02:42:37 INFO - 'NO_EM_RESTART': '1', 02:42:37 INFO - 'PAGER': '/bin/cat', 02:42:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:37 INFO - 'PWD': '/builds/slave/test', 02:42:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:37 INFO - 'SHELL': '/bin/bash', 02:42:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HcMOJGFc1k/Listeners', 02:42:37 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 02:42:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:37 INFO - 'USER': 'cltbld', 02:42:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:37 INFO - 'XPC_FLAGS': '0x0', 02:42:37 INFO - 'XPC_SERVICE_NAME': '0', 02:42:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:38 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 02:42:38 INFO - Cleaning up... 02:42:38 INFO - Return code: 0 02:42:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:42: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')]} 02:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42: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')]} 02:42:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42: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', '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 0x103307b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10330a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894b562d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030e5d30>, '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 0x10300cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030dabe8>, '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.C56yZiuR98/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.HcMOJGFc1k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 02:42: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', 'psutil>=3.1.1'] in /builds/slave/test/build 02:42: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 psutil>=3.1.1 02:42:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C56yZiuR98/Render', 02:42:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:38 INFO - 'HOME': '/Users/cltbld', 02:42:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:38 INFO - 'LOGNAME': 'cltbld', 02:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:38 INFO - 'MOZ_NO_REMOTE': '1', 02:42:38 INFO - 'NO_EM_RESTART': '1', 02:42:38 INFO - 'PAGER': '/bin/cat', 02:42:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:38 INFO - 'PWD': '/builds/slave/test', 02:42:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:38 INFO - 'SHELL': '/bin/bash', 02:42:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HcMOJGFc1k/Listeners', 02:42:38 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 02:42:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:38 INFO - 'USER': 'cltbld', 02:42:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:38 INFO - 'XPC_FLAGS': '0x0', 02:42:38 INFO - 'XPC_SERVICE_NAME': '0', 02:42:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:38 INFO - Downloading/unpacking psutil>=3.1.1 02:42:38 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 02:42:38 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 02:42:38 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 02:42:38 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 02:42:38 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 02:42:38 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 02:42:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:42:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:42:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:42:42 INFO - Installing collected packages: psutil 02:42:42 INFO - Running setup.py install for psutil 02:42:42 INFO - building 'psutil._psutil_osx' extension 02:42:42 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 02:42:42 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 02:42:42 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 02:42:43 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 02:42:43 INFO - building 'psutil._psutil_posix' extension 02:42:43 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 02:42:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:42:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:42:43 INFO - ^ 02:42:43 INFO - 1 warning generated. 02:42:43 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 02:42:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:42:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:42:43 INFO - Successfully installed psutil 02:42:43 INFO - Cleaning up... 02:42:43 INFO - Return code: 0 02:42:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103307b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10330a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894b562d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030e5d30>, '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 0x10300cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030dabe8>, '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.C56yZiuR98/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.HcMOJGFc1k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 02:42:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:42:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:42:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C56yZiuR98/Render', 02:42:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:43 INFO - 'HOME': '/Users/cltbld', 02:42:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:43 INFO - 'LOGNAME': 'cltbld', 02:42:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:43 INFO - 'MOZ_NO_REMOTE': '1', 02:42:43 INFO - 'NO_EM_RESTART': '1', 02:42:43 INFO - 'PAGER': '/bin/cat', 02:42:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:43 INFO - 'PWD': '/builds/slave/test', 02:42:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:43 INFO - 'SHELL': '/bin/bash', 02:42:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HcMOJGFc1k/Listeners', 02:42:43 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 02:42:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:43 INFO - 'USER': 'cltbld', 02:42:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:43 INFO - 'XPC_FLAGS': '0x0', 02:42:43 INFO - 'XPC_SERVICE_NAME': '0', 02:42:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:42:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:42:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:42:46 INFO - Installing collected packages: mozsystemmonitor 02:42:46 INFO - Running setup.py install for mozsystemmonitor 02:42:46 INFO - Successfully installed mozsystemmonitor 02:42:46 INFO - Cleaning up... 02:42:46 INFO - Return code: 0 02:42:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:42:46 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')]} 02:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:46 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')]} 02:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:46 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 0x103307b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10330a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894b562d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030e5d30>, '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 0x10300cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030dabe8>, '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.C56yZiuR98/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.HcMOJGFc1k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 02:42:46 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 02:42:46 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 02:42:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C56yZiuR98/Render', 02:42:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:46 INFO - 'HOME': '/Users/cltbld', 02:42:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:46 INFO - 'LOGNAME': 'cltbld', 02:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:46 INFO - 'MOZ_NO_REMOTE': '1', 02:42:46 INFO - 'NO_EM_RESTART': '1', 02:42:46 INFO - 'PAGER': '/bin/cat', 02:42:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:46 INFO - 'PWD': '/builds/slave/test', 02:42:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:46 INFO - 'SHELL': '/bin/bash', 02:42:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HcMOJGFc1k/Listeners', 02:42:46 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 02:42:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:46 INFO - 'USER': 'cltbld', 02:42:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:46 INFO - 'XPC_FLAGS': '0x0', 02:42:46 INFO - 'XPC_SERVICE_NAME': '0', 02:42:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:46 INFO - Downloading/unpacking jsonschema==2.5.1 02:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:42:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:49 INFO - Downloading functools32-3.2.3-2.tar.gz 02:42:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:42:50 INFO - warning: no files found matching '*.txt' 02:42:50 INFO - no previously-included directories found matching 'build' 02:42:50 INFO - no previously-included directories found matching 'dist' 02:42:50 INFO - no previously-included directories found matching '.git*' 02:42:50 INFO - Installing collected packages: jsonschema, functools32 02:42:50 INFO - Running setup.py install for functools32 02:42:50 INFO - warning: no files found matching '*.txt' 02:42:50 INFO - no previously-included directories found matching 'build' 02:42:50 INFO - no previously-included directories found matching 'dist' 02:42:50 INFO - no previously-included directories found matching '.git*' 02:42:50 INFO - Successfully installed jsonschema functools32 02:42:50 INFO - Cleaning up... 02:42:50 INFO - Return code: 0 02:42:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:42:50 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')]} 02:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:50 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')]} 02:42:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:50 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 0x103307b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10330a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894b562d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030e5d30>, '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 0x10300cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030dabe8>, '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.C56yZiuR98/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.HcMOJGFc1k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 02:42:50 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 02:42:50 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 02:42:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C56yZiuR98/Render', 02:42:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:50 INFO - 'HOME': '/Users/cltbld', 02:42:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:50 INFO - 'LOGNAME': 'cltbld', 02:42:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:50 INFO - 'MOZ_NO_REMOTE': '1', 02:42:50 INFO - 'NO_EM_RESTART': '1', 02:42:50 INFO - 'PAGER': '/bin/cat', 02:42:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:50 INFO - 'PWD': '/builds/slave/test', 02:42:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:50 INFO - 'SHELL': '/bin/bash', 02:42:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HcMOJGFc1k/Listeners', 02:42:50 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 02:42:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:50 INFO - 'USER': 'cltbld', 02:42:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:50 INFO - 'XPC_FLAGS': '0x0', 02:42:50 INFO - 'XPC_SERVICE_NAME': '0', 02:42:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:42:50 INFO - Cleaning up... 02:42:50 INFO - Return code: 0 02:42:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:42:50 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')]} 02:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:50 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')]} 02:42:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:50 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 0x103307b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10330a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894b562d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030e5d30>, '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 0x10300cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030dabe8>, '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.C56yZiuR98/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.HcMOJGFc1k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 02:42:50 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 02:42:50 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 02:42:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C56yZiuR98/Render', 02:42:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:50 INFO - 'HOME': '/Users/cltbld', 02:42:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:50 INFO - 'LOGNAME': 'cltbld', 02:42:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:50 INFO - 'MOZ_NO_REMOTE': '1', 02:42:50 INFO - 'NO_EM_RESTART': '1', 02:42:50 INFO - 'PAGER': '/bin/cat', 02:42:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:50 INFO - 'PWD': '/builds/slave/test', 02:42:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:50 INFO - 'SHELL': '/bin/bash', 02:42:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HcMOJGFc1k/Listeners', 02:42:50 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 02:42:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:50 INFO - 'USER': 'cltbld', 02:42:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:50 INFO - 'XPC_FLAGS': '0x0', 02:42:50 INFO - 'XPC_SERVICE_NAME': '0', 02:42:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:50 INFO - Downloading/unpacking blobuploader==1.2.4 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42:53 INFO - Downloading blobuploader-1.2.4.tar.gz 02:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:42:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:42:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42:54 INFO - Downloading docopt-0.6.1.tar.gz 02:42:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:42:54 INFO - Installing collected packages: blobuploader, requests, docopt 02:42:54 INFO - Running setup.py install for blobuploader 02:42:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:42:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:42:54 INFO - Running setup.py install for requests 02:42:55 INFO - Running setup.py install for docopt 02:42:55 INFO - Successfully installed blobuploader requests docopt 02:42:55 INFO - Cleaning up... 02:42:55 INFO - Return code: 0 02:42:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:55 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 0x103307b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10330a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894b562d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030e5d30>, '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 0x10300cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030dabe8>, '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.C56yZiuR98/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.HcMOJGFc1k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 02:42:55 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 02:42:55 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 02:42:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C56yZiuR98/Render', 02:42:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:55 INFO - 'HOME': '/Users/cltbld', 02:42:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:55 INFO - 'LOGNAME': 'cltbld', 02:42:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:55 INFO - 'MOZ_NO_REMOTE': '1', 02:42:55 INFO - 'NO_EM_RESTART': '1', 02:42:55 INFO - 'PAGER': '/bin/cat', 02:42:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:55 INFO - 'PWD': '/builds/slave/test', 02:42:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:55 INFO - 'SHELL': '/bin/bash', 02:42:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HcMOJGFc1k/Listeners', 02:42:55 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 02:42:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:55 INFO - 'USER': 'cltbld', 02:42:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:55 INFO - 'XPC_FLAGS': '0x0', 02:42:55 INFO - 'XPC_SERVICE_NAME': '0', 02:42:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:42:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-CDMaRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:42:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-P26Ek8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:42:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-PcldKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:42:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-j7Suwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:42:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-o7fVsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:42:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-skUk41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:42:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-oXPRUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:42:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-f1kQ5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:42:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-SS2dOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:42:56 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-AvnoDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:42:57 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-ot5F5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:42:57 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-CJqnk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:42:57 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-1DyAuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:42:57 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-DEAtWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:42:57 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-eCFWM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:42:57 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Jg7M1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:42:57 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-EKgQJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:42:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:42:57 INFO - Running setup.py install for manifestparser 02:42:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:42:57 INFO - Running setup.py install for mozcrash 02:42:58 INFO - Running setup.py install for mozdebug 02:42:58 INFO - Running setup.py install for mozdevice 02:42:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:42:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:42:58 INFO - Running setup.py install for mozfile 02:42:58 INFO - Running setup.py install for mozhttpd 02:42:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:42:58 INFO - Running setup.py install for mozinfo 02:42:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:42:58 INFO - Running setup.py install for mozInstall 02:42:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:42:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:42:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:42:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:42:58 INFO - Running setup.py install for mozleak 02:42:59 INFO - Running setup.py install for mozlog 02:42:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:42:59 INFO - Running setup.py install for moznetwork 02:42:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:42:59 INFO - Running setup.py install for mozprocess 02:42:59 INFO - Running setup.py install for mozprofile 02:42:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:42:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:42:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:42:59 INFO - Running setup.py install for mozrunner 02:42:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:42:59 INFO - Running setup.py install for mozscreenshot 02:43:00 INFO - Running setup.py install for moztest 02:43:00 INFO - Running setup.py install for mozversion 02:43:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:43:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:43:00 INFO - Cleaning up... 02:43:00 INFO - Return code: 0 02:43:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:43:00 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')]} 02:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:43:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:43:00 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')]} 02:43:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:43:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:43:00 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 0x103307b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10330a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894b562d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030e5d30>, '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 0x10300cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030dabe8>, '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.C56yZiuR98/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.HcMOJGFc1k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 02:43:00 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 02:43:00 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 02:43:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C56yZiuR98/Render', 02:43:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:43:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:43:00 INFO - 'HOME': '/Users/cltbld', 02:43:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:43:00 INFO - 'LOGNAME': 'cltbld', 02:43:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:43:00 INFO - 'MOZ_NO_REMOTE': '1', 02:43:00 INFO - 'NO_EM_RESTART': '1', 02:43:00 INFO - 'PAGER': '/bin/cat', 02:43:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:43:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:43:00 INFO - 'PWD': '/builds/slave/test', 02:43:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:43:00 INFO - 'SHELL': '/bin/bash', 02:43:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HcMOJGFc1k/Listeners', 02:43:00 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 02:43:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:43:00 INFO - 'USER': 'cltbld', 02:43:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:43:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:43:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:43:00 INFO - 'XPC_FLAGS': '0x0', 02:43:00 INFO - 'XPC_SERVICE_NAME': '0', 02:43:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:43:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:43:00 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-DnugAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:43:00 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)) 02:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:43:00 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Ggrl8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:43:00 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)) 02:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:43:00 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-uXPFpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:43:00 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)) 02:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:43:00 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-jCDlKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:43:01 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)) 02:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:43:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-3CgV3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:43:01 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)) 02:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:43:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-WVv2YJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:43:01 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)) 02:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:43:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-9_g04b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:43:01 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)) 02:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:43:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-4L8jh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:43:01 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)) 02:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:43:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-gQghVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:43:01 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)) 02:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:43:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-plvuMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:43:01 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)) 02:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:43:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-zZP2Vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:43:01 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)) 02:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:43:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-pRMIab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:43:01 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)) 02:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:43:01 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-hqkUF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:43:02 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)) 02:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:43:02 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-k0jL2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:43:02 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)) 02:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:43:02 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-_iJGzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:43:02 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)) 02:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:43:02 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-ZrGSrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:43:02 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)) 02:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:43:02 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Yk0gnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:43:02 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)) 02:43:02 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)) 02:43:02 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)) 02:43:02 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)) 02:43:02 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)) 02:43:02 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)) 02:43:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:43:02 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 02:43:02 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 02:43:02 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 02:43:02 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 02:43:02 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 02:43:02 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 02:43:05 INFO - Downloading blessings-1.6.tar.gz 02:43:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:43:05 INFO - Installing collected packages: blessings 02:43:05 INFO - Running setup.py install for blessings 02:43:05 INFO - Successfully installed blessings 02:43:05 INFO - Cleaning up... 02:43:05 INFO - Return code: 0 02:43:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:43:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:43:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:43:05 INFO - Reading from file tmpfile_stdout 02:43:05 INFO - Current package versions: 02:43:05 INFO - blessings == 1.6 02:43:05 INFO - blobuploader == 1.2.4 02:43:05 INFO - docopt == 0.6.1 02:43:05 INFO - functools32 == 3.2.3-2 02:43:05 INFO - jsonschema == 2.5.1 02:43:05 INFO - manifestparser == 1.1 02:43:05 INFO - mozInstall == 1.12 02:43:05 INFO - mozcrash == 1.0 02:43:05 INFO - mozdebug == 0.1 02:43:05 INFO - mozdevice == 0.49 02:43:05 INFO - mozfile == 1.2 02:43:05 INFO - mozhttpd == 0.7 02:43:05 INFO - mozinfo == 0.9 02:43:05 INFO - mozleak == 0.1 02:43:05 INFO - mozlog == 3.4 02:43:05 INFO - moznetwork == 0.27 02:43:05 INFO - mozprocess == 0.25 02:43:05 INFO - mozprofile == 0.28 02:43:05 INFO - mozrunner == 6.13 02:43:05 INFO - mozscreenshot == 0.1 02:43:05 INFO - mozsystemmonitor == 0.3 02:43:05 INFO - moztest == 0.8 02:43:05 INFO - mozversion == 1.4 02:43:05 INFO - psutil == 3.1.1 02:43:05 INFO - requests == 1.2.3 02:43:05 INFO - wsgiref == 0.1.2 02:43:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:43:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:43:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:43:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:43:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:43:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:43:05 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 0x103307b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10330a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894b562d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030e5d30>, '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 0x10300cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030dabe8>, '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.C56yZiuR98/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.HcMOJGFc1k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 02:43:05 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 02:43:05 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 02:43:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C56yZiuR98/Render', 02:43:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:43:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:43:05 INFO - 'HOME': '/Users/cltbld', 02:43:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:43:05 INFO - 'LOGNAME': 'cltbld', 02:43:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:43:05 INFO - 'MOZ_NO_REMOTE': '1', 02:43:05 INFO - 'NO_EM_RESTART': '1', 02:43:05 INFO - 'PAGER': '/bin/cat', 02:43:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:43:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:43:05 INFO - 'PWD': '/builds/slave/test', 02:43:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:43:05 INFO - 'SHELL': '/bin/bash', 02:43:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HcMOJGFc1k/Listeners', 02:43:05 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 02:43:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:43:05 INFO - 'USER': 'cltbld', 02:43:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:43:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:43:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:43:05 INFO - 'XPC_FLAGS': '0x0', 02:43:05 INFO - 'XPC_SERVICE_NAME': '0', 02:43:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:43:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:43:06 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)) 02:43:06 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)) 02:43:06 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)) 02:43:06 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)) 02:43:06 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)) 02:43:06 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)) 02:43:06 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)) 02:43:06 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)) 02:43:06 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)) 02:43:06 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)) 02:43:06 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)) 02:43:06 INFO - Cleaning up... 02:43:06 INFO - Return code: 0 02:43:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:43:06 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')]} 02:43:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:43:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:43:06 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')]} 02:43:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:43:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:43:06 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 0x103307b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10330a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894b562d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030e5d30>, '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 0x10300cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030dabe8>, '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.C56yZiuR98/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.HcMOJGFc1k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 02:43:06 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 02:43:06 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 02:43:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C56yZiuR98/Render', 02:43:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:43:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:43:06 INFO - 'HOME': '/Users/cltbld', 02:43:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:43:06 INFO - 'LOGNAME': 'cltbld', 02:43:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:43:06 INFO - 'MOZ_NO_REMOTE': '1', 02:43:06 INFO - 'NO_EM_RESTART': '1', 02:43:06 INFO - 'PAGER': '/bin/cat', 02:43:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:43:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:43:06 INFO - 'PWD': '/builds/slave/test', 02:43:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:43:06 INFO - 'SHELL': '/bin/bash', 02:43:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HcMOJGFc1k/Listeners', 02:43:06 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 02:43:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:43:06 INFO - 'USER': 'cltbld', 02:43:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:43:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:43:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:43:06 INFO - 'XPC_FLAGS': '0x0', 02:43:06 INFO - 'XPC_SERVICE_NAME': '0', 02:43:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:43:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:43:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:43:06 INFO - Cleaning up... 02:43:06 INFO - Return code: 0 02:43:06 INFO - Running post-action listener: _resource_record_post_action 02:43:06 INFO - Running post-action listener: _start_resource_monitoring 02:43:06 INFO - Starting resource monitoring. 02:43:06 INFO - [mozharness: 2017-04-15 09:43:06.336085Z] Finished create-virtualenv step (success) 02:43:06 INFO - [mozharness: 2017-04-15 09:43:06.337052Z] Running install step. 02:43:06 INFO - Running pre-action listener: _resource_record_pre_action 02:43:06 INFO - Running main action method: install 02:43:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:43:06 INFO - Reading from file tmpfile_stdout 02:43:06 INFO - Detecting whether we're running mozinstall >=1.0... 02:43:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:43:06 INFO - Reading from file tmpfile_stdout 02:43:06 INFO - Output received: 02:43:06 INFO - Usage: mozinstall [options] installer 02:43:06 INFO - Options: 02:43:06 INFO - -h, --help show this help message and exit 02:43:06 INFO - -d DEST, --destination=DEST 02:43:06 INFO - Directory to install application into. [default: 02:43:06 INFO - "/builds/slave/test"] 02:43:06 INFO - --app=APP Application being installed. [default: firefox] 02:43:06 INFO - mkdir: /builds/slave/test/build/application 02:43:06 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'] 02:43:06 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 02:43:27 INFO - Reading from file tmpfile_stdout 02:43:27 INFO - Output received: 02:43:27 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:43:27 INFO - Running post-action listener: _resource_record_post_action 02:43:27 INFO - [mozharness: 2017-04-15 09:43:27.127753Z] Finished install step (success) 02:43:27 INFO - [mozharness: 2017-04-15 09:43:27.127881Z] Running run-tests step. 02:43:27 INFO - Running pre-action listener: _resource_record_pre_action 02:43:27 INFO - Running main action method: run_tests 02:43:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:43:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:43:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:43:27 INFO - 2017-04-15 02:43:27.142 screenresolution[1644:13165] starting screenresolution argv=screenresolution get 02:43:27 INFO - 2017-04-15 02:43:27.145 screenresolution[1644:13165] Display 0: 1600x1200x32@60 02:43:27 INFO - 2017-04-15 02:43:27.150 screenresolution[1645:13170] starting screenresolution argv=screenresolution list 02:43:27 INFO - Available Modes on Display 0 02:43:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:43:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:43:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:43:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:43:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:43:27 INFO - Intel Iris: 02:43:27 INFO - Chipset Model: Intel Iris 02:43:27 INFO - Type: GPU 02:43:27 INFO - Bus: Built-In 02:43:27 INFO - VRAM (Dynamic, Max): 1536 MB 02:43:27 INFO - Vendor: Intel (0x8086) 02:43:27 INFO - Device ID: 0x0a2e 02:43:27 INFO - Revision ID: 0x0009 02:43:27 INFO - Displays: 02:43:27 INFO - Display: 02:43:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:43:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:43:27 INFO - Display Serial Number: 1600x1200 60 02:43:27 INFO - Main Display: Yes 02:43:27 INFO - Mirror: Off 02:43:27 INFO - Online: Yes 02:43:27 INFO - Rotation: Supported 02:43:27 INFO - Return code: 0 02:43:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:43:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:43:27 INFO - Python 2.7.3 02:43:27 INFO - Return code: 0 02:43:27 INFO - grabbing minidump binary from tooltool 02:43:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:43:27 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 0x1030e5d30>, '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 0x10300cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030dabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:43:27 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 02:43:27 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 02:43:27 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 02:43:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:43:27 INFO - Return code: 0 02:43:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:43:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:43:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:43:27 INFO - ENV: RUST_BACKTRACE is now 1 02:43:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:43:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:43:27 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/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--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 02:43:27 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/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0324 --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 02:43:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.C56yZiuR98/Render', 02:43:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:43:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:43:27 INFO - 'HOME': '/Users/cltbld', 02:43:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:43:27 INFO - 'LOGNAME': 'cltbld', 02:43:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:43:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:43:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:43:27 INFO - 'MOZ_NO_REMOTE': '1', 02:43:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:43:27 INFO - 'NO_EM_RESTART': '1', 02:43:27 INFO - 'PAGER': '/bin/cat', 02:43:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:43:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:43:27 INFO - 'PWD': '/builds/slave/test', 02:43:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:43:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:43:27 INFO - 'RUST_BACKTRACE': '1', 02:43:27 INFO - 'SHELL': '/bin/bash', 02:43:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HcMOJGFc1k/Listeners', 02:43:27 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 02:43:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:43:27 INFO - 'USER': 'cltbld', 02:43:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:43:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:43:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:43:27 INFO - 'XPC_FLAGS': '0x0', 02:43:27 INFO - 'XPC_SERVICE_NAME': '0', 02:43:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:43:27 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/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--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 02:43:27 INFO - mozversion application_buildid: 20170415004005 02:43:27 INFO - mozversion application_changeset: e53f7f1785ebedaf692d599d70e3a7a9e1bb5f4f 02:43:27 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:43:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:43:27 INFO - mozversion application_name: Firefox 02:43:27 INFO - mozversion application_remotingname: firefox-dev 02:43:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:43:27 INFO - mozversion application_vendor: Mozilla 02:43:27 INFO - mozversion application_version: 54.0a2 02:43:27 INFO - mozversion platform_buildid: 20170415004005 02:43:27 INFO - mozversion platform_changeset: e53f7f1785ebedaf692d599d70e3a7a9e1bb5f4f 02:43:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:43:27 INFO - mozversion platform_version: 54.0a2 02:43:27 INFO - using testdate: 1492249407 02:43:27 INFO - actual date: 1492249407 02:43:27 INFO - starting webserver on 'localhost:49244' 02:43:27 INFO - SUITE-START | Running 2 tests 02:43:27 INFO - TEST-START | dromaeo_css 02:43:27 INFO - operating with platform_type : mac_ 02:43:27 INFO - Initialising browser for dromaeo_css test... 02:43:27 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpa76A4U/profile http://localhost:49244/getInfo.html) 02:43:30 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 02:43:30 INFO - PROCESS | 1655 | colorDepth:24 02:43:30 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 02:43:30 INFO - PROCESS | 1655 | __metrics 02:43:30 INFO - PROCESS | 1655 | 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" 02:43:30 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:43:31 INFO - TEST-INFO | 1655: exit 0 02:43:31 INFO - Browser initialized. 02:43:31 INFO - Running cycle 1/1 for dromaeo_css test... 02:43:31 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpa76A4U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:43:43 INFO - PROCESS | 1659 | RSS: Main: 210423808 02:43:43 INFO - PROCESS | 1659 | 02:46:46 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 02:46:49 INFO - PROCESS | 1659 | RSS: Main: 447930368 02:46:49 INFO - PROCESS | 1659 | 02:48:32 INFO - PROCESS | 1659 | ************************* 02:48:32 INFO - PROCESS | 1659 | A coding exception was thrown and uncaught in a Task. 02:48:32 INFO - PROCESS | 1659 | 02:48:32 ERROR - PROCESS | 1659 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:48:32 INFO - PROCESS | 1659 | Full stack: 02:48:32 INFO - PROCESS | 1659 | ************************* 02:48:32 INFO - PROCESS | 1659 | ************************* 02:48:32 INFO - PROCESS | 1659 | A coding exception was thrown and uncaught in a Task. 02:48:32 INFO - PROCESS | 1659 | 02:48:32 ERROR - PROCESS | 1659 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:48:32 INFO - PROCESS | 1659 | Full stack: 02:48:32 INFO - PROCESS | 1659 | ************************* 02:48:32 INFO - PROCESS | 1659 | ************************* 02:48:32 INFO - PROCESS | 1659 | A coding exception was thrown and uncaught in a Task. 02:48:32 INFO - PROCESS | 1659 | 02:48:32 ERROR - PROCESS | 1659 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:48:32 INFO - PROCESS | 1659 | Full stack: 02:48:32 INFO - PROCESS | 1659 | ************************* 02:48:32 INFO - PROCESS | 1659 | ************************* 02:48:32 INFO - PROCESS | 1659 | A coding exception was thrown and uncaught in a Task. 02:48:32 INFO - PROCESS | 1659 | 02:48:32 ERROR - PROCESS | 1659 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:48:32 INFO - PROCESS | 1659 | Full stack: 02:48:32 INFO - PROCESS | 1659 | ************************* 02:49:52 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 02:49:53 INFO - PROCESS | 1659 | RSS: Main: 351571968 02:49:53 INFO - PROCESS | 1659 | 02:52:55 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 02:52:56 INFO - PROCESS | 1659 | RSS: Main: 325029888 02:52:56 INFO - PROCESS | 1659 | 02:55:59 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 02:55:59 INFO - PROCESS | 1659 | RSS: Main: 328179712 02:55:59 INFO - PROCESS | 1659 | 02:59:03 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 02:59:04 INFO - PROCESS | 1659 | RSS: Main: 326762496 02:59:04 INFO - PROCESS | 1659 | 03:02:07 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 03:02:07 INFO - PROCESS | 1659 | RSS: Main: 461512704 03:02:07 INFO - PROCESS | 1659 | 03:02:07 INFO - PROCESS | 1659 | __start_tp_report 03:02:07 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 03:02:07 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 03:02:07 INFO - PROCESS | 1659 | |i|pagename|runs| 03:02:07 INFO - PROCESS | 1659 | |0;dojo.html;3444.156181735475;3455.153487395588;3459.584849818022;3490.8080055596934;3511.5786105667307;6102.206713127298;6102.938970610293;6108.78619248327;6126.417990290926;6143.692815359231;6087.78692745754;6150.723217455214;6160.353162917885;6176.876462470748;6181.320054793969;5430.456954304577;5458.754356053987;5498.422665620113;5524;5592.7762889484575;5392.703401312946;5409.756560954766;5431.131019036955;5490.929268792587;5583.720813959306;5563.721813909308;5566.4990150886415;5575.776968921238;5579.7489112990015;5581.107022876341;5572.665640061609;5573.442655734435;5579;5581.10702287632;5593.272874526327;4212.304969679999;5365.812196573102;5455.890882182334;5531.170324451365;5541.390447050821;5006.324146240284;6115.694215289218;6121.859371575408;6165.630062196283;6172.043333283348;6428.260750013762;6431.710573024225;6434.099226108348;6435.935640643581;6447.097406363113;6422.229332480086;6434.549581529295;6478.578892371981;6488.902666460007;6490.93509064908;6067.514598832127;6088.11722300264;6131.84670383244;6190.133381326618;6230.59501132425;6674.299198584175;6690.330966903271;6697.832554186184;6706.865862682719;6711.3288671132495;5341.332333458317;6291.087792270096;6305.968470157712;6356;6371.713272902731;6306.936930630727;6330.8417289567205;6457.870842583216;6476.31998640136;6489.967550162313;5394.487523685244;6264.310925798159;6313.40022697843;6391.041343798374;6396.10454536356;6387.968060159669;6388.137601423841;6392;6392.584482008655;6406.199225096863;5142.845714628567;5206.453322401169;5210.557102646242;5215.504527069922;5238.188080847474;4558.544145585415;4582.9770851146195;4625.352450656911;4626.953730462719;4642.280446530793;6134.681512609229;6148.1699970503405;6158;6179.69101544921;6192.380761923861;6529.902051469232;6531.804045878631;6532.346765323429;6542.051402546699;6542.280349161589;5973.313068997121;6005.939940600538;6057.969710151509;6120.357362476965;6133.662648554283;5656.208130861683;5701.2588363513305;5773.191753154561;5817.883642327214;5821.476067153976;6766.661666916536;6774;6785.558938668971;6789.422899053537;6791.558548694319;6420.1974753155855;6522.6412547308455;6527.02094685801;6540.084388185563;6558.311377305505;6454.838629034311;6507.642079685663;6549.4760419165395;6762.391384775394;6782.491313151435;6310.84222894413;6340.778072767431;6363.5863668861375;6364.554481186273;6378.6810659467765;5141.897162056813;6390.329015453311;6433.453156481752;6467.385598368148;6480.837979050561;6302.464290535172;6331.715072821642;6335.208098987627;6344;6363.077353783768;6267.2165979252595;6319.083732858801;6351.523635727432;6353.841153971188;6366.172397588284;6196.8140955769595;6199.659018754011;6201.658908759881;6209.658468784261;6223.097650840693;6251.312355640966;6266.624002559861;6291.811245662454;6305.495560355253;6340.682965851781;5274.68351898872;6350.491960643045;6358.713857876314;6359.459445947146;6379.011253255753 03:02:07 INFO - PROCESS | 1659 | |1;ext.html;787.9487833290835;809.2554849538424;868.887044684191;881.4667126388539;884.893812742471;38145.427818582706;38575.80712096439;40578.188436231256;40699.59300406995;40740.38889416661;39410.60589394105;39622.20755584894;39717.60282397175;39722.404163937565;39750.80124599373;7259.41924646027;9036.960749513808;9168.587413566373;9185.35702500822;9186.311026672993;7310.707571697126;10866.34801911884;11059.170562207826;11188.888111118866;11247.325160490356;11063.446827658585;11085.667429977073;11098.889011109906;11104.722381940435;11143.164262680291;10968.725781855437;11017.063549598293;11040.558377664884;11040.779184416348;11043.944780276048;10697.679069627924;10738.677839664822;10743.570257189704;10744.892551074467;10750.78498430027;37390.43914341287;37748.24503509914;37771;37787.62212377869;37794.622053779385;3748.9437658435145;3784.829682664287;3786.772793632391;3809.695224382043;3817.9045523862123;24587.63118553223;25556.10553630614;26164.60753088686;26338;26707.59938600903;2985.358147998191;3049;3091.8674999500945;3120.5319202119636;3126.702963218491;363268;365144;365843;367911;368558;42176.945576360224;42846.785766071596;42894;42956.57043429526;43864.561354386045;45050.87372815706;45132.3230151548;45148.77425612851;45168.3224751629;45283.86790330202;13102.279374634487;13502.257375844227;13508;13510.256935868425;13516.5269215577;11668.591599293984;11690.123240756977;11700.414979251003;11713.297202167898;11720.413979301002;11461.770764584662;11497.367644779617;11585.710357241136;11626;11645.883541164649;5226.9546090782;5496.7526461309335;5602.607817452739;5608.551315894719;5617.634853734494;1043.5617040842865;1061.2093989977516;1106.1261603333276;1142.149098921309;1218.981715274254;25173.867175977124;25250.873745631154;25787.355316117246;25789.871050644626;25824.74175258261;1225.2723659639864;1244.9875501245053;1259.1563652352945;1276.183242724657;1301.5965050834272;12974.545890893962;13254.801177982337;13318.733625327633;13362.59912202616;13442.991775616867;473810;496831;497493;497574;498324;52702.73648631732;53073.2039019415;53226.73386633042;53251;53274.668133296975;1402.4530433131088;1461.8099647046029;1464.5899148238511;1468.8237411510688;1469.353484466831;23379.298621041395;28209.43581128407;29681.40637187201;30353.69646303509;30402.63188156494;50492.4852254433;50627;50698.493015069376;50837.491625083276;50943.23585146152;446655;451444;451794;452871;453025;29201.7079829199;29827.552586710783;30755.53866692046;31024.53463198099;31357.216069598442;36885.81557092197;36934.63065369419;37985.81007094947;38068.04829879275;38305.4254186193;26987;27044.729552704222;27161.728382716712;27723.44553108967;28056.158315250574;26419.471610568067;26827.32931676724;26909;27200.319991999568;27956.86021569879;30664.693353067076;31929.6807031936;31962.840185798923;32058.519122212718;32078.358432830744;24977;25032.249032529053;25057.248282551554;27381.45237095287;27660.446791064467;21071;21794.237201697866;21920.93106965058;22119.66820497726;22263.44341391478 03:02:07 INFO - PROCESS | 1659 | |2;jquery.html;342.6539195412636;394.33160792456795;411.21458015190984;420.28761249681787;443.0629219201388;12714.722413963127;14407.279636018196;16005.839941600596;20321.390358289238;20733.585328293426;18309.633807323844;22290.554188916212;22318.884055797225;22665;22922.65616015761;6371.203599550056;6442.581232219914;6531.0856480092825;6636.701348439307;6709.832254193635;4154.210699966997;6527.184101987252;6662.400383965442;6665.70004349803;6672.866542669144;4331.056688939269;4419.845305414311;4531.3203019547;7665.616719164047;7666.38668906489;7386.741464048786;7622.428317876126;7641.617919104023;7663.885041724378;7665.770026899147;7513.737019204302;7515.8496830063095;7516.135644400854;7517.887231691529;7525.134609519921;27177.45645087087;27403.862980685168;27477.31306717328;27563.586546201623;27835.860820695765;2886.494863398918;2889.970248850283;2898.231968528342;2906.912792616225;2928.180109569324;16661.83338166627;16823.579410514714;17120;17314;17783.911080444643;1833.2025568877582;1873.6721073812164;1875.2311552263507;1894.2991093295568;1912.7130930360418;133995;136469;138556.53608659763;138620.6137938618;140420;21434.78565214359;21853.67219491709;21961.01175447109;22025;22127.44681382978;34577.82711086429;35310.29379412397;35505.46741798875;35555.466668;35622.46566301508;10691.129052415796;11018.889811101786;11221.326720396803;11273.887261127284;11443.397924290606;9043.59303831329;9055.59249833759;9070.455772653662;9233.353665243369;9247.445153290824;10626.574937002588;10782.46087695612;10796.89203107959;10906.236563440483;10931.125509959184;3546.3793836078585;3758.571742737742;4041.474608300961;4106.556598823695;4144.544614539383;784.9372050235968;876.0144837058309;896.3635818568758;910.6767097680361;925.121134921827;22240.332790016324;22886.084556617883;22975.88512057429;23148.95829687668;23225.88387058054;844.2190973349627;930.7859192385789;931.8322701913721;955.5126885288553;957.9664711735056;11474.311541307548;11825;12200.877991220152;12202.694932626755;12205.145639805305;724085;727280;734266;741059;756757;131978;133669.66330336768;153436;155440.22279888528;157040.00556876144;1205.8070708686569;1220.969475763113;1278.4630455208835;1283.826683397748;1284.2551320234243;19769.802301976797;20079.698804518237;20442.69335959932;20455.488612784204;20524.589508209454;63764.3623563777;63917.680411599504;64089.35910641021;64557.48097633121;65164;82431.58784206041;82672.58663706643;95101;95739.52130239583;98430.5078474603;7157.320054594805;10382.532786024496;10629.734256643645;11330.206885517935;11411.942940285246;12863;12875.806862896876;13192.670183245496;13226.933865330997;13279.535216267384;9230.861537076815;10968.122550195805;11050.889491105308;11320.26418282809;11325;10337.689869303633;10338.896611034093;10524.421156836448;10598.417087059977;10776.622818201326;11093.667189984313;11411.657650270505;11480.311181329147;11504.597339093092;11517.02105321057;9996.900030999888;11350.716232094264;11364.715882102682;11383.772324553296;11806;9172.6789562365;11041;11332.320060796379;11415.60045398407;11644 03:02:07 INFO - PROCESS | 1659 | |3;mootools.html;1476.7194233095718;1514.280716659587;1535.5316628428325;1541.6531280461893;1569.082086979117;10857.83713244301;11386.373749443797;11389.316641001546;11399.772004559904;11422.42887855607;10652.893471065287;11069.335839849615;11207.21549491536;11212.271202371861;11227;2356.929292121239;2401.0275838285525;2409.409694624819;2410.108259943823;2411.397150712322;2314.745378008418;2393.74865639108;2394.7006624171977;2404.13451157584;2407.350015495815;2308.5267520158413;2369.407648087978;2371.4071482129466;2390.2112302939986;2394.6408038794148;2291.690621766057;2363.550925324183;2370.028288401747;2371.0989823866994;2387.4866903615807;2352.4118970257437;2379.6668466414717;2385.3440303916386;2387.997041242665;2390.9163179288817;9141.634334626608;11001.064909482704;11007.06439952597;11025;11041.061509771678;5492.642978206443;5660.716964151817;5672.06410942194;5684.8294551163535;5699.829005129853;3516.296740651854;3564.8395822188063;3605.080704420356;3638.308721342936;3647.7993710345922;1158.2066284594996;1159.594142050284;1198.922070065443;1200.0639501189087;1211.4669545400084;13711.931440342834;14084.225367604779;14361.999999999896;15421.306041228172;15551.066935983765;4504.144212599661;4659.370984917061;4659.95340046602;4661.347411362344;4662.813487460464;11771.941140294244;11783;11791.29252244868;11794.410279485992;11840.703982400337;4847.587955023862;5020.849374518771;5415.783368665288;5590.97204513983;5657.60396772222;5089.134847075932;5212.087884620177;5281.313429254173;5301.284326615934;5304.549113325411;3761.8495260189834;3768.189839184534;3848.3457812171996;3878.670313023368;3880.7387599030426;749.4079677055065;972.3728195313982;995.1690338567232;998.1116806042628;1000.8448690452846;771.7684694591712;788.3614272439228;807.2371608829717;809.9554524501091;822.3791037766448;5156.484351564814;5498.95519851235;6176.969115154486;6525.445337146396;6646.169228846395;979.9510024498746;1012.71643939697;1019.3170575714289;1019.4138370437029;1025.1593693171528;4143.502779666399;4158.272302347077;4161.3549899765585;4289.442372491556;4294.549072347359;12235.143539952118;13888.166709997433;13919.234442105579;13928.86071139296;13941.86058139426;12198.57304994321;13535.984801139954;13808.861911380758;13828.93085534566;13834.377453014638;10128.291019628556;10269.024442677934;10293.330933489298;10298.17614590831;10301.896981030093;6317.399847014526;6372.330905254881;6504.349565043458;6527.738890444329;6592.307807680317;8941.508217047865;10731.034206921415;12081;12330.691732706755;12369.381530923598;12537.435815388148;12539.749205015665;12566.423883103758;12571.87428125707;12596.307203103917;8868.778280543038;9166.724998250063;9383.859242111237;9391.953040234755;9407.717768466957;10174.542145603318;10249.538770755484;10313.381197127896;10373.636922707668;10425.478726063515;8725.696360481701;8807.207351338411;8873.822523549365;8929.464232146092;8941;8594.957025214833;8597.914020859962;8666.739997800076;8729.170728780755;8743.125687431206;7682.308592226726;7683.154852966058;7794.298513133618;7835.177306382976;7853.332466740391;5763.193152958505;5809.331926828469;5828.067509198508;5990.7903223386975;6003;5848.853778655568;5874.412558744262;5908.763649453974;5976.61152025117;6024.216851809237 03:02:07 INFO - PROCESS | 1659 | |4;prototype.html;354.04408098135036;367.1006035213727;369.5842177550256;378.9602091780361;379.65034303052647;4989.700617962925;5019.247112933057;5021.29701841742;5061.595072394209;5100.2859599655985;4025.6449976763556;4417.403650507181;4873.415190177184;4981.352424184851;4991.552449789557;3962.9801850990702;4035.01142220155;4179.749215047092;4183.414321994931;4185.162967406515;4142.337226043834;4144.730592511492;4151.7924103794685;4181.58184181581;4192.685548583853;4012.8996775080564;4146.896327591804;4193.427464700737;4233.280342341794;4249.723767955088;3158.578639612184;3956.7823769692654;4140.358244472111;4168.478505345557;4233.391311301716;3441.741869359783;3455.9308813823836;3469.340825243196;3786.690119027391;3828;4260.1445691649105;4277.764722940237;4387.890302742425;4605.930911036337;4836.056968891067;3953.9522026662958;4773.4510531289;4789.4971028042255;4835.468098509161;4914.483979182206;4632.583067523905;5007.37407824022;5060.544550990428;5307.442718514539;5325.360956685223;3785.9432108518395;4208.516020657633;4268.713090920005;4280.602862397037;4292.484901811803;5312.973435132838;5417.59368047394;5430.56555475561;5662.517551705712;5714.485696287354;3324.5345651608795;3411.965880341165;3934.7245692801803;4079.8776036718946;4136.958630413717;3957.584453632385;4429.424174857248;4441.68908176431;4448.844290449819;4476.328550717419;4754.358161648133;4790.808367665254;4792.784324705397;4824.927626085541;4868.63678170113;4887.682300650446;5104.591632669378;5113.795448182031;5159.819406320761;5210.6613070150315;1443.588577255477;1467.1783801071433;1480.6668499587722;1519.7796319533827;1534.8081489813774;1082.637316498966;1142.9427031343548;1147.1912301827192;1150.8561429821273;1161.814109742437;4526.162659907927;4943.429416703366;4943.752812359367;4984.675996060245;4996.875078123076;5340.172273297645;5387.572293342268;5394.271773310631;5471.042567550663;5544.722763861791;3855.8960037169786;4433.889152771207;4575.794089265991;4769.523047695203;4994.4755800640405;2492.9002839886202;2862.957055644167;2879.9280017999718;2891.6842836509336;2906.6802651708294;5526.889462210734;5721.456461636138;5730.7421166047625;5746.827595172152;5750;4134.338505839051;5338.492843179892;5608.822147349021;5642.971785141048;5655.575831812548;5139.974300128551;5166.734150133241;5278.308062888032;5427;5560.638558493685;4851.75741212945;4955.4053513577155;5220.530152286313;5465.07093794048;5514.751836167462;4766.594839438687;4942.3080768693135;4979.302897594267;5440.626811376814;5457.962987032452;5114.33513643224;5161.535461808456;5612.793249451388;5716.626780512994;5741.770329186786;3574.7121842020606;3865.9226815464094;4347.413099231563;4373.628241599499;4380.882874867016;4473.821047158077;4898.804047838189;5289.047971365191;5555.611107222456;5568.415316391721;4929.408470983505;5014.749262536931;5124.232914226276;5337.332833395825;5338.466153384786;4233.428487154195;5057.988402319477;5137.095807186491;5378.784848606012;5402.621816472809;4820.204666230033;4952.752362381794;5156.226566015128;5276.208568714724;5302.2046692996355;4876.56110950002;5127.641065125555;5324.512444348958;5342.866428339323;5416.593755468434;4238.19474299882;4681.7659117044695;5194.2341802193105;5222.563794956417;5243.449437808975 03:02:07 INFO - PROCESS | 1659 | |5;yui.html;702.704863957138;729.7044696897758;773.5474747272846;820.9532845621832;830.5224495914848;15459.99510031847;16191.919040404811;16248.106354150517;16256.51230463085;16259.024458532496;14593.34329955152;14655.413783448676;14671.486497972573;14763.704725905502;14787.630309242248;798.4849771897123;914.8261830252264;915.6383228624723;937.6296362936636;940.7507010642186;935.1350001248852;939.1500691873865;952.9761755956122;960.2462067277189;961.6778379242927;986.3982970388057;986.9407835529855;987.1214618989105;988.2044953812178;990.267202270322;978.8042391521653;979.4417182206074;981.6760469045233;981.8330883749744;983.6163896080534;727.82532192274;728.04262394951;728.1698863295846;728.9963550182268;729.9343059124704;16267.91866040674;16461.58846028859;16702.49892503227;16720.16399180036;16872.409465668767;14118;14314.498992535211;14366.56900292993;14373.640658983504;14392.280385980765;11774.61634135581;11806.527738890434;11893.643190704293;11919.82120268188;12359.196652217577;3603.0992251937014;3660.5973342932257;3686.207465394953;3760.041189496661;3846.288436639231;117832.41083794612;118519;118646.40676796647;120731;121517;10829.133669306437;11005.339679619248;11130.33218006906;11145.888541114648;11273.267237629529;22621.20825771123;22621.88689056544;22629.43426414353;22678.659820102712;22682;7389.8152546186775;7432.851342973218;7680.615969201517;7964.761057168294;7975.202479752094;4228.6617070634275;4371.267957526546;4406.691531592822;4424.203643344164;4562.749048802348;3536.3634545781492;3555.5377800886235;3636.4363523653874;3637.181634132299;3644.526211592529;617.8084793713873;622.9252489701174;624.1542709628479;630.2153818496001;638.6583177999748;660.6432526435767;675.9797206083726;679.6542845166598;682.3006418421159;704.9189343225493;9951.402915825074;9990.200783937267;10159.187265018782;10422.478876056166;10672.359658420519;651.7979426377838;680.8229860236374;684.784507499183;691.2638040486866;712.0920825946959;4784.832530861403;5063.620228482879;5080.872978175576;5085.1355269604255;5091.847244582668;81808;89713.55143224243;95758;97374.51312743391;101700;82949.75575366375;84477;84605.73091403634;86473.56763216143;91590.54204728933;79054.41891162144;79511.80732288904;79737.60131199307;81403.37193256101;82166.58916705378;9243;10908.618198363336;11115.333080015225;11234.101271898391;11263.887361126284;49554.008919822125;50457.49542504528;50524.72638731339;50732.74979652674;51431.74284128555;63690.04464933123;63878.68060659853;64206;64880;66222;12299.631011069681;12568.30874301922;12739.6178114653;12754.936225318814;12885.904698100767;13492.730145397234;14256.928715356771;14403.279836007949;14686.926565367105;15155.92422037927;12830.358482075671;13688.589342319747;13695.726085478434;14011.859881401464;14179.55889997269;12785.232886026868;13518;14051.437942482185;14125.646858828612;14297.35661895238;12795.36023198855;12877.935610321889;13349.332533373099;13481.056326057082;13589.728205436033;9474.810503789748;9953.452560109263;10145.03622155894;10173;10324.277300589187;7733.2461199977015;9515.381500202742;9776.608935642495;9800;9826.361286516354 03:02:07 INFO - PROCESS | 1659 | __end_tp_report 03:02:07 INFO - PROCESS | 1659 | __start_cc_report 03:02:07 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2964 03:02:07 INFO - PROCESS | 1659 | __end_cc_report 03:02:07 INFO - PROCESS | 1659 | __startTimestamp1492250527770__endTimestamp 03:02:07 INFO - PROCESS | 1659 | 03:02:07 INFO - PROCESS | 1659 | ------- Summary: start ------- 03:02:07 INFO - PROCESS | 1659 | Number of tests: 6 03:02:07 INFO - PROCESS | 1659 | 03:02:07 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5996.70 Median:6223.10 stddev:681.32 (10.9%) stddev-sans-first:653.32 03:02:07 INFO - PROCESS | 1659 | Values: 3444.2 3455.2 3459.6 3490.8 3511.6 6102.2 6102.9 6108.8 6126.4 6143.7 6087.8 6150.7 6160.4 6176.9 6181.3 5430.5 5458.8 5498.4 5524.0 5592.8 5392.7 5409.8 5431.1 5490.9 5583.7 5563.7 5566.5 5575.8 5579.7 5581.1 5572.7 5573.4 5579.0 5581.1 5593.3 4212.3 5365.8 5455.9 5531.2 5541.4 5006.3 6115.7 6121.9 6165.6 6172.0 6428.3 6431.7 6434.1 6435.9 6447.1 6422.2 6434.5 6478.6 6488.9 6490.9 6067.5 6088.1 6131.8 6190.1 6230.6 6674.3 6690.3 6697.8 6706.9 6711.3 5341.3 6291.1 6306.0 6356.0 6371.7 6306.9 6330.8 6457.9 6476.3 6490.0 5394.5 6264.3 6313.4 6391.0 6396.1 6388.0 6388.1 6392.0 6392.6 6406.2 5142.8 5206.5 5210.6 5215.5 5238.2 4558.5 4583.0 4625.4 4627.0 4642.3 6134.7 6148.2 6158.0 6179.7 6192.4 6529.9 6531.8 6532.3 6542.1 6542.3 5973.3 6005.9 6058.0 6120.4 6133.7 5656.2 5701.3 5773.2 5817.9 5821.5 6766.7 6774.0 6785.6 6789.4 6791.6 6420.2 6522.6 6527.0 6540.1 6558.3 6454.8 6507.6 6549.5 6762.4 6782.5 6310.8 6340.8 6363.6 6364.6 6378.7 5141.9 6390.3 6433.5 6467.4 6480.8 6302.5 6331.7 6335.2 6344.0 6363.1 6267.2 6319.1 6351.5 6353.8 6366.2 6196.8 6199.7 6201.7 6209.7 6223.1 6251.3 6266.6 6291.8 6305.5 6340.7 5274.7 6350.5 6358.7 6359.5 6379.0 03:02:07 INFO - PROCESS | 1659 | 03:02:07 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:56112.82 Median:30378.16 stddev:117042.63 (385.3%) stddev-sans-first:117297.25 03:02:07 INFO - PROCESS | 1659 | Values: 787.9 809.3 868.9 881.5 884.9 38145.4 38575.8 40578.2 40699.6 40740.4 39410.6 39622.2 39717.6 39722.4 39750.8 7259.4 9037.0 9168.6 9185.4 9186.3 7310.7 10866.3 11059.2 11188.9 11247.3 11063.4 11085.7 11098.9 11104.7 11143.2 10968.7 11017.1 11040.6 11040.8 11043.9 10697.7 10738.7 10743.6 10744.9 10750.8 37390.4 37748.2 37771.0 37787.6 37794.6 3748.9 3784.8 3786.8 3809.7 3817.9 24587.6 25556.1 26164.6 26338.0 26707.6 2985.4 3049.0 3091.9 3120.5 3126.7 363268.0 365144.0 365843.0 367911.0 368558.0 42176.9 42846.8 42894.0 42956.6 43864.6 45050.9 45132.3 45148.8 45168.3 45283.9 13102.3 13502.3 13508.0 13510.3 13516.5 11668.6 11690.1 11700.4 11713.3 11720.4 11461.8 11497.4 11585.7 11626.0 11645.9 5227.0 5496.8 5602.6 5608.6 5617.6 1043.6 1061.2 1106.1 1142.1 1219.0 25173.9 25250.9 25787.4 25789.9 25824.7 1225.3 1245.0 1259.2 1276.2 1301.6 12974.5 13254.8 13318.7 13362.6 13443.0 473810.0 496831.0 497493.0 497574.0 498324.0 52702.7 53073.2 53226.7 53251.0 53274.7 1402.5 1461.8 1464.6 1468.8 1469.4 23379.3 28209.4 29681.4 30353.7 30402.6 50492.5 50627.0 50698.5 50837.5 50943.2 446655.0 451444.0 451794.0 452871.0 453025.0 29201.7 29827.6 30755.5 31024.5 31357.2 36885.8 36934.6 37985.8 38068.0 38305.4 26987.0 27044.7 27161.7 27723.4 28056.2 26419.5 26827.3 26909.0 27200.3 27956.9 30664.7 31929.7 31962.8 32058.5 32078.4 24977.0 25032.2 25057.2 27381.5 27660.4 21071.0 21794.2 21920.9 22119.7 22263.4 03:02:07 INFO - PROCESS | 1659 | 03:02:07 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:42391.40 Median:22775.54 stddev:122491.99 (537.8%) stddev-sans-first:122794.92 03:02:07 INFO - PROCESS | 1659 | Values: 342.7 394.3 411.2 420.3 443.1 12714.7 14407.3 16005.8 20321.4 20733.6 18309.6 22290.6 22318.9 22665.0 22922.7 6371.2 6442.6 6531.1 6636.7 6709.8 4154.2 6527.2 6662.4 6665.7 6672.9 4331.1 4419.8 4531.3 7665.6 7666.4 7386.7 7622.4 7641.6 7663.9 7665.8 7513.7 7515.8 7516.1 7517.9 7525.1 27177.5 27403.9 27477.3 27563.6 27835.9 2886.5 2890.0 2898.2 2906.9 2928.2 16661.8 16823.6 17120.0 17314.0 17783.9 1833.2 1873.7 1875.2 1894.3 1912.7 133995.0 136469.0 138556.5 138620.6 140420.0 21434.8 21853.7 21961.0 22025.0 22127.4 34577.8 35310.3 35505.5 35555.5 35622.5 10691.1 11018.9 11221.3 11273.9 11443.4 9043.6 9055.6 9070.5 9233.4 9247.4 10626.6 10782.5 10796.9 10906.2 10931.1 3546.4 3758.6 4041.5 4106.6 4144.5 784.9 876.0 896.4 910.7 925.1 22240.3 22886.1 22975.9 23149.0 23225.9 844.2 930.8 931.8 955.5 958.0 11474.3 11825.0 12200.9 12202.7 12205.1 724085.0 727280.0 734266.0 741059.0 756757.0 131978.0 133669.7 153436.0 155440.2 157040.0 1205.8 1221.0 1278.5 1283.8 1284.3 19769.8 20079.7 20442.7 20455.5 20524.6 63764.4 63917.7 64089.4 64557.5 65164.0 82431.6 82672.6 95101.0 95739.5 98430.5 7157.3 10382.5 10629.7 11330.2 11411.9 12863.0 12875.8 13192.7 13226.9 13279.5 9230.9 10968.1 11050.9 11320.3 11325.0 10337.7 10338.9 10524.4 10598.4 10776.6 11093.7 11411.7 11480.3 11504.6 11517.0 9996.9 11350.7 11364.7 11383.8 11806.0 9172.7 11041.0 11332.3 11415.6 11644.0 03:02:07 INFO - PROCESS | 1659 | 03:02:07 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:6652.10 Median:2409.76 stddev:4179.05 (173.4%) stddev-sans-first:4172.68 03:02:07 INFO - PROCESS | 1659 | Values: 1476.7 1514.3 1535.5 1541.7 1569.1 10857.8 11386.4 11389.3 11399.8 11422.4 10652.9 11069.3 11207.2 11212.3 11227.0 2356.9 2401.0 2409.4 2410.1 2411.4 2314.7 2393.7 2394.7 2404.1 2407.4 2308.5 2369.4 2371.4 2390.2 2394.6 2291.7 2363.6 2370.0 2371.1 2387.5 2352.4 2379.7 2385.3 2388.0 2390.9 9141.6 11001.1 11007.1 11025.0 11041.1 5492.6 5660.7 5672.1 5684.8 5699.8 3516.3 3564.8 3605.1 3638.3 3647.8 1158.2 1159.6 1198.9 1200.1 1211.5 13711.9 14084.2 14362.0 15421.3 15551.1 4504.1 4659.4 4660.0 4661.3 4662.8 11771.9 11783.0 11791.3 11794.4 11840.7 4847.6 5020.8 5415.8 5591.0 5657.6 5089.1 5212.1 5281.3 5301.3 5304.5 3761.8 3768.2 3848.3 3878.7 3880.7 749.4 972.4 995.2 998.1 1000.8 771.8 788.4 807.2 810.0 822.4 5156.5 5499.0 6177.0 6525.4 6646.2 980.0 1012.7 1019.3 1019.4 1025.2 4143.5 4158.3 4161.4 4289.4 4294.5 12235.1 13888.2 13919.2 13928.9 13941.9 12198.6 13536.0 13808.9 13828.9 13834.4 10128.3 10269.0 10293.3 10298.2 10301.9 6317.4 6372.3 6504.3 6527.7 6592.3 8941.5 10731.0 12081.0 12330.7 12369.4 12537.4 12539.7 12566.4 12571.9 12596.3 8868.8 9166.7 9383.9 9392.0 9407.7 10174.5 10249.5 10313.4 10373.6 10425.5 8725.7 8807.2 8873.8 8929.5 8941.0 8595.0 8597.9 8666.7 8729.2 8743.1 7682.3 7683.2 7794.3 7835.2 7853.3 5763.2 5809.3 5828.1 5990.8 6003.0 5848.9 5874.4 5908.8 5976.6 6024.2 03:02:07 INFO - PROCESS | 1659 | 03:02:07 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4415.64 Median:4830.20 stddev:1234.39 (25.6%) stddev-sans-first:1199.61 03:02:07 INFO - PROCESS | 1659 | Values: 354.0 367.1 369.6 379.0 379.7 4989.7 5019.2 5021.3 5061.6 5100.3 4025.6 4417.4 4873.4 4981.4 4991.6 3963.0 4035.0 4179.7 4183.4 4185.2 4142.3 4144.7 4151.8 4181.6 4192.7 4012.9 4146.9 4193.4 4233.3 4249.7 3158.6 3956.8 4140.4 4168.5 4233.4 3441.7 3455.9 3469.3 3786.7 3828.0 4260.1 4277.8 4387.9 4605.9 4836.1 3954.0 4773.5 4789.5 4835.5 4914.5 4632.6 5007.4 5060.5 5307.4 5325.4 3785.9 4208.5 4268.7 4280.6 4292.5 5313.0 5417.6 5430.6 5662.5 5714.5 3324.5 3412.0 3934.7 4079.9 4137.0 3957.6 4429.4 4441.7 4448.8 4476.3 4754.4 4790.8 4792.8 4824.9 4868.6 4887.7 5104.6 5113.8 5159.8 5210.7 1443.6 1467.2 1480.7 1519.8 1534.8 1082.6 1142.9 1147.2 1150.9 1161.8 4526.2 4943.4 4943.8 4984.7 4996.9 5340.2 5387.6 5394.3 5471.0 5544.7 3855.9 4433.9 4575.8 4769.5 4994.5 2492.9 2863.0 2879.9 2891.7 2906.7 5526.9 5721.5 5730.7 5746.8 5750.0 4134.3 5338.5 5608.8 5643.0 5655.6 5140.0 5166.7 5278.3 5427.0 5560.6 4851.8 4955.4 5220.5 5465.1 5514.8 4766.6 4942.3 4979.3 5440.6 5458.0 5114.3 5161.5 5612.8 5716.6 5741.8 3574.7 3865.9 4347.4 4373.6 4380.9 4473.8 4898.8 5289.0 5555.6 5568.4 4929.4 5014.7 5124.2 5337.3 5338.5 4233.4 5058.0 5137.1 5378.8 5402.6 4820.2 4952.8 5156.2 5276.2 5302.2 4876.6 5127.6 5324.5 5342.9 5416.6 4238.2 4681.8 5194.2 5222.6 5243.4 03:02:07 INFO - PROCESS | 1659 | 03:02:07 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:20593.71 Median:4673.79 stddev:29683.60 (635.1%) stddev-sans-first:29729.30 03:02:07 INFO - PROCESS | 1659 | Values: 702.7 729.7 773.5 821.0 830.5 15460.0 16191.9 16248.1 16256.5 16259.0 14593.3 14655.4 14671.5 14763.7 14787.6 798.5 914.8 915.6 937.6 940.8 935.1 939.2 953.0 960.2 961.7 986.4 986.9 987.1 988.2 990.3 978.8 979.4 981.7 981.8 983.6 727.8 728.0 728.2 729.0 729.9 16267.9 16461.6 16702.5 16720.2 16872.4 14118.0 14314.5 14366.6 14373.6 14392.3 11774.6 11806.5 11893.6 11919.8 12359.2 3603.1 3660.6 3686.2 3760.0 3846.3 117832.4 118519.0 118646.4 120731.0 121517.0 10829.1 11005.3 11130.3 11145.9 11273.3 22621.2 22621.9 22629.4 22678.7 22682.0 7389.8 7432.9 7680.6 7964.8 7975.2 4228.7 4371.3 4406.7 4424.2 4562.7 3536.4 3555.5 3636.4 3637.2 3644.5 617.8 622.9 624.2 630.2 638.7 660.6 676.0 679.7 682.3 704.9 9951.4 9990.2 10159.2 10422.5 10672.4 651.8 680.8 684.8 691.3 712.1 4784.8 5063.6 5080.9 5085.1 5091.8 81808.0 89713.6 95758.0 97374.5 101700.0 82949.8 84477.0 84605.7 86473.6 91590.5 79054.4 79511.8 79737.6 81403.4 82166.6 9243.0 10908.6 11115.3 11234.1 11263.9 49554.0 50457.5 50524.7 50732.7 51431.7 63690.0 63878.7 64206.0 64880.0 66222.0 12299.6 12568.3 12739.6 12754.9 12885.9 13492.7 14256.9 14403.3 14686.9 15155.9 12830.4 13688.6 13695.7 14011.9 14179.6 12785.2 13518.0 14051.4 14125.6 14297.4 12795.4 12877.9 13349.3 13481.1 13589.7 9474.8 9953.5 10145.0 10173.0 10324.3 7733.2 9515.4 9776.6 9800.0 9826.4 03:02:07 INFO - PROCESS | 1659 | -------- Summary: end -------- 03:02:07 INFO - PROCESS | 1659 | 03:02:08 INFO - TEST-INFO | 1659: exit 0 03:02:08 INFO - TEST-OK | dromaeo_css | took 1120700ms 03:02:08 INFO - TEST-START | kraken 03:02:08 INFO - operating with platform_type : mac_ 03:02:08 INFO - Initialising browser for kraken test... 03:02:08 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpBNapag/profile http://localhost:49244/getInfo.html) 03:02:10 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 03:02:10 INFO - PROCESS | 1665 | colorDepth:24 03:02:10 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 03:02:10 INFO - PROCESS | 1665 | __metrics 03:02:10 INFO - PROCESS | 1665 | 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" 03:02:10 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:02:11 INFO - TEST-INFO | 1665: exit 0 03:02:11 INFO - Browser initialized. 03:02:11 INFO - Running cycle 1/1 for kraken test... 03:02:11 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpBNapag/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:02:24 INFO - PROCESS | 1667 | RSS: Main: 210702336 03:02:24 INFO - PROCESS | 1667 | 03:02:26 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 03:02:27 INFO - PROCESS | 1667 | RSS: Main: 263917568 03:02:27 INFO - PROCESS | 1667 | 03:02:30 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 03:02:30 INFO - PROCESS | 1667 | RSS: Main: 277458944 03:02:30 INFO - PROCESS | 1667 | 03:02:34 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 03:02:34 INFO - PROCESS | 1667 | RSS: Main: 373882880 03:02:34 INFO - PROCESS | 1667 | 03:02:36 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 03:02:37 INFO - PROCESS | 1667 | RSS: Main: 276373504 03:02:37 INFO - PROCESS | 1667 | 03:02:39 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:02:40 INFO - PROCESS | 1667 | RSS: Main: 279490560 03:02:40 INFO - PROCESS | 1667 | 03:02:43 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 03:02:43 INFO - PROCESS | 1667 | RSS: Main: 319979520 03:02:43 INFO - PROCESS | 1667 | 03:02:46 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 03:02:47 INFO - PROCESS | 1667 | RSS: Main: 320225280 03:02:47 INFO - PROCESS | 1667 | 03:02:50 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 03:02:50 INFO - PROCESS | 1667 | RSS: Main: 316928000 03:02:50 INFO - PROCESS | 1667 | 03:02:52 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:02:53 INFO - PROCESS | 1667 | RSS: Main: 295628800 03:02:53 INFO - PROCESS | 1667 | 03:02:54 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:02:55 INFO - PROCESS | 1667 | RSS: Main: 300478464 03:02:55 INFO - PROCESS | 1667 | 03:02:57 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:02:58 INFO - PROCESS | 1667 | RSS: Main: 303108096 03:02:58 INFO - PROCESS | 1667 | 03:03:01 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:03:02 INFO - PROCESS | 1667 | RSS: Main: 318894080 03:03:02 INFO - PROCESS | 1667 | 03:03:04 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:03:05 INFO - PROCESS | 1667 | RSS: Main: 312496128 03:03:05 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 03:03:07 INFO - PROCESS | 1667 | RSS: Main: 356741120 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | __start_tp_report 03:03:07 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 03:03:07 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 03:03:07 INFO - PROCESS | 1667 | |i|pagename|runs| 03:03:07 INFO - PROCESS | 1667 | |0;ai-astar;105;116;115;116;116;116;115;116;120;172 03:03:07 INFO - PROCESS | 1667 | |1;audio-beat-detection;144;109;107;144;112;108;110;108;133;110 03:03:07 INFO - PROCESS | 1667 | |2;audio-dft;183;185;184;188;184;183;184;182;185;186 03:03:07 INFO - PROCESS | 1667 | |3;audio-fft;82;61;72;91;64;61;62;59;77;61 03:03:07 INFO - PROCESS | 1667 | |4;audio-oscillator;85;70;89;69;73;78;113;99;80;83 03:03:07 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;95;89;94;94;90;94;93;87;94;89 03:03:07 INFO - PROCESS | 1667 | |6;imaging-darkroom;96;97;98;99;96;96;97;99;97;97 03:03:07 INFO - PROCESS | 1667 | |7;imaging-desaturate;84;84;86;83;83;84;82;83;83;86 03:03:07 INFO - PROCESS | 1667 | |8;json-parse-financial;63;61;60;61;63;64;64;65;63;66 03:03:07 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;58;72;64;63;60;58;58;59;58;59 03:03:07 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;85;82;85;82;82;82;80;80;83;86 03:03:07 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;137;136;137;126;142;144;144;132;208;171 03:03:07 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;166;167;164;164;166;165;162;170;164;165 03:03:07 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;65;66;67;65;66;60;74;85;75;79 03:03:07 INFO - PROCESS | 1667 | __end_tp_report 03:03:07 INFO - PROCESS | 1667 | __start_cc_report 03:03:07 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,2135 03:03:07 INFO - PROCESS | 1667 | __end_cc_report 03:03:07 INFO - PROCESS | 1667 | __startTimestamp1492250587556__endTimestamp 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | ------- Summary: start ------- 03:03:07 INFO - PROCESS | 1667 | Number of tests: 14 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:120.70 Median:116.00 stddev:18.42 (15.9%) stddev-sans-first:18.64 03:03:07 INFO - PROCESS | 1667 | Values: 105.0 116.0 115.0 116.0 116.0 116.0 115.0 116.0 120.0 172.0 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:118.50 Median:111.00 stddev:15.42 (13.9%) stddev-sans-first:13.31 03:03:07 INFO - PROCESS | 1667 | Values: 144.0 109.0 107.0 144.0 112.0 108.0 110.0 108.0 133.0 110.0 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:184.40 Median:184.50 stddev:1.71 (0.9%) stddev-sans-first:1.74 03:03:07 INFO - PROCESS | 1667 | Values: 183.0 185.0 184.0 188.0 184.0 183.0 184.0 182.0 185.0 186.0 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:69.00 Median:68.00 stddev:11.02 (16.2%) stddev-sans-first:10.63 03:03:07 INFO - PROCESS | 1667 | Values: 82.0 61.0 72.0 91.0 64.0 61.0 62.0 59.0 77.0 61.0 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:83.90 Median:81.50 stddev:13.69 (16.8%) stddev-sans-first:14.52 03:03:07 INFO - PROCESS | 1667 | Values: 85.0 70.0 89.0 69.0 73.0 78.0 113.0 99.0 80.0 83.0 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:91.90 Median:94.00 stddev:2.85 (3.0%) stddev-sans-first:2.79 03:03:07 INFO - PROCESS | 1667 | Values: 95.0 89.0 94.0 94.0 90.0 94.0 93.0 87.0 94.0 89.0 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:97.00 stddev:1.14 (1.2%) stddev-sans-first:1.12 03:03:07 INFO - PROCESS | 1667 | Values: 96.0 97.0 98.0 99.0 96.0 96.0 97.0 99.0 97.0 97.0 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:83.80 Median:84.00 stddev:1.32 (1.6%) stddev-sans-first:1.39 03:03:07 INFO - PROCESS | 1667 | Values: 84.0 84.0 86.0 83.0 83.0 84.0 82.0 83.0 83.0 86.0 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:63.00 Median:63.50 stddev:1.89 (3.0%) stddev-sans-first:2.00 03:03:07 INFO - PROCESS | 1667 | Values: 63.0 61.0 60.0 61.0 63.0 64.0 64.0 65.0 63.0 66.0 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:60.90 Median:59.50 stddev:4.46 (7.5%) stddev-sans-first:4.60 03:03:07 INFO - PROCESS | 1667 | Values: 58.0 72.0 64.0 63.0 60.0 58.0 58.0 59.0 58.0 59.0 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:82.50 stddev:2.06 (2.5%) stddev-sans-first:2.01 03:03:07 INFO - PROCESS | 1667 | Values: 85.0 82.0 85.0 82.0 82.0 82.0 80.0 80.0 83.0 86.0 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:143.00 stddev:24.32 (17.0%) stddev-sans-first:25.48 03:03:07 INFO - PROCESS | 1667 | Values: 137.0 136.0 137.0 126.0 142.0 144.0 144.0 132.0 208.0 171.0 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.30 Median:165.50 stddev:2.16 (1.3%) stddev-sans-first:2.28 03:03:07 INFO - PROCESS | 1667 | Values: 166.0 167.0 164.0 164.0 166.0 165.0 162.0 170.0 164.0 165.0 03:03:07 INFO - PROCESS | 1667 | 03:03:07 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:70.50 stddev:7.73 (11.0%) stddev-sans-first:7.97 03:03:07 INFO - PROCESS | 1667 | Values: 65.0 66.0 67.0 65.0 66.0 60.0 74.0 85.0 75.0 79.0 03:03:07 INFO - PROCESS | 1667 | -------- Summary: end -------- 03:03:07 INFO - PROCESS | 1667 | 03:03:08 INFO - TEST-INFO | 1667: exit 0 03:03:08 INFO - TEST-OK | kraken | took 60029ms 03:03:08 INFO - SUITE-END | took 1180s 03:03:08 INFO - Completed test suite (00:19:40) 03:03:08 INFO - javascript benchmark 03:03:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7620.582974074639, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3444.156181735475, 3455.153487395588, 3459.584849818022, 3490.8080055596934, 3511.5786105667307, 6102.206713127298, 6102.938970610293, 6108.78619248327, 6126.417990290926, 6143.692815359231, 6087.78692745754, 6150.723217455214, 6160.353162917885, 6176.876462470748, 6181.320054793969, 5430.456954304577, 5458.754356053987, 5498.422665620113, 5524.0, 5592.7762889484575, 5392.703401312946, 5409.756560954766, 5431.131019036955, 5490.929268792587, 5583.720813959306, 5563.721813909308, 5566.4990150886415, 5575.776968921238, 5579.7489112990015, 5581.107022876341, 5572.665640061609, 5573.442655734435, 5579.0, 5581.10702287632, 5593.272874526327, 4212.304969679999, 5365.812196573102, 5455.890882182334, 5531.170324451365, 5541.390447050821, 5006.324146240284, 6115.694215289218, 6121.859371575408, 6165.630062196283, 6172.043333283348, 6428.260750013762, 6431.710573024225, 6434.099226108348, 6435.935640643581, 6447.097406363113, 6422.229332480086, 6434.549581529295, 6478.578892371981, 6488.902666460007, 6490.93509064908, 6067.514598832127, 6088.11722300264, 6131.84670383244, 6190.133381326618, 6230.59501132425, 6674.299198584175, 6690.330966903271, 6697.832554186184, 6706.865862682719, 6711.3288671132495, 5341.332333458317, 6291.087792270096, 6305.968470157712, 6356.0, 6371.713272902731, 6306.936930630727, 6330.8417289567205, 6457.870842583216, 6476.31998640136, 6489.967550162313, 5394.487523685244, 6264.310925798159, 6313.40022697843, 6391.041343798374, 6396.10454536356, 6387.968060159669, 6388.137601423841, 6392.0, 6392.584482008655, 6406.199225096863, 5142.845714628567, 5206.453322401169, 5210.557102646242, 5215.504527069922, 5238.188080847474, 4558.544145585415, 4582.9770851146195, 4625.352450656911, 4626.953730462719, 4642.280446530793, 6134.681512609229, 6148.1699970503405, 6158.0, 6179.69101544921, 6192.380761923861, 6529.902051469232, 6531.804045878631, 6532.346765323429, 6542.051402546699, 6542.280349161589, 5973.313068997121, 6005.939940600538, 6057.969710151509, 6120.357362476965, 6133.662648554283, 5656.208130861683, 5701.2588363513305, 5773.191753154561, 5817.883642327214, 5821.476067153976, 6766.661666916536, 6774.0, 6785.558938668971, 6789.422899053537, 6791.558548694319, 6420.1974753155855, 6522.6412547308455, 6527.02094685801, 6540.084388185563, 6558.311377305505, 6454.838629034311, 6507.642079685663, 6549.4760419165395, 6762.391384775394, 6782.491313151435, 6310.84222894413, 6340.778072767431, 6363.5863668861375, 6364.554481186273, 6378.6810659467765, 5141.897162056813, 6390.329015453311, 6433.453156481752, 6467.385598368148, 6480.837979050561, 6302.464290535172, 6331.715072821642, 6335.208098987627, 6344.0, 6363.077353783768, 6267.2165979252595, 6319.083732858801, 6351.523635727432, 6353.841153971188, 6366.172397588284, 6196.8140955769595, 6199.659018754011, 6201.658908759881, 6209.658468784261, 6223.097650840693, 6251.312355640966, 6266.624002559861, 6291.811245662454, 6305.495560355253, 6340.682965851781, 5274.68351898872, 6350.491960643045, 6358.713857876314, 6359.459445947146, 6379.011253255753], "value": 5954.087135984209, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.9487833290835, 809.2554849538424, 868.887044684191, 881.4667126388539, 884.893812742471, 38145.427818582706, 38575.80712096439, 40578.188436231256, 40699.59300406995, 40740.38889416661, 39410.60589394105, 39622.20755584894, 39717.60282397175, 39722.404163937565, 39750.80124599373, 7259.41924646027, 9036.960749513808, 9168.587413566373, 9185.35702500822, 9186.311026672993, 7310.707571697126, 10866.34801911884, 11059.170562207826, 11188.888111118866, 11247.325160490356, 11063.446827658585, 11085.667429977073, 11098.889011109906, 11104.722381940435, 11143.164262680291, 10968.725781855437, 11017.063549598293, 11040.558377664884, 11040.779184416348, 11043.944780276048, 10697.679069627924, 10738.677839664822, 10743.570257189704, 10744.892551074467, 10750.78498430027, 37390.43914341287, 37748.24503509914, 37771.0, 37787.62212377869, 37794.622053779385, 3748.9437658435145, 3784.829682664287, 3786.772793632391, 3809.695224382043, 3817.9045523862123, 24587.63118553223, 25556.10553630614, 26164.60753088686, 26338.0, 26707.59938600903, 2985.358147998191, 3049.0, 3091.8674999500945, 3120.5319202119636, 3126.702963218491, 363268.0, 365144.0, 365843.0, 367911.0, 368558.0, 42176.945576360224, 42846.785766071596, 42894.0, 42956.57043429526, 43864.561354386045, 45050.87372815706, 45132.3230151548, 45148.77425612851, 45168.3224751629, 45283.86790330202, 13102.279374634487, 13502.257375844227, 13508.0, 13510.256935868425, 13516.5269215577, 11668.591599293984, 11690.123240756977, 11700.414979251003, 11713.297202167898, 11720.413979301002, 11461.770764584662, 11497.367644779617, 11585.710357241136, 11626.0, 11645.883541164649, 5226.9546090782, 5496.7526461309335, 5602.607817452739, 5608.551315894719, 5617.634853734494, 1043.5617040842865, 1061.2093989977516, 1106.1261603333276, 1142.149098921309, 1218.981715274254, 25173.867175977124, 25250.873745631154, 25787.355316117246, 25789.871050644626, 25824.74175258261, 1225.2723659639864, 1244.9875501245053, 1259.1563652352945, 1276.183242724657, 1301.5965050834272, 12974.545890893962, 13254.801177982337, 13318.733625327633, 13362.59912202616, 13442.991775616867, 473810.0, 496831.0, 497493.0, 497574.0, 498324.0, 52702.73648631732, 53073.2039019415, 53226.73386633042, 53251.0, 53274.668133296975, 1402.4530433131088, 1461.8099647046029, 1464.5899148238511, 1468.8237411510688, 1469.353484466831, 23379.298621041395, 28209.43581128407, 29681.40637187201, 30353.69646303509, 30402.63188156494, 50492.4852254433, 50627.0, 50698.493015069376, 50837.491625083276, 50943.23585146152, 446655.0, 451444.0, 451794.0, 452871.0, 453025.0, 29201.7079829199, 29827.552586710783, 30755.53866692046, 31024.53463198099, 31357.216069598442, 36885.81557092197, 36934.63065369419, 37985.81007094947, 38068.04829879275, 38305.4254186193, 26987.0, 27044.729552704222, 27161.728382716712, 27723.44553108967, 28056.158315250574, 26419.471610568067, 26827.32931676724, 26909.0, 27200.319991999568, 27956.86021569879, 30664.693353067076, 31929.6807031936, 31962.840185798923, 32058.519122212718, 32078.358432830744, 24977.0, 25032.249032529053, 25057.248282551554, 27381.45237095287, 27660.446791064467, 21071.0, 21794.237201697866, 21920.93106965058, 22119.66820497726, 22263.44341391478], "value": 18204.12788386449, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.6539195412636, 394.33160792456795, 411.21458015190984, 420.28761249681787, 443.0629219201388, 12714.722413963127, 14407.279636018196, 16005.839941600596, 20321.390358289238, 20733.585328293426, 18309.633807323844, 22290.554188916212, 22318.884055797225, 22665.0, 22922.65616015761, 6371.203599550056, 6442.581232219914, 6531.0856480092825, 6636.701348439307, 6709.832254193635, 4154.210699966997, 6527.184101987252, 6662.400383965442, 6665.70004349803, 6672.866542669144, 4331.056688939269, 4419.845305414311, 4531.3203019547, 7665.616719164047, 7666.38668906489, 7386.741464048786, 7622.428317876126, 7641.617919104023, 7663.885041724378, 7665.770026899147, 7513.737019204302, 7515.8496830063095, 7516.135644400854, 7517.887231691529, 7525.134609519921, 27177.45645087087, 27403.862980685168, 27477.31306717328, 27563.586546201623, 27835.860820695765, 2886.494863398918, 2889.970248850283, 2898.231968528342, 2906.912792616225, 2928.180109569324, 16661.83338166627, 16823.579410514714, 17120.0, 17314.0, 17783.911080444643, 1833.2025568877582, 1873.6721073812164, 1875.2311552263507, 1894.2991093295568, 1912.7130930360418, 133995.0, 136469.0, 138556.53608659763, 138620.6137938618, 140420.0, 21434.78565214359, 21853.67219491709, 21961.01175447109, 22025.0, 22127.44681382978, 34577.82711086429, 35310.29379412397, 35505.46741798875, 35555.466668, 35622.46566301508, 10691.129052415796, 11018.889811101786, 11221.326720396803, 11273.887261127284, 11443.397924290606, 9043.59303831329, 9055.59249833759, 9070.455772653662, 9233.353665243369, 9247.445153290824, 10626.574937002588, 10782.46087695612, 10796.89203107959, 10906.236563440483, 10931.125509959184, 3546.3793836078585, 3758.571742737742, 4041.474608300961, 4106.556598823695, 4144.544614539383, 784.9372050235968, 876.0144837058309, 896.3635818568758, 910.6767097680361, 925.121134921827, 22240.332790016324, 22886.084556617883, 22975.88512057429, 23148.95829687668, 23225.88387058054, 844.2190973349627, 930.7859192385789, 931.8322701913721, 955.5126885288553, 957.9664711735056, 11474.311541307548, 11825.0, 12200.877991220152, 12202.694932626755, 12205.145639805305, 724085.0, 727280.0, 734266.0, 741059.0, 756757.0, 131978.0, 133669.66330336768, 153436.0, 155440.22279888528, 157040.00556876144, 1205.8070708686569, 1220.969475763113, 1278.4630455208835, 1283.826683397748, 1284.2551320234243, 19769.802301976797, 20079.698804518237, 20442.69335959932, 20455.488612784204, 20524.589508209454, 63764.3623563777, 63917.680411599504, 64089.35910641021, 64557.48097633121, 65164.0, 82431.58784206041, 82672.58663706643, 95101.0, 95739.52130239583, 98430.5078474603, 7157.320054594805, 10382.532786024496, 10629.734256643645, 11330.206885517935, 11411.942940285246, 12863.0, 12875.806862896876, 13192.670183245496, 13226.933865330997, 13279.535216267384, 9230.861537076815, 10968.122550195805, 11050.889491105308, 11320.26418282809, 11325.0, 10337.689869303633, 10338.896611034093, 10524.421156836448, 10598.417087059977, 10776.622818201326, 11093.667189984313, 11411.657650270505, 11480.311181329147, 11504.597339093092, 11517.02105321057, 9996.900030999888, 11350.716232094264, 11364.715882102682, 11383.772324553296, 11806.0, 9172.6789562365, 11041.0, 11332.320060796379, 11415.60045398407, 11644.0], "value": 11534.870794942091, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1476.7194233095718, 1514.280716659587, 1535.5316628428325, 1541.6531280461893, 1569.082086979117, 10857.83713244301, 11386.373749443797, 11389.316641001546, 11399.772004559904, 11422.42887855607, 10652.893471065287, 11069.335839849615, 11207.21549491536, 11212.271202371861, 11227.0, 2356.929292121239, 2401.0275838285525, 2409.409694624819, 2410.108259943823, 2411.397150712322, 2314.745378008418, 2393.74865639108, 2394.7006624171977, 2404.13451157584, 2407.350015495815, 2308.5267520158413, 2369.407648087978, 2371.4071482129466, 2390.2112302939986, 2394.6408038794148, 2291.690621766057, 2363.550925324183, 2370.028288401747, 2371.0989823866994, 2387.4866903615807, 2352.4118970257437, 2379.6668466414717, 2385.3440303916386, 2387.997041242665, 2390.9163179288817, 9141.634334626608, 11001.064909482704, 11007.06439952597, 11025.0, 11041.061509771678, 5492.642978206443, 5660.716964151817, 5672.06410942194, 5684.8294551163535, 5699.829005129853, 3516.296740651854, 3564.8395822188063, 3605.080704420356, 3638.308721342936, 3647.7993710345922, 1158.2066284594996, 1159.594142050284, 1198.922070065443, 1200.0639501189087, 1211.4669545400084, 13711.931440342834, 14084.225367604779, 14361.999999999896, 15421.306041228172, 15551.066935983765, 4504.144212599661, 4659.370984917061, 4659.95340046602, 4661.347411362344, 4662.813487460464, 11771.941140294244, 11783.0, 11791.29252244868, 11794.410279485992, 11840.703982400337, 4847.587955023862, 5020.849374518771, 5415.783368665288, 5590.97204513983, 5657.60396772222, 5089.134847075932, 5212.087884620177, 5281.313429254173, 5301.284326615934, 5304.549113325411, 3761.8495260189834, 3768.189839184534, 3848.3457812171996, 3878.670313023368, 3880.7387599030426, 749.4079677055065, 972.3728195313982, 995.1690338567232, 998.1116806042628, 1000.8448690452846, 771.7684694591712, 788.3614272439228, 807.2371608829717, 809.9554524501091, 822.3791037766448, 5156.484351564814, 5498.95519851235, 6176.969115154486, 6525.445337146396, 6646.169228846395, 979.9510024498746, 1012.71643939697, 1019.3170575714289, 1019.4138370437029, 1025.1593693171528, 4143.502779666399, 4158.272302347077, 4161.3549899765585, 4289.442372491556, 4294.549072347359, 12235.143539952118, 13888.166709997433, 13919.234442105579, 13928.86071139296, 13941.86058139426, 12198.57304994321, 13535.984801139954, 13808.861911380758, 13828.93085534566, 13834.377453014638, 10128.291019628556, 10269.024442677934, 10293.330933489298, 10298.17614590831, 10301.896981030093, 6317.399847014526, 6372.330905254881, 6504.349565043458, 6527.738890444329, 6592.307807680317, 8941.508217047865, 10731.034206921415, 12081.0, 12330.691732706755, 12369.381530923598, 12537.435815388148, 12539.749205015665, 12566.423883103758, 12571.87428125707, 12596.307203103917, 8868.778280543038, 9166.724998250063, 9383.859242111237, 9391.953040234755, 9407.717768466957, 10174.542145603318, 10249.538770755484, 10313.381197127896, 10373.636922707668, 10425.478726063515, 8725.696360481701, 8807.207351338411, 8873.822523549365, 8929.464232146092, 8941.0, 8594.957025214833, 8597.914020859962, 8666.739997800076, 8729.170728780755, 8743.125687431206, 7682.308592226726, 7683.154852966058, 7794.298513133618, 7835.177306382976, 7853.332466740391, 5763.193152958505, 5809.331926828469, 5828.067509198508, 5990.7903223386975, 6003.0, 5848.853778655568, 5874.412558744262, 5908.763649453974, 5976.61152025117, 6024.216851809237], "value": 5049.171130239661, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.04408098135036, 367.1006035213727, 369.5842177550256, 378.9602091780361, 379.65034303052647, 4989.700617962925, 5019.247112933057, 5021.29701841742, 5061.595072394209, 5100.2859599655985, 4025.6449976763556, 4417.403650507181, 4873.415190177184, 4981.352424184851, 4991.552449789557, 3962.9801850990702, 4035.01142220155, 4179.749215047092, 4183.414321994931, 4185.162967406515, 4142.337226043834, 4144.730592511492, 4151.7924103794685, 4181.58184181581, 4192.685548583853, 4012.8996775080564, 4146.896327591804, 4193.427464700737, 4233.280342341794, 4249.723767955088, 3158.578639612184, 3956.7823769692654, 4140.358244472111, 4168.478505345557, 4233.391311301716, 3441.741869359783, 3455.9308813823836, 3469.340825243196, 3786.690119027391, 3828.0, 4260.1445691649105, 4277.764722940237, 4387.890302742425, 4605.930911036337, 4836.056968891067, 3953.9522026662958, 4773.4510531289, 4789.4971028042255, 4835.468098509161, 4914.483979182206, 4632.583067523905, 5007.37407824022, 5060.544550990428, 5307.442718514539, 5325.360956685223, 3785.9432108518395, 4208.516020657633, 4268.713090920005, 4280.602862397037, 4292.484901811803, 5312.973435132838, 5417.59368047394, 5430.56555475561, 5662.517551705712, 5714.485696287354, 3324.5345651608795, 3411.965880341165, 3934.7245692801803, 4079.8776036718946, 4136.958630413717, 3957.584453632385, 4429.424174857248, 4441.68908176431, 4448.844290449819, 4476.328550717419, 4754.358161648133, 4790.808367665254, 4792.784324705397, 4824.927626085541, 4868.63678170113, 4887.682300650446, 5104.591632669378, 5113.795448182031, 5159.819406320761, 5210.6613070150315, 1443.588577255477, 1467.1783801071433, 1480.6668499587722, 1519.7796319533827, 1534.8081489813774, 1082.637316498966, 1142.9427031343548, 1147.1912301827192, 1150.8561429821273, 1161.814109742437, 4526.162659907927, 4943.429416703366, 4943.752812359367, 4984.675996060245, 4996.875078123076, 5340.172273297645, 5387.572293342268, 5394.271773310631, 5471.042567550663, 5544.722763861791, 3855.8960037169786, 4433.889152771207, 4575.794089265991, 4769.523047695203, 4994.4755800640405, 2492.9002839886202, 2862.957055644167, 2879.9280017999718, 2891.6842836509336, 2906.6802651708294, 5526.889462210734, 5721.456461636138, 5730.7421166047625, 5746.827595172152, 5750.0, 4134.338505839051, 5338.492843179892, 5608.822147349021, 5642.971785141048, 5655.575831812548, 5139.974300128551, 5166.734150133241, 5278.308062888032, 5427.0, 5560.638558493685, 4851.75741212945, 4955.4053513577155, 5220.530152286313, 5465.07093794048, 5514.751836167462, 4766.594839438687, 4942.3080768693135, 4979.302897594267, 5440.626811376814, 5457.962987032452, 5114.33513643224, 5161.535461808456, 5612.793249451388, 5716.626780512994, 5741.770329186786, 3574.7121842020606, 3865.9226815464094, 4347.413099231563, 4373.628241599499, 4380.882874867016, 4473.821047158077, 4898.804047838189, 5289.047971365191, 5555.611107222456, 5568.415316391721, 4929.408470983505, 5014.749262536931, 5124.232914226276, 5337.332833395825, 5338.466153384786, 4233.428487154195, 5057.988402319477, 5137.095807186491, 5378.784848606012, 5402.621816472809, 4820.204666230033, 4952.752362381794, 5156.226566015128, 5276.208568714724, 5302.2046692996355, 4876.56110950002, 5127.641065125555, 5324.512444348958, 5342.866428339323, 5416.593755468434, 4238.19474299882, 4681.7659117044695, 5194.2341802193105, 5222.563794956417, 5243.449437808975], "value": 4061.040108072446, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.704863957138, 729.7044696897758, 773.5474747272846, 820.9532845621832, 830.5224495914848, 15459.99510031847, 16191.919040404811, 16248.106354150517, 16256.51230463085, 16259.024458532496, 14593.34329955152, 14655.413783448676, 14671.486497972573, 14763.704725905502, 14787.630309242248, 798.4849771897123, 914.8261830252264, 915.6383228624723, 937.6296362936636, 940.7507010642186, 935.1350001248852, 939.1500691873865, 952.9761755956122, 960.2462067277189, 961.6778379242927, 986.3982970388057, 986.9407835529855, 987.1214618989105, 988.2044953812178, 990.267202270322, 978.8042391521653, 979.4417182206074, 981.6760469045233, 981.8330883749744, 983.6163896080534, 727.82532192274, 728.04262394951, 728.1698863295846, 728.9963550182268, 729.9343059124704, 16267.91866040674, 16461.58846028859, 16702.49892503227, 16720.16399180036, 16872.409465668767, 14118.0, 14314.498992535211, 14366.56900292993, 14373.640658983504, 14392.280385980765, 11774.61634135581, 11806.527738890434, 11893.643190704293, 11919.82120268188, 12359.196652217577, 3603.0992251937014, 3660.5973342932257, 3686.207465394953, 3760.041189496661, 3846.288436639231, 117832.41083794612, 118519.0, 118646.40676796647, 120731.0, 121517.0, 10829.133669306437, 11005.339679619248, 11130.33218006906, 11145.888541114648, 11273.267237629529, 22621.20825771123, 22621.88689056544, 22629.43426414353, 22678.659820102712, 22682.0, 7389.8152546186775, 7432.851342973218, 7680.615969201517, 7964.761057168294, 7975.202479752094, 4228.6617070634275, 4371.267957526546, 4406.691531592822, 4424.203643344164, 4562.749048802348, 3536.3634545781492, 3555.5377800886235, 3636.4363523653874, 3637.181634132299, 3644.526211592529, 617.8084793713873, 622.9252489701174, 624.1542709628479, 630.2153818496001, 638.6583177999748, 660.6432526435767, 675.9797206083726, 679.6542845166598, 682.3006418421159, 704.9189343225493, 9951.402915825074, 9990.200783937267, 10159.187265018782, 10422.478876056166, 10672.359658420519, 651.7979426377838, 680.8229860236374, 684.784507499183, 691.2638040486866, 712.0920825946959, 4784.832530861403, 5063.620228482879, 5080.872978175576, 5085.1355269604255, 5091.847244582668, 81808.0, 89713.55143224243, 95758.0, 97374.51312743391, 101700.0, 82949.75575366375, 84477.0, 84605.73091403634, 86473.56763216143, 91590.54204728933, 79054.41891162144, 79511.80732288904, 79737.60131199307, 81403.37193256101, 82166.58916705378, 9243.0, 10908.618198363336, 11115.333080015225, 11234.101271898391, 11263.887361126284, 49554.008919822125, 50457.49542504528, 50524.72638731339, 50732.74979652674, 51431.74284128555, 63690.04464933123, 63878.68060659853, 64206.0, 64880.0, 66222.0, 12299.631011069681, 12568.30874301922, 12739.6178114653, 12754.936225318814, 12885.904698100767, 13492.730145397234, 14256.928715356771, 14403.279836007949, 14686.926565367105, 15155.92422037927, 12830.358482075671, 13688.589342319747, 13695.726085478434, 14011.859881401464, 14179.55889997269, 12785.232886026868, 13518.0, 14051.437942482185, 14125.646858828612, 14297.35661895238, 12795.36023198855, 12877.935610321889, 13349.332533373099, 13481.056326057082, 13589.728205436033, 9474.810503789748, 9953.452560109263, 10145.03622155894, 10173.0, 10324.277300589187, 7733.2461199977015, 9515.381500202742, 9776.608935642495, 9800.0, 9826.361286516354], "value": 7638.9154500232835, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1439.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 115.0, 116.0, 116.0, 116.0, 115.0, 116.0, 120.0, 172.0], "value": 120.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 109.0, 107.0, 144.0, 112.0, 108.0, 110.0, 108.0, 133.0, 110.0], "value": 118.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 184.0, 188.0, 184.0, 183.0, 184.0, 182.0, 185.0, 186.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 72.0, 91.0, 64.0, 61.0, 62.0, 59.0, 77.0, 61.0], "value": 69.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 89.0, 69.0, 73.0, 78.0, 113.0, 99.0, 80.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 89.0, 94.0, 94.0, 90.0, 94.0, 93.0, 87.0, 94.0, 89.0], "value": 91.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 98.0, 99.0, 96.0, 96.0, 97.0, 99.0, 97.0, 97.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 83.0, 83.0, 84.0, 82.0, 83.0, 83.0, 86.0], "value": 83.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 60.0, 61.0, 63.0, 64.0, 64.0, 65.0, 63.0, 66.0], "value": 63.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 72.0, 64.0, 63.0, 60.0, 58.0, 58.0, 59.0, 58.0, 59.0], "value": 60.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 85.0, 82.0, 82.0, 82.0, 80.0, 80.0, 83.0, 86.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 137.0, 126.0, 142.0, 144.0, 144.0, 132.0, 208.0, 171.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 164.0, 164.0, 166.0, 165.0, 162.0, 170.0, 164.0, 165.0], "value": 165.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 65.0, 66.0, 60.0, 74.0, 85.0, 75.0, 79.0], "value": 70.2, "unit": "score"}]}]} 03:03:08 INFO - Return code: 0 03:03:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:03:08 ERROR - # TBPL SUCCESS # 03:03:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:03:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:03:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:03:08 INFO - 2017-04-15 03:03:08.847 screenresolution[1671:15472] starting screenresolution argv=screenresolution get 03:03:08 INFO - 2017-04-15 03:03:08.851 screenresolution[1671:15472] Display 0: 1600x1200x32@60 03:03:08 INFO - 2017-04-15 03:03:08.857 screenresolution[1672:15475] starting screenresolution argv=screenresolution list 03:03:08 INFO - Available Modes on Display 0 03:03:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:03:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:03:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:03:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:03:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:03:09 INFO - Intel Iris: 03:03:09 INFO - Chipset Model: Intel Iris 03:03:09 INFO - Type: GPU 03:03:09 INFO - Bus: Built-In 03:03:09 INFO - VRAM (Dynamic, Max): 1536 MB 03:03:09 INFO - Vendor: Intel (0x8086) 03:03:09 INFO - Device ID: 0x0a2e 03:03:09 INFO - Revision ID: 0x0009 03:03:09 INFO - Displays: 03:03:09 INFO - Display: 03:03:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:03:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:03:09 INFO - Display Serial Number: 1600x1200 60 03:03:09 INFO - Main Display: Yes 03:03:09 INFO - Mirror: Off 03:03:09 INFO - Online: Yes 03:03:09 INFO - Rotation: Supported 03:03:09 INFO - Return code: 0 03:03:09 INFO - Running post-action listener: _resource_record_post_action 03:03:09 INFO - [mozharness: 2017-04-15 10:03:09.096762Z] Finished run-tests step (success) 03:03:09 INFO - Running post-run listener: _resource_record_post_run 03:03:09 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32222208; Write bytes: 511837184; Read time: 196; Write time: 1560 03:03:09 INFO - TinderboxPrint: CPU usage
53.9% 03:03:09 INFO - TinderboxPrint: I/O read bytes / time
32,222,208 / 196 03:03:09 INFO - TinderboxPrint: I/O write bytes / time
511,837,184 / 1,560 03:03:09 INFO - TinderboxPrint: CPU idle
2,219.3 (46.1%) 03:03:09 INFO - TinderboxPrint: CPU system
959.9 (20.0%) 03:03:09 INFO - TinderboxPrint: CPU user
1,630.4 (33.9%) 03:03:09 INFO - TinderboxPrint: Swap in / out
236,150,784 / 0 03:03:09 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200716800; Write bytes: 202377216; Read time: 15681; Write time: 435 03:03:09 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 31288320; Write bytes: 299539456; Read time: 185; Write time: 1091 03:03:09 INFO - Running post-run listener: _upload_blobber_files 03:03:09 INFO - Blob upload gear active. 03:03:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:03:09 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 03:03:09 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'] 03:03:09 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 03:03:09 INFO - (blobuploader) - INFO - Open directory for files ... 03:03:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:03:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:03:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:03:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:03:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:03:11 INFO - (blobuploader) - INFO - Done attempting. 03:03:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:03:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:03:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:03:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:03:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:03:12 INFO - (blobuploader) - INFO - Done attempting. 03:03:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:03:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:03:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:03:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:03:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:03:13 INFO - (blobuploader) - INFO - Done attempting. 03:03:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:03:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:03:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:03:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:03:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:03:15 INFO - (blobuploader) - INFO - Done attempting. 03:03:15 INFO - (blobuploader) - INFO - Iteration through files over. 03:03:15 INFO - Return code: 0 03:03:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:03:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:03:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/665daa3fc4debe85a4b2d9e786f567a201ec5a19fb178b7cd537d4d8168af3482fdec783cfad0ec73e760e55f48496c997ca7bd63f92f5bb434d1eeee465e702", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/697aa9317bfb21a803ecaa2c85b847100555ceb2766920d5175a6d0441c48b4283ce61cf181e6bfe82dc0d2bb437e788ca0bd1bae34b5e5da03959b6f6ecd01f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d98cbb7c6b2a29f75109c60bde3a0ae7316aa4939322de7e90deb40ccb94a55d8bb1eb7a183c78d560f534ebdf16e6d4e52c48492c4f5bf0ae62fe3c646e3e8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/553b9df5e2ceb1524cb1a0191afc7679f6ff6c55435e49597b30d5d7f9f6104e0087e6e67e25a351ba4f851f8cd5dff52ed4a25c7a1ff74d80d6d30481e206a3"} 03:03:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:03:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:03:15 INFO - Contents: 03:03:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/665daa3fc4debe85a4b2d9e786f567a201ec5a19fb178b7cd537d4d8168af3482fdec783cfad0ec73e760e55f48496c997ca7bd63f92f5bb434d1eeee465e702", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/697aa9317bfb21a803ecaa2c85b847100555ceb2766920d5175a6d0441c48b4283ce61cf181e6bfe82dc0d2bb437e788ca0bd1bae34b5e5da03959b6f6ecd01f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d98cbb7c6b2a29f75109c60bde3a0ae7316aa4939322de7e90deb40ccb94a55d8bb1eb7a183c78d560f534ebdf16e6d4e52c48492c4f5bf0ae62fe3c646e3e8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/553b9df5e2ceb1524cb1a0191afc7679f6ff6c55435e49597b30d5d7f9f6104e0087e6e67e25a351ba4f851f8cd5dff52ed4a25c7a1ff74d80d6d30481e206a3"} 03:03:15 INFO - Running post-run listener: copy_logs_to_upload_dir 03:03:15 INFO - Copying logs to upload dir... 03:03:15 INFO - mkdir: /builds/slave/test/build/upload/logs 03:03:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.521707 ========= master_lag: -0.09 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-15 03:03:15.163541) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-15 03:03:15.167546) ========= 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.C56yZiuR98/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.HcMOJGFc1k/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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/665daa3fc4debe85a4b2d9e786f567a201ec5a19fb178b7cd537d4d8168af3482fdec783cfad0ec73e760e55f48496c997ca7bd63f92f5bb434d1eeee465e702", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/697aa9317bfb21a803ecaa2c85b847100555ceb2766920d5175a6d0441c48b4283ce61cf181e6bfe82dc0d2bb437e788ca0bd1bae34b5e5da03959b6f6ecd01f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d98cbb7c6b2a29f75109c60bde3a0ae7316aa4939322de7e90deb40ccb94a55d8bb1eb7a183c78d560f534ebdf16e6d4e52c48492c4f5bf0ae62fe3c646e3e8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/553b9df5e2ceb1524cb1a0191afc7679f6ff6c55435e49597b30d5d7f9f6104e0087e6e67e25a351ba4f851f8cd5dff52ed4a25c7a1ff74d80d6d30481e206a3"} build_url:https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011178 build_url: 'https://queue.taskcluster.net/v1/task/Anz96S3lRsSlnJkTaTEAFw/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/665daa3fc4debe85a4b2d9e786f567a201ec5a19fb178b7cd537d4d8168af3482fdec783cfad0ec73e760e55f48496c997ca7bd63f92f5bb434d1eeee465e702", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/697aa9317bfb21a803ecaa2c85b847100555ceb2766920d5175a6d0441c48b4283ce61cf181e6bfe82dc0d2bb437e788ca0bd1bae34b5e5da03959b6f6ecd01f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d98cbb7c6b2a29f75109c60bde3a0ae7316aa4939322de7e90deb40ccb94a55d8bb1eb7a183c78d560f534ebdf16e6d4e52c48492c4f5bf0ae62fe3c646e3e8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/553b9df5e2ceb1524cb1a0191afc7679f6ff6c55435e49597b30d5d7f9f6104e0087e6e67e25a351ba4f851f8cd5dff52ed4a25c7a1ff74d80d6d30481e206a3"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-15 03:03:15.207619) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 03:03:15.208032) ========= 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.C56yZiuR98/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.HcMOJGFc1k/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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.005274 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 03:03:15.256854) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-15 03:03:15.257212) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-15 03:03:15.257593) ========= ========= Total master_lag: 0.09 =========