builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0274 starttime: 1486310401.9 results: success (0) buildid: 20170205072508 builduid: 029cc9ebe90e4e4d856de4e05d3a4ea0 revision: da7e987a9146ab24a64bc31ea22f75dbc958984b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-05 08:00:01.901174) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-05 08:00:01.901857) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-05 08:00:01.902178) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-05 08:00:01.936611) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 08:00:01.937222) ========= 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.Duq9FTzHpz/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.cccmXtVwQL/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-05 08:00:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 173M=0s 2017-02-05 08:00:02 (173 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.484895 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 08:00:02.450205) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 08:00:02.453596) ========= 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.Duq9FTzHpz/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.cccmXtVwQL/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.027863 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 08:00:02.504575) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-05 08:00:02.504927) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev da7e987a9146ab24a64bc31ea22f75dbc958984b --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 da7e987a9146ab24a64bc31ea22f75dbc958984b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Duq9FTzHpz/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.cccmXtVwQL/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-05 08:00:02,617 truncating revision to first 12 chars 2017-02-05 08:00:02,617 Setting DEBUG logging. 2017-02-05 08:00:02,617 attempt 1/10 2017-02-05 08:00:02,617 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/da7e987a9146?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-05 08:00:03,926 unpacking tar archive at: mozilla-aurora-da7e987a9146/testing/mozharness/ program finished with exit code 0 elapsedTime=1.580410 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-05 08:00:04.115613) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-05 08:00:04.116045) ========= script_repo_revision: da7e987a9146ab24a64bc31ea22f75dbc958984b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-05 08:00:04.116523) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-05 08:00:04.116792) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-05 08:00:04.136889) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-05 08:00:04.137298) ========= /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.Duq9FTzHpz/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.cccmXtVwQL/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:00:04 INFO - MultiFileLogger online at 20170205 08:00:04 in /builds/slave/test 08:00:04 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 08:00:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:00:04 INFO - {'append_to_log': False, 08:00:04 INFO - 'base_work_dir': '/builds/slave/test', 08:00:04 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:00:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:00:04 INFO - 'branch': 'Mozilla-Aurora', 08:00:04 INFO - 'buildbot_json_path': 'buildprops.json', 08:00:04 INFO - 'config_files': ('talos/mac_config.py',), 08:00:04 INFO - 'default_actions': ('clobber', 08:00:04 INFO - 'read-buildbot-config', 08:00:04 INFO - 'download-and-extract', 08:00:04 INFO - 'populate-webroot', 08:00:04 INFO - 'create-virtualenv', 08:00:04 INFO - 'install', 08:00:04 INFO - 'run-tests'), 08:00:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:00:04 INFO - 'download_minidump_stackwalk': True, 08:00:04 INFO - 'download_symbols': 'ondemand', 08:00:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:00:04 INFO - 'tooltool.py': '/tools/tooltool.py', 08:00:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:00:04 INFO - '/tools/misc-python/virtualenv.py')}, 08:00:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:00:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:00:04 INFO - 'installer_path': 'installer.exe', 08:00:04 INFO - 'log_level': 'info', 08:00:04 INFO - 'log_name': 'talos', 08:00:04 INFO - 'log_to_console': True, 08:00:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:00:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:00:04 INFO - 'opt_config_files': (), 08:00:04 INFO - 'pip_index': False, 08:00:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:00:04 INFO - 'cmd': ('bash', 08:00:04 INFO - '-c', 08:00:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:00:04 INFO - 'enabled': True, 08:00:04 INFO - 'halt_on_failure': False, 08:00:04 INFO - 'name': 'check_screen_resolution'},), 08:00:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:00:04 INFO - 'cmd': ('bash', 08:00:04 INFO - '-c', 08:00:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:00:04 INFO - 'enabled': True, 08:00:04 INFO - 'halt_on_failure': False, 08:00:04 INFO - 'name': 'check_screen_resolution'},), 08:00:04 INFO - 'run_cmd_checks_enabled': True, 08:00:04 INFO - 'sps_profile': False, 08:00:04 INFO - 'sps_profile_interval': 0, 08:00:04 INFO - 'suite': 'dromaeojs', 08:00:04 INFO - 'system_bits': '32', 08:00:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:00:04 INFO - 'title': 't-yosemite-r7-0274', 08:00:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:00:04 INFO - 'use_talos_json': True, 08:00:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:00:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:00:04 INFO - 'work_dir': 'build'} 08:00:04 INFO - [mozharness: 2017-02-05 16:00:04.349297Z] Running clobber step. 08:00:04 INFO - Running pre-action listener: _resource_record_pre_action 08:00:04 INFO - Running main action method: clobber 08:00:04 INFO - rmtree: /builds/slave/test/build 08:00:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:00:06 INFO - Running post-action listener: _resource_record_post_action 08:00:06 INFO - [mozharness: 2017-02-05 16:00:06.808798Z] Finished clobber step (success) 08:00:06 INFO - [mozharness: 2017-02-05 16:00:06.808927Z] Running read-buildbot-config step. 08:00:06 INFO - Running pre-action listener: _resource_record_pre_action 08:00:06 INFO - Running main action method: read_buildbot_config 08:00:06 INFO - Using buildbot properties: 08:00:06 INFO - { 08:00:06 INFO - "project": "", 08:00:06 INFO - "product": "firefox", 08:00:06 INFO - "script_repo_revision": "production", 08:00:06 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:00:06 INFO - "repository": "", 08:00:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:00:06 INFO - "buildid": "20170205072508", 08:00:06 INFO - "pgo_build": "False", 08:00:06 INFO - "basedir": "/builds/slave/test", 08:00:06 INFO - "buildnumber": 39, 08:00:06 INFO - "slavename": "t-yosemite-r7-0274", 08:00:06 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:00:06 INFO - "platform": "yosemite_r7", 08:00:06 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:00:06 INFO - "branch": "mozilla-aurora", 08:00:06 INFO - "repo_path": "releases/mozilla-aurora", 08:00:06 INFO - "revision": "da7e987a9146ab24a64bc31ea22f75dbc958984b", 08:00:06 INFO - "stage_platform": "macosx64", 08:00:06 INFO - "builduid": "029cc9ebe90e4e4d856de4e05d3a4ea0", 08:00:06 INFO - "slavebuilddir": "test" 08:00:06 INFO - } 08:00:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 08:00:06 INFO - Running post-action listener: _resource_record_post_action 08:00:06 INFO - [mozharness: 2017-02-05 16:00:06.812216Z] Finished read-buildbot-config step (success) 08:00:06 INFO - [mozharness: 2017-02-05 16:00:06.812331Z] Running download-and-extract step. 08:00:06 INFO - Running pre-action listener: _resource_record_pre_action 08:00:06 INFO - Running main action method: download_and_extract 08:00:06 INFO - mkdir: /builds/slave/test/build/tests 08:00: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')]} 08:00:06 INFO - trying https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 08:00:06 INFO - Downloading https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 08:00:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 08:00:07 INFO - Downloaded 1199 bytes. 08:00:07 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 08:00:07 INFO - Using the following test package requirements: 08:00:07 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:00:07 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:00:07 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 08:00:07 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:00:07 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 08:00:07 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:00:07 INFO - u'jsshell-mac.zip'], 08:00:07 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:00:07 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 08:00:07 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:00:07 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:00:07 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 08:00:07 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:00:07 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 08:00:07 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:00:07 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 08:00:07 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:00:07 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 08:00:07 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:00:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:00:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:00:07 INFO - Fetch https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:00:08 INFO - Expected file size: 16356325 08:00:08 INFO - Obtained file size: 16356325 08:00:15 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:00:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:00:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:00:15 INFO - Fetch https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:00:16 INFO - Expected file size: 16356325 08:00:16 INFO - Obtained file size: 16356325 08:00:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 08:00:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:00:22 INFO - Fetch https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 08:00:23 INFO - Expected file size: 14403485 08:00:23 INFO - Obtained file size: 14403485 08:00:24 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')]} 08:00:24 INFO - trying https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:00:24 INFO - Downloading https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 08:00:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 08:00:26 INFO - Downloaded 73828805 bytes. 08:00:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:00:26 INFO - mkdir: /builds/slave/test/properties 08:00:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:00:26 INFO - Writing to file /builds/slave/test/properties/build_url 08:00:26 INFO - Contents: 08:00:26 INFO - build_url:https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:00:26 INFO - Running post-action listener: _resource_record_post_action 08:00:26 INFO - Running post-action listener: set_extra_try_arguments 08:00:26 INFO - [mozharness: 2017-02-05 16:00:26.939599Z] Finished download-and-extract step (success) 08:00:26 INFO - [mozharness: 2017-02-05 16:00:26.939705Z] Running populate-webroot step. 08:00:26 INFO - Running pre-action listener: _resource_record_pre_action 08:00:26 INFO - Running main action method: populate_webroot 08:00:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:00:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:00:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:00:26 INFO - '--tppagecycles', 08:00:26 INFO - '7'], 08:00:26 INFO - 'tests': ['tsvgm']}}, 08:00:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:00:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:00:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:00:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:00:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:00:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:00:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:00:26 INFO - 'talos_options': ['--disable-e10s'], 08:00:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:00:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:00:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:00:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:00:26 INFO - 'talos_options': ['--disable-e10s'], 08:00:26 INFO - 'tests': ['damp', 'tps']}, 08:00:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:00:26 INFO - 'tests': ['damp', 'tps']}, 08:00:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:00:26 INFO - 'tests': ['dromaeo_dom']}, 08:00:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:00:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:00:26 INFO - 'tests': ['basic_compositor_video']}, 08:00:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:00:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:00:26 INFO - 'tests': ['a11yr', 08:00:26 INFO - 'ts_paint', 08:00:26 INFO - 'tpaint', 08:00:26 INFO - 'sessionrestore', 08:00:26 INFO - 'sessionrestore_no_auto_restore', 08:00:26 INFO - 'tabpaint']}, 08:00:26 INFO - 'other-e10s': {'tests': ['a11yr', 08:00:26 INFO - 'ts_paint', 08:00:26 INFO - 'tpaint', 08:00:26 INFO - 'sessionrestore', 08:00:26 INFO - 'sessionrestore_no_auto_restore', 08:00:26 INFO - 'tabpaint']}, 08:00:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:00:26 INFO - 'ts_paint', 08:00:26 INFO - 'tpaint', 08:00:26 INFO - 'sessionrestore', 08:00:26 INFO - 'sessionrestore_no_auto_restore', 08:00:26 INFO - 'tabpaint']}, 08:00:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:00:26 INFO - 'ts_paint', 08:00:26 INFO - 'tpaint', 08:00:26 INFO - 'sessionrestore', 08:00:26 INFO - 'sessionrestore_no_auto_restore', 08:00:26 INFO - 'tabpaint']}, 08:00:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:00:26 INFO - 'tests': ['a11yr', 08:00:26 INFO - 'ts_paint', 08:00:26 INFO - 'tpaint', 08:00:26 INFO - 'sessionrestore', 08:00:26 INFO - 'sessionrestore_no_auto_restore', 08:00:26 INFO - 'tabpaint']}, 08:00:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:00:26 INFO - 'tests': ['a11yr', 08:00:26 INFO - 'ts_paint', 08:00:26 INFO - 'tpaint', 08:00:26 INFO - 'sessionrestore', 08:00:26 INFO - 'sessionrestore_no_auto_restore', 08:00:26 INFO - 'tabpaint']}, 08:00:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:00:26 INFO - 'tests': ['tsvgx', 08:00:26 INFO - 'tsvgr_opacity', 08:00:26 INFO - 'tart', 08:00:26 INFO - 'tscrollx', 08:00:26 INFO - 'cart', 08:00:26 INFO - 'tsvg_static']}, 08:00:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:00:26 INFO - 'tsvgr_opacity', 08:00:26 INFO - 'tart', 08:00:26 INFO - 'tscrollx', 08:00:26 INFO - 'cart', 08:00:26 INFO - 'tsvg_static']}, 08:00:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:00:26 INFO - 'talos_options': ['--disable-e10s'], 08:00:26 INFO - 'tests': ['tp5o']}, 08:00:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:00:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:00:26 INFO - 'talos_options': ['--disable-e10s', 08:00:26 INFO - '--xperf_path', 08:00:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:00:26 INFO - 'tests': ['tp5n']}, 08:00:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:00:26 INFO - 'talos_options': ['--xperf_path', 08:00:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:00:26 INFO - 'tests': ['tp5n']}}, 08:00:26 INFO - 'talos.zip': {'path': '', 08:00:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:00:26 INFO - Running post-action listener: _resource_record_post_action 08:00:26 INFO - [mozharness: 2017-02-05 16:00:26.958189Z] Finished populate-webroot step (success) 08:00:26 INFO - [mozharness: 2017-02-05 16:00:26.958330Z] Running create-virtualenv step. 08:00:26 INFO - Running pre-action listener: _resource_record_pre_action 08:00:26 INFO - Running main action method: create_virtualenv 08:00:26 INFO - Creating virtualenv /builds/slave/test/build/venv 08:00:26 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 08:00:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:00:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:00:27 INFO - Using real prefix '/tools/python27' 08:00:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:00:27 INFO - Installing distribute.............................................................................................................................................................................................done. 08:00:30 INFO - Installing pip.................done. 08:00:30 INFO - Return code: 0 08:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:00:30 INFO - Reading from file tmpfile_stdout 08:00:30 INFO - Output received: 08:00:30 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) 08:00:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:30 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 0x110130b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110133768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f881249bdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ed30>, '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 0x10fe35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff03be8>, '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.Duq9FTzHpz/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.cccmXtVwQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:00:30 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 08:00:30 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 08:00:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Duq9FTzHpz/Render', 08:00:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:30 INFO - 'HOME': '/Users/cltbld', 08:00:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:30 INFO - 'LOGNAME': 'cltbld', 08:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:30 INFO - 'MOZ_NO_REMOTE': '1', 08:00:30 INFO - 'NO_EM_RESTART': '1', 08:00:30 INFO - 'PAGER': '/bin/cat', 08:00:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:30 INFO - 'PWD': '/builds/slave/test', 08:00:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:30 INFO - 'SHELL': '/bin/bash', 08:00:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cccmXtVwQL/Listeners', 08:00:30 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:00:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:30 INFO - 'USER': 'cltbld', 08:00:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:30 INFO - 'XPC_FLAGS': '0x0', 08:00:30 INFO - 'XPC_SERVICE_NAME': '0', 08:00:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:31 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 08:00:31 INFO - Cleaning up... 08:00:31 INFO - Return code: 0 08:00:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:00: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')]} 08:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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')]} 08:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:31 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 0x110130b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110133768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f881249bdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ed30>, '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 0x10fe35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff03be8>, '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.Duq9FTzHpz/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.cccmXtVwQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:00:31 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 08:00:31 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 08:00:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Duq9FTzHpz/Render', 08:00:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:31 INFO - 'HOME': '/Users/cltbld', 08:00:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:31 INFO - 'LOGNAME': 'cltbld', 08:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:31 INFO - 'MOZ_NO_REMOTE': '1', 08:00:31 INFO - 'NO_EM_RESTART': '1', 08:00:31 INFO - 'PAGER': '/bin/cat', 08:00:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:31 INFO - 'PWD': '/builds/slave/test', 08:00:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:31 INFO - 'SHELL': '/bin/bash', 08:00:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cccmXtVwQL/Listeners', 08:00:31 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:00:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:31 INFO - 'USER': 'cltbld', 08:00:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:31 INFO - 'XPC_FLAGS': '0x0', 08:00:31 INFO - 'XPC_SERVICE_NAME': '0', 08:00:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:31 INFO - Downloading/unpacking psutil>=3.1.1 08:00:31 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 08:00:31 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 08:00:31 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 08:00:31 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 08:00:31 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 08:00:31 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 08:00:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:00:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:35 INFO - Installing collected packages: psutil 08:00:35 INFO - Running setup.py install for psutil 08:00:35 INFO - building 'psutil._psutil_osx' extension 08:00:35 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 08:00:36 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 08:00:36 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 08:00:36 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 08:00:36 INFO - building 'psutil._psutil_posix' extension 08:00:36 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 08:00:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:00:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:00:36 INFO - ^ 08:00:36 INFO - 1 warning generated. 08:00:36 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 08:00:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:36 INFO - Successfully installed psutil 08:00:36 INFO - Cleaning up... 08:00:36 INFO - Return code: 0 08:00:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:36 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 0x110130b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110133768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f881249bdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ed30>, '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 0x10fe35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff03be8>, '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.Duq9FTzHpz/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.cccmXtVwQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:00:36 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 08:00:36 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 08:00:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Duq9FTzHpz/Render', 08:00:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:36 INFO - 'HOME': '/Users/cltbld', 08:00:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:36 INFO - 'LOGNAME': 'cltbld', 08:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:36 INFO - 'MOZ_NO_REMOTE': '1', 08:00:36 INFO - 'NO_EM_RESTART': '1', 08:00:36 INFO - 'PAGER': '/bin/cat', 08:00:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:36 INFO - 'PWD': '/builds/slave/test', 08:00:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:36 INFO - 'SHELL': '/bin/bash', 08:00:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cccmXtVwQL/Listeners', 08:00:36 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:00:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:36 INFO - 'USER': 'cltbld', 08:00:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:36 INFO - 'XPC_FLAGS': '0x0', 08:00:36 INFO - 'XPC_SERVICE_NAME': '0', 08:00:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:00:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:00:39 INFO - Installing collected packages: mozsystemmonitor 08:00:39 INFO - Running setup.py install for mozsystemmonitor 08:00:39 INFO - Successfully installed mozsystemmonitor 08:00:39 INFO - Cleaning up... 08:00:39 INFO - Return code: 0 08:00:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:00:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110130b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110133768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f881249bdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ed30>, '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 0x10fe35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff03be8>, '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.Duq9FTzHpz/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.cccmXtVwQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:00:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:00:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Duq9FTzHpz/Render', 08:00:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:39 INFO - 'HOME': '/Users/cltbld', 08:00:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:39 INFO - 'LOGNAME': 'cltbld', 08:00:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:39 INFO - 'MOZ_NO_REMOTE': '1', 08:00:39 INFO - 'NO_EM_RESTART': '1', 08:00:39 INFO - 'PAGER': '/bin/cat', 08:00:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:40 INFO - 'PWD': '/builds/slave/test', 08:00:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:40 INFO - 'SHELL': '/bin/bash', 08:00:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cccmXtVwQL/Listeners', 08:00:40 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:00:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:40 INFO - 'USER': 'cltbld', 08:00:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:40 INFO - 'XPC_FLAGS': '0x0', 08:00:40 INFO - 'XPC_SERVICE_NAME': '0', 08:00:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:40 INFO - Downloading/unpacking jsonschema==2.5.1 08:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:00:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:00:42 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 08:00:42 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 08:00:42 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 08:00:42 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 08:00:42 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 08:00:42 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 08:00:43 INFO - Downloading functools32-3.2.3-2.tar.gz 08:00:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:00:43 INFO - warning: no files found matching '*.txt' 08:00:43 INFO - no previously-included directories found matching 'build' 08:00:43 INFO - no previously-included directories found matching 'dist' 08:00:43 INFO - no previously-included directories found matching '.git*' 08:00:43 INFO - Installing collected packages: jsonschema, functools32 08:00:43 INFO - Running setup.py install for functools32 08:00:43 INFO - warning: no files found matching '*.txt' 08:00:43 INFO - no previously-included directories found matching 'build' 08:00:43 INFO - no previously-included directories found matching 'dist' 08:00:43 INFO - no previously-included directories found matching '.git*' 08:00:43 INFO - Successfully installed jsonschema functools32 08:00:43 INFO - Cleaning up... 08:00:43 INFO - Return code: 0 08:00:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:00: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')]} 08:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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')]} 08:00:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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', '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 0x110130b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110133768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f881249bdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ed30>, '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 0x10fe35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff03be8>, '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.Duq9FTzHpz/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.cccmXtVwQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:00: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:00: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 functools32==3.2.3-2 08:00:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Duq9FTzHpz/Render', 08:00:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:43 INFO - 'HOME': '/Users/cltbld', 08:00:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:43 INFO - 'LOGNAME': 'cltbld', 08:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:43 INFO - 'MOZ_NO_REMOTE': '1', 08:00:43 INFO - 'NO_EM_RESTART': '1', 08:00:43 INFO - 'PAGER': '/bin/cat', 08:00:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:43 INFO - 'PWD': '/builds/slave/test', 08:00:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:43 INFO - 'SHELL': '/bin/bash', 08:00:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cccmXtVwQL/Listeners', 08:00:43 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:00:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:43 INFO - 'USER': 'cltbld', 08:00:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:43 INFO - 'XPC_FLAGS': '0x0', 08:00:43 INFO - 'XPC_SERVICE_NAME': '0', 08:00:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:00:43 INFO - Cleaning up... 08:00:43 INFO - Return code: 0 08:00:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:00: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')]} 08:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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')]} 08:00:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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', '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 0x110130b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110133768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f881249bdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ed30>, '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 0x10fe35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff03be8>, '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.Duq9FTzHpz/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.cccmXtVwQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:00: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:00: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 blobuploader==1.2.4 08:00:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Duq9FTzHpz/Render', 08:00:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:43 INFO - 'HOME': '/Users/cltbld', 08:00:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:43 INFO - 'LOGNAME': 'cltbld', 08:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:43 INFO - 'MOZ_NO_REMOTE': '1', 08:00:43 INFO - 'NO_EM_RESTART': '1', 08:00:43 INFO - 'PAGER': '/bin/cat', 08:00:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:43 INFO - 'PWD': '/builds/slave/test', 08:00:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:43 INFO - 'SHELL': '/bin/bash', 08:00:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cccmXtVwQL/Listeners', 08:00:43 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:00:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:43 INFO - 'USER': 'cltbld', 08:00:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:43 INFO - 'XPC_FLAGS': '0x0', 08:00:43 INFO - 'XPC_SERVICE_NAME': '0', 08:00:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:44 INFO - Downloading/unpacking blobuploader==1.2.4 08:00:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:46 INFO - Downloading blobuploader-1.2.4.tar.gz 08:00:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:00:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:00: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 08:00: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 08:00: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 08:00: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 08:00: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 08:00: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 08:00:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:00:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:00:47 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 08:00:47 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 08:00:47 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 08:00:47 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 08:00:47 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 08:00:47 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 08:00:48 INFO - Downloading docopt-0.6.1.tar.gz 08:00:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:00:48 INFO - Installing collected packages: blobuploader, requests, docopt 08:00:48 INFO - Running setup.py install for blobuploader 08:00:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:00:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:00:48 INFO - Running setup.py install for requests 08:00:48 INFO - Running setup.py install for docopt 08:00:49 INFO - Successfully installed blobuploader requests docopt 08:00:49 INFO - Cleaning up... 08:00:49 INFO - Return code: 0 08:00:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:49 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 0x110130b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110133768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f881249bdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ed30>, '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 0x10fe35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff03be8>, '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.Duq9FTzHpz/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.cccmXtVwQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:00:49 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 08:00:49 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 08:00:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Duq9FTzHpz/Render', 08:00:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:49 INFO - 'HOME': '/Users/cltbld', 08:00:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:49 INFO - 'LOGNAME': 'cltbld', 08:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:49 INFO - 'MOZ_NO_REMOTE': '1', 08:00:49 INFO - 'NO_EM_RESTART': '1', 08:00:49 INFO - 'PAGER': '/bin/cat', 08:00:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:49 INFO - 'PWD': '/builds/slave/test', 08:00:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:49 INFO - 'SHELL': '/bin/bash', 08:00:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cccmXtVwQL/Listeners', 08:00:49 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:00:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:49 INFO - 'USER': 'cltbld', 08:00:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:49 INFO - 'XPC_FLAGS': '0x0', 08:00:49 INFO - 'XPC_SERVICE_NAME': '0', 08:00:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:00:49 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-kiUv9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:00:49 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-vsM2m8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:00:49 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-QsHa5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:00:49 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-RUOOBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:00:49 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-2ZH0QD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:00:49 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-iRPPud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:00:49 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-n809Zl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:00:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-0lf1xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:00:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-3dHsvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:00:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-qrAbBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:00:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ZaKxA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:00:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Cm_mPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:00:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-X6r4Nb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:00:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-rjUk72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-KUh67C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:00:50 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-0JIu7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:00:51 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Q6ENVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:00:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:00:51 INFO - Running setup.py install for manifestparser 08:00:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:00:51 INFO - Running setup.py install for mozcrash 08:00:51 INFO - Running setup.py install for mozdebug 08:00:51 INFO - Running setup.py install for mozdevice 08:00:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:00:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:00:51 INFO - Running setup.py install for mozfile 08:00:51 INFO - Running setup.py install for mozhttpd 08:00:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Running setup.py install for mozinfo 08:00:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Running setup.py install for mozInstall 08:00:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Running setup.py install for mozleak 08:00:52 INFO - Running setup.py install for mozlog 08:00:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Running setup.py install for moznetwork 08:00:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:00:52 INFO - Running setup.py install for mozprocess 08:00:52 INFO - Running setup.py install for mozprofile 08:00:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:00:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:00:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:00:53 INFO - Running setup.py install for mozrunner 08:00:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:00:53 INFO - Running setup.py install for mozscreenshot 08:00:53 INFO - Running setup.py install for moztest 08:00:53 INFO - Running setup.py install for mozversion 08:00:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:00:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:00:53 INFO - Cleaning up... 08:00:53 INFO - Return code: 0 08:00:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:53 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 0x110130b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110133768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f881249bdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ed30>, '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 0x10fe35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff03be8>, '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.Duq9FTzHpz/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.cccmXtVwQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:00:53 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 08:00:53 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 08:00:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Duq9FTzHpz/Render', 08:00:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:53 INFO - 'HOME': '/Users/cltbld', 08:00:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:53 INFO - 'LOGNAME': 'cltbld', 08:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:53 INFO - 'MOZ_NO_REMOTE': '1', 08:00:53 INFO - 'NO_EM_RESTART': '1', 08:00:53 INFO - 'PAGER': '/bin/cat', 08:00:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:53 INFO - 'PWD': '/builds/slave/test', 08:00:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:53 INFO - 'SHELL': '/bin/bash', 08:00:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cccmXtVwQL/Listeners', 08:00:53 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:00:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:53 INFO - 'USER': 'cltbld', 08:00:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:53 INFO - 'XPC_FLAGS': '0x0', 08:00:53 INFO - 'XPC_SERVICE_NAME': '0', 08:00:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:00:53 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-nN7xPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:00:54 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)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:00:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-pQ2lDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:00:54 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)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:00:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-0x3LQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:00:54 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)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:00:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-hMoqlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:00:54 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)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:00:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-6w13qo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:00:54 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)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:00:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-SxIHMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:00:54 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)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:00:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-QxW6ED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:00:54 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)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:00:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-BPDxCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:00:54 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)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:00:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-UZLS_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:00:54 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)) 08:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:00:54 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Rrip9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:00:55 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)) 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:00:55 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-gsmR5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:00:55 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)) 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:00:55 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-WP8YBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:00:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:00:55 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Cy5sB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:00:55 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)) 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:00:55 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-QPe4Or-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:00:55 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)) 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:55 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-U36MKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:55 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)) 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:00:55 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-5z1qdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:00:55 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)) 08:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:00:55 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-S8Gjiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:00:55 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)) 08:00:55 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)) 08:00:55 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)) 08:00:55 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)) 08:00:55 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)) 08:00:55 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)) 08:00:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:58 INFO - Downloading blessings-1.6.tar.gz 08:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:00:58 INFO - Installing collected packages: blessings 08:00:58 INFO - Running setup.py install for blessings 08:00:58 INFO - Successfully installed blessings 08:00:58 INFO - Cleaning up... 08:00:58 INFO - Return code: 0 08:00:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:00:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:00:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:00:59 INFO - Reading from file tmpfile_stdout 08:00:59 INFO - Current package versions: 08:00:59 INFO - blessings == 1.6 08:00:59 INFO - blobuploader == 1.2.4 08:00:59 INFO - docopt == 0.6.1 08:00:59 INFO - functools32 == 3.2.3-2 08:00:59 INFO - jsonschema == 2.5.1 08:00:59 INFO - manifestparser == 1.1 08:00:59 INFO - mozInstall == 1.12 08:00:59 INFO - mozcrash == 1.0 08:00:59 INFO - mozdebug == 0.1 08:00:59 INFO - mozdevice == 0.49 08:00:59 INFO - mozfile == 1.2 08:00:59 INFO - mozhttpd == 0.7 08:00:59 INFO - mozinfo == 0.9 08:00:59 INFO - mozleak == 0.1 08:00:59 INFO - mozlog == 3.4 08:00:59 INFO - moznetwork == 0.27 08:00:59 INFO - mozprocess == 0.24 08:00:59 INFO - mozprofile == 0.28 08:00:59 INFO - mozrunner == 6.13 08:00:59 INFO - mozscreenshot == 0.1 08:00:59 INFO - mozsystemmonitor == 0.3 08:00:59 INFO - moztest == 0.8 08:00:59 INFO - mozversion == 1.4 08:00:59 INFO - psutil == 3.1.1 08:00:59 INFO - requests == 1.2.3 08:00:59 INFO - wsgiref == 0.1.2 08:00:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:59 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')]} 08:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:59 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')]} 08:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:59 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 0x110130b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110133768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f881249bdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ed30>, '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 0x10fe35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff03be8>, '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.Duq9FTzHpz/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.cccmXtVwQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:00:59 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 08:00:59 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 08:00:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Duq9FTzHpz/Render', 08:00:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:59 INFO - 'HOME': '/Users/cltbld', 08:00:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:59 INFO - 'LOGNAME': 'cltbld', 08:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:59 INFO - 'MOZ_NO_REMOTE': '1', 08:00:59 INFO - 'NO_EM_RESTART': '1', 08:00:59 INFO - 'PAGER': '/bin/cat', 08:00:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:59 INFO - 'PWD': '/builds/slave/test', 08:00:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:59 INFO - 'SHELL': '/bin/bash', 08:00:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cccmXtVwQL/Listeners', 08:00:59 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:00:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:59 INFO - 'USER': 'cltbld', 08:00:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:59 INFO - 'XPC_FLAGS': '0x0', 08:00:59 INFO - 'XPC_SERVICE_NAME': '0', 08:00:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:59 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)) 08:00:59 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)) 08:00:59 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)) 08:00:59 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)) 08:00:59 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)) 08:00:59 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)) 08:00:59 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)) 08:00:59 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)) 08:00:59 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)) 08:00:59 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)) 08:00:59 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)) 08:00:59 INFO - Cleaning up... 08:00:59 INFO - Return code: 0 08:00:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:00:59 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')]} 08:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:59 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')]} 08:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:59 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 0x110130b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110133768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f881249bdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff0ed30>, '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 0x10fe35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff03be8>, '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.Duq9FTzHpz/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.cccmXtVwQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 08:00:59 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 08:00:59 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 08:00:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Duq9FTzHpz/Render', 08:00:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:59 INFO - 'HOME': '/Users/cltbld', 08:00:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:59 INFO - 'LOGNAME': 'cltbld', 08:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:59 INFO - 'MOZ_NO_REMOTE': '1', 08:00:59 INFO - 'NO_EM_RESTART': '1', 08:00:59 INFO - 'PAGER': '/bin/cat', 08:00:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:59 INFO - 'PWD': '/builds/slave/test', 08:00:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:59 INFO - 'SHELL': '/bin/bash', 08:00:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cccmXtVwQL/Listeners', 08:00:59 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:00:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:59 INFO - 'USER': 'cltbld', 08:00:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:59 INFO - 'XPC_FLAGS': '0x0', 08:00:59 INFO - 'XPC_SERVICE_NAME': '0', 08:00:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:00:59 INFO - Cleaning up... 08:00:59 INFO - Return code: 0 08:00:59 INFO - Running post-action listener: _resource_record_post_action 08:00:59 INFO - Running post-action listener: _start_resource_monitoring 08:00:59 INFO - Starting resource monitoring. 08:00:59 INFO - [mozharness: 2017-02-05 16:00:59.621579Z] Finished create-virtualenv step (success) 08:00:59 INFO - [mozharness: 2017-02-05 16:00:59.622437Z] Running install step. 08:00:59 INFO - Running pre-action listener: _resource_record_pre_action 08:00:59 INFO - Running main action method: install 08:00:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:00:59 INFO - Reading from file tmpfile_stdout 08:00:59 INFO - Detecting whether we're running mozinstall >=1.0... 08:00:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:00:59 INFO - Reading from file tmpfile_stdout 08:00:59 INFO - Output received: 08:00:59 INFO - Usage: mozinstall [options] installer 08:00:59 INFO - Options: 08:00:59 INFO - -h, --help show this help message and exit 08:00:59 INFO - -d DEST, --destination=DEST 08:00:59 INFO - Directory to install application into. [default: 08:00:59 INFO - "/builds/slave/test"] 08:00:59 INFO - --app=APP Application being installed. [default: firefox] 08:00:59 INFO - mkdir: /builds/slave/test/build/application 08:00:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:01:19 INFO - Reading from file tmpfile_stdout 08:01:19 INFO - Output received: 08:01:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:01:19 INFO - Running post-action listener: _resource_record_post_action 08:01:19 INFO - [mozharness: 2017-02-05 16:01:19.892207Z] Finished install step (success) 08:01:19 INFO - [mozharness: 2017-02-05 16:01:19.892364Z] Running run-tests step. 08:01:19 INFO - Running pre-action listener: _resource_record_pre_action 08:01:19 INFO - Running main action method: run_tests 08:01:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:01:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:01:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:01:19 INFO - 2017-02-05 08:01:19.906 screenresolution[1641:10900] starting screenresolution argv=screenresolution get 08:01:19 INFO - 2017-02-05 08:01:19.909 screenresolution[1641:10900] Display 0: 1600x1200x32@60 08:01:19 INFO - 2017-02-05 08:01:19.915 screenresolution[1642:10903] starting screenresolution argv=screenresolution list 08:01:19 INFO - Available Modes on Display 0 08:01:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:01:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:01:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:01:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:01:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:01:20 INFO - Intel Iris: 08:01:20 INFO - Chipset Model: Intel Iris 08:01:20 INFO - Type: GPU 08:01:20 INFO - Bus: Built-In 08:01:20 INFO - VRAM (Dynamic, Max): 1536 MB 08:01:20 INFO - Vendor: Intel (0x8086) 08:01:20 INFO - Device ID: 0x0a2e 08:01:20 INFO - Revision ID: 0x0009 08:01:20 INFO - Displays: 08:01:20 INFO - Display: 08:01:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:01:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:01:20 INFO - Display Serial Number: 1600x1200 60 08:01:20 INFO - Main Display: Yes 08:01:20 INFO - Mirror: Off 08:01:20 INFO - Online: Yes 08:01:20 INFO - Rotation: Supported 08:01:20 INFO - Return code: 0 08:01:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:01:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:01:20 INFO - Python 2.7.3 08:01:20 INFO - Return code: 0 08:01:20 INFO - grabbing minidump binary from tooltool 08:01:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:20 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 0x10ff0ed30>, '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 0x10fe35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff03be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:01:20 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 08:01:20 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 08:01:20 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 08:01:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:01:20 INFO - Return code: 0 08:01:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:01:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:01:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:01:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:01:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:01:20 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/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0274', '--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 08:01:20 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/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0274 --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 08:01:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Duq9FTzHpz/Render', 08:01:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:01:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:01:20 INFO - 'HOME': '/Users/cltbld', 08:01:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:01:20 INFO - 'LOGNAME': 'cltbld', 08:01:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:01:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:01:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:20 INFO - 'MOZ_NO_REMOTE': '1', 08:01:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:01:20 INFO - 'NO_EM_RESTART': '1', 08:01:20 INFO - 'PAGER': '/bin/cat', 08:01:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:01:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:01:20 INFO - 'PWD': '/builds/slave/test', 08:01:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:01:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:01:20 INFO - 'SHELL': '/bin/bash', 08:01:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cccmXtVwQL/Listeners', 08:01:20 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 08:01:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:01:20 INFO - 'USER': 'cltbld', 08:01:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:01:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:01:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:20 INFO - 'XPC_FLAGS': '0x0', 08:01:20 INFO - 'XPC_SERVICE_NAME': '0', 08:01:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:01:20 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/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0274', '--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 08:01:20 INFO - mozversion application_buildid: 20170205072508 08:01:20 INFO - mozversion application_changeset: da7e987a9146ab24a64bc31ea22f75dbc958984b 08:01:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:01:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:01:20 INFO - mozversion application_name: Firefox 08:01:20 INFO - mozversion application_remotingname: firefox-dev 08:01:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:01:20 INFO - mozversion application_vendor: Mozilla 08:01:20 INFO - mozversion application_version: 53.0a2 08:01:20 INFO - mozversion platform_buildid: 20170205072508 08:01:20 INFO - mozversion platform_changeset: da7e987a9146ab24a64bc31ea22f75dbc958984b 08:01:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:01:20 INFO - mozversion platform_version: 53.0a2 08:01:20 INFO - using testdate: 1486310480 08:01:20 INFO - actual date: 1486310480 08:01:20 INFO - starting webserver on 'localhost:49243' 08:01:20 INFO - SUITE-START | Running 2 tests 08:01:20 INFO - TEST-START | dromaeo_css 08:01:20 INFO - operating with platform_type : mac_ 08:01:20 INFO - Initialising browser for dromaeo_css test... 08:01:20 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpmEOsQ0/profile http://localhost:49243/getInfo.html) 08:01:22 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 08:01:22 INFO - PROCESS | 1652 | colorDepth:24 08:01:22 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 08:01:22 INFO - PROCESS | 1652 | __metrics 08:01:22 INFO - PROCESS | 1652 | 1486310482923 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 08:01:22 INFO - PROCESS | 1652 | 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" 08:01:22 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:01:23 INFO - TEST-INFO | 1652: exit 0 08:01:23 INFO - Browser initialized. 08:01:23 INFO - Running cycle 1/1 for dromaeo_css test... 08:01:23 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpmEOsQ0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:01:35 INFO - PROCESS | 1655 | RSS: Main: 212733952 08:01:35 INFO - PROCESS | 1655 | 08:04:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 08:04:40 INFO - PROCESS | 1655 | RSS: Main: 387166208 08:04:40 INFO - PROCESS | 1655 | 08:06:25 INFO - PROCESS | 1655 | ************************* 08:06:25 INFO - PROCESS | 1655 | A coding exception was thrown and uncaught in a Task. 08:06:25 INFO - PROCESS | 1655 | 08:06:25 ERROR - PROCESS | 1655 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:06:25 INFO - PROCESS | 1655 | Full stack: 08:06:25 INFO - PROCESS | 1655 | ************************* 08:07:43 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 08:07:44 INFO - PROCESS | 1655 | RSS: Main: 349261824 08:07:44 INFO - PROCESS | 1655 | 08:10:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 08:10:47 INFO - PROCESS | 1655 | RSS: Main: 328888320 08:10:47 INFO - PROCESS | 1655 | 08:13:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 08:13:50 INFO - PROCESS | 1655 | RSS: Main: 324005888 08:13:50 INFO - PROCESS | 1655 | 08:16:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 08:16:54 INFO - PROCESS | 1655 | RSS: Main: 312430592 08:16:54 INFO - PROCESS | 1655 | 08:19:57 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 08:19:57 INFO - PROCESS | 1655 | RSS: Main: 429170688 08:19:57 INFO - PROCESS | 1655 | 08:19:57 INFO - PROCESS | 1655 | __start_tp_report 08:19:57 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 08:19:57 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 08:19:57 INFO - PROCESS | 1655 | |i|pagename|runs| 08:19:57 INFO - PROCESS | 1655 | |0;dojo.html;3315.4860996545535;3343.6990670839627;3356.781809182403;3373.1061268763797;3401.0987088421557;5708.771649134035;5734.225879506265;5744.281964754406;5762.308522977238;5764.423557644233;5654.349749778777;5694.658320500773;5774.422557744224;5775.249217601717;5783.797567085153;5152.587792976572;5213.061648903196;5215.113430716769;5242.4233334333385;5268.104422248227;5154.561862241714;5172.896542069157;5307.310049693534;5311.601629877755;5313.442088580701;5107.182850743881;5170.948290517084;5181.1192097343355;5187.896242075175;5215.217717342391;4239.851605193803;5158.303629010074;5160.458151894053;5170.121079416508;5182.067227898977;4198.622124008823;5000.1249781288125;5134.512221338967;5139.10065738498;5177.715225662587;5490.450954904518;5527.419620939824;5541.0580201365865;5585.804496842591;5599.692016939067;6121.081837724377;6122.877542449126;6128.264608246995;6129.877402451971;6143.232095988002;6239.65681887496;6249.625022498664;6305.968470157666;6320.431161195468;6325.588836725598;4996.600271978269;5779.162021506858;5807.709614519257;5809.9419005809405;5838.9124163137585;6165.414285642857;6298.622082675054;6323.241211054611;6343.809685709436;6371.936280637135;5068;6067.757289708362;6121.112438696364;6197.659128747872;6201.379862013762;6259.530535209878;6301.558890877686;6307.716152773136;6317;6332.778352757631;5991.400859914061;6225.159603453476;6244.500439964792;6263.217097862767;6267.843303917439;6097.512199024156;6136.470306274282;6146.7233974471255;6160.661163635953;6161.353057928853;5034.647574669812;5037.5214354636255;5053.772580233898;5081.618878584121;5086.872828179251;4448.55514448559;4454.888627784331;4473.597376236155;4476.776161191928;4500.234960056799;5235.9266350398075;5589.301337332833;5766.481016708516;5785.334686511105;5832.093432923441;5747.798827041117;6211.844703882349;6255.812325630239;6258.217722784652;6273.749050038039;5600.187972744011;5647.068233741386;5671.602987790815;5678.716064196857;5700.657960522382;5414.945850541523;5416.431274716177;5426.131818908955;5432.239486471897;5443.7822487100075;6299.84250393753;6303.873922521616;6311.810645680637;6312.337204593543;6316.557840951089;5127.66670166438;6122.093225952484;6159.322474527888;6175.104609831639;6297.55917085818;6278.686065696788;6281.905771413341;6290.622562646256;6301.369863013662;6304.5271604628915;6103.450689437972;6166.660833654192;6172.444479996821;6183.3816618337805;6183.505319574333;5306.97346513265;6110.1638887921135;6171.228596425447;6257.68711564429;6281.842953926006;6077.939220607915;6156.722947467285;6166.167567378347;6183.227096612924;6192.81421557354;6160.9383906160365;6161.907571386343;6165.876682466415;6171.166892469639;6177.7837775677635;5733.111367738007;5838.32859221195;5947.553933454921;5955;6022.307434645072;5943.346231914573;6024.939750602438;6047.03247480401;6059.697015149313;6069.635821850703;5891.705414729332;5911.113333000085;5921.437463440966;5922.615030023035;5942.524598032061 08:19:57 INFO - PROCESS | 1655 | |1;ext.html;432.4853424424934;458.9196890544154;464.5958016525621;503.15972326215217;530.0723733466434;30782.230444238907;32838.85064022754;32875.17812054697;32895.835520822424;32925.34149317012;33299.66700332996;42456.36315455271;43893.9026524337;43993.34009989853;46004.76997615016;9176.22002129818;9326.207272381856;9373.578188981512;9376.437413755162;9377.906220937772;9307.34848560601;9429.622815087389;9431.386959847623;9450.33847630666;9451.33840631156;8398.160183981583;8615.95692021539;8617.052124266325;8711.47731136134;8739.868901966476;11048.116150707965;11334.829977550384;11378.317300961886;11501.539938402453;11535.59625413115;11172.329660220414;11255.831162532568;11261.436928153642;11276.492557834918;11288.435578221057;25703;32173.83913080443;39804.800975994935;40208.798956005325;40272.194556109;3864.7101467390057;3917.118648304109;4026.939595906064;4033.2740106780766;4044.595540445932;25765.742342576523;27209.45581088367;28038.859805701046;28729.85635071832;28863;3185.108169712484;3214.6624604416666;3236.058942502183;3278.655741147169;3286.375588638175;492443;500419.4979025118;500822;503286;503351;69258.65370673114;70515;70516;70547;70567.58864822777;73649;74758.62620686862;74922;74973.625131874;75109;16688;17244.310227591006;17264.913675431544;17285;17299.567510812078;13094.672633184056;13209.273489958145;13220.537281195304;13221.537246196336;13282.070255082052;18368.173432195585;20422.183112675622;21472.033758480906;21473.570528589342;21476.24833130864;7285.526440781332;8163.020437547532;8309.169083091643;8862.689805856764;9075.092490750872;784.3803395317749;881.9118088191258;897.9057198994011;914.346242436661;931.5854444772147;30229.65177312789;30351;30360.54459183114;30588.38823223523;30782.53826192609;1018.760591261053;1041.9843702344472;1066.5946940162844;1072.9141668666643;1166.836642869999;12798.872011279767;15514.689706205812;15673.451429200151;15673.764893526606;16065.67868642644;677013;681062;681881;682003;682657;61453.38546614566;61998.69000654968;62050.689746550976;62089;62096.758064838454;1434.6588080683196;1452.4480697335157;1465.194143221224;1488.3748825493103;1491.1873029199112;28833.279168020967;29099.981500647376;29470.705292947652;29614;30525.54211686871;52253.73873130762;52416;52534.73732631312;52689.20966185587;52711;451864;540743;541154;541897;542369;42526.149477010906;42850.357244640734;43545.346819797094;43683.344749828146;43701.125977479634;47387.42427950057;47795.28307075327;48359.758201208766;48705.75647121741;49196.75401622969;37718.8114059428;38051.80974095112;38130;38419.6158038416;39319.80340098281;36054.63945360513;38574.035649109;38653.226935461695;38727;39244.80377598208;34083.488747669304;35154.64845351514;35871.56513739526;36073.91778246657;36166.63833361738;30777.69222307838;31712.682873171896;31780;31845.681543184273;32039.519407208445;24003.519929601658;24204.39489012789;24296.635550466402;24711.752882470944;25152.49695006126 08:19:57 INFO - PROCESS | 1655 | |2;jquery.html;332.9573069220333;339.9546394835877;379.43464238284946;397.21254355400697;419.92499202042774;15745.763813542791;15893.68212635748;17991.82008179916;21346.25288114916;22040.889795551;21631.485795994246;22345.55308893821;22458.43853903653;22896.31311060671;23101.537969240606;5766.769329226826;5786.218860453848;5944.702764861761;5973.1040343948325;6063.120847477113;4218.3250679891225;5875.676837773921;5995.6702381369005;6003.249593800775;6017.097435384683;4410.691251612372;4446.822127114911;5157.020166168416;5658.519025882784;6547.181602299713;6357.268914074848;6596.571222870546;6597.1423714916755;6608.041833934077;6608.537402381835;5706.572007099443;5782.97108514459;5791.826245212608;5796.652200867961;5813.941860581424;25466.10868619608;25804.35489112787;25843.74156258451;25896.87051564749;27241.318967025785;2777.4167424840807;2807.129789765159;2814.071356452386;2817.985910070457;2837.7729781617422;9291.67479138227;9451.905480945241;9811.018898110204;9908.10827025564;15216.391344346213;1751.903645299508;1802.3241284518306;1805.5034865411985;1810.2849374497;1814.6733587954163;102239;122103;124508.37745811213;130201.34899325538;144163.837542438;9585.712428627152;11898.940505297418;12047.33739644328;12160.33118178491;12211.877881221251;15958.441454549267;15979.360825567079;16014.119223442822;16031.19844007795;16035.919820400823;8636.870446943301;8791.780205494913;8958.059403762642;8971.820563588692;9075.954620226856;8549.401541892132;8782.209601135928;8793.912060879436;8918.37571369998;8944.01615822259;4737.952620473751;4760.880977975509;4782.569568738831;4793.640476964241;4856.902861942742;1631.4453085950834;1856.5173055005796;1900.8859468431663;1905.571246469561;1909.3794516782102;927.3184209605935;930.5998420679173;970.5777986575862;972.3290929258789;981.7153025622632;20684.069216885277;21804.890975545022;21850.45373865666;21928.013239404267;21980.230691925706;977.7213494154132;979.6081567372966;987.5901500877055;990.6532713550343;1004.8141093897652;10377.688669339932;11326.773464530663;11356.886431135583;11363.829542556869;11656.125790565571;758362;761364;764677;774712.632224051;791983;150229;153543;153911.23044384707;155248;156907;973.3235401395962;973.5618971462728;993.5181437003048;996.9432401654125;1001.639409812467;18387.804792688432;18664.81335186631;18954.24183032663;18983.9558824266;19162.23351065942;46670.06659866851;56650;58093.70953145207;58807;59918;78219.60890195513;78825;80145.19854801611;82005.58997204975;87563.1243687555;7874.094479134744;7945.284924356835;8048.758537243892;8481.957590212009;8575.95712021436;9119.088091190828;12080.335581543099;12917.354132293536;13359.998000149833;13400.798988015382;10075.244356673426;11028.779424411307;11204.047655949009;11294.661160165208;11742.647720568397;11150.163737719542;11303.660890173307;11322.716932076764;11650.6504804856;11690.76618467643;9003.694464302509;11499;11822.231554948901;11942.96969515167;11960.880391196324;8307.460015098999;8392.622331995197;8399.874001890099;8497.957510212409;8506.06433292325;9490.572924218288;11103.72240693989;11682.94158529202;11714.88285117138;11804.82292765592 08:19:57 INFO - PROCESS | 1655 | |3;mootools.html;1291.5285920638967;1441.66120961574;1451.8911081668878;1460.7370673278808;1463;7986.32116270117;8434.072252052285;8434.156584341563;8446.15538446155;8454.704085357022;6724.899126513106;7455.925440745591;7674.347680447155;7677.500962437438;8283.378746593999;2253.752087270403;2326.9418264543438;2329.1149363241943;2334.241371554243;2334.4747431827786;2046.6316063108634;2284.565932472833;2360.9409764755846;2361.716594008713;2376.2158487699103;2041.5916816636732;2120.130746393979;2232.464208589935;2307.746147923727;2316.270374831931;2280.5096904165603;2329.4292898239955;2343.648452732104;2347.1784875293683;2358.1392791630933;2310.676505289261;2369.360272726354;2378.488624945645;2386.844855084414;2388.8088952883727;8320.583970801435;8401.697736850798;8417.158284171595;8437.746867593982;8463.788405289855;5044.344235249431;5188.273641690166;5215.035218484554;5218.295530103425;5222.791088356461;3293.604767427924;3407.914802129942;3410.3520331137006;3424.229548351601;3498.160441494023;1165.2717051842599;1222.1322860768776;1250.8186312984658;1261.426051146726;1263.2483672215017;10529.631462898762;10726.570937162503;10781.245312828105;10851.945740271327;10899.67300980964;4463.330500424962;4614.607758340579;4662;4723.669343146015;4728.71627702339;9331.90668093324;9483.288753343526;9502.857457138149;9521.714348569554;9534.332596718163;5187.714675692873;5197.922031169535;5201.479852014768;5203.479652034766;5204.7918083277;3999.920001599952;4142.813573389205;4634.58288754007;4744.24092145262;4800.807967681323;3893.396523538856;3909.3354129798004;3971.980140099281;3975.6421922027157;4002.659773919191;1110.2125577819704;1276.304414094321;1278.6931136527166;1284.8201251824753;1285.6014635463036;904.2494729374604;913.9634414623473;931.7810314576069;940.3981451870808;977.7848873247874;5340.2256672783005;5399.93317408154;5640.379558248589;5687.77248910039;5688.459596338341;556.899758043548;556.9777208911679;567.0983136812406;571.8884817460555;572.5648507134607;3632.763870348419;3660.8352624131976;3664.4136938089778;3678.926421471609;3777.773333599993;8477.788055298544;9943.453110079015;10021.498925053864;10021.549030293654;10033.698989030341;9695.418274903388;9788.314817962817;9849.60601575929;9980.550875210633;9983.351082179635;7689.307962283421;7699.49953253048;7779.7277095302525;7862.606869656494;7883.487573307717;5336.493033161843;5420.83737487876;5434.076207044733;5525;5555.749991250323;8903.866442003246;9249.398789078688;9282.886053673332;9293.488858112869;9294.953525232331;8870.379073464796;9251.167394934488;9256.629734810802;9455.243580513405;9464.668736594185;6529.542931994714;6740.662966851736;6904.447644188353;7128.893066604109;7213.567185968858;7617.5048621839405;7947.086085100056;7960.283574478325;7962.163972782774;7965.601719913865;7038.683259253244;7136.3220494052985;7168.928310717035;7249.818754531179;7301.123865136217;6708.362705542965;6754.3921047106;6913.861722765617;6957.165140183008;6970.825729356807;6119.265688117454;6284.277308109443;6365.3316401779;6368.64972426521;6450.064740612679;4757.881052973703;4893.926591101065;4913.1893237615395;4953.133201689762;4953.2570114483115;4801.735904525284;4857.562819346276;4911.165101932608;4976.154053810788;4991.775370108281 08:19:57 INFO - PROCESS | 1655 | |4;prototype.html;347.52909807211233;356.14169850659897;358.77935069931993;367.6911394428683;370.7404816628361;4266.488021437424;4727.716337019777;4867.685724854292;5081.265270026533;5214.8373110620405;4277.700560960734;4546.272236658004;5080.364954380702;5197.896042079156;5215.687058776476;3481.843317050726;3493.1660878039106;4123.577365808791;4160.172641170116;4177.582241775828;3472.7221822254287;3573.589037260709;3985.3623420252766;4049.58264607388;4255.957440425587;3660.7437479376454;3769;4117.2177286315655;4139.6817782042;4165.104502531955;4110.876673699794;4118.629323360882;4128.669706423479;4132.524759652649;4135.77253251071;3366.5791776027995;3641.635836416364;3714.254300478771;3731.9813400933094;3744.307303148926;4261.531231564525;4372.912541749147;5065.240213967897;5151.68174321553;5185.248139019859;3803.4294855771577;4290.2706539888295;4343.913121737548;4409.2583429545475;4812.639052071074;4887.265020917559;4939.839137802614;5564.4991950724625;5614.073677843151;5666.829995100154;3140.4975203967256;3929.0766669832565;4054.3715724062818;4061.1724723874377;4069.7965101744794;4590.403247577827;5029.421616514148;5199.370802227153;5459.836204913859;5982.611130276518;3547;3699.79651119191;4163.354680024601;4185.6232939035635;4223.662107031491;3556.8440256916606;4229.429027081304;4278.2085314216965;4281.272183728774;4289.378040184219;4034.7088931541625;4608.746518941423;4838.564529192389;4878.878028049327;4907.607391408679;5290.68255904647;5771.6148124450665;5793.565482588822;5794.420557944256;5796.188533605298;1149.454009345571;1276.7296539942631;1281.7951125941586;1299.9675008124873;1397.73443045823;970.5001924009141;979.2655508368723;982.381099907054;988.1551273661061;994.5474808961905;4273.508546517191;4349.064951035542;4641.651876109306;4930.619426560569;5103.642745007814;4700;4743.810247590127;5021.819872329999;5266.315379000705;5444;4456.509783923766;4463.5227448766245;4569.702969306984;4929.531694489017;4969.776360063805;2723.986380068087;2860.599516067752;2871.296532349588;2893.9276518087217;2902.492063888811;5175.378954525406;5222.3733152022005;5445.537129343971;5796.88406231873;5810.4189581041555;4634.4242957394645;4929.5387674154945;5610.531366198941;5707.719210592056;5712.542996560265;4772.6420518461255;5374.516293533601;5675.2054712340305;5680.81288703852;5813.534917206698;4326.762028088486;4490.326451032372;5135.435102138687;5389.083855744454;5489.64317319373;4524.16302983949;4781.450133234723;5328.467153284795;5346.25152478661;5359.196120581944;5289.5503882168905;5623.341114371188;5648.689322087161;5654.660720356792;5755.798547050833;3604.215913123021;3908.257431088084;3944.8244163447553;4002.939955900722;4028.637422631977;4633.235516139799;5279.25478751055;5363.5440987516495;5556.499915007668;5569.387367389665;4721.858344249678;5347.732613369239;5556.916646250229;5567.693776842312;5687.374388817309;5552.833414997556;5575.355270195221;5596.18855265976;5617.719114044363;5665.971670141787;4508.752018639007;4843.515648435128;5290.365156181129;5543.639663422026;5544.972275138599;4517.638588912813;5617.017022021048;5660.547156227574;5661.46216109469;5663.292088488939;4690.882727931829;4925.48282430354;5241.947580524146;5538.47384498472;5580.070463006954 08:19:57 INFO - PROCESS | 1655 | |5;yui.html;692.4356649330795;725.7677543186179;781.7967328494593;824;827.288531862598;14950;15557.84442155581;15671.764923526127;15705.921470392646;15747.291371888265;14407.423703051865;14562.490312839052;14577.781333279983;14598.562043138696;14605.853941460582;681.7545683553917;703.5637904499217;708.5005071424633;859.5916939453741;877.692807517367;710.481348615511;712.5866997141671;715.0275625149792;846.1411667157839;875.0549406640845;935.0042205051599;938.2869019545161;943.1370296178987;943.6461327002374;948.924086073116;940.4921342475124;942.3591957468918;942.7454587261435;943.1652987106426;944.2681921510815;706.7243774927782;707.4764674141098;707.6603230449413;709.2411120101493;710.2684235237662;12079.818802717878;12351.567695130718;12600.4329805159;12603.117781755282;15470.226488675633;14064.226467544384;14139.929300353535;14245.358958846773;14299.428022879072;14870.256487175599;9493.145616894444;11305.208635395526;11504.309741415458;11673.474693638807;11734.765304693943;3700.7964561949084;3771.6605505504635;3773.64150405711;3833.7891415972113;3855.749376290532;88462;95494;96071;96320.03679963181;97822.04355912842;10706.357618542912;10849;11102.333859968428;11147.72130696739;11243.66269011931;21567.78432215689;21804.90975451221;22033.559328813335;22206.777932220793;22217.44456388603;7769.417293702995;7797.376209903194;7854.450188486865;7860.528368297921;7867.291943725092;4274.978625106854;4343.261645520269;4447.399601053881;4471.642268618502;4510.571495707903;3590.605033446319;3594.173340131732;3639.79981101042;3672.7979961102396;3714.7028237740915;673.1854456108066;675.1155985658803;679.0798468075785;679.4700133895569;687.2062767503542;638.7764282501088;664.5746722097862;669.1769123977535;679.8300424893777;708.8972099045609;9891.351758594761;10243.94878025605;10421.322614030063;10437.425941573287;10717.83923241152;660.4716227018462;660.8678264347054;679.8538314262358;682.6791408038214;692.4564217089561;3816.9809150954065;4112.856050038235;4898.804047838046;4908.705477671351;4929.630277729185;99736.50131749295;101127;101516;103013;105839.47080264549;86459.56770216234;88999.55500222587;89384.5530772342;89542.10457895468;90382.99999999869;74048.62975685087;78756.60621696855;79100.02249943797;80514.59742701249;80802.59598701968;9586.90413095888;9606.039396060338;9670.516474176404;9673.70978870635;9704.02959704024;50965.49034509608;51539.22691159561;52201;52548;53452.46547534475;68367;68578.9713154313;69785.65107174635;70336.64831675809;70606.94089588558;11092.445377731243;11268.661940141808;11375.715607109889;11633.6509904703;11703.882961170279;10542.683719488428;11914.38763376953;12543.811842822182;12973.351332433529;12988.805167922677;11963.64109076729;12024;12046.698832529257;12205.084618653458;12457.813132802834;11334.263272887236;11987.820182697093;12081.758364832478;12207.087182807269;12521.937390313355;11113.444327783416;12062.819057713965;12227.165925111305;12287.201331913397;12355.32045737493;9528;9845.60617575289;10068.345557538736;10094.19246460296;10415.114715249289;9772.951135244279;9775.511224438607;9783.706488805346;9811;9812.607495700378 08:19:57 INFO - PROCESS | 1655 | __end_tp_report 08:19:57 INFO - PROCESS | 1655 | __start_cc_report 08:19:57 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1839 08:19:57 INFO - PROCESS | 1655 | __end_cc_report 08:19:57 INFO - PROCESS | 1655 | __startTimestamp1486311597530__endTimestamp 08:19:57 INFO - PROCESS | 1655 | 08:19:57 INFO - PROCESS | 1655 | ------- Summary: start ------- 08:19:57 INFO - PROCESS | 1655 | Number of tests: 6 08:19:57 INFO - PROCESS | 1655 | 08:19:57 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5703.63 Median:5911.11 stddev:654.48 (11.1%) stddev-sans-first:629.10 08:19:57 INFO - PROCESS | 1655 | Values: 3315.5 3343.7 3356.8 3373.1 3401.1 5708.8 5734.2 5744.3 5762.3 5764.4 5654.3 5694.7 5774.4 5775.2 5783.8 5152.6 5213.1 5215.1 5242.4 5268.1 5154.6 5172.9 5307.3 5311.6 5313.4 5107.2 5170.9 5181.1 5187.9 5215.2 4239.9 5158.3 5160.5 5170.1 5182.1 4198.6 5000.1 5134.5 5139.1 5177.7 5490.5 5527.4 5541.1 5585.8 5599.7 6121.1 6122.9 6128.3 6129.9 6143.2 6239.7 6249.6 6306.0 6320.4 6325.6 4996.6 5779.2 5807.7 5809.9 5838.9 6165.4 6298.6 6323.2 6343.8 6371.9 5068.0 6067.8 6121.1 6197.7 6201.4 6259.5 6301.6 6307.7 6317.0 6332.8 5991.4 6225.2 6244.5 6263.2 6267.8 6097.5 6136.5 6146.7 6160.7 6161.4 5034.6 5037.5 5053.8 5081.6 5086.9 4448.6 4454.9 4473.6 4476.8 4500.2 5235.9 5589.3 5766.5 5785.3 5832.1 5747.8 6211.8 6255.8 6258.2 6273.7 5600.2 5647.1 5671.6 5678.7 5700.7 5414.9 5416.4 5426.1 5432.2 5443.8 6299.8 6303.9 6311.8 6312.3 6316.6 5127.7 6122.1 6159.3 6175.1 6297.6 6278.7 6281.9 6290.6 6301.4 6304.5 6103.5 6166.7 6172.4 6183.4 6183.5 5307.0 6110.2 6171.2 6257.7 6281.8 6077.9 6156.7 6166.2 6183.2 6192.8 6160.9 6161.9 6165.9 6171.2 6177.8 5733.1 5838.3 5947.6 5955.0 6022.3 5943.3 6024.9 6047.0 6059.7 6069.6 5891.7 5911.1 5921.4 5922.6 5942.5 08:19:57 INFO - PROCESS | 1655 | 08:19:57 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:71028.06 Median:38690.11 stddev:153462.64 (396.6%) stddev-sans-first:153801.60 08:19:57 INFO - PROCESS | 1655 | Values: 432.5 458.9 464.6 503.2 530.1 30782.2 32838.9 32875.2 32895.8 32925.3 33299.7 42456.4 43893.9 43993.3 46004.8 9176.2 9326.2 9373.6 9376.4 9377.9 9307.3 9429.6 9431.4 9450.3 9451.3 8398.2 8616.0 8617.1 8711.5 8739.9 11048.1 11334.8 11378.3 11501.5 11535.6 11172.3 11255.8 11261.4 11276.5 11288.4 25703.0 32173.8 39804.8 40208.8 40272.2 3864.7 3917.1 4026.9 4033.3 4044.6 25765.7 27209.5 28038.9 28729.9 28863.0 3185.1 3214.7 3236.1 3278.7 3286.4 492443.0 500419.5 500822.0 503286.0 503351.0 69258.7 70515.0 70516.0 70547.0 70567.6 73649.0 74758.6 74922.0 74973.6 75109.0 16688.0 17244.3 17264.9 17285.0 17299.6 13094.7 13209.3 13220.5 13221.5 13282.1 18368.2 20422.2 21472.0 21473.6 21476.2 7285.5 8163.0 8309.2 8862.7 9075.1 784.4 881.9 897.9 914.3 931.6 30229.7 30351.0 30360.5 30588.4 30782.5 1018.8 1042.0 1066.6 1072.9 1166.8 12798.9 15514.7 15673.5 15673.8 16065.7 677013.0 681062.0 681881.0 682003.0 682657.0 61453.4 61998.7 62050.7 62089.0 62096.8 1434.7 1452.4 1465.2 1488.4 1491.2 28833.3 29100.0 29470.7 29614.0 30525.5 52253.7 52416.0 52534.7 52689.2 52711.0 451864.0 540743.0 541154.0 541897.0 542369.0 42526.1 42850.4 43545.3 43683.3 43701.1 47387.4 47795.3 48359.8 48705.8 49196.8 37718.8 38051.8 38130.0 38419.6 39319.8 36054.6 38574.0 38653.2 38727.0 39244.8 34083.5 35154.6 35871.6 36073.9 36166.6 30777.7 31712.7 31780.0 31845.7 32039.5 24003.5 24204.4 24296.6 24711.8 25152.5 08:19:57 INFO - PROCESS | 1655 | 08:19:57 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:41066.04 Median:359.69 stddev:127946.97 (35571.0%) stddev-sans-first:128269.34 08:19:57 INFO - PROCESS | 1655 | Values: 333.0 340.0 379.4 397.2 419.9 15745.8 15893.7 17991.8 21346.3 22040.9 21631.5 22345.6 22458.4 22896.3 23101.5 5766.8 5786.2 5944.7 5973.1 6063.1 4218.3 5875.7 5995.7 6003.2 6017.1 4410.7 4446.8 5157.0 5658.5 6547.2 6357.3 6596.6 6597.1 6608.0 6608.5 5706.6 5783.0 5791.8 5796.7 5813.9 25466.1 25804.4 25843.7 25896.9 27241.3 2777.4 2807.1 2814.1 2818.0 2837.8 9291.7 9451.9 9811.0 9908.1 15216.4 1751.9 1802.3 1805.5 1810.3 1814.7 102239.0 122103.0 124508.4 130201.3 144163.8 9585.7 11898.9 12047.3 12160.3 12211.9 15958.4 15979.4 16014.1 16031.2 16035.9 8636.9 8791.8 8958.1 8971.8 9076.0 8549.4 8782.2 8793.9 8918.4 8944.0 4738.0 4760.9 4782.6 4793.6 4856.9 1631.4 1856.5 1900.9 1905.6 1909.4 927.3 930.6 970.6 972.3 981.7 20684.1 21804.9 21850.5 21928.0 21980.2 977.7 979.6 987.6 990.7 1004.8 10377.7 11326.8 11356.9 11363.8 11656.1 758362.0 761364.0 764677.0 774712.6 791983.0 150229.0 153543.0 153911.2 155248.0 156907.0 973.3 973.6 993.5 996.9 1001.6 18387.8 18664.8 18954.2 18984.0 19162.2 46670.1 56650.0 58093.7 58807.0 59918.0 78219.6 78825.0 80145.2 82005.6 87563.1 7874.1 7945.3 8048.8 8482.0 8576.0 9119.1 12080.3 12917.4 13360.0 13400.8 10075.2 11028.8 11204.0 11294.7 11742.6 11150.2 11303.7 11322.7 11650.7 11690.8 9003.7 11499.0 11822.2 11943.0 11960.9 8307.5 8392.6 8399.9 8498.0 8506.1 9490.6 11103.7 11682.9 11714.9 11804.8 08:19:57 INFO - PROCESS | 1655 | 08:19:57 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5381.81 Median:5216.67 stddev:2941.50 (56.4%) stddev-sans-first:2933.69 08:19:57 INFO - PROCESS | 1655 | Values: 1291.5 1441.7 1451.9 1460.7 1463.0 7986.3 8434.1 8434.2 8446.2 8454.7 6724.9 7455.9 7674.3 7677.5 8283.4 2253.8 2326.9 2329.1 2334.2 2334.5 2046.6 2284.6 2360.9 2361.7 2376.2 2041.6 2120.1 2232.5 2307.7 2316.3 2280.5 2329.4 2343.6 2347.2 2358.1 2310.7 2369.4 2378.5 2386.8 2388.8 8320.6 8401.7 8417.2 8437.7 8463.8 5044.3 5188.3 5215.0 5218.3 5222.8 3293.6 3407.9 3410.4 3424.2 3498.2 1165.3 1222.1 1250.8 1261.4 1263.2 10529.6 10726.6 10781.2 10851.9 10899.7 4463.3 4614.6 4662.0 4723.7 4728.7 9331.9 9483.3 9502.9 9521.7 9534.3 5187.7 5197.9 5201.5 5203.5 5204.8 3999.9 4142.8 4634.6 4744.2 4800.8 3893.4 3909.3 3972.0 3975.6 4002.7 1110.2 1276.3 1278.7 1284.8 1285.6 904.2 914.0 931.8 940.4 977.8 5340.2 5399.9 5640.4 5687.8 5688.5 556.9 557.0 567.1 571.9 572.6 3632.8 3660.8 3664.4 3678.9 3777.8 8477.8 9943.5 10021.5 10021.5 10033.7 9695.4 9788.3 9849.6 9980.6 9983.4 7689.3 7699.5 7779.7 7862.6 7883.5 5336.5 5420.8 5434.1 5525.0 5555.7 8903.9 9249.4 9282.9 9293.5 9295.0 8870.4 9251.2 9256.6 9455.2 9464.7 6529.5 6740.7 6904.4 7128.9 7213.6 7617.5 7947.1 7960.3 7962.2 7965.6 7038.7 7136.3 7168.9 7249.8 7301.1 6708.4 6754.4 6913.9 6957.2 6970.8 6119.3 6284.3 6365.3 6368.6 6450.1 4757.9 4893.9 4913.2 4953.1 4953.3 4801.7 4857.6 4911.2 4976.2 4991.8 08:19:57 INFO - PROCESS | 1655 | 08:19:57 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4412.95 Median:4777.05 stddev:1303.97 (27.3%) stddev-sans-first:1271.43 08:19:57 INFO - PROCESS | 1655 | Values: 347.5 356.1 358.8 367.7 370.7 4266.5 4727.7 4867.7 5081.3 5214.8 4277.7 4546.3 5080.4 5197.9 5215.7 3481.8 3493.2 4123.6 4160.2 4177.6 3472.7 3573.6 3985.4 4049.6 4256.0 3660.7 3769.0 4117.2 4139.7 4165.1 4110.9 4118.6 4128.7 4132.5 4135.8 3366.6 3641.6 3714.3 3732.0 3744.3 4261.5 4372.9 5065.2 5151.7 5185.2 3803.4 4290.3 4343.9 4409.3 4812.6 4887.3 4939.8 5564.5 5614.1 5666.8 3140.5 3929.1 4054.4 4061.2 4069.8 4590.4 5029.4 5199.4 5459.8 5982.6 3547.0 3699.8 4163.4 4185.6 4223.7 3556.8 4229.4 4278.2 4281.3 4289.4 4034.7 4608.7 4838.6 4878.9 4907.6 5290.7 5771.6 5793.6 5794.4 5796.2 1149.5 1276.7 1281.8 1300.0 1397.7 970.5 979.3 982.4 988.2 994.5 4273.5 4349.1 4641.7 4930.6 5103.6 4700.0 4743.8 5021.8 5266.3 5444.0 4456.5 4463.5 4569.7 4929.5 4969.8 2724.0 2860.6 2871.3 2893.9 2902.5 5175.4 5222.4 5445.5 5796.9 5810.4 4634.4 4929.5 5610.5 5707.7 5712.5 4772.6 5374.5 5675.2 5680.8 5813.5 4326.8 4490.3 5135.4 5389.1 5489.6 4524.2 4781.5 5328.5 5346.3 5359.2 5289.6 5623.3 5648.7 5654.7 5755.8 3604.2 3908.3 3944.8 4002.9 4028.6 4633.2 5279.3 5363.5 5556.5 5569.4 4721.9 5347.7 5556.9 5567.7 5687.4 5552.8 5575.4 5596.2 5617.7 5666.0 4508.8 4843.5 5290.4 5543.6 5545.0 4517.6 5617.0 5660.5 5661.5 5663.3 4690.9 4925.5 5241.9 5538.5 5580.1 08:19:57 INFO - PROCESS | 1655 | 08:19:57 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:19960.53 Median:51252.36 stddev:28747.22 (56.1%) stddev-sans-first:28791.46 08:19:57 INFO - PROCESS | 1655 | Values: 692.4 725.8 781.8 824.0 827.3 14950.0 15557.8 15671.8 15705.9 15747.3 14407.4 14562.5 14577.8 14598.6 14605.9 681.8 703.6 708.5 859.6 877.7 710.5 712.6 715.0 846.1 875.1 935.0 938.3 943.1 943.6 948.9 940.5 942.4 942.7 943.2 944.3 706.7 707.5 707.7 709.2 710.3 12079.8 12351.6 12600.4 12603.1 15470.2 14064.2 14139.9 14245.4 14299.4 14870.3 9493.1 11305.2 11504.3 11673.5 11734.8 3700.8 3771.7 3773.6 3833.8 3855.7 88462.0 95494.0 96071.0 96320.0 97822.0 10706.4 10849.0 11102.3 11147.7 11243.7 21567.8 21804.9 22033.6 22206.8 22217.4 7769.4 7797.4 7854.5 7860.5 7867.3 4275.0 4343.3 4447.4 4471.6 4510.6 3590.6 3594.2 3639.8 3672.8 3714.7 673.2 675.1 679.1 679.5 687.2 638.8 664.6 669.2 679.8 708.9 9891.4 10243.9 10421.3 10437.4 10717.8 660.5 660.9 679.9 682.7 692.5 3817.0 4112.9 4898.8 4908.7 4929.6 99736.5 101127.0 101516.0 103013.0 105839.5 86459.6 88999.6 89384.6 89542.1 90383.0 74048.6 78756.6 79100.0 80514.6 80802.6 9586.9 9606.0 9670.5 9673.7 9704.0 50965.5 51539.2 52201.0 52548.0 53452.5 68367.0 68579.0 69785.7 70336.6 70606.9 11092.4 11268.7 11375.7 11633.7 11703.9 10542.7 11914.4 12543.8 12973.4 12988.8 11963.6 12024.0 12046.7 12205.1 12457.8 11334.3 11987.8 12081.8 12207.1 12521.9 11113.4 12062.8 12227.2 12287.2 12355.3 9528.0 9845.6 10068.3 10094.2 10415.1 9773.0 9775.5 9783.7 9811.0 9812.6 08:19:57 INFO - PROCESS | 1655 | -------- Summary: end -------- 08:19:57 INFO - PROCESS | 1655 | 08:19:58 INFO - TEST-INFO | 1655: exit 0 08:19:58 INFO - TEST-OK | dromaeo_css | took 1117686ms 08:19:58 INFO - TEST-START | kraken 08:19:58 INFO - operating with platform_type : mac_ 08:19:58 INFO - Initialising browser for kraken test... 08:19:58 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpWuze6i/profile http://localhost:49243/getInfo.html) 08:20:00 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 08:20:00 INFO - PROCESS | 1661 | colorDepth:24 08:20:00 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 08:20:00 INFO - PROCESS | 1661 | __metrics 08:20:00 INFO - PROCESS | 1661 | 1486311600632 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 08:20:00 INFO - PROCESS | 1661 | 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" 08:20:00 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:20:01 INFO - TEST-INFO | 1661: exit 0 08:20:01 INFO - Browser initialized. 08:20:01 INFO - Running cycle 1/1 for kraken test... 08:20:01 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpWuze6i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:20:13 INFO - PROCESS | 1662 | RSS: Main: 212869120 08:20:13 INFO - PROCESS | 1662 | 08:20:16 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 08:20:17 INFO - PROCESS | 1662 | RSS: Main: 263434240 08:20:17 INFO - PROCESS | 1662 | 08:20:19 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 08:20:20 INFO - PROCESS | 1662 | RSS: Main: 276418560 08:20:20 INFO - PROCESS | 1662 | 08:20:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 08:20:24 INFO - PROCESS | 1662 | RSS: Main: 374517760 08:20:24 INFO - PROCESS | 1662 | 08:20:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 08:20:26 INFO - PROCESS | 1662 | RSS: Main: 277532672 08:20:26 INFO - PROCESS | 1662 | 08:20:28 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:20:29 INFO - PROCESS | 1662 | RSS: Main: 279085056 08:20:29 INFO - PROCESS | 1662 | 08:20:32 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 08:20:32 INFO - PROCESS | 1662 | RSS: Main: 319029248 08:20:32 INFO - PROCESS | 1662 | 08:20:35 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 08:20:36 INFO - PROCESS | 1662 | RSS: Main: 318984192 08:20:36 INFO - PROCESS | 1662 | 08:20:39 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 08:20:40 INFO - PROCESS | 1662 | RSS: Main: 316403712 08:20:40 INFO - PROCESS | 1662 | 08:20:41 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:20:42 INFO - PROCESS | 1662 | RSS: Main: 294625280 08:20:42 INFO - PROCESS | 1662 | 08:20:44 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:20:44 INFO - PROCESS | 1662 | RSS: Main: 297734144 08:20:44 INFO - PROCESS | 1662 | 08:20:47 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:20:47 INFO - PROCESS | 1662 | RSS: Main: 299184128 08:20:47 INFO - PROCESS | 1662 | 08:20:50 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:20:51 INFO - PROCESS | 1662 | RSS: Main: 316112896 08:20:51 INFO - PROCESS | 1662 | 08:20:54 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:20:54 INFO - PROCESS | 1662 | RSS: Main: 310394880 08:20:54 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 08:20:56 INFO - PROCESS | 1662 | RSS: Main: 354574336 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | __start_tp_report 08:20:56 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 08:20:56 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 08:20:56 INFO - PROCESS | 1662 | |i|pagename|runs| 08:20:56 INFO - PROCESS | 1662 | |0;ai-astar;112;114;114;114;113;116;114;113;114;114 08:20:56 INFO - PROCESS | 1662 | |1;audio-beat-detection;142;110;105;165;110;111;110;110;129;112 08:20:56 INFO - PROCESS | 1662 | |2;audio-dft;187;182;185;179;183;191;185;184;185;183 08:20:56 INFO - PROCESS | 1662 | |3;audio-fft;79;58;58;89;58;58;59;61;74;59 08:20:56 INFO - PROCESS | 1662 | |4;audio-oscillator;92;70;86;70;67;69;81;68;68;82 08:20:56 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;87;85;88;86;86;84;88;84;87;84 08:20:56 INFO - PROCESS | 1662 | |6;imaging-darkroom;93;93;151;93;93;94;94;93;98;139 08:20:56 INFO - PROCESS | 1662 | |7;imaging-desaturate;87;86;84;89;90;134;137;112;85;86 08:20:56 INFO - PROCESS | 1662 | |8;json-parse-financial;54;55;54;55;58;58;58;56;57;56 08:20:56 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;65;71;70;72;73;76;102;103;89;71 08:20:56 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;85;83;87;83;82;84;83;84;85;83 08:20:56 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;141;162;149;148;137;137;141;141;178;149 08:20:56 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;166;167;168;235;172;167;167;177;165;169 08:20:56 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;66;67;66;65;67;67;64;66;65;65 08:20:56 INFO - PROCESS | 1662 | __end_tp_report 08:20:56 INFO - PROCESS | 1662 | __start_cc_report 08:20:56 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1972 08:20:56 INFO - PROCESS | 1662 | __end_cc_report 08:20:56 INFO - PROCESS | 1662 | __startTimestamp1486311656874__endTimestamp 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | ------- Summary: start ------- 08:20:56 INFO - PROCESS | 1662 | Number of tests: 14 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:1.03 (0.9%) stddev-sans-first:0.87 08:20:56 INFO - PROCESS | 1662 | Values: 112.0 114.0 114.0 114.0 113.0 116.0 114.0 113.0 114.0 114.0 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:120.40 Median:111.50 stddev:19.32 (17.3%) stddev-sans-first:18.84 08:20:56 INFO - PROCESS | 1662 | Values: 142.0 110.0 105.0 165.0 110.0 111.0 110.0 110.0 129.0 112.0 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:184.40 Median:185.00 stddev:3.17 (1.7%) stddev-sans-first:3.22 08:20:56 INFO - PROCESS | 1662 | Values: 187.0 182.0 185.0 179.0 183.0 191.0 185.0 184.0 185.0 183.0 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:65.30 Median:60.00 stddev:11.24 (18.7%) stddev-sans-first:10.77 08:20:56 INFO - PROCESS | 1662 | Values: 79.0 58.0 58.0 89.0 58.0 58.0 59.0 61.0 74.0 59.0 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:75.30 Median:75.50 stddev:9.08 (12.0%) stddev-sans-first:7.35 08:20:56 INFO - PROCESS | 1662 | Values: 92.0 70.0 86.0 70.0 67.0 69.0 81.0 68.0 68.0 82.0 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:86.50 stddev:1.60 (1.8%) stddev-sans-first:1.64 08:20:56 INFO - PROCESS | 1662 | Values: 87.0 85.0 88.0 86.0 86.0 84.0 88.0 84.0 87.0 84.0 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:104.10 Median:93.00 stddev:21.79 (23.4%) stddev-sans-first:22.74 08:20:56 INFO - PROCESS | 1662 | Values: 93.0 93.0 151.0 93.0 93.0 94.0 94.0 93.0 98.0 139.0 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:99.00 Median:86.00 stddev:20.88 (24.3%) stddev-sans-first:21.69 08:20:56 INFO - PROCESS | 1662 | Values: 87.0 86.0 84.0 89.0 90.0 134.0 137.0 112.0 85.0 86.0 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:56.50 stddev:1.60 (2.8%) stddev-sans-first:1.50 08:20:56 INFO - PROCESS | 1662 | Values: 54.0 55.0 54.0 55.0 58.0 58.0 58.0 56.0 57.0 56.0 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:79.20 Median:71.50 stddev:13.76 (19.2%) stddev-sans-first:13.60 08:20:56 INFO - PROCESS | 1662 | Values: 65.0 71.0 70.0 72.0 73.0 76.0 102.0 103.0 89.0 71.0 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.00 stddev:1.45 (1.7%) stddev-sans-first:1.48 08:20:56 INFO - PROCESS | 1662 | Values: 85.0 83.0 87.0 83.0 82.0 84.0 83.0 84.0 85.0 83.0 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:148.50 stddev:12.85 (8.7%) stddev-sans-first:13.36 08:20:56 INFO - PROCESS | 1662 | Values: 141.0 162.0 149.0 148.0 137.0 137.0 141.0 141.0 178.0 149.0 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.30 Median:168.50 stddev:21.27 (12.6%) stddev-sans-first:22.29 08:20:56 INFO - PROCESS | 1662 | Values: 166.0 167.0 168.0 235.0 172.0 167.0 167.0 177.0 165.0 169.0 08:20:56 INFO - PROCESS | 1662 | 08:20:56 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.03 (1.6%) stddev-sans-first:1.09 08:20:56 INFO - PROCESS | 1662 | Values: 66.0 67.0 66.0 65.0 67.0 67.0 64.0 66.0 65.0 65.0 08:20:56 INFO - PROCESS | 1662 | -------- Summary: end -------- 08:20:56 INFO - PROCESS | 1662 | 08:20:57 INFO - TEST-INFO | 1662: exit 0 08:20:57 INFO - TEST-OK | kraken | took 59599ms 08:20:57 INFO - SUITE-END | took 1177s 08:20:58 INFO - Completed test suite (00:19:37) 08:20:58 INFO - javascript benchmark 08:20:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3315.4860996545535, 3343.6990670839627, 3356.781809182403, 3373.1061268763797, 3401.0987088421557, 5708.771649134035, 5734.225879506265, 5744.281964754406, 5762.308522977238, 5764.423557644233, 5654.349749778777, 5694.658320500773, 5774.422557744224, 5775.249217601717, 5783.797567085153, 5152.587792976572, 5213.061648903196, 5215.113430716769, 5242.4233334333385, 5268.104422248227, 5154.561862241714, 5172.896542069157, 5307.310049693534, 5311.601629877755, 5313.442088580701, 5107.182850743881, 5170.948290517084, 5181.1192097343355, 5187.896242075175, 5215.217717342391, 4239.851605193803, 5158.303629010074, 5160.458151894053, 5170.121079416508, 5182.067227898977, 4198.622124008823, 5000.1249781288125, 5134.512221338967, 5139.10065738498, 5177.715225662587, 5490.450954904518, 5527.419620939824, 5541.0580201365865, 5585.804496842591, 5599.692016939067, 6121.081837724377, 6122.877542449126, 6128.264608246995, 6129.877402451971, 6143.232095988002, 6239.65681887496, 6249.625022498664, 6305.968470157666, 6320.431161195468, 6325.588836725598, 4996.600271978269, 5779.162021506858, 5807.709614519257, 5809.9419005809405, 5838.9124163137585, 6165.414285642857, 6298.622082675054, 6323.241211054611, 6343.809685709436, 6371.936280637135, 5068.0, 6067.757289708362, 6121.112438696364, 6197.659128747872, 6201.379862013762, 6259.530535209878, 6301.558890877686, 6307.716152773136, 6317.0, 6332.778352757631, 5991.400859914061, 6225.159603453476, 6244.500439964792, 6263.217097862767, 6267.843303917439, 6097.512199024156, 6136.470306274282, 6146.7233974471255, 6160.661163635953, 6161.353057928853, 5034.647574669812, 5037.5214354636255, 5053.772580233898, 5081.618878584121, 5086.872828179251, 4448.55514448559, 4454.888627784331, 4473.597376236155, 4476.776161191928, 4500.234960056799, 5235.9266350398075, 5589.301337332833, 5766.481016708516, 5785.334686511105, 5832.093432923441, 5747.798827041117, 6211.844703882349, 6255.812325630239, 6258.217722784652, 6273.749050038039, 5600.187972744011, 5647.068233741386, 5671.602987790815, 5678.716064196857, 5700.657960522382, 5414.945850541523, 5416.431274716177, 5426.131818908955, 5432.239486471897, 5443.7822487100075, 6299.84250393753, 6303.873922521616, 6311.810645680637, 6312.337204593543, 6316.557840951089, 5127.66670166438, 6122.093225952484, 6159.322474527888, 6175.104609831639, 6297.55917085818, 6278.686065696788, 6281.905771413341, 6290.622562646256, 6301.369863013662, 6304.5271604628915, 6103.450689437972, 6166.660833654192, 6172.444479996821, 6183.3816618337805, 6183.505319574333, 5306.97346513265, 6110.1638887921135, 6171.228596425447, 6257.68711564429, 6281.842953926006, 6077.939220607915, 6156.722947467285, 6166.167567378347, 6183.227096612924, 6192.81421557354, 6160.9383906160365, 6161.907571386343, 6165.876682466415, 6171.166892469639, 6177.7837775677635, 5733.111367738007, 5838.32859221195, 5947.553933454921, 5955.0, 6022.307434645072, 5943.346231914573, 6024.939750602438, 6047.03247480401, 6059.697015149313, 6069.635821850703, 5891.705414729332, 5911.113333000085, 5921.437463440966, 5922.615030023035, 5942.524598032061], "value": 5662.649594231703, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.4853424424934, 458.9196890544154, 464.5958016525621, 503.15972326215217, 530.0723733466434, 30782.230444238907, 32838.85064022754, 32875.17812054697, 32895.835520822424, 32925.34149317012, 33299.66700332996, 42456.36315455271, 43893.9026524337, 43993.34009989853, 46004.76997615016, 9176.22002129818, 9326.207272381856, 9373.578188981512, 9376.437413755162, 9377.906220937772, 9307.34848560601, 9429.622815087389, 9431.386959847623, 9450.33847630666, 9451.33840631156, 8398.160183981583, 8615.95692021539, 8617.052124266325, 8711.47731136134, 8739.868901966476, 11048.116150707965, 11334.829977550384, 11378.317300961886, 11501.539938402453, 11535.59625413115, 11172.329660220414, 11255.831162532568, 11261.436928153642, 11276.492557834918, 11288.435578221057, 25703.0, 32173.83913080443, 39804.800975994935, 40208.798956005325, 40272.194556109, 3864.7101467390057, 3917.118648304109, 4026.939595906064, 4033.2740106780766, 4044.595540445932, 25765.742342576523, 27209.45581088367, 28038.859805701046, 28729.85635071832, 28863.0, 3185.108169712484, 3214.6624604416666, 3236.058942502183, 3278.655741147169, 3286.375588638175, 492443.0, 500419.4979025118, 500822.0, 503286.0, 503351.0, 69258.65370673114, 70515.0, 70516.0, 70547.0, 70567.58864822777, 73649.0, 74758.62620686862, 74922.0, 74973.625131874, 75109.0, 16688.0, 17244.310227591006, 17264.913675431544, 17285.0, 17299.567510812078, 13094.672633184056, 13209.273489958145, 13220.537281195304, 13221.537246196336, 13282.070255082052, 18368.173432195585, 20422.183112675622, 21472.033758480906, 21473.570528589342, 21476.24833130864, 7285.526440781332, 8163.020437547532, 8309.169083091643, 8862.689805856764, 9075.092490750872, 784.3803395317749, 881.9118088191258, 897.9057198994011, 914.346242436661, 931.5854444772147, 30229.65177312789, 30351.0, 30360.54459183114, 30588.38823223523, 30782.53826192609, 1018.760591261053, 1041.9843702344472, 1066.5946940162844, 1072.9141668666643, 1166.836642869999, 12798.872011279767, 15514.689706205812, 15673.451429200151, 15673.764893526606, 16065.67868642644, 677013.0, 681062.0, 681881.0, 682003.0, 682657.0, 61453.38546614566, 61998.69000654968, 62050.689746550976, 62089.0, 62096.758064838454, 1434.6588080683196, 1452.4480697335157, 1465.194143221224, 1488.3748825493103, 1491.1873029199112, 28833.279168020967, 29099.981500647376, 29470.705292947652, 29614.0, 30525.54211686871, 52253.73873130762, 52416.0, 52534.73732631312, 52689.20966185587, 52711.0, 451864.0, 540743.0, 541154.0, 541897.0, 542369.0, 42526.149477010906, 42850.357244640734, 43545.346819797094, 43683.344749828146, 43701.125977479634, 47387.42427950057, 47795.28307075327, 48359.758201208766, 48705.75647121741, 49196.75401622969, 37718.8114059428, 38051.80974095112, 38130.0, 38419.6158038416, 39319.80340098281, 36054.63945360513, 38574.035649109, 38653.226935461695, 38727.0, 39244.80377598208, 34083.488747669304, 35154.64845351514, 35871.56513739526, 36073.91778246657, 36166.63833361738, 30777.69222307838, 31712.682873171896, 31780.0, 31845.681543184273, 32039.519407208445, 24003.519929601658, 24204.39489012789, 24296.635550466402, 24711.752882470944, 25152.49695006126], "value": 20445.267035966255, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.9573069220333, 339.9546394835877, 379.43464238284946, 397.21254355400697, 419.92499202042774, 15745.763813542791, 15893.68212635748, 17991.82008179916, 21346.25288114916, 22040.889795551, 21631.485795994246, 22345.55308893821, 22458.43853903653, 22896.31311060671, 23101.537969240606, 5766.769329226826, 5786.218860453848, 5944.702764861761, 5973.1040343948325, 6063.120847477113, 4218.3250679891225, 5875.676837773921, 5995.6702381369005, 6003.249593800775, 6017.097435384683, 4410.691251612372, 4446.822127114911, 5157.020166168416, 5658.519025882784, 6547.181602299713, 6357.268914074848, 6596.571222870546, 6597.1423714916755, 6608.041833934077, 6608.537402381835, 5706.572007099443, 5782.97108514459, 5791.826245212608, 5796.652200867961, 5813.941860581424, 25466.10868619608, 25804.35489112787, 25843.74156258451, 25896.87051564749, 27241.318967025785, 2777.4167424840807, 2807.129789765159, 2814.071356452386, 2817.985910070457, 2837.7729781617422, 9291.67479138227, 9451.905480945241, 9811.018898110204, 9908.10827025564, 15216.391344346213, 1751.903645299508, 1802.3241284518306, 1805.5034865411985, 1810.2849374497, 1814.6733587954163, 102239.0, 122103.0, 124508.37745811213, 130201.34899325538, 144163.837542438, 9585.712428627152, 11898.940505297418, 12047.33739644328, 12160.33118178491, 12211.877881221251, 15958.441454549267, 15979.360825567079, 16014.119223442822, 16031.19844007795, 16035.919820400823, 8636.870446943301, 8791.780205494913, 8958.059403762642, 8971.820563588692, 9075.954620226856, 8549.401541892132, 8782.209601135928, 8793.912060879436, 8918.37571369998, 8944.01615822259, 4737.952620473751, 4760.880977975509, 4782.569568738831, 4793.640476964241, 4856.902861942742, 1631.4453085950834, 1856.5173055005796, 1900.8859468431663, 1905.571246469561, 1909.3794516782102, 927.3184209605935, 930.5998420679173, 970.5777986575862, 972.3290929258789, 981.7153025622632, 20684.069216885277, 21804.890975545022, 21850.45373865666, 21928.013239404267, 21980.230691925706, 977.7213494154132, 979.6081567372966, 987.5901500877055, 990.6532713550343, 1004.8141093897652, 10377.688669339932, 11326.773464530663, 11356.886431135583, 11363.829542556869, 11656.125790565571, 758362.0, 761364.0, 764677.0, 774712.632224051, 791983.0, 150229.0, 153543.0, 153911.23044384707, 155248.0, 156907.0, 973.3235401395962, 973.5618971462728, 993.5181437003048, 996.9432401654125, 1001.639409812467, 18387.804792688432, 18664.81335186631, 18954.24183032663, 18983.9558824266, 19162.23351065942, 46670.06659866851, 56650.0, 58093.70953145207, 58807.0, 59918.0, 78219.60890195513, 78825.0, 80145.19854801611, 82005.58997204975, 87563.1243687555, 7874.094479134744, 7945.284924356835, 8048.758537243892, 8481.957590212009, 8575.95712021436, 9119.088091190828, 12080.335581543099, 12917.354132293536, 13359.998000149833, 13400.798988015382, 10075.244356673426, 11028.779424411307, 11204.047655949009, 11294.661160165208, 11742.647720568397, 11150.163737719542, 11303.660890173307, 11322.716932076764, 11650.6504804856, 11690.76618467643, 9003.694464302509, 11499.0, 11822.231554948901, 11942.96969515167, 11960.880391196324, 8307.460015098999, 8392.622331995197, 8399.874001890099, 8497.957510212409, 8506.06433292325, 9490.572924218288, 11103.72240693989, 11682.94158529202, 11714.88285117138, 11804.82292765592], "value": 9907.970387119405, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1291.5285920638967, 1441.66120961574, 1451.8911081668878, 1460.7370673278808, 1463.0, 7986.32116270117, 8434.072252052285, 8434.156584341563, 8446.15538446155, 8454.704085357022, 6724.899126513106, 7455.925440745591, 7674.347680447155, 7677.500962437438, 8283.378746593999, 2253.752087270403, 2326.9418264543438, 2329.1149363241943, 2334.241371554243, 2334.4747431827786, 2046.6316063108634, 2284.565932472833, 2360.9409764755846, 2361.716594008713, 2376.2158487699103, 2041.5916816636732, 2120.130746393979, 2232.464208589935, 2307.746147923727, 2316.270374831931, 2280.5096904165603, 2329.4292898239955, 2343.648452732104, 2347.1784875293683, 2358.1392791630933, 2310.676505289261, 2369.360272726354, 2378.488624945645, 2386.844855084414, 2388.8088952883727, 8320.583970801435, 8401.697736850798, 8417.158284171595, 8437.746867593982, 8463.788405289855, 5044.344235249431, 5188.273641690166, 5215.035218484554, 5218.295530103425, 5222.791088356461, 3293.604767427924, 3407.914802129942, 3410.3520331137006, 3424.229548351601, 3498.160441494023, 1165.2717051842599, 1222.1322860768776, 1250.8186312984658, 1261.426051146726, 1263.2483672215017, 10529.631462898762, 10726.570937162503, 10781.245312828105, 10851.945740271327, 10899.67300980964, 4463.330500424962, 4614.607758340579, 4662.0, 4723.669343146015, 4728.71627702339, 9331.90668093324, 9483.288753343526, 9502.857457138149, 9521.714348569554, 9534.332596718163, 5187.714675692873, 5197.922031169535, 5201.479852014768, 5203.479652034766, 5204.7918083277, 3999.920001599952, 4142.813573389205, 4634.58288754007, 4744.24092145262, 4800.807967681323, 3893.396523538856, 3909.3354129798004, 3971.980140099281, 3975.6421922027157, 4002.659773919191, 1110.2125577819704, 1276.304414094321, 1278.6931136527166, 1284.8201251824753, 1285.6014635463036, 904.2494729374604, 913.9634414623473, 931.7810314576069, 940.3981451870808, 977.7848873247874, 5340.2256672783005, 5399.93317408154, 5640.379558248589, 5687.77248910039, 5688.459596338341, 556.899758043548, 556.9777208911679, 567.0983136812406, 571.8884817460555, 572.5648507134607, 3632.763870348419, 3660.8352624131976, 3664.4136938089778, 3678.926421471609, 3777.773333599993, 8477.788055298544, 9943.453110079015, 10021.498925053864, 10021.549030293654, 10033.698989030341, 9695.418274903388, 9788.314817962817, 9849.60601575929, 9980.550875210633, 9983.351082179635, 7689.307962283421, 7699.49953253048, 7779.7277095302525, 7862.606869656494, 7883.487573307717, 5336.493033161843, 5420.83737487876, 5434.076207044733, 5525.0, 5555.749991250323, 8903.866442003246, 9249.398789078688, 9282.886053673332, 9293.488858112869, 9294.953525232331, 8870.379073464796, 9251.167394934488, 9256.629734810802, 9455.243580513405, 9464.668736594185, 6529.542931994714, 6740.662966851736, 6904.447644188353, 7128.893066604109, 7213.567185968858, 7617.5048621839405, 7947.086085100056, 7960.283574478325, 7962.163972782774, 7965.601719913865, 7038.683259253244, 7136.3220494052985, 7168.928310717035, 7249.818754531179, 7301.123865136217, 6708.362705542965, 6754.3921047106, 6913.861722765617, 6957.165140183008, 6970.825729356807, 6119.265688117454, 6284.277308109443, 6365.3316401779, 6368.64972426521, 6450.064740612679, 4757.881052973703, 4893.926591101065, 4913.1893237615395, 4953.133201689762, 4953.2570114483115, 4801.735904525284, 4857.562819346276, 4911.165101932608, 4976.154053810788, 4991.775370108281], "value": 4337.36471894866, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.52909807211233, 356.14169850659897, 358.77935069931993, 367.6911394428683, 370.7404816628361, 4266.488021437424, 4727.716337019777, 4867.685724854292, 5081.265270026533, 5214.8373110620405, 4277.700560960734, 4546.272236658004, 5080.364954380702, 5197.896042079156, 5215.687058776476, 3481.843317050726, 3493.1660878039106, 4123.577365808791, 4160.172641170116, 4177.582241775828, 3472.7221822254287, 3573.589037260709, 3985.3623420252766, 4049.58264607388, 4255.957440425587, 3660.7437479376454, 3769.0, 4117.2177286315655, 4139.6817782042, 4165.104502531955, 4110.876673699794, 4118.629323360882, 4128.669706423479, 4132.524759652649, 4135.77253251071, 3366.5791776027995, 3641.635836416364, 3714.254300478771, 3731.9813400933094, 3744.307303148926, 4261.531231564525, 4372.912541749147, 5065.240213967897, 5151.68174321553, 5185.248139019859, 3803.4294855771577, 4290.2706539888295, 4343.913121737548, 4409.2583429545475, 4812.639052071074, 4887.265020917559, 4939.839137802614, 5564.4991950724625, 5614.073677843151, 5666.829995100154, 3140.4975203967256, 3929.0766669832565, 4054.3715724062818, 4061.1724723874377, 4069.7965101744794, 4590.403247577827, 5029.421616514148, 5199.370802227153, 5459.836204913859, 5982.611130276518, 3547.0, 3699.79651119191, 4163.354680024601, 4185.6232939035635, 4223.662107031491, 3556.8440256916606, 4229.429027081304, 4278.2085314216965, 4281.272183728774, 4289.378040184219, 4034.7088931541625, 4608.746518941423, 4838.564529192389, 4878.878028049327, 4907.607391408679, 5290.68255904647, 5771.6148124450665, 5793.565482588822, 5794.420557944256, 5796.188533605298, 1149.454009345571, 1276.7296539942631, 1281.7951125941586, 1299.9675008124873, 1397.73443045823, 970.5001924009141, 979.2655508368723, 982.381099907054, 988.1551273661061, 994.5474808961905, 4273.508546517191, 4349.064951035542, 4641.651876109306, 4930.619426560569, 5103.642745007814, 4700.0, 4743.810247590127, 5021.819872329999, 5266.315379000705, 5444.0, 4456.509783923766, 4463.5227448766245, 4569.702969306984, 4929.531694489017, 4969.776360063805, 2723.986380068087, 2860.599516067752, 2871.296532349588, 2893.9276518087217, 2902.492063888811, 5175.378954525406, 5222.3733152022005, 5445.537129343971, 5796.88406231873, 5810.4189581041555, 4634.4242957394645, 4929.5387674154945, 5610.531366198941, 5707.719210592056, 5712.542996560265, 4772.6420518461255, 5374.516293533601, 5675.2054712340305, 5680.81288703852, 5813.534917206698, 4326.762028088486, 4490.326451032372, 5135.435102138687, 5389.083855744454, 5489.64317319373, 4524.16302983949, 4781.450133234723, 5328.467153284795, 5346.25152478661, 5359.196120581944, 5289.5503882168905, 5623.341114371188, 5648.689322087161, 5654.660720356792, 5755.798547050833, 3604.215913123021, 3908.257431088084, 3944.8244163447553, 4002.939955900722, 4028.637422631977, 4633.235516139799, 5279.25478751055, 5363.5440987516495, 5556.499915007668, 5569.387367389665, 4721.858344249678, 5347.732613369239, 5556.916646250229, 5567.693776842312, 5687.374388817309, 5552.833414997556, 5575.355270195221, 5596.18855265976, 5617.719114044363, 5665.971670141787, 4508.752018639007, 4843.515648435128, 5290.365156181129, 5543.639663422026, 5544.972275138599, 4517.638588912813, 5617.017022021048, 5660.547156227574, 5661.46216109469, 5663.292088488939, 4690.882727931829, 4925.48282430354, 5241.947580524146, 5538.47384498472, 5580.070463006954], "value": 4024.609828468708, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.4356649330795, 725.7677543186179, 781.7967328494593, 824.0, 827.288531862598, 14950.0, 15557.84442155581, 15671.764923526127, 15705.921470392646, 15747.291371888265, 14407.423703051865, 14562.490312839052, 14577.781333279983, 14598.562043138696, 14605.853941460582, 681.7545683553917, 703.5637904499217, 708.5005071424633, 859.5916939453741, 877.692807517367, 710.481348615511, 712.5866997141671, 715.0275625149792, 846.1411667157839, 875.0549406640845, 935.0042205051599, 938.2869019545161, 943.1370296178987, 943.6461327002374, 948.924086073116, 940.4921342475124, 942.3591957468918, 942.7454587261435, 943.1652987106426, 944.2681921510815, 706.7243774927782, 707.4764674141098, 707.6603230449413, 709.2411120101493, 710.2684235237662, 12079.818802717878, 12351.567695130718, 12600.4329805159, 12603.117781755282, 15470.226488675633, 14064.226467544384, 14139.929300353535, 14245.358958846773, 14299.428022879072, 14870.256487175599, 9493.145616894444, 11305.208635395526, 11504.309741415458, 11673.474693638807, 11734.765304693943, 3700.7964561949084, 3771.6605505504635, 3773.64150405711, 3833.7891415972113, 3855.749376290532, 88462.0, 95494.0, 96071.0, 96320.03679963181, 97822.04355912842, 10706.357618542912, 10849.0, 11102.333859968428, 11147.72130696739, 11243.66269011931, 21567.78432215689, 21804.90975451221, 22033.559328813335, 22206.777932220793, 22217.44456388603, 7769.417293702995, 7797.376209903194, 7854.450188486865, 7860.528368297921, 7867.291943725092, 4274.978625106854, 4343.261645520269, 4447.399601053881, 4471.642268618502, 4510.571495707903, 3590.605033446319, 3594.173340131732, 3639.79981101042, 3672.7979961102396, 3714.7028237740915, 673.1854456108066, 675.1155985658803, 679.0798468075785, 679.4700133895569, 687.2062767503542, 638.7764282501088, 664.5746722097862, 669.1769123977535, 679.8300424893777, 708.8972099045609, 9891.351758594761, 10243.94878025605, 10421.322614030063, 10437.425941573287, 10717.83923241152, 660.4716227018462, 660.8678264347054, 679.8538314262358, 682.6791408038214, 692.4564217089561, 3816.9809150954065, 4112.856050038235, 4898.804047838046, 4908.705477671351, 4929.630277729185, 99736.50131749295, 101127.0, 101516.0, 103013.0, 105839.47080264549, 86459.56770216234, 88999.55500222587, 89384.5530772342, 89542.10457895468, 90382.99999999869, 74048.62975685087, 78756.60621696855, 79100.02249943797, 80514.59742701249, 80802.59598701968, 9586.90413095888, 9606.039396060338, 9670.516474176404, 9673.70978870635, 9704.02959704024, 50965.49034509608, 51539.22691159561, 52201.0, 52548.0, 53452.46547534475, 68367.0, 68578.9713154313, 69785.65107174635, 70336.64831675809, 70606.94089588558, 11092.445377731243, 11268.661940141808, 11375.715607109889, 11633.6509904703, 11703.882961170279, 10542.683719488428, 11914.38763376953, 12543.811842822182, 12973.351332433529, 12988.805167922677, 11963.64109076729, 12024.0, 12046.698832529257, 12205.084618653458, 12457.813132802834, 11334.263272887236, 11987.820182697093, 12081.758364832478, 12207.087182807269, 12521.937390313355, 11113.444327783416, 12062.819057713965, 12227.165925111305, 12287.201331913397, 12355.32045737493, 9528.0, 9845.60617575289, 10068.345557538736, 10094.19246460296, 10415.114715249289, 9772.951135244279, 9775.511224438607, 9783.706488805346, 9811.0, 9812.607495700378], "value": 7312.81367134125, "unit": "score"}], "name": "dromaeo_css", "value": 7260.172614958868, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 114.0, 114.0, 113.0, 116.0, 114.0, 113.0, 114.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 110.0, 105.0, 165.0, 110.0, 111.0, 110.0, 110.0, 129.0, 112.0], "value": 120.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 182.0, 185.0, 179.0, 183.0, 191.0, 185.0, 184.0, 185.0, 183.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 58.0, 89.0, 58.0, 58.0, 59.0, 61.0, 74.0, 59.0], "value": 65.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 70.0, 86.0, 70.0, 67.0, 69.0, 81.0, 68.0, 68.0, 82.0], "value": 75.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 86.0, 86.0, 84.0, 88.0, 84.0, 87.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 151.0, 93.0, 93.0, 94.0, 94.0, 93.0, 98.0, 139.0], "value": 104.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 84.0, 89.0, 90.0, 134.0, 137.0, 112.0, 85.0, 86.0], "value": 99.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 55.0, 58.0, 58.0, 58.0, 56.0, 57.0, 56.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 70.0, 72.0, 73.0, 76.0, 102.0, 103.0, 89.0, 71.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 87.0, 83.0, 82.0, 84.0, 83.0, 84.0, 85.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 162.0, 149.0, 148.0, 137.0, 137.0, 141.0, 141.0, 178.0, 149.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 168.0, 235.0, 172.0, 167.0, 167.0, 177.0, 165.0, 169.0], "value": 175.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 65.0, 67.0, 67.0, 64.0, 66.0, 65.0, 65.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1456.8, "alertThreshold": 2.0}]} 08:20:58 INFO - Return code: 0 08:20:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:20:58 ERROR - # TBPL SUCCESS # 08:20:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:20:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:20:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:20:58 INFO - 2017-02-05 08:20:58.219 screenresolution[1666:13269] starting screenresolution argv=screenresolution get 08:20:58 INFO - 2017-02-05 08:20:58.222 screenresolution[1666:13269] Display 0: 1600x1200x32@60 08:20:58 INFO - 2017-02-05 08:20:58.228 screenresolution[1667:13272] starting screenresolution argv=screenresolution list 08:20:58 INFO - Available Modes on Display 0 08:20:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:20:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:20:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:20:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:20:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:20:58 INFO - Intel Iris: 08:20:58 INFO - Chipset Model: Intel Iris 08:20:58 INFO - Type: GPU 08:20:58 INFO - Bus: Built-In 08:20:58 INFO - VRAM (Dynamic, Max): 1536 MB 08:20:58 INFO - Vendor: Intel (0x8086) 08:20:58 INFO - Device ID: 0x0a2e 08:20:58 INFO - Revision ID: 0x0009 08:20:58 INFO - Displays: 08:20:58 INFO - Display: 08:20:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:20:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:20:58 INFO - Display Serial Number: 1600x1200 60 08:20:58 INFO - Main Display: Yes 08:20:58 INFO - Mirror: Off 08:20:58 INFO - Online: Yes 08:20:58 INFO - Rotation: Supported 08:20:58 INFO - Return code: 0 08:20:58 INFO - Running post-action listener: _resource_record_post_action 08:20:58 INFO - [mozharness: 2017-02-05 16:20:58.463437Z] Finished run-tests step (success) 08:20:58 INFO - Running post-run listener: _resource_record_post_run 08:20:58 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 32422912; Write bytes: 510052864; Read time: 189; Write time: 1603 08:20:58 INFO - TinderboxPrint: CPU usage
53.6% 08:20:58 INFO - TinderboxPrint: I/O read bytes / time
32,422,912 / 189 08:20:58 INFO - TinderboxPrint: I/O write bytes / time
510,052,864 / 1,603 08:20:58 INFO - TinderboxPrint: CPU idle
2,224.9 (46.4%) 08:20:58 INFO - TinderboxPrint: CPU system
956.0 (19.9%) 08:20:58 INFO - TinderboxPrint: CPU user
1,612.7 (33.6%) 08:20:58 INFO - TinderboxPrint: Swap in / out
235,806,720 / 0 08:20:58 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205359616; Write bytes: 198795264; Read time: 15750; Write time: 439 08:20:58 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 28929024; Write bytes: 300173824; Read time: 167; Write time: 1127 08:20:58 INFO - Running post-run listener: _upload_blobber_files 08:20:58 INFO - Blob upload gear active. 08:20:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:20:58 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 08:20:58 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'] 08:20:58 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 08:20:59 INFO - (blobuploader) - INFO - Open directory for files ... 08:20:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:20:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:20:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:21:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:21:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:21:00 INFO - (blobuploader) - INFO - Done attempting. 08:21:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:21:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:21:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:21:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:21:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:21:01 INFO - (blobuploader) - INFO - Done attempting. 08:21:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:21:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:21:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:21:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:21:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:21:02 INFO - (blobuploader) - INFO - Done attempting. 08:21:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:21:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:21:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:21:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:21:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:21:04 INFO - (blobuploader) - INFO - Done attempting. 08:21:04 INFO - (blobuploader) - INFO - Iteration through files over. 08:21:04 INFO - Return code: 0 08:21:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:21:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:21:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b7698da1509d8ab216ccc362ba9d285d23da9e6e4df9198de01cefc67326283b073aa4422df43277315b857be5cd36e0cc771070670dd892028104b4a66d20c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9dc7ed32eb53affb08299be1f99a215f400e1b51083bcd77f8b8d33c7e0b85cbbbe282e34996e5d04cbbb4ce39592b04f73b1ef45aae8b98c3cbacc485f7bb1d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/546e23d3fd12216b4c1a045295d45a94b46b6b7d8fae8bd2a6bff6b12660f1ff276a5bd23242a78e365526ab824fa5293454aa3ecf693f60c120501dc568109d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49c797291ad95846ba5821b0ce3d46bb7d4381e17f3d41a92d163619f92cd3eef1895a47377d86881ba83bb9ea02fd5e60ea0f7f31440a294b088072e6672562"} 08:21:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:21:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:21:04 INFO - Contents: 08:21:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b7698da1509d8ab216ccc362ba9d285d23da9e6e4df9198de01cefc67326283b073aa4422df43277315b857be5cd36e0cc771070670dd892028104b4a66d20c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9dc7ed32eb53affb08299be1f99a215f400e1b51083bcd77f8b8d33c7e0b85cbbbe282e34996e5d04cbbb4ce39592b04f73b1ef45aae8b98c3cbacc485f7bb1d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/546e23d3fd12216b4c1a045295d45a94b46b6b7d8fae8bd2a6bff6b12660f1ff276a5bd23242a78e365526ab824fa5293454aa3ecf693f60c120501dc568109d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49c797291ad95846ba5821b0ce3d46bb7d4381e17f3d41a92d163619f92cd3eef1895a47377d86881ba83bb9ea02fd5e60ea0f7f31440a294b088072e6672562"} 08:21:04 INFO - Running post-run listener: copy_logs_to_upload_dir 08:21:04 INFO - Copying logs to upload dir... 08:21:04 INFO - mkdir: /builds/slave/test/build/upload/logs 08:21:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.855533 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-05 08:21:05.048851) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-05 08:21:05.053034) ========= 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.Duq9FTzHpz/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.cccmXtVwQL/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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/0b7698da1509d8ab216ccc362ba9d285d23da9e6e4df9198de01cefc67326283b073aa4422df43277315b857be5cd36e0cc771070670dd892028104b4a66d20c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9dc7ed32eb53affb08299be1f99a215f400e1b51083bcd77f8b8d33c7e0b85cbbbe282e34996e5d04cbbb4ce39592b04f73b1ef45aae8b98c3cbacc485f7bb1d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/546e23d3fd12216b4c1a045295d45a94b46b6b7d8fae8bd2a6bff6b12660f1ff276a5bd23242a78e365526ab824fa5293454aa3ecf693f60c120501dc568109d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49c797291ad95846ba5821b0ce3d46bb7d4381e17f3d41a92d163619f92cd3eef1895a47377d86881ba83bb9ea02fd5e60ea0f7f31440a294b088072e6672562"} build_url:https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011072 build_url: 'https://queue.taskcluster.net/v1/task/QkF09ENoRbmMEwUk4LlzKw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b7698da1509d8ab216ccc362ba9d285d23da9e6e4df9198de01cefc67326283b073aa4422df43277315b857be5cd36e0cc771070670dd892028104b4a66d20c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9dc7ed32eb53affb08299be1f99a215f400e1b51083bcd77f8b8d33c7e0b85cbbbe282e34996e5d04cbbb4ce39592b04f73b1ef45aae8b98c3cbacc485f7bb1d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/546e23d3fd12216b4c1a045295d45a94b46b6b7d8fae8bd2a6bff6b12660f1ff276a5bd23242a78e365526ab824fa5293454aa3ecf693f60c120501dc568109d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49c797291ad95846ba5821b0ce3d46bb7d4381e17f3d41a92d163619f92cd3eef1895a47377d86881ba83bb9ea02fd5e60ea0f7f31440a294b088072e6672562"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-05 08:21:05.091581) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 08:21:05.091983) ========= 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.Duq9FTzHpz/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.cccmXtVwQL/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.005250 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 08:21:05.141938) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-05 08:21:05.142492) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-05 08:21:05.142959) ========= ========= Total master_lag: 0.21 =========