builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0329 starttime: 1491814259.52 results: success (0) buildid: 20170410010548 builduid: b37d9b8ea13a455280152c94aba605c0 revision: ef9f29a17da06dda0a3a7660ea556ac068f16ad4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 01:50:59.515883) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 01:50:59.516318) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 01:50:59.516626) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 01:50:59.542230) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 01:50:59.542621) ========= 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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-10 01:50:59-- 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% 8.34M=0.001s 2017-04-10 01:51:00 (8.34 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.507355 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 01:51:00.074307) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 01:51:00.074669) ========= 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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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.021498 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 01:51:00.123627) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 01:51:00.123980) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ef9f29a17da06dda0a3a7660ea556ac068f16ad4 --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 ef9f29a17da06dda0a3a7660ea556ac068f16ad4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-10 01:51:00,214 truncating revision to first 12 chars 2017-04-10 01:51:00,214 Setting DEBUG logging. 2017-04-10 01:51:00,214 attempt 1/10 2017-04-10 01:51:00,214 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ef9f29a17da0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-10 01:51:00,525 unpacking tar archive at: mozilla-aurora-ef9f29a17da0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.592506 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 01:51:00.770571) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 01:51:00.770869) ========= script_repo_revision: ef9f29a17da06dda0a3a7660ea556ac068f16ad4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 01:51:00.771262) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 01:51:00.774612) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 01:51:00.875137) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-10 01:51:00.875509) ========= /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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 01:51:01 INFO - MultiFileLogger online at 20170410 01:51:01 in /builds/slave/test 01:51:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 01:51:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:51:01 INFO - {'append_to_log': False, 01:51:01 INFO - 'base_work_dir': '/builds/slave/test', 01:51:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:51:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:51:01 INFO - 'branch': 'Mozilla-Aurora', 01:51:01 INFO - 'buildbot_json_path': 'buildprops.json', 01:51:01 INFO - 'config_files': ('talos/mac_config.py',), 01:51:01 INFO - 'default_actions': ('clobber', 01:51:01 INFO - 'read-buildbot-config', 01:51:01 INFO - 'download-and-extract', 01:51:01 INFO - 'populate-webroot', 01:51:01 INFO - 'create-virtualenv', 01:51:01 INFO - 'install', 01:51:01 INFO - 'run-tests'), 01:51:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:51:01 INFO - 'download_minidump_stackwalk': True, 01:51:01 INFO - 'download_symbols': 'ondemand', 01:51:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:51:01 INFO - 'tooltool.py': '/tools/tooltool.py', 01:51:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:51:01 INFO - '/tools/misc-python/virtualenv.py')}, 01:51:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:51:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:51:01 INFO - 'gecko_profile': False, 01:51:01 INFO - 'gecko_profile_interval': 0, 01:51:01 INFO - 'installer_path': 'installer.exe', 01:51:01 INFO - 'log_level': 'info', 01:51:01 INFO - 'log_name': 'talos', 01:51:01 INFO - 'log_to_console': True, 01:51:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:51:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:51:01 INFO - 'opt_config_files': (), 01:51:01 INFO - 'pip_index': False, 01:51:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:51:01 INFO - 'cmd': ('bash', 01:51:01 INFO - '-c', 01:51:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:51:01 INFO - 'enabled': True, 01:51:01 INFO - 'halt_on_failure': False, 01:51:01 INFO - 'name': 'check_screen_resolution'},), 01:51:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:51:01 INFO - 'cmd': ('bash', 01:51:01 INFO - '-c', 01:51:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:51:01 INFO - 'enabled': True, 01:51:01 INFO - 'halt_on_failure': False, 01:51:01 INFO - 'name': 'check_screen_resolution'},), 01:51:01 INFO - 'run_cmd_checks_enabled': True, 01:51:01 INFO - 'suite': 'dromaeojs', 01:51:01 INFO - 'system_bits': '32', 01:51:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:51:01 INFO - 'title': 't-yosemite-r7-0329', 01:51:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:51:01 INFO - 'use_talos_json': True, 01:51:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:51:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:51:01 INFO - 'work_dir': 'build'} 01:51:01 INFO - [mozharness: 2017-04-10 08:51:01.083593Z] Running clobber step. 01:51:01 INFO - Running pre-action listener: _resource_record_pre_action 01:51:01 INFO - Running main action method: clobber 01:51:01 INFO - rmtree: /builds/slave/test/build 01:51:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:51:02 INFO - Running post-action listener: _resource_record_post_action 01:51:02 INFO - [mozharness: 2017-04-10 08:51:02.834866Z] Finished clobber step (success) 01:51:02 INFO - [mozharness: 2017-04-10 08:51:02.835026Z] Running read-buildbot-config step. 01:51:02 INFO - Running pre-action listener: _resource_record_pre_action 01:51:02 INFO - Running main action method: read_buildbot_config 01:51:02 INFO - Using buildbot properties: 01:51:02 INFO - { 01:51:02 INFO - "project": "", 01:51:02 INFO - "product": "firefox", 01:51:02 INFO - "script_repo_revision": "production", 01:51:02 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 01:51:02 INFO - "repository": "", 01:51:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 01:51:02 INFO - "buildid": "20170410010548", 01:51:02 INFO - "pgo_build": "False", 01:51:02 INFO - "basedir": "/builds/slave/test", 01:51:02 INFO - "buildnumber": 108, 01:51:02 INFO - "slavename": "t-yosemite-r7-0329", 01:51:02 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 01:51:02 INFO - "platform": "yosemite_r7", 01:51:02 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:51:02 INFO - "branch": "mozilla-aurora", 01:51:02 INFO - "repo_path": "releases/mozilla-aurora", 01:51:02 INFO - "revision": "ef9f29a17da06dda0a3a7660ea556ac068f16ad4", 01:51:02 INFO - "stage_platform": "macosx64", 01:51:02 INFO - "builduid": "b37d9b8ea13a455280152c94aba605c0", 01:51:02 INFO - "slavebuilddir": "test" 01:51:02 INFO - } 01:51:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 01:51:02 INFO - Running post-action listener: _resource_record_post_action 01:51:02 INFO - [mozharness: 2017-04-10 08:51:02.838479Z] Finished read-buildbot-config step (success) 01:51:02 INFO - [mozharness: 2017-04-10 08:51:02.838577Z] Running download-and-extract step. 01:51:02 INFO - Running pre-action listener: _resource_record_pre_action 01:51:02 INFO - Running main action method: download_and_extract 01:51:02 INFO - mkdir: /builds/slave/test/build/tests 01:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:51:02 INFO - trying https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 01:51:02 INFO - Downloading https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:51:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 01:51:04 INFO - Downloaded 1199 bytes. 01:51:04 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:51:04 INFO - Using the following test package requirements: 01:51:04 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:51:04 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:51:04 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 01:51:04 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:51:04 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 01:51:04 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:51:04 INFO - u'jsshell-mac.zip'], 01:51:04 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:51:04 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 01:51:04 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:51:04 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:51:04 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 01:51:04 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:51:04 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 01:51:04 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:51:04 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 01:51:04 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:51:04 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 01:51:04 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:51:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:51:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:51:04 INFO - Fetch https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:51:05 INFO - Expected file size: 51790003 01:51:05 INFO - Obtained file size: 51790003 01:51:21 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:51:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:51:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:51:21 INFO - Fetch https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:51:23 INFO - Expected file size: 51790003 01:51:23 INFO - Obtained file size: 51790003 01:51:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 01:51:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:51:38 INFO - Fetch https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 01:51:40 INFO - Expected file size: 14404424 01:51:40 INFO - Obtained file size: 14404424 01:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:51:40 INFO - trying https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:51:40 INFO - Downloading https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 01:51:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 01:51:42 INFO - Downloaded 74695290 bytes. 01:51:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:51:42 INFO - mkdir: /builds/slave/test/properties 01:51:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:51:42 INFO - Writing to file /builds/slave/test/properties/build_url 01:51:42 INFO - Contents: 01:51:42 INFO - build_url:https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:51:43 INFO - Running post-action listener: _resource_record_post_action 01:51:43 INFO - Running post-action listener: set_extra_try_arguments 01:51:43 INFO - [mozharness: 2017-04-10 08:51:43.221562Z] Finished download-and-extract step (success) 01:51:43 INFO - [mozharness: 2017-04-10 08:51:43.221725Z] Running populate-webroot step. 01:51:43 INFO - Running pre-action listener: _resource_record_pre_action 01:51:43 INFO - Running main action method: populate_webroot 01:51:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:51:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:51:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:51:43 INFO - '--tppagecycles', 01:51:43 INFO - '7'], 01:51:43 INFO - 'tests': ['tsvgm']}}, 01:51:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:51:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:51:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:51:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:51:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:51:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:51:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:51:43 INFO - 'talos_options': ['--disable-e10s'], 01:51:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:51:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:51:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:51:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:51:43 INFO - 'talos_options': ['--disable-e10s'], 01:51:43 INFO - 'tests': ['damp', 'tps']}, 01:51:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:51:43 INFO - 'tests': ['damp', 'tps']}, 01:51:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:51:43 INFO - 'tests': ['dromaeo_dom']}, 01:51:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:51:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:51:43 INFO - 'tests': ['basic_compositor_video']}, 01:51:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:51:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:51:43 INFO - 'tests': ['a11yr', 01:51:43 INFO - 'ts_paint', 01:51:43 INFO - 'tpaint', 01:51:43 INFO - 'sessionrestore', 01:51:43 INFO - 'sessionrestore_no_auto_restore', 01:51:43 INFO - 'tabpaint']}, 01:51:43 INFO - 'other-e10s': {'tests': ['a11yr', 01:51:43 INFO - 'ts_paint', 01:51:43 INFO - 'tpaint', 01:51:43 INFO - 'sessionrestore', 01:51:43 INFO - 'sessionrestore_no_auto_restore', 01:51:43 INFO - 'tabpaint']}, 01:51:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:51:43 INFO - 'ts_paint', 01:51:43 INFO - 'tpaint', 01:51:43 INFO - 'sessionrestore', 01:51:43 INFO - 'sessionrestore_no_auto_restore', 01:51:43 INFO - 'tabpaint']}, 01:51:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:51:43 INFO - 'ts_paint', 01:51:43 INFO - 'tpaint', 01:51:43 INFO - 'sessionrestore', 01:51:43 INFO - 'sessionrestore_no_auto_restore', 01:51:43 INFO - 'tabpaint']}, 01:51:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:51:43 INFO - 'tests': ['a11yr', 01:51:43 INFO - 'ts_paint', 01:51:43 INFO - 'tpaint', 01:51:43 INFO - 'sessionrestore', 01:51:43 INFO - 'sessionrestore_no_auto_restore', 01:51:43 INFO - 'tabpaint']}, 01:51:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:51:43 INFO - 'tests': ['a11yr', 01:51:43 INFO - 'ts_paint', 01:51:43 INFO - 'tpaint', 01:51:43 INFO - 'sessionrestore', 01:51:43 INFO - 'sessionrestore_no_auto_restore', 01:51:43 INFO - 'tabpaint']}, 01:51:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:51:43 INFO - 'tests': ['tsvgx', 01:51:43 INFO - 'tsvgr_opacity', 01:51:43 INFO - 'tart', 01:51:43 INFO - 'tscrollx', 01:51:43 INFO - 'cart', 01:51:43 INFO - 'tsvg_static']}, 01:51:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:51:43 INFO - 'tsvgr_opacity', 01:51:43 INFO - 'tart', 01:51:43 INFO - 'tscrollx', 01:51:43 INFO - 'cart', 01:51:43 INFO - 'tsvg_static']}, 01:51:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:51:43 INFO - 'talos_options': ['--disable-e10s'], 01:51:43 INFO - 'tests': ['tp5o']}, 01:51:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:51:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:51:43 INFO - 'talos_options': ['--disable-e10s', 01:51:43 INFO - '--xperf_path', 01:51:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:51:43 INFO - 'tests': ['tp5n']}, 01:51:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:51:43 INFO - 'talos_options': ['--xperf_path', 01:51:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:51:43 INFO - 'tests': ['tp5n']}}, 01:51:43 INFO - 'talos.zip': {'path': '', 01:51:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:51:43 INFO - Running post-action listener: _resource_record_post_action 01:51:43 INFO - [mozharness: 2017-04-10 08:51:43.240568Z] Finished populate-webroot step (success) 01:51:43 INFO - [mozharness: 2017-04-10 08:51:43.240703Z] Running create-virtualenv step. 01:51:43 INFO - Running pre-action listener: _resource_record_pre_action 01:51:43 INFO - Running main action method: create_virtualenv 01:51:43 INFO - Creating virtualenv /builds/slave/test/build/venv 01:51:43 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:51:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:51:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:51:43 INFO - Using real prefix '/tools/python27' 01:51:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:51:44 INFO - Installing distribute.............................................................................................................................................................................................done. 01:51:46 INFO - Installing pip.................done. 01:51:46 INFO - Return code: 0 01:51:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:51:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:51:47 INFO - Reading from file tmpfile_stdout 01:51:47 INFO - Output received: 01:51:47 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 01:51:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:51:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:51:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:51:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:51:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f2e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce51c5f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0c7d30>, '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 0x10efeedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0bcbe8>, '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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:51:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:51:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y2Jg7gZQXz/Render', 01:51:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:51:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:51:47 INFO - 'HOME': '/Users/cltbld', 01:51:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:51:47 INFO - 'LOGNAME': 'cltbld', 01:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:51:47 INFO - 'MOZ_NO_REMOTE': '1', 01:51:47 INFO - 'NO_EM_RESTART': '1', 01:51:47 INFO - 'PAGER': '/bin/cat', 01:51:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:51:47 INFO - 'PWD': '/builds/slave/test', 01:51:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:51:47 INFO - 'SHELL': '/bin/bash', 01:51:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x0RQDBTEhT/Listeners', 01:51:47 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 01:51:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:51:47 INFO - 'USER': 'cltbld', 01:51:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:51:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:51:47 INFO - 'XPC_FLAGS': '0x0', 01:51:47 INFO - 'XPC_SERVICE_NAME': '0', 01:51:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:51:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:51:47 INFO - Cleaning up... 01:51:47 INFO - Return code: 0 01:51:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:51:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:51:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:51:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:51:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f2e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce51c5f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0c7d30>, '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 0x10efeedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0bcbe8>, '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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:51:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:51:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y2Jg7gZQXz/Render', 01:51:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:51:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:51:47 INFO - 'HOME': '/Users/cltbld', 01:51:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:51:47 INFO - 'LOGNAME': 'cltbld', 01:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:51:47 INFO - 'MOZ_NO_REMOTE': '1', 01:51:47 INFO - 'NO_EM_RESTART': '1', 01:51:47 INFO - 'PAGER': '/bin/cat', 01:51:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:51:47 INFO - 'PWD': '/builds/slave/test', 01:51:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:51:47 INFO - 'SHELL': '/bin/bash', 01:51:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x0RQDBTEhT/Listeners', 01:51:47 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 01:51:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:51:47 INFO - 'USER': 'cltbld', 01:51:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:51:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:51:47 INFO - 'XPC_FLAGS': '0x0', 01:51:47 INFO - 'XPC_SERVICE_NAME': '0', 01:51:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:51:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:51:47 INFO - Downloading/unpacking psutil>=3.1.1 01:51: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 01:51: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 01:51: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 01:51: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 01:51: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 01:51: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 01:51:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:51:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:51:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:51:51 INFO - Installing collected packages: psutil 01:51:51 INFO - Running setup.py install for psutil 01:51:51 INFO - building 'psutil._psutil_osx' extension 01:51:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 01:51:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 01:51:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 01:51:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 01:51:52 INFO - building 'psutil._psutil_posix' extension 01:51:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 01:51:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:51:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:51:52 INFO - ^ 01:51:52 INFO - 1 warning generated. 01:51:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 01:51:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:51:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:51:52 INFO - Successfully installed psutil 01:51:52 INFO - Cleaning up... 01:51:52 INFO - Return code: 0 01:51:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:51:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:51:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:51:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:51:52 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 0x10f2e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce51c5f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0c7d30>, '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 0x10efeedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0bcbe8>, '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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:51:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:51:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y2Jg7gZQXz/Render', 01:51:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:51:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:51:52 INFO - 'HOME': '/Users/cltbld', 01:51:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:51:52 INFO - 'LOGNAME': 'cltbld', 01:51:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:51:52 INFO - 'MOZ_NO_REMOTE': '1', 01:51:52 INFO - 'NO_EM_RESTART': '1', 01:51:52 INFO - 'PAGER': '/bin/cat', 01:51:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:51:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:51:52 INFO - 'PWD': '/builds/slave/test', 01:51:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:51:52 INFO - 'SHELL': '/bin/bash', 01:51:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x0RQDBTEhT/Listeners', 01:51:52 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 01:51:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:51:52 INFO - 'USER': 'cltbld', 01:51:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:51:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:51:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:51:52 INFO - 'XPC_FLAGS': '0x0', 01:51:52 INFO - 'XPC_SERVICE_NAME': '0', 01:51:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:51:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:51:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:51:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:51:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:51:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:51:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:51:55 INFO - Installing collected packages: mozsystemmonitor 01:51:55 INFO - Running setup.py install for mozsystemmonitor 01:51:55 INFO - Successfully installed mozsystemmonitor 01:51:55 INFO - Cleaning up... 01:51:55 INFO - Return code: 0 01:51:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:51:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:51:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:51:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:51:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:51:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:51:55 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 0x10f2e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce51c5f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0c7d30>, '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 0x10efeedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0bcbe8>, '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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:51:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:51:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:51:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y2Jg7gZQXz/Render', 01:51:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:51:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:51:55 INFO - 'HOME': '/Users/cltbld', 01:51:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:51:55 INFO - 'LOGNAME': 'cltbld', 01:51:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:51:55 INFO - 'MOZ_NO_REMOTE': '1', 01:51:55 INFO - 'NO_EM_RESTART': '1', 01:51:55 INFO - 'PAGER': '/bin/cat', 01:51:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:51:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:51:55 INFO - 'PWD': '/builds/slave/test', 01:51:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:51:55 INFO - 'SHELL': '/bin/bash', 01:51:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x0RQDBTEhT/Listeners', 01:51:55 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 01:51:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:51:55 INFO - 'USER': 'cltbld', 01:51:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:51:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:51:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:51:55 INFO - 'XPC_FLAGS': '0x0', 01:51:55 INFO - 'XPC_SERVICE_NAME': '0', 01:51:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:51:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:51:56 INFO - Downloading/unpacking jsonschema==2.5.1 01:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:51:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:51:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:51:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:51:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:51:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:51:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:51:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:51:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:51:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:51:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:51:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:51:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:51:59 INFO - Downloading functools32-3.2.3-2.tar.gz 01:51:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:51:59 INFO - warning: no files found matching '*.txt' 01:51:59 INFO - no previously-included directories found matching 'build' 01:51:59 INFO - no previously-included directories found matching 'dist' 01:51:59 INFO - no previously-included directories found matching '.git*' 01:51:59 INFO - Installing collected packages: jsonschema, functools32 01:51:59 INFO - Running setup.py install for functools32 01:51:59 INFO - warning: no files found matching '*.txt' 01:51:59 INFO - no previously-included directories found matching 'build' 01:51:59 INFO - no previously-included directories found matching 'dist' 01:51:59 INFO - no previously-included directories found matching '.git*' 01:51:59 INFO - Successfully installed jsonschema functools32 01:51:59 INFO - Cleaning up... 01:51:59 INFO - Return code: 0 01:51:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:51: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')]} 01:51:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:51:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:51: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')]} 01:51:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:51:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:51: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', '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 0x10f2e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce51c5f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0c7d30>, '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 0x10efeedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0bcbe8>, '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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:51: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:51: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 functools32==3.2.3-2 01:51:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y2Jg7gZQXz/Render', 01:51:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:51:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:51:59 INFO - 'HOME': '/Users/cltbld', 01:51:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:51:59 INFO - 'LOGNAME': 'cltbld', 01:51:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:51:59 INFO - 'MOZ_NO_REMOTE': '1', 01:51:59 INFO - 'NO_EM_RESTART': '1', 01:51:59 INFO - 'PAGER': '/bin/cat', 01:51:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:51:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:51:59 INFO - 'PWD': '/builds/slave/test', 01:51:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:51:59 INFO - 'SHELL': '/bin/bash', 01:51:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x0RQDBTEhT/Listeners', 01:51:59 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 01:51:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:51:59 INFO - 'USER': 'cltbld', 01:51:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:51:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:51:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:51:59 INFO - 'XPC_FLAGS': '0x0', 01:51:59 INFO - 'XPC_SERVICE_NAME': '0', 01:51:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:51:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:51:59 INFO - Cleaning up... 01:51:59 INFO - Return code: 0 01:51:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:51: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')]} 01:51:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:51:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:51: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')]} 01:51:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:51:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:51: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', '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 0x10f2e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce51c5f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0c7d30>, '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 0x10efeedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0bcbe8>, '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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:51: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:51: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 blobuploader==1.2.4 01:51:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y2Jg7gZQXz/Render', 01:51:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:51:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:51:59 INFO - 'HOME': '/Users/cltbld', 01:51:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:51:59 INFO - 'LOGNAME': 'cltbld', 01:51:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:51:59 INFO - 'MOZ_NO_REMOTE': '1', 01:51:59 INFO - 'NO_EM_RESTART': '1', 01:51:59 INFO - 'PAGER': '/bin/cat', 01:51:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:51:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:51:59 INFO - 'PWD': '/builds/slave/test', 01:51:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:51:59 INFO - 'SHELL': '/bin/bash', 01:51:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x0RQDBTEhT/Listeners', 01:51:59 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 01:51:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:51:59 INFO - 'USER': 'cltbld', 01:51:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:51:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:51:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:51:59 INFO - 'XPC_FLAGS': '0x0', 01:51:59 INFO - 'XPC_SERVICE_NAME': '0', 01:51:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:51:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:51:59 INFO - Downloading/unpacking blobuploader==1.2.4 01:51:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:51:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:51:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:51:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:51:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:51:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:02 INFO - Downloading blobuploader-1.2.4.tar.gz 01:52:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:52:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:52:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:52:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:03 INFO - Downloading docopt-0.6.1.tar.gz 01:52:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:52:03 INFO - Installing collected packages: blobuploader, requests, docopt 01:52:03 INFO - Running setup.py install for blobuploader 01:52:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:52:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:52:04 INFO - Running setup.py install for requests 01:52:04 INFO - Running setup.py install for docopt 01:52:04 INFO - Successfully installed blobuploader requests docopt 01:52:04 INFO - Cleaning up... 01:52:04 INFO - Return code: 0 01:52:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:52:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:04 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 0x10f2e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce51c5f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0c7d30>, '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 0x10efeedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0bcbe8>, '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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:52:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:52:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:52:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y2Jg7gZQXz/Render', 01:52:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:04 INFO - 'HOME': '/Users/cltbld', 01:52:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:04 INFO - 'LOGNAME': 'cltbld', 01:52:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:04 INFO - 'MOZ_NO_REMOTE': '1', 01:52:04 INFO - 'NO_EM_RESTART': '1', 01:52:04 INFO - 'PAGER': '/bin/cat', 01:52:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:04 INFO - 'PWD': '/builds/slave/test', 01:52:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:04 INFO - 'SHELL': '/bin/bash', 01:52:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x0RQDBTEhT/Listeners', 01:52:04 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 01:52:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:04 INFO - 'USER': 'cltbld', 01:52:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:04 INFO - 'XPC_FLAGS': '0x0', 01:52:04 INFO - 'XPC_SERVICE_NAME': '0', 01:52:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:52:05 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-U2zUzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:52:05 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-OaClHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:52:05 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-0xgNdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:52:05 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-5XT5EZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:52:05 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-9svYxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:52:05 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-d35hor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:52:05 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-ykO1W9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:52:05 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-GyUqJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:52:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-0rYM7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:52:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-JeWwfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:52:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-A4NXYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:52:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-xuJ5z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:52:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-P8zylB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:52:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-EvZXS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-EWC6fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:52:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-MKMdvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:52:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:52:06 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-VMFARf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:52:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:52:06 INFO - Running setup.py install for manifestparser 01:52:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:52:07 INFO - Running setup.py install for mozcrash 01:52:07 INFO - Running setup.py install for mozdebug 01:52:07 INFO - Running setup.py install for mozdevice 01:52:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:52:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:52:07 INFO - Running setup.py install for mozfile 01:52:07 INFO - Running setup.py install for mozhttpd 01:52:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:52:07 INFO - Running setup.py install for mozinfo 01:52:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:52:08 INFO - Running setup.py install for mozInstall 01:52:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:52:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:52:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:52:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:52:08 INFO - Running setup.py install for mozleak 01:52:08 INFO - Running setup.py install for mozlog 01:52:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:52:08 INFO - Running setup.py install for moznetwork 01:52:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:52:08 INFO - Running setup.py install for mozprocess 01:52:08 INFO - Running setup.py install for mozprofile 01:52:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:52:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:52:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:52:08 INFO - Running setup.py install for mozrunner 01:52:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:52:09 INFO - Running setup.py install for mozscreenshot 01:52:09 INFO - Running setup.py install for moztest 01:52:09 INFO - Running setup.py install for mozversion 01:52:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:52:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:52:09 INFO - Cleaning up... 01:52:09 INFO - Return code: 0 01:52:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:09 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 0x10f2e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce51c5f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0c7d30>, '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 0x10efeedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0bcbe8>, '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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:52:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:52:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y2Jg7gZQXz/Render', 01:52:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:09 INFO - 'HOME': '/Users/cltbld', 01:52:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:09 INFO - 'LOGNAME': 'cltbld', 01:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:09 INFO - 'MOZ_NO_REMOTE': '1', 01:52:09 INFO - 'NO_EM_RESTART': '1', 01:52:09 INFO - 'PAGER': '/bin/cat', 01:52:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:09 INFO - 'PWD': '/builds/slave/test', 01:52:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:09 INFO - 'SHELL': '/bin/bash', 01:52:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x0RQDBTEhT/Listeners', 01:52:09 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 01:52:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:09 INFO - 'USER': 'cltbld', 01:52:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:09 INFO - 'XPC_FLAGS': '0x0', 01:52:09 INFO - 'XPC_SERVICE_NAME': '0', 01:52:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:52:09 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-SdCuQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:52:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:52:09 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-Ag439i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:52:10 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-5gIcRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:52:10 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-OWStYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:52:10 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-EXJKqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:52:10 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-4DOLLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:52:10 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-9kLvjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:52:10 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-LlnbSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:52:10 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-4YzHyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:52:10 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-8F0c6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:52:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:52:10 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-J_g4qw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:52:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:52:11 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-6Iej7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:52:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:52:11 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-bSeSyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:52:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:52:11 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-dV5uMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:52:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:11 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-FtVBSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:52:11 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-5yEIlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:52:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:52:11 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-Tv2Kkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:52:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:52:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:52:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:52:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:52:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:52:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:52:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:14 INFO - Downloading blessings-1.6.tar.gz 01:52:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:52:14 INFO - Installing collected packages: blessings 01:52:14 INFO - Running setup.py install for blessings 01:52:14 INFO - Successfully installed blessings 01:52:14 INFO - Cleaning up... 01:52:14 INFO - Return code: 0 01:52:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:52:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:52:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:52:14 INFO - Reading from file tmpfile_stdout 01:52:14 INFO - Current package versions: 01:52:14 INFO - blessings == 1.6 01:52:14 INFO - blobuploader == 1.2.4 01:52:14 INFO - docopt == 0.6.1 01:52:14 INFO - functools32 == 3.2.3-2 01:52:14 INFO - jsonschema == 2.5.1 01:52:14 INFO - manifestparser == 1.1 01:52:14 INFO - mozInstall == 1.12 01:52:14 INFO - mozcrash == 1.0 01:52:14 INFO - mozdebug == 0.1 01:52:14 INFO - mozdevice == 0.49 01:52:14 INFO - mozfile == 1.2 01:52:14 INFO - mozhttpd == 0.7 01:52:14 INFO - mozinfo == 0.9 01:52:14 INFO - mozleak == 0.1 01:52:14 INFO - mozlog == 3.4 01:52:14 INFO - moznetwork == 0.27 01:52:14 INFO - mozprocess == 0.25 01:52:14 INFO - mozprofile == 0.28 01:52:14 INFO - mozrunner == 6.13 01:52:14 INFO - mozscreenshot == 0.1 01:52:14 INFO - mozsystemmonitor == 0.3 01:52:14 INFO - moztest == 0.8 01:52:14 INFO - mozversion == 1.4 01:52:14 INFO - psutil == 3.1.1 01:52:14 INFO - requests == 1.2.3 01:52:14 INFO - wsgiref == 0.1.2 01:52:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:52:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:14 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 0x10f2e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce51c5f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0c7d30>, '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 0x10efeedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0bcbe8>, '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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:52:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:52:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:52:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y2Jg7gZQXz/Render', 01:52:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:14 INFO - 'HOME': '/Users/cltbld', 01:52:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:14 INFO - 'LOGNAME': 'cltbld', 01:52:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:14 INFO - 'MOZ_NO_REMOTE': '1', 01:52:14 INFO - 'NO_EM_RESTART': '1', 01:52:14 INFO - 'PAGER': '/bin/cat', 01:52:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:14 INFO - 'PWD': '/builds/slave/test', 01:52:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:14 INFO - 'SHELL': '/bin/bash', 01:52:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x0RQDBTEhT/Listeners', 01:52:14 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 01:52:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:14 INFO - 'USER': 'cltbld', 01:52:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:14 INFO - 'XPC_FLAGS': '0x0', 01:52:14 INFO - 'XPC_SERVICE_NAME': '0', 01:52:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:52:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:52:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:52:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:52:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:52:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:52:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:52:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:52:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:52:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:52:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:52:15 INFO - Cleaning up... 01:52:15 INFO - Return code: 0 01:52:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:52:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:15 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 0x10f2e9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2ec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce51c5f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0c7d30>, '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 0x10efeedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0bcbe8>, '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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:52:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:52:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:52:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y2Jg7gZQXz/Render', 01:52:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:15 INFO - 'HOME': '/Users/cltbld', 01:52:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:15 INFO - 'LOGNAME': 'cltbld', 01:52:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:15 INFO - 'MOZ_NO_REMOTE': '1', 01:52:15 INFO - 'NO_EM_RESTART': '1', 01:52:15 INFO - 'PAGER': '/bin/cat', 01:52:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:15 INFO - 'PWD': '/builds/slave/test', 01:52:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:15 INFO - 'SHELL': '/bin/bash', 01:52:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x0RQDBTEhT/Listeners', 01:52:15 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 01:52:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:15 INFO - 'USER': 'cltbld', 01:52:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:15 INFO - 'XPC_FLAGS': '0x0', 01:52:15 INFO - 'XPC_SERVICE_NAME': '0', 01:52:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:52:15 INFO - Cleaning up... 01:52:15 INFO - Return code: 0 01:52:15 INFO - Running post-action listener: _resource_record_post_action 01:52:15 INFO - Running post-action listener: _start_resource_monitoring 01:52:15 INFO - Starting resource monitoring. 01:52:15 INFO - [mozharness: 2017-04-10 08:52:15.505837Z] Finished create-virtualenv step (success) 01:52:15 INFO - [mozharness: 2017-04-10 08:52:15.506848Z] Running install step. 01:52:15 INFO - Running pre-action listener: _resource_record_pre_action 01:52:15 INFO - Running main action method: install 01:52:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:52:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:52:15 INFO - Reading from file tmpfile_stdout 01:52:15 INFO - Detecting whether we're running mozinstall >=1.0... 01:52:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:52:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:52:15 INFO - Reading from file tmpfile_stdout 01:52:15 INFO - Output received: 01:52:15 INFO - Usage: mozinstall [options] installer 01:52:15 INFO - Options: 01:52:15 INFO - -h, --help show this help message and exit 01:52:15 INFO - -d DEST, --destination=DEST 01:52:15 INFO - Directory to install application into. [default: 01:52:15 INFO - "/builds/slave/test"] 01:52:15 INFO - --app=APP Application being installed. [default: firefox] 01:52:15 INFO - mkdir: /builds/slave/test/build/application 01:52:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:52:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 01:52:35 INFO - Reading from file tmpfile_stdout 01:52:35 INFO - Output received: 01:52:35 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:52:35 INFO - Running post-action listener: _resource_record_post_action 01:52:35 INFO - [mozharness: 2017-04-10 08:52:35.781583Z] Finished install step (success) 01:52:35 INFO - [mozharness: 2017-04-10 08:52:35.781763Z] Running run-tests step. 01:52:35 INFO - Running pre-action listener: _resource_record_pre_action 01:52:35 INFO - Running main action method: run_tests 01:52:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:52:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:52:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:52:35 INFO - 2017-04-10 01:52:35.796 screenresolution[1910:23068] starting screenresolution argv=screenresolution get 01:52:35 INFO - 2017-04-10 01:52:35.799 screenresolution[1910:23068] Display 0: 1600x1200x32@60 01:52:35 INFO - 2017-04-10 01:52:35.805 screenresolution[1911:23071] starting screenresolution argv=screenresolution list 01:52:35 INFO - Available Modes on Display 0 01:52:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:52:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:52:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:52:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:52:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:52:36 INFO - Intel Iris: 01:52:36 INFO - Chipset Model: Intel Iris 01:52:36 INFO - Type: GPU 01:52:36 INFO - Bus: Built-In 01:52:36 INFO - VRAM (Dynamic, Max): 1536 MB 01:52:36 INFO - Vendor: Intel (0x8086) 01:52:36 INFO - Device ID: 0x0a2e 01:52:36 INFO - Revision ID: 0x0009 01:52:36 INFO - Displays: 01:52:36 INFO - Display: 01:52:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:52:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:52:36 INFO - Display Serial Number: 1600x1200 60 01:52:36 INFO - Main Display: Yes 01:52:36 INFO - Mirror: Off 01:52:36 INFO - Online: Yes 01:52:36 INFO - Rotation: Supported 01:52:36 INFO - Return code: 0 01:52:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:52:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:52:36 INFO - Python 2.7.3 01:52:36 INFO - Return code: 0 01:52:36 INFO - grabbing minidump binary from tooltool 01:52: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')]} 01:52:36 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 0x10f0c7d30>, '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 0x10efeedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0bcbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:52:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:52:36 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 01:52:36 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 01:52:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:52:36 INFO - Return code: 0 01:52:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:52:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:52:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:52:36 INFO - ENV: RUST_BACKTRACE is now 1 01:52:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:52:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:52:36 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/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0329', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:52:36 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/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0329 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:52:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y2Jg7gZQXz/Render', 01:52:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:36 INFO - 'HOME': '/Users/cltbld', 01:52:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:36 INFO - 'LOGNAME': 'cltbld', 01:52:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:52:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:52:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:36 INFO - 'MOZ_NO_REMOTE': '1', 01:52:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:52:36 INFO - 'NO_EM_RESTART': '1', 01:52:36 INFO - 'PAGER': '/bin/cat', 01:52:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:36 INFO - 'PWD': '/builds/slave/test', 01:52:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:52:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:36 INFO - 'RUST_BACKTRACE': '1', 01:52:36 INFO - 'SHELL': '/bin/bash', 01:52:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x0RQDBTEhT/Listeners', 01:52:36 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 01:52:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:36 INFO - 'USER': 'cltbld', 01:52:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:36 INFO - 'XPC_FLAGS': '0x0', 01:52:36 INFO - 'XPC_SERVICE_NAME': '0', 01:52:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:36 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/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0329', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:52:36 INFO - mozversion application_buildid: 20170410010548 01:52:36 INFO - mozversion application_changeset: ef9f29a17da06dda0a3a7660ea556ac068f16ad4 01:52:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:52:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:52:36 INFO - mozversion application_name: Firefox 01:52:36 INFO - mozversion application_remotingname: firefox-dev 01:52:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:52:36 INFO - mozversion application_vendor: Mozilla 01:52:36 INFO - mozversion application_version: 54.0a2 01:52:36 INFO - mozversion platform_buildid: 20170410010548 01:52:36 INFO - mozversion platform_changeset: ef9f29a17da06dda0a3a7660ea556ac068f16ad4 01:52:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:52:36 INFO - mozversion platform_version: 54.0a2 01:52:36 INFO - using testdate: 1491814356 01:52:36 INFO - actual date: 1491814356 01:52:36 INFO - starting webserver on 'localhost:56537' 01:52:36 INFO - SUITE-START | Running 2 tests 01:52:36 INFO - TEST-START | dromaeo_css 01:52:36 INFO - operating with platform_type : mac_ 01:52:36 INFO - Initialising browser for dromaeo_css test... 01:52:36 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpDDpexy/profile http://localhost:56537/getInfo.html) 01:52:38 INFO - PROCESS | 1921 | __metrics Screen width/height:1600/1200 01:52:38 INFO - PROCESS | 1921 | colorDepth:24 01:52:38 INFO - PROCESS | 1921 | Browser inner width/height: 1024/689 01:52:38 INFO - PROCESS | 1921 | __metrics 01:52:38 INFO - PROCESS | 1921 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:52:38 INFO - PROCESS | 1921 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:52:39 INFO - TEST-INFO | 1921: exit 0 01:52:39 INFO - Browser initialized. 01:52:39 INFO - Running cycle 1/1 for dromaeo_css test... 01:52:39 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpDDpexy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:52:52 INFO - PROCESS | 1923 | RSS: Main: 214994944 01:52:52 INFO - PROCESS | 1923 | 01:55:55 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:56537/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56537/tests/dromaeo/cssquery-ext.html) 01:55:57 INFO - PROCESS | 1923 | RSS: Main: 377126912 01:55:57 INFO - PROCESS | 1923 | 01:57:41 INFO - PROCESS | 1923 | ************************* 01:57:41 INFO - PROCESS | 1923 | A coding exception was thrown and uncaught in a Task. 01:57:41 INFO - PROCESS | 1923 | 01:57:41 ERROR - PROCESS | 1923 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:57:41 INFO - PROCESS | 1923 | Full stack: 01:57:41 INFO - PROCESS | 1923 | ************************* 01:57:41 INFO - PROCESS | 1923 | ************************* 01:57:41 INFO - PROCESS | 1923 | A coding exception was thrown and uncaught in a Task. 01:57:41 INFO - PROCESS | 1923 | 01:57:41 ERROR - PROCESS | 1923 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:57:41 INFO - PROCESS | 1923 | Full stack: 01:57:41 INFO - PROCESS | 1923 | ************************* 01:57:41 INFO - PROCESS | 1923 | ************************* 01:57:41 INFO - PROCESS | 1923 | A coding exception was thrown and uncaught in a Task. 01:57:41 INFO - PROCESS | 1923 | 01:57:41 ERROR - PROCESS | 1923 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:57:41 INFO - PROCESS | 1923 | Full stack: 01:57:41 INFO - PROCESS | 1923 | ************************* 01:57:41 INFO - PROCESS | 1923 | ************************* 01:57:41 INFO - PROCESS | 1923 | A coding exception was thrown and uncaught in a Task. 01:57:41 INFO - PROCESS | 1923 | 01:57:41 ERROR - PROCESS | 1923 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:57:41 INFO - PROCESS | 1923 | Full stack: 01:57:41 INFO - PROCESS | 1923 | ************************* 01:59:00 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:56537/tests/dromaeo/cssquery-ext.html (next: http://localhost:56537/tests/dromaeo/cssquery-jquery.html) 01:59:01 INFO - PROCESS | 1923 | RSS: Main: 348884992 01:59:01 INFO - PROCESS | 1923 | 02:02:03 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:56537/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56537/tests/dromaeo/cssquery-mootools.html) 02:02:04 INFO - PROCESS | 1923 | RSS: Main: 334729216 02:02:04 INFO - PROCESS | 1923 | 02:05:06 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:56537/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56537/tests/dromaeo/cssquery-prototype.html) 02:05:07 INFO - PROCESS | 1923 | RSS: Main: 314650624 02:05:07 INFO - PROCESS | 1923 | 02:08:11 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:56537/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56537/tests/dromaeo/cssquery-yui.html) 02:08:11 INFO - PROCESS | 1923 | RSS: Main: 315494400 02:08:11 INFO - PROCESS | 1923 | 02:11:15 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:56537/tests/dromaeo/cssquery-yui.html (next: http://localhost:56537/tests/dromaeo/cssquery-dojo.html) 02:11:15 INFO - PROCESS | 1923 | RSS: Main: 503705600 02:11:15 INFO - PROCESS | 1923 | 02:11:15 INFO - PROCESS | 1923 | __start_tp_report 02:11:15 INFO - PROCESS | 1923 | _x_x_mozilla_page_load 02:11:15 INFO - PROCESS | 1923 | _x_x_mozilla_page_load_details 02:11:15 INFO - PROCESS | 1923 | |i|pagename|runs| 02:11:15 INFO - PROCESS | 1923 | |0;dojo.html;3338.89983300501;3340.649231830659;3347;3379.408603494388;3390.9660903390973;5622.987862184805;5651.745671444784;5660.3490598581175;5663.3204015518095;5715.199872017915;5603.887922241553;5653.717314134287;5661.801836935708;5663.150527420889;5677.091665333547;5136.357955255593;5164.328637277167;5175.456577059392;5192.247124166994;5218.8695282617855;5092.261622064799;5100.948990510103;5140.2546630738525;5257.947420525803;5279.894402111955;5137.6403651744395;5162.199859021839;5176.378834539861;5187.1181899077055;5231.5814734821315;4262.637675797561;5222.216667499905;5222.373315202162;5226.608004399666;5230;5070.31550740649;5182.352205974254;5186.170212765977;5189.351331083614;5225.137852254374;5499.587530935156;5533.280673512418;5560;5583.664980101166;5643.322801263834;6123.842125456537;6139.032198454786;6145.187169784924;6174.351693072208;6207.472364848948;6203.131561581382;6267.435930766253;6283.8743225135695;6290.685465726741;6291.716872740738;5601.579881508903;5697.544196464273;5747.942520574825;5761.5678824087945;5766.135079738031;6278.874422511615;6310.368963103653;6337.841553961188;6387.680615969276;6391.712372943136;6138.202033735675;6141.539384546177;6150.04674275488;6152.415520525543;6155.076738489262;6207.720652570645;6218.751249950042;6274.027525733519;6287.339829317947;6295.716692748837;5194.896102077937;5789.899919015374;5945.64326140433;5980;6209.130721698965;6065.666388348595;6068.271807383054;6079.118527813443;6116.640127388518;6158.7228574714245;4952.950470495321;4980.05378977993;4989.5758860496535;4996.400431948116;5003.024410239968;3741.1956429367274;4446.088551846864;4483.193025255486;4490.887727806766;4491.011977364975;5591.07747221712;5604.411536788579;5626.099824028136;5744.224529688522;5754.597178197486;6186.350433204539;6218.782342617987;6288.27684816252;6289.77985770505;6302.810915672537;5737.08206686928;5742.971285143631;5743.655380677089;5750.396208398058;5780.624259423124;5365.141577347606;5406.162044883049;5407.107827208467;5426.104692725656;5444.373897001896;6295.559310848196;6332.968335158294;6335.619862808247;6340.587861788969;6371.362863713592;6045.818625441244;6126.3955342902;6130.856783744145;6132.99804414626;6139.461473530676;5289.418164002033;6265.718042687999;6379.936200637934;6393.456556192589;6400.327965563735;5905.320888097752;5948.940510594839;5962.612430192024;6002.759889604367;6124.816255512342;6183.505319574333;6216.098665693539;6241.750329986749;6251.156093927261;6261.24865016201;5683.204351390891;5839.912401313899;5921.496672782861;6013.518918486423;6021.909671354845;6086.695665216633;6089.786857459968;6129.84675383119;6135.7238924249405;6138.785142519991;5904.3800400959;5906.40935906406;5906.822795316148;5911.7930872420975;5915.733791979457;5818.330891947311;5862.091375836752;5871.7651293947765;5874.5887787854435;6029.397060293935;5855.94144058554;5856.94143058564;5901.734421950937;5926.407359264039;5928.4960778334325 02:11:15 INFO - PROCESS | 1923 | |1;ext.html;697.6163110289341;736.4955005821013;800.8238187598727;815.8857759913614;878.9033206347297;28025.15924522265;39224.82325530231;39733.205335893304;39790.602093979054;39819.601803981954;39616.80191599045;39794.8010259949;39843.80078099613;39858.60141398585;39921.800390998076;7253.419726421874;9105.226055785266;9285.628574856997;9292;9303.813923721522;7376.262373762607;7457.5152615080115;7462.589557574332;7463.626818659072;7466.962665186694;9238.353315267934;11182.888171118308;11189.216754827165;11199.160062995266;11201.719957001058;10960.958708922639;10990;11051.226414150973;11055.336679799238;11059.668209953634;7197.568145911209;7288.234735352765;10756.569737210502;10830.78338433227;10833;32130.678693213235;38338.80830595857;38472;38517.61482385196;39839.60160398388;3338.849751761177;3820.770753754756;3847.576766555677;3862.1503269280715;3938.4879965604573;26120.085796997013;26664.46671066587;26881.73118268812;27829;28636;3071.7696172786905;3129.3115514586752;3262.6411094779555;3287.917802054944;3290.654481279455;364868;366884;367240.65518689476;367296;375697;52284.47715522872;52419.21371179436;52433.95132097413;52438.47561524336;52449.737751311;55318.44681553133;55338.72330638321;55387;55394;55395.723021384634;13826.101303415246;14215.147091174562;14221.928890355483;14224.85775142256;14239.644008899653;12151.96708279806;12156.392180390945;12167.817482737766;12171.695707607381;12177.878221217852;10412;10587.735306617242;10609.045185933302;11618.88381116195;12194.57318993831;5193.480651934852;5335.253064570963;5347.037533243975;5351.742340549968;5354.196870469383;1011.1809434358047;1049.1659130990881;1051.6319288249051;1063.3223357316067;1075.9462026898623;24700.13549525758;24837.254882353198;24875.751242487706;24986.875065624554;24990.750092499205;941.2611100286242;1086.8043752124693;1095.118429664128;1108.939008354532;1143.114086582905;12216.3281019543;12310.138290319592;12446.190997585129;12799.488020479077;12835.93582032084;423615;427359;427497;427873;430667;53414;53715;53752.19371709428;53775;53814.46185538094;1411.089847048648;1458.4020551573803;1458.50410860308;1460.2114857976574;1463.8828893688478;22572.548549029256;23530.88234558816;26293;27299.31751706223;28138;36803.63196368002;44202.78986050749;45603.77198113988;45835.54164458313;45855.77072114618;430388;482981;483482;489376;490364.54817725683;28703.712962870104;28914.710852892043;29319;29354.41291174122;29392.70607293985;28212.435751284447;34803.30393392067;36173.45739813957;38383.23233535258;39313.39218356521;25711.871440642677;26019.089331873292;27463.313417164733;27713.475758833458;27901.162965111078;27094.322641934108;27709.861450692617;27737.30656733517;28148.06725275393;28158.577621335287;31941;32548.674513254566;32587.511187332682;32687;32756.508652369757;27419.725802741716;27463.450730985667;27869.442611148068;27978.300542486602;28611.570826437204;22052.88973555122;22504.324870253262;22898;22986.54026919486;23069.65395519102 02:11:15 INFO - PROCESS | 1923 | |2;jquery.html;427.4487253210657;427.9122779830135;428.8842012656582;434.1664005110188;444.5399012022555;18964.241430342787;20212.797872021274;21417.036233369497;21475;21626.567468650635;20298.797012029874;21166.68249976248;21246;21301.786982130176;21345.893270533663;6228.875422491547;6668.499862510307;6669.599824010551;6681.231658359278;6681.565698229624;6310.810675679737;6410.487161027107;6539.280679125292;6542.214934207902;6561.442277406426;6504.317046710098;6552.082708420825;6561;6570.080188773575;6971.965140174318;7321.231270716578;7377.778666639956;7424.480286379956;7433.219511951222;7441.59071251081;6907.965460172717;7147.606881621508;7203.459740519482;7226.74706385274;7227.132744070693;24929.376765580822;25038.624420633703;25439.98240070395;25498;25655.84548695313;2876.741093301613;2895.7972941894077;2896.522073857811;2921.4595299869593;2998.670146283907;14199.929000354932;15367.923160384238;15431.768523472158;15718.18961562239;15874.20628968547;1979.267670961739;1980.366282789508;1992.750906136733;1993.3820515640198;2014.305064752658;84409;111556;139781;154831.2258438712;161715.1914240445;18451.723224151647;18749.968751718785;18971.905140474486;19002.52493687669;19018.80981190198;29855.253618659706;30104.548431773543;30144.09567712972;30278.84860575727;30282.242943926136;8664.436811607226;9314.44113353201;9661.661841835503;10234.692959211236;10258.897411025795;8285.544295063708;8300.70947516834;8321.750347489586;8485.448445851;8500.574971251413;7975.641096150687;8172.877406838783;8785.780355491164;8887.555622218992;8948.910510894808;3305.7024867761534;3457.8616855325504;3786.924261514754;3823.5602905665646;3889.163829776555;946.8248374050822;1017.1354348803458;1027.019196667181;1044.2272718188485;1044.8641676581997;20287.4928126798;20599.794002060087;20616.38150855477;20650.380488585368;20949;904.6517090919912;988.8566157907063;994.7761753605525;999.7904630765943;1074.887136850635;9999.600015999278;10080.143187829117;10543.894561054445;10641.414722190353;10664.146868250522;764807;767539;774952;779682.1015894884;789942;108257;162546;165155;166075;167637;1317.8089177069462;1355.9254241016642;1382.4470211915313;1388.8888888888864;1391.3391139208795;17300.913495432946;17559.82440175633;17911.283548657906;17959.191836367132;18242;55789;56378.71810640921;61227.08159377524;63591.364086358546;63688;92107;92160;93257;93716.53141734247;95525.52237239048;11025.29107988263;11150;11225.550977961117;11317.434128293717;11465.885341146482;12250.081243906565;12253.938730306649;12394;12622.368881556069;12714.364281786058;8953.552322383724;10699.89300106989;10755.838662419914;10899.237053406185;10929.071028962628;9631.78468952131;9866.358686685342;9912.851307230254;9961.452120133463;10175.338602990782;10197.541110650187;10227.181825453798;10911.890881091087;11094.889051109385;11149.386783726648;10979.505922233679;11016.17378696585;11059.22585419013;11094.611688590861;11299.3785341807;8793.988691300583;10517.9474102629;10859.891401086204;10882.94558527234;10889.074428673335 02:11:15 INFO - PROCESS | 1923 | |3;mootools.html;1512.5311153542395;1544.1352842408253;1568.9450869219577;1575.3541048170246;1590.9602259943504;10489.055984961353;11083.002529772319;11090.778184436307;11096.389698566576;11100;10522.947385263082;10741.409222492759;10902.400367979759;10952.123830093573;10958.506867190976;2349.5888219561552;2385.892634831428;2386.844855084414;2388.9761102389016;2389.0443822471066;2284.903246441709;2285.6800047993293;2368.360542653491;2370.715514138306;2371.5138396628736;2098.7586427560873;2322.93031209064;2384.3681424422543;2385.105585405473;2389.6296074108454;2322.5006623576014;2330.603797354454;2342.9882850585723;2354.2348736660483;2363.161077817376;2356.363781778927;2361.6457531370434;2364.18435639704;2365.6924599802087;2366.6450032495263;11016.944915275373;11107;11107.500162492708;11111.499982500807;11124.555017799279;5475.515823921465;5698.458646428624;5768.019436695772;5777.826665200051;5792.768289268424;2933.589297498352;3301.7193538549254;3503.4744788281705;3521.6478352164577;3554.182538016245;977.9168770654431;1094.2832444748703;1137.1414581990628;1166.3993043582561;1182.024829515653;13443.731125377437;13764.31178441074;14050.718985620333;15356.769648455282;15364.846351536453;4510.075434535912;4651.953140618117;4658.301254811805;4659.603933665609;4695.154872123051;11824.1131915105;11833.526658933546;11843.46704398304;11866.110041746904;11877.22798018126;4832.347633069561;4988.625853060962;5443.265159203536;5694.9430505695245;5698.31620205578;5160.277561141444;5172.120739474299;5248.475152484797;5250.422453530108;5262.842114736564;3741.4574886641285;3817.9236415272094;3822.904427389282;3865.9124073000266;3882.456456096149;1015.3806178231273;1018.6740243122277;1024.1755386913605;1027.825269704152;1032.5043978890176;918.196577994255;931.5528546297815;948.4167237149104;954.9713508594754;992.0773680477081;6494.740210391531;6499.935000650027;6684.629650921549;6758.513127112027;6762.391384775394;836.9372297077743;964.3297907953905;966.1401352795876;976.5800705696619;1024.7591815923251;4037.5760545142925;4050.1292222172374;4065.390191471303;4095.508538975342;4122.938530734633;13265.270410127536;13776.173429594257;13781.86218137826;13787.379567919266;13792.655183620489;11262.662120136409;13229.470821167046;13536.187828730308;13570;13612.863871361358;9907.455089969977;10154.086132248132;10158.441285729356;10180.592776289013;10188.184945204366;6589.27517973013;6807.795766127024;6962.895556566555;6988.475864310095;7097.183823860322;11791.938725514745;12069.75860482803;12157.878421215675;12510.87489125097;12528.498860045496;9831.606735730491;12474.68813279639;12604;12714.982801375683;12892.871071289543;9124.95437522808;9403.81192376135;9415.19970802462;9419.670311539063;9422.152006319464;10033.44816035125;10246.743831404274;10357.17142628603;10357.792844142925;10368.637097701545;8503.404761666625;8698.956505217433;8763.60563774619;8793.164649358301;8911.06433824439;7293.52592081513;8464.703735369232;8477.830443390974;8559.95720021396;8569.357298202534;7760.223977602194;7817.804554886174;7941.801454963672;7946.046474423106;7948.205179482005;5735.512481439125;5787.536997040311;5848.385919478394;6009.969950150221;6012.939870601238;5943.197668314722;5980.37206093354;6025.517958563392;6098.237720284965;6230.968845155927 02:11:15 INFO - PROCESS | 1923 | |4;prototype.html;374.8313259033435;381.30602303807063;381.7308797297905;390.2215080913578;391.0770581427832;4442.355858400538;4971.825986090487;4981.402231732188;5022.422421421537;5046.467472769058;4320.3735458358515;4680.625549956004;4685.180093483636;4706.587723900171;4975.751212439382;3270.9182270443193;3778.338790711628;4148.585141485857;4153.127843152926;4247.235497610429;3685.4570346460855;4191.958080419187;4193.980334159415;4207.43199668044;4237.495689030396;3750.324941510527;3924.960750392502;4226.978865105685;4232.846529335649;4237.609912070331;3866.732610943748;3937.7243592948507;4111.8560850370395;4146.896327591834;4247.642558380097;3314.839806067881;3826.655600995896;3850.0374906273432;3866.3233934061427;3870.1679138985255;4156.750594964282;4220.725652832587;4329.393884856123;4562.429696287964;4650.907036846338;4546.499885012646;4753.429588449409;4773.331733557305;4820.108279968217;4828.830990915336;4399.626031787302;5126.307948426952;5280;5304.416514183436;5325.281087053237;3568.7145028397927;3672.008557689409;3880.9223815523533;4082.408050832613;4370.8033138508845;4770.713757174581;4996.625253105995;5154.871128221825;5388.84752419831;5638.633488823254;3870.967741935506;4253.659707223414;4457.3091170868565;4464.598186163261;4504.48198457175;3665.8350374233223;3917.4319723639915;4257.914841703148;4286.335617979156;4598.563136502027;4314.676399270067;4799.928001079987;4843.564079232816;4885.706857588556;4950.900981980412;4313.61177494027;4439.4006809080365;4551.977240113844;4901.705897646153;5344.5724342052545;1388.4168649167173;1481.748102822501;1538.1309560098732;1541.3218183999004;1552.9301181446863;1068.7755571330108;1081.0594782539083;1148.454484120053;1156.259993604094;1157.9363135027654;4756.809727610926;5009.44896061433;5023.497650234947;5093.351457242287;5110.616703747234;4810.4467986182035;5079.923801142986;5099.719515426687;5490.286262785812;5491.862703432446;4393.582609652078;4689.273162659793;4705.388299521041;4964.503549645079;5133.255677926754;2419.9758002420103;2520.7227205007434;2831.3012192684223;2906.2589039794716;2916.3584011517432;4700.529947005341;5357.437469065692;5526.621959683786;5696.772129114789;5876.266501382102;4708.788104535304;4887.3890763654545;4959.247617572282;5020.7740651670765;5651.580793619883;5066.695998240118;5129.897402052013;5293.470652934753;5487.972560137254;5738;4803.495632958628;4937.469384490676;5414.908228243226;5430.565554755689;5437.673739575638;4915.7542122893;4952.1581331173065;5081.078379931628;5257.842264732064;5269.762860671356;5000.724960127228;5077.593792496666;5518.86202844916;5815.5929084963645;5844.895837498412;4244.978775106105;4277.775556710555;4334.1981733379425;4370.67219958498;4388.398234644268;4808.557432770225;4885.731284779371;5362.640438207176;5506.64206826555;5598.3484871962055;4549.430446495953;4955.2567114933045;5046.3439752833365;5117.8464646060675;5334.51989320963;4596.977015114903;4770.356001939694;5262.026525092717;5264.341957255343;5275.604329675213;4940.75296235194;5224.2947202127225;5294.841154765363;5296;5312.654677445954;4667.393238878924;4673.696209746356;4799.3041009052795;5462.006367675849;5470.945290547043;4511.593956543927;5244.685318880879;5298.94701053;5320.17537281722;5321.574274057989 02:11:15 INFO - PROCESS | 1923 | |5;yui.html;729.9561626873568;753.3107206905681;758.525921299188;820.2166930581294;831.8419500294946;14715.264236788158;15955.601109972242;16271.674566508662;16334.754978675315;16370.427035053775;14333;14592.927035364835;14676.046057006288;14706.926465367658;14774.261286935664;922.7600823785814;941.7880976780239;944.7826999790052;946.7633091727068;948.5636607160715;926.754410081329;947.9146876781089;949.278548303291;949.6438835436711;963.4026903319951;1004.4274763384874;1005.0452070532987;1005.2912696548915;1006.3157053203819;1007.7833265847842;998.0967224661692;1000.7197984564298;1000.8248556502583;1001.419176877413;1001.6394098124742;742.9962850185715;743.8958545803591;744.4528271720282;744.56070918158;744.9942577520359;15566.37734490619;15874.682506349809;15948;16307.592310192222;16620.168991550374;14118.576442706735;14159.858401415957;14245.572632820928;14262.786058209134;14317.35571899267;10510.369377837278;11749.94125029378;11899.345535995517;12048.216865903689;12096.516139354504;3620.2216523447587;3643.1074386775144;3662.0661731258624;3676.099355657881;3726.6273372662517;96831.0316896829;119318;121266.39366803197;123259;124391;11129.554817807197;11317.981381675689;11368;11372.658820235407;11536.423178841025;21962.89018554897;22196.556068878534;22362.55274894493;22606.09575616957;22648;7847.097583777939;7909.960450197712;7910.762677119696;7912.723054693058;7997.640106195119;4306.698531102856;4345.217860785026;4347.413099231627;4429.291313389842;4521.276595744665;3041.4829478988627;3438.914027149291;3482.6169121396624;3488.3720930232294;3540.8760693376125;533.3919331961567;618.7339444039017;624.3413199074929;631.3875540725489;632.5066448170435;658.5390226841134;673.7843889955266;690.775497963164;701.6491754122939;738.7672883041824;9488.810223795486;9980.500974951223;10371;10449.216308776871;10658.946705266579;683.8598087392073;699.192432740186;706.7314420520272;728.3408515293668;738.5753191914671;4774.355462012653;4906.607471402279;4950.405951285797;4966.15575352191;4970.776315065831;74589;98316.50841745888;100012;101071;101638.49180754197;53429;63162;68054.65972670105;87620;97373.0262697378;70085;71921;73909.63045184739;80350.79473808015;80637.59681201556;10508.94910508943;10965.671029869116;11211.271267367594;11260.38067906273;11436.771264574496;47382;49833;50023.74988125036;51012.74493627508;51896;54150.72924635352;69369.30630693628;69754.65122674557;69804.30195698181;73629.52740945258;12290.324032177958;12526.498940042296;12796.168249063781;12835.165714228546;13026.2835544046;14073.788893166815;14338.087160686677;14598.78068874595;14752.114873107648;15133.621659458162;13087.476500939856;13891.027628065938;14065.437382504586;14258.001939864525;14389.208593527037;12879.291638959572;13810.171389716648;13957.651058723613;14105.435782568582;14267.857321427067;12423.689407764517;13542.187468751907;13547.454824437276;13550.660880544272;14009.9850224663;9402.576884040098;9923;9927.652532161339;9945.104940555384;10066.798664026825;10427.322224055411;11044.171687123337;11283.435828208721;11504.654860354203;11506 02:11:15 INFO - PROCESS | 1923 | __end_tp_report 02:11:15 INFO - PROCESS | 1923 | __start_cc_report 02:11:15 INFO - PROCESS | 1923 | _x_x_mozilla_cycle_collect,1818 02:11:15 INFO - PROCESS | 1923 | __end_cc_report 02:11:15 INFO - PROCESS | 1923 | __startTimestamp1491815475408__endTimestamp 02:11:15 INFO - PROCESS | 1923 | 02:11:15 INFO - PROCESS | 1923 | ------- Summary: start ------- 02:11:15 INFO - PROCESS | 1923 | Number of tests: 6 02:11:15 INFO - PROCESS | 1923 | 02:11:15 INFO - PROCESS | 1923 | [#0] dojo.html Cycles:165 Average:5687.83 Median:5856.94 stddev:642.19 (11.0%) stddev-sans-first:617.15 02:11:15 INFO - PROCESS | 1923 | Values: 3338.9 3340.6 3347.0 3379.4 3391.0 5623.0 5651.7 5660.3 5663.3 5715.2 5603.9 5653.7 5661.8 5663.2 5677.1 5136.4 5164.3 5175.5 5192.2 5218.9 5092.3 5100.9 5140.3 5257.9 5279.9 5137.6 5162.2 5176.4 5187.1 5231.6 4262.6 5222.2 5222.4 5226.6 5230.0 5070.3 5182.4 5186.2 5189.4 5225.1 5499.6 5533.3 5560.0 5583.7 5643.3 6123.8 6139.0 6145.2 6174.4 6207.5 6203.1 6267.4 6283.9 6290.7 6291.7 5601.6 5697.5 5747.9 5761.6 5766.1 6278.9 6310.4 6337.8 6387.7 6391.7 6138.2 6141.5 6150.0 6152.4 6155.1 6207.7 6218.8 6274.0 6287.3 6295.7 5194.9 5789.9 5945.6 5980.0 6209.1 6065.7 6068.3 6079.1 6116.6 6158.7 4953.0 4980.1 4989.6 4996.4 5003.0 3741.2 4446.1 4483.2 4490.9 4491.0 5591.1 5604.4 5626.1 5744.2 5754.6 6186.4 6218.8 6288.3 6289.8 6302.8 5737.1 5743.0 5743.7 5750.4 5780.6 5365.1 5406.2 5407.1 5426.1 5444.4 6295.6 6333.0 6335.6 6340.6 6371.4 6045.8 6126.4 6130.9 6133.0 6139.5 5289.4 6265.7 6379.9 6393.5 6400.3 5905.3 5948.9 5962.6 6002.8 6124.8 6183.5 6216.1 6241.8 6251.2 6261.2 5683.2 5839.9 5921.5 6013.5 6021.9 6086.7 6089.8 6129.8 6135.7 6138.8 5904.4 5906.4 5906.8 5911.8 5915.7 5818.3 5862.1 5871.8 5874.6 6029.4 5855.9 5856.9 5901.7 5926.4 5928.5 02:11:15 INFO - PROCESS | 1923 | 02:11:15 INFO - PROCESS | 1923 | [#1] ext.html Cycles:180 Average:55135.73 Median:32035.84 stddev:113300.86 (353.7%) stddev-sans-first:113544.97 02:11:15 INFO - PROCESS | 1923 | Values: 697.6 736.5 800.8 815.9 878.9 28025.2 39224.8 39733.2 39790.6 39819.6 39616.8 39794.8 39843.8 39858.6 39921.8 7253.4 9105.2 9285.6 9292.0 9303.8 7376.3 7457.5 7462.6 7463.6 7467.0 9238.4 11182.9 11189.2 11199.2 11201.7 10961.0 10990.0 11051.2 11055.3 11059.7 7197.6 7288.2 10756.6 10830.8 10833.0 32130.7 38338.8 38472.0 38517.6 39839.6 3338.8 3820.8 3847.6 3862.2 3938.5 26120.1 26664.5 26881.7 27829.0 28636.0 3071.8 3129.3 3262.6 3287.9 3290.7 364868.0 366884.0 367240.7 367296.0 375697.0 52284.5 52419.2 52434.0 52438.5 52449.7 55318.4 55338.7 55387.0 55394.0 55395.7 13826.1 14215.1 14221.9 14224.9 14239.6 12152.0 12156.4 12167.8 12171.7 12177.9 10412.0 10587.7 10609.0 11618.9 12194.6 5193.5 5335.3 5347.0 5351.7 5354.2 1011.2 1049.2 1051.6 1063.3 1075.9 24700.1 24837.3 24875.8 24986.9 24990.8 941.3 1086.8 1095.1 1108.9 1143.1 12216.3 12310.1 12446.2 12799.5 12835.9 423615.0 427359.0 427497.0 427873.0 430667.0 53414.0 53715.0 53752.2 53775.0 53814.5 1411.1 1458.4 1458.5 1460.2 1463.9 22572.5 23530.9 26293.0 27299.3 28138.0 36803.6 44202.8 45603.8 45835.5 45855.8 430388.0 482981.0 483482.0 489376.0 490364.5 28703.7 28914.7 29319.0 29354.4 29392.7 28212.4 34803.3 36173.5 38383.2 39313.4 25711.9 26019.1 27463.3 27713.5 27901.2 27094.3 27709.9 27737.3 28148.1 28158.6 31941.0 32548.7 32587.5 32687.0 32756.5 27419.7 27463.5 27869.4 27978.3 28611.6 22052.9 22504.3 22898.0 22986.5 23069.7 02:11:15 INFO - PROCESS | 1923 | 02:11:15 INFO - PROCESS | 1923 | [#2] jquery.html Cycles:180 Average:42857.75 Median:25239.30 stddev:128896.25 (510.7%) stddev-sans-first:129218.46 02:11:15 INFO - PROCESS | 1923 | Values: 427.4 427.9 428.9 434.2 444.5 18964.2 20212.8 21417.0 21475.0 21626.6 20298.8 21166.7 21246.0 21301.8 21345.9 6228.9 6668.5 6669.6 6681.2 6681.6 6310.8 6410.5 6539.3 6542.2 6561.4 6504.3 6552.1 6561.0 6570.1 6972.0 7321.2 7377.8 7424.5 7433.2 7441.6 6908.0 7147.6 7203.5 7226.7 7227.1 24929.4 25038.6 25440.0 25498.0 25655.8 2876.7 2895.8 2896.5 2921.5 2998.7 14199.9 15367.9 15431.8 15718.2 15874.2 1979.3 1980.4 1992.8 1993.4 2014.3 84409.0 111556.0 139781.0 154831.2 161715.2 18451.7 18750.0 18971.9 19002.5 19018.8 29855.3 30104.5 30144.1 30278.8 30282.2 8664.4 9314.4 9661.7 10234.7 10258.9 8285.5 8300.7 8321.8 8485.4 8500.6 7975.6 8172.9 8785.8 8887.6 8948.9 3305.7 3457.9 3786.9 3823.6 3889.2 946.8 1017.1 1027.0 1044.2 1044.9 20287.5 20599.8 20616.4 20650.4 20949.0 904.7 988.9 994.8 999.8 1074.9 9999.6 10080.1 10543.9 10641.4 10664.1 764807.0 767539.0 774952.0 779682.1 789942.0 108257.0 162546.0 165155.0 166075.0 167637.0 1317.8 1355.9 1382.4 1388.9 1391.3 17300.9 17559.8 17911.3 17959.2 18242.0 55789.0 56378.7 61227.1 63591.4 63688.0 92107.0 92160.0 93257.0 93716.5 95525.5 11025.3 11150.0 11225.6 11317.4 11465.9 12250.1 12253.9 12394.0 12622.4 12714.4 8953.6 10699.9 10755.8 10899.2 10929.1 9631.8 9866.4 9912.9 9961.5 10175.3 10197.5 10227.2 10911.9 11094.9 11149.4 10979.5 11016.2 11059.2 11094.6 11299.4 8794.0 10517.9 10859.9 10882.9 10889.1 02:11:15 INFO - PROCESS | 1923 | 02:11:15 INFO - PROCESS | 1923 | [#3] mootools.html Cycles:180 Average:6659.75 Median:2389.01 stddev:4159.05 (174.1%) stddev-sans-first:4152.73 02:11:15 INFO - PROCESS | 1923 | Values: 1512.5 1544.1 1568.9 1575.4 1591.0 10489.1 11083.0 11090.8 11096.4 11100.0 10522.9 10741.4 10902.4 10952.1 10958.5 2349.6 2385.9 2386.8 2389.0 2389.0 2284.9 2285.7 2368.4 2370.7 2371.5 2098.8 2322.9 2384.4 2385.1 2389.6 2322.5 2330.6 2343.0 2354.2 2363.2 2356.4 2361.6 2364.2 2365.7 2366.6 11016.9 11107.0 11107.5 11111.5 11124.6 5475.5 5698.5 5768.0 5777.8 5792.8 2933.6 3301.7 3503.5 3521.6 3554.2 977.9 1094.3 1137.1 1166.4 1182.0 13443.7 13764.3 14050.7 15356.8 15364.8 4510.1 4652.0 4658.3 4659.6 4695.2 11824.1 11833.5 11843.5 11866.1 11877.2 4832.3 4988.6 5443.3 5694.9 5698.3 5160.3 5172.1 5248.5 5250.4 5262.8 3741.5 3817.9 3822.9 3865.9 3882.5 1015.4 1018.7 1024.2 1027.8 1032.5 918.2 931.6 948.4 955.0 992.1 6494.7 6499.9 6684.6 6758.5 6762.4 836.9 964.3 966.1 976.6 1024.8 4037.6 4050.1 4065.4 4095.5 4122.9 13265.3 13776.2 13781.9 13787.4 13792.7 11262.7 13229.5 13536.2 13570.0 13612.9 9907.5 10154.1 10158.4 10180.6 10188.2 6589.3 6807.8 6962.9 6988.5 7097.2 11791.9 12069.8 12157.9 12510.9 12528.5 9831.6 12474.7 12604.0 12715.0 12892.9 9125.0 9403.8 9415.2 9419.7 9422.2 10033.4 10246.7 10357.2 10357.8 10368.6 8503.4 8699.0 8763.6 8793.2 8911.1 7293.5 8464.7 8477.8 8560.0 8569.4 7760.2 7817.8 7941.8 7946.0 7948.2 5735.5 5787.5 5848.4 6010.0 6012.9 5943.2 5980.4 6025.5 6098.2 6231.0 02:11:15 INFO - PROCESS | 1923 | 02:11:15 INFO - PROCESS | 1923 | [#4] prototype.html Cycles:180 Average:4387.31 Median:4731.11 stddev:1208.19 (25.5%) stddev-sans-first:1173.44 02:11:15 INFO - PROCESS | 1923 | Values: 374.8 381.3 381.7 390.2 391.1 4442.4 4971.8 4981.4 5022.4 5046.5 4320.4 4680.6 4685.2 4706.6 4975.8 3270.9 3778.3 4148.6 4153.1 4247.2 3685.5 4192.0 4194.0 4207.4 4237.5 3750.3 3925.0 4227.0 4232.8 4237.6 3866.7 3937.7 4111.9 4146.9 4247.6 3314.8 3826.7 3850.0 3866.3 3870.2 4156.8 4220.7 4329.4 4562.4 4650.9 4546.5 4753.4 4773.3 4820.1 4828.8 4399.6 5126.3 5280.0 5304.4 5325.3 3568.7 3672.0 3880.9 4082.4 4370.8 4770.7 4996.6 5154.9 5388.8 5638.6 3871.0 4253.7 4457.3 4464.6 4504.5 3665.8 3917.4 4257.9 4286.3 4598.6 4314.7 4799.9 4843.6 4885.7 4950.9 4313.6 4439.4 4552.0 4901.7 5344.6 1388.4 1481.7 1538.1 1541.3 1552.9 1068.8 1081.1 1148.5 1156.3 1157.9 4756.8 5009.4 5023.5 5093.4 5110.6 4810.4 5079.9 5099.7 5490.3 5491.9 4393.6 4689.3 4705.4 4964.5 5133.3 2420.0 2520.7 2831.3 2906.3 2916.4 4700.5 5357.4 5526.6 5696.8 5876.3 4708.8 4887.4 4959.2 5020.8 5651.6 5066.7 5129.9 5293.5 5488.0 5738.0 4803.5 4937.5 5414.9 5430.6 5437.7 4915.8 4952.2 5081.1 5257.8 5269.8 5000.7 5077.6 5518.9 5815.6 5844.9 4245.0 4277.8 4334.2 4370.7 4388.4 4808.6 4885.7 5362.6 5506.6 5598.3 4549.4 4955.3 5046.3 5117.8 5334.5 4597.0 4770.4 5262.0 5264.3 5275.6 4940.8 5224.3 5294.8 5296.0 5312.7 4667.4 4673.7 4799.3 5462.0 5470.9 4511.6 5244.7 5298.9 5320.2 5321.6 02:11:15 INFO - PROCESS | 1923 | 02:11:15 INFO - PROCESS | 1923 | [#5] yui.html Cycles:180 Average:20206.29 Median:3240.20 stddev:28941.46 (893.2%) stddev-sans-first:28985.70 02:11:15 INFO - PROCESS | 1923 | Values: 730.0 753.3 758.5 820.2 831.8 14715.3 15955.6 16271.7 16334.8 16370.4 14333.0 14592.9 14676.0 14706.9 14774.3 922.8 941.8 944.8 946.8 948.6 926.8 947.9 949.3 949.6 963.4 1004.4 1005.0 1005.3 1006.3 1007.8 998.1 1000.7 1000.8 1001.4 1001.6 743.0 743.9 744.5 744.6 745.0 15566.4 15874.7 15948.0 16307.6 16620.2 14118.6 14159.9 14245.6 14262.8 14317.4 10510.4 11749.9 11899.3 12048.2 12096.5 3620.2 3643.1 3662.1 3676.1 3726.6 96831.0 119318.0 121266.4 123259.0 124391.0 11129.6 11318.0 11368.0 11372.7 11536.4 21962.9 22196.6 22362.6 22606.1 22648.0 7847.1 7910.0 7910.8 7912.7 7997.6 4306.7 4345.2 4347.4 4429.3 4521.3 3041.5 3438.9 3482.6 3488.4 3540.9 533.4 618.7 624.3 631.4 632.5 658.5 673.8 690.8 701.6 738.8 9488.8 9980.5 10371.0 10449.2 10658.9 683.9 699.2 706.7 728.3 738.6 4774.4 4906.6 4950.4 4966.2 4970.8 74589.0 98316.5 100012.0 101071.0 101638.5 53429.0 63162.0 68054.7 87620.0 97373.0 70085.0 71921.0 73909.6 80350.8 80637.6 10508.9 10965.7 11211.3 11260.4 11436.8 47382.0 49833.0 50023.7 51012.7 51896.0 54150.7 69369.3 69754.7 69804.3 73629.5 12290.3 12526.5 12796.2 12835.2 13026.3 14073.8 14338.1 14598.8 14752.1 15133.6 13087.5 13891.0 14065.4 14258.0 14389.2 12879.3 13810.2 13957.7 14105.4 14267.9 12423.7 13542.2 13547.5 13550.7 14010.0 9402.6 9923.0 9927.7 9945.1 10066.8 10427.3 11044.2 11283.4 11504.7 11506.0 02:11:15 INFO - PROCESS | 1923 | -------- Summary: end -------- 02:11:15 INFO - PROCESS | 1923 | 02:11:16 INFO - TEST-INFO | 1923: exit 0 02:11:16 INFO - TEST-OK | dromaeo_css | took 1119723ms 02:11:16 INFO - TEST-START | kraken 02:11:16 INFO - operating with platform_type : mac_ 02:11:16 INFO - Initialising browser for kraken test... 02:11:16 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpIXRYU4/profile http://localhost:56537/getInfo.html) 02:11:18 INFO - PROCESS | 1926 | __metrics Screen width/height:1600/1200 02:11:18 INFO - PROCESS | 1926 | colorDepth:24 02:11:18 INFO - PROCESS | 1926 | Browser inner width/height: 1024/689 02:11:18 INFO - PROCESS | 1926 | __metrics 02:11:18 INFO - PROCESS | 1926 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:11:18 INFO - PROCESS | 1926 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:11:19 INFO - TEST-INFO | 1926: exit 0 02:11:19 INFO - Browser initialized. 02:11:19 INFO - Running cycle 1/1 for kraken test... 02:11:19 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpIXRYU4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:11:31 INFO - PROCESS | 1927 | RSS: Main: 215130112 02:11:31 INFO - PROCESS | 1927 | 02:11:34 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56537/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56537/tests/kraken/driver.html?testName=audio-beat-detection) 02:11:35 INFO - PROCESS | 1927 | RSS: Main: 267022336 02:11:35 INFO - PROCESS | 1927 | 02:11:38 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56537/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56537/tests/kraken/driver.html?testName=audio-dft) 02:11:38 INFO - PROCESS | 1927 | RSS: Main: 278528000 02:11:38 INFO - PROCESS | 1927 | 02:11:41 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56537/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56537/tests/kraken/driver.html?testName=audio-fft) 02:11:42 INFO - PROCESS | 1927 | RSS: Main: 376643584 02:11:42 INFO - PROCESS | 1927 | 02:11:44 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56537/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56537/tests/kraken/driver.html?testName=audio-oscillator) 02:11:45 INFO - PROCESS | 1927 | RSS: Main: 277360640 02:11:45 INFO - PROCESS | 1927 | 02:11:47 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56537/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56537/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:11:47 INFO - PROCESS | 1927 | RSS: Main: 280055808 02:11:47 INFO - PROCESS | 1927 | 02:11:50 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56537/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56537/tests/kraken/driver.html?testName=imaging-darkroom) 02:11:51 INFO - PROCESS | 1927 | RSS: Main: 320679936 02:11:51 INFO - PROCESS | 1927 | 02:11:54 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56537/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56537/tests/kraken/driver.html?testName=imaging-desaturate) 02:11:54 INFO - PROCESS | 1927 | RSS: Main: 321568768 02:11:54 INFO - PROCESS | 1927 | 02:11:57 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56537/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56537/tests/kraken/driver.html?testName=json-parse-financial) 02:11:58 INFO - PROCESS | 1927 | RSS: Main: 316129280 02:11:58 INFO - PROCESS | 1927 | 02:12:00 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56537/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56537/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:12:00 INFO - PROCESS | 1927 | RSS: Main: 294518784 02:12:00 INFO - PROCESS | 1927 | 02:12:02 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56537/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56537/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:12:03 INFO - PROCESS | 1927 | RSS: Main: 299028480 02:12:03 INFO - PROCESS | 1927 | 02:12:05 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56537/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56537/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:12:06 INFO - PROCESS | 1927 | RSS: Main: 302280704 02:12:06 INFO - PROCESS | 1927 | 02:12:08 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56537/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56537/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:12:09 INFO - PROCESS | 1927 | RSS: Main: 320151552 02:12:09 INFO - PROCESS | 1927 | 02:12:12 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56537/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56537/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:12:13 INFO - PROCESS | 1927 | RSS: Main: 313274368 02:12:13 INFO - PROCESS | 1927 | 02:12:14 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56537/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56537/tests/kraken/driver.html?testName=ai-astar) 02:12:15 INFO - PROCESS | 1927 | RSS: Main: 358035456 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | __start_tp_report 02:12:15 INFO - PROCESS | 1927 | _x_x_mozilla_page_load 02:12:15 INFO - PROCESS | 1927 | _x_x_mozilla_page_load_details 02:12:15 INFO - PROCESS | 1927 | |i|pagename|runs| 02:12:15 INFO - PROCESS | 1927 | |0;ai-astar;118;115;115;115;117;115;116;116;116;117 02:12:15 INFO - PROCESS | 1927 | |1;audio-beat-detection;145;95;107;171;138;137;107;109;129;110 02:12:15 INFO - PROCESS | 1927 | |2;audio-dft;184;183;182;182;184;184;217;243;183;185 02:12:15 INFO - PROCESS | 1927 | |3;audio-fft;82;60;62;92;63;60;60;61;75;60 02:12:15 INFO - PROCESS | 1927 | |4;audio-oscillator;90;68;87;71;68;68;83;69;69;85 02:12:15 INFO - PROCESS | 1927 | |5;imaging-gaussian-blur;87;87;87;87;88;87;87;86;92;125 02:12:15 INFO - PROCESS | 1927 | |6;imaging-darkroom;95;99;94;97;95;99;94;97;94;93 02:12:15 INFO - PROCESS | 1927 | |7;imaging-desaturate;83;84;84;84;84;86;84;84;87;86 02:12:15 INFO - PROCESS | 1927 | |8;json-parse-financial;63;65;60;63;67;68;68;65;66;65 02:12:15 INFO - PROCESS | 1927 | |9;json-stringify-tinderbox;54;65;57;66;60;58;58;60;59;59 02:12:15 INFO - PROCESS | 1927 | |10;stanford-crypto-aes;80;85;85;82;84;81;83;83;83;87 02:12:15 INFO - PROCESS | 1927 | |11;stanford-crypto-ccm;134;136;143;139;137;141;141;144;182;151 02:12:15 INFO - PROCESS | 1927 | |12;stanford-crypto-pbkdf2;165;165;171;167;165;165;164;164;175;162 02:12:15 INFO - PROCESS | 1927 | |13;stanford-crypto-sha256-iterative;66;64;65;64;64;65;64;64;64;65 02:12:15 INFO - PROCESS | 1927 | __end_tp_report 02:12:15 INFO - PROCESS | 1927 | __start_cc_report 02:12:15 INFO - PROCESS | 1927 | _x_x_mozilla_cycle_collect,2139 02:12:15 INFO - PROCESS | 1927 | __end_cc_report 02:12:15 INFO - PROCESS | 1927 | __startTimestamp1491815535136__endTimestamp 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | ------- Summary: start ------- 02:12:15 INFO - PROCESS | 1927 | Number of tests: 14 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | [#0] ai-astar Cycles:10 Average:116.00 Median:116.00 stddev:1.05 (0.9%) stddev-sans-first:0.83 02:12:15 INFO - PROCESS | 1927 | Values: 118.0 115.0 115.0 115.0 117.0 115.0 116.0 116.0 116.0 117.0 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:137.50 stddev:23.27 (16.9%) stddev-sans-first:23.51 02:12:15 INFO - PROCESS | 1927 | Values: 145.0 95.0 107.0 171.0 138.0 137.0 107.0 109.0 129.0 110.0 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | [#2] audio-dft Cycles:10 Average:192.70 Median:184.00 stddev:20.61 (11.2%) stddev-sans-first:21.62 02:12:15 INFO - PROCESS | 1927 | Values: 184.0 183.0 182.0 182.0 184.0 184.0 217.0 243.0 183.0 185.0 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | [#3] audio-fft Cycles:10 Average:67.50 Median:62.50 stddev:11.47 (18.4%) stddev-sans-first:10.90 02:12:15 INFO - PROCESS | 1927 | Values: 82.0 60.0 62.0 92.0 63.0 60.0 60.0 61.0 75.0 60.0 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:77.00 stddev:9.20 (11.9%) stddev-sans-first:8.20 02:12:15 INFO - PROCESS | 1927 | Values: 90.0 68.0 87.0 71.0 68.0 68.0 83.0 69.0 69.0 85.0 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:87.00 stddev:11.95 (13.7%) stddev-sans-first:12.58 02:12:15 INFO - PROCESS | 1927 | Values: 87.0 87.0 87.0 87.0 88.0 87.0 87.0 86.0 92.0 125.0 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | [#6] imaging-darkroom Cycles:10 Average:95.70 Median:96.00 stddev:2.16 (2.3%) stddev-sans-first:2.28 02:12:15 INFO - PROCESS | 1927 | Values: 95.0 99.0 94.0 97.0 95.0 99.0 94.0 97.0 94.0 93.0 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:84.00 stddev:1.26 (1.5%) stddev-sans-first:1.20 02:12:15 INFO - PROCESS | 1927 | Values: 83.0 84.0 84.0 84.0 84.0 86.0 84.0 84.0 87.0 86.0 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:65.50 stddev:2.49 (3.8%) stddev-sans-first:2.54 02:12:15 INFO - PROCESS | 1927 | Values: 63.0 65.0 60.0 63.0 67.0 68.0 68.0 65.0 66.0 65.0 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:59.50 stddev:3.57 (6.0%) stddev-sans-first:3.15 02:12:15 INFO - PROCESS | 1927 | Values: 54.0 65.0 57.0 66.0 60.0 58.0 58.0 60.0 59.0 59.0 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:83.50 stddev:2.06 (2.5%) stddev-sans-first:1.80 02:12:15 INFO - PROCESS | 1927 | Values: 80.0 85.0 85.0 82.0 84.0 81.0 83.0 83.0 83.0 87.0 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:142.00 stddev:13.92 (9.8%) stddev-sans-first:14.20 02:12:15 INFO - PROCESS | 1927 | Values: 134.0 136.0 143.0 139.0 137.0 141.0 141.0 144.0 182.0 151.0 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:165.00 stddev:3.86 (2.3%) stddev-sans-first:4.07 02:12:15 INFO - PROCESS | 1927 | Values: 165.0 165.0 171.0 167.0 165.0 165.0 164.0 164.0 175.0 162.0 02:12:15 INFO - PROCESS | 1927 | 02:12:15 INFO - PROCESS | 1927 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:64.50 stddev:0.71 (1.1%) stddev-sans-first:0.50 02:12:15 INFO - PROCESS | 1927 | Values: 66.0 64.0 65.0 64.0 64.0 65.0 64.0 64.0 64.0 65.0 02:12:15 INFO - PROCESS | 1927 | -------- Summary: end -------- 02:12:15 INFO - PROCESS | 1927 | 02:12:16 INFO - TEST-INFO | 1927: exit 0 02:12:16 INFO - TEST-OK | kraken | took 59950ms 02:12:16 INFO - SUITE-END | took 1179s 02:12:16 INFO - Completed test suite (00:19:39) 02:12:16 INFO - javascript benchmark 02:12:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7501.135236519328, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3338.89983300501, 3340.649231830659, 3347.0, 3379.408603494388, 3390.9660903390973, 5622.987862184805, 5651.745671444784, 5660.3490598581175, 5663.3204015518095, 5715.199872017915, 5603.887922241553, 5653.717314134287, 5661.801836935708, 5663.150527420889, 5677.091665333547, 5136.357955255593, 5164.328637277167, 5175.456577059392, 5192.247124166994, 5218.8695282617855, 5092.261622064799, 5100.948990510103, 5140.2546630738525, 5257.947420525803, 5279.894402111955, 5137.6403651744395, 5162.199859021839, 5176.378834539861, 5187.1181899077055, 5231.5814734821315, 4262.637675797561, 5222.216667499905, 5222.373315202162, 5226.608004399666, 5230.0, 5070.31550740649, 5182.352205974254, 5186.170212765977, 5189.351331083614, 5225.137852254374, 5499.587530935156, 5533.280673512418, 5560.0, 5583.664980101166, 5643.322801263834, 6123.842125456537, 6139.032198454786, 6145.187169784924, 6174.351693072208, 6207.472364848948, 6203.131561581382, 6267.435930766253, 6283.8743225135695, 6290.685465726741, 6291.716872740738, 5601.579881508903, 5697.544196464273, 5747.942520574825, 5761.5678824087945, 5766.135079738031, 6278.874422511615, 6310.368963103653, 6337.841553961188, 6387.680615969276, 6391.712372943136, 6138.202033735675, 6141.539384546177, 6150.04674275488, 6152.415520525543, 6155.076738489262, 6207.720652570645, 6218.751249950042, 6274.027525733519, 6287.339829317947, 6295.716692748837, 5194.896102077937, 5789.899919015374, 5945.64326140433, 5980.0, 6209.130721698965, 6065.666388348595, 6068.271807383054, 6079.118527813443, 6116.640127388518, 6158.7228574714245, 4952.950470495321, 4980.05378977993, 4989.5758860496535, 4996.400431948116, 5003.024410239968, 3741.1956429367274, 4446.088551846864, 4483.193025255486, 4490.887727806766, 4491.011977364975, 5591.07747221712, 5604.411536788579, 5626.099824028136, 5744.224529688522, 5754.597178197486, 6186.350433204539, 6218.782342617987, 6288.27684816252, 6289.77985770505, 6302.810915672537, 5737.08206686928, 5742.971285143631, 5743.655380677089, 5750.396208398058, 5780.624259423124, 5365.141577347606, 5406.162044883049, 5407.107827208467, 5426.104692725656, 5444.373897001896, 6295.559310848196, 6332.968335158294, 6335.619862808247, 6340.587861788969, 6371.362863713592, 6045.818625441244, 6126.3955342902, 6130.856783744145, 6132.99804414626, 6139.461473530676, 5289.418164002033, 6265.718042687999, 6379.936200637934, 6393.456556192589, 6400.327965563735, 5905.320888097752, 5948.940510594839, 5962.612430192024, 6002.759889604367, 6124.816255512342, 6183.505319574333, 6216.098665693539, 6241.750329986749, 6251.156093927261, 6261.24865016201, 5683.204351390891, 5839.912401313899, 5921.496672782861, 6013.518918486423, 6021.909671354845, 6086.695665216633, 6089.786857459968, 6129.84675383119, 6135.7238924249405, 6138.785142519991, 5904.3800400959, 5906.40935906406, 5906.822795316148, 5911.7930872420975, 5915.733791979457, 5818.330891947311, 5862.091375836752, 5871.7651293947765, 5874.5887787854435, 6029.397060293935, 5855.94144058554, 5856.94143058564, 5901.734421950937, 5926.407359264039, 5928.4960778334325], "value": 5646.987473605842, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.6163110289341, 736.4955005821013, 800.8238187598727, 815.8857759913614, 878.9033206347297, 28025.15924522265, 39224.82325530231, 39733.205335893304, 39790.602093979054, 39819.601803981954, 39616.80191599045, 39794.8010259949, 39843.80078099613, 39858.60141398585, 39921.800390998076, 7253.419726421874, 9105.226055785266, 9285.628574856997, 9292.0, 9303.813923721522, 7376.262373762607, 7457.5152615080115, 7462.589557574332, 7463.626818659072, 7466.962665186694, 9238.353315267934, 11182.888171118308, 11189.216754827165, 11199.160062995266, 11201.719957001058, 10960.958708922639, 10990.0, 11051.226414150973, 11055.336679799238, 11059.668209953634, 7197.568145911209, 7288.234735352765, 10756.569737210502, 10830.78338433227, 10833.0, 32130.678693213235, 38338.80830595857, 38472.0, 38517.61482385196, 39839.60160398388, 3338.849751761177, 3820.770753754756, 3847.576766555677, 3862.1503269280715, 3938.4879965604573, 26120.085796997013, 26664.46671066587, 26881.73118268812, 27829.0, 28636.0, 3071.7696172786905, 3129.3115514586752, 3262.6411094779555, 3287.917802054944, 3290.654481279455, 364868.0, 366884.0, 367240.65518689476, 367296.0, 375697.0, 52284.47715522872, 52419.21371179436, 52433.95132097413, 52438.47561524336, 52449.737751311, 55318.44681553133, 55338.72330638321, 55387.0, 55394.0, 55395.723021384634, 13826.101303415246, 14215.147091174562, 14221.928890355483, 14224.85775142256, 14239.644008899653, 12151.96708279806, 12156.392180390945, 12167.817482737766, 12171.695707607381, 12177.878221217852, 10412.0, 10587.735306617242, 10609.045185933302, 11618.88381116195, 12194.57318993831, 5193.480651934852, 5335.253064570963, 5347.037533243975, 5351.742340549968, 5354.196870469383, 1011.1809434358047, 1049.1659130990881, 1051.6319288249051, 1063.3223357316067, 1075.9462026898623, 24700.13549525758, 24837.254882353198, 24875.751242487706, 24986.875065624554, 24990.750092499205, 941.2611100286242, 1086.8043752124693, 1095.118429664128, 1108.939008354532, 1143.114086582905, 12216.3281019543, 12310.138290319592, 12446.190997585129, 12799.488020479077, 12835.93582032084, 423615.0, 427359.0, 427497.0, 427873.0, 430667.0, 53414.0, 53715.0, 53752.19371709428, 53775.0, 53814.46185538094, 1411.089847048648, 1458.4020551573803, 1458.50410860308, 1460.2114857976574, 1463.8828893688478, 22572.548549029256, 23530.88234558816, 26293.0, 27299.31751706223, 28138.0, 36803.63196368002, 44202.78986050749, 45603.77198113988, 45835.54164458313, 45855.77072114618, 430388.0, 482981.0, 483482.0, 489376.0, 490364.54817725683, 28703.712962870104, 28914.710852892043, 29319.0, 29354.41291174122, 29392.70607293985, 28212.435751284447, 34803.30393392067, 36173.45739813957, 38383.23233535258, 39313.39218356521, 25711.871440642677, 26019.089331873292, 27463.313417164733, 27713.475758833458, 27901.162965111078, 27094.322641934108, 27709.861450692617, 27737.30656733517, 28148.06725275393, 28158.577621335287, 31941.0, 32548.674513254566, 32587.511187332682, 32687.0, 32756.508652369757, 27419.725802741716, 27463.450730985667, 27869.442611148068, 27978.300542486602, 28611.570826437204, 22052.88973555122, 22504.324870253262, 22898.0, 22986.54026919486, 23069.65395519102], "value": 17845.304734861704, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.4487253210657, 427.9122779830135, 428.8842012656582, 434.1664005110188, 444.5399012022555, 18964.241430342787, 20212.797872021274, 21417.036233369497, 21475.0, 21626.567468650635, 20298.797012029874, 21166.68249976248, 21246.0, 21301.786982130176, 21345.893270533663, 6228.875422491547, 6668.499862510307, 6669.599824010551, 6681.231658359278, 6681.565698229624, 6310.810675679737, 6410.487161027107, 6539.280679125292, 6542.214934207902, 6561.442277406426, 6504.317046710098, 6552.082708420825, 6561.0, 6570.080188773575, 6971.965140174318, 7321.231270716578, 7377.778666639956, 7424.480286379956, 7433.219511951222, 7441.59071251081, 6907.965460172717, 7147.606881621508, 7203.459740519482, 7226.74706385274, 7227.132744070693, 24929.376765580822, 25038.624420633703, 25439.98240070395, 25498.0, 25655.84548695313, 2876.741093301613, 2895.7972941894077, 2896.522073857811, 2921.4595299869593, 2998.670146283907, 14199.929000354932, 15367.923160384238, 15431.768523472158, 15718.18961562239, 15874.20628968547, 1979.267670961739, 1980.366282789508, 1992.750906136733, 1993.3820515640198, 2014.305064752658, 84409.0, 111556.0, 139781.0, 154831.2258438712, 161715.1914240445, 18451.723224151647, 18749.968751718785, 18971.905140474486, 19002.52493687669, 19018.80981190198, 29855.253618659706, 30104.548431773543, 30144.09567712972, 30278.84860575727, 30282.242943926136, 8664.436811607226, 9314.44113353201, 9661.661841835503, 10234.692959211236, 10258.897411025795, 8285.544295063708, 8300.70947516834, 8321.750347489586, 8485.448445851, 8500.574971251413, 7975.641096150687, 8172.877406838783, 8785.780355491164, 8887.555622218992, 8948.910510894808, 3305.7024867761534, 3457.8616855325504, 3786.924261514754, 3823.5602905665646, 3889.163829776555, 946.8248374050822, 1017.1354348803458, 1027.019196667181, 1044.2272718188485, 1044.8641676581997, 20287.4928126798, 20599.794002060087, 20616.38150855477, 20650.380488585368, 20949.0, 904.6517090919912, 988.8566157907063, 994.7761753605525, 999.7904630765943, 1074.887136850635, 9999.600015999278, 10080.143187829117, 10543.894561054445, 10641.414722190353, 10664.146868250522, 764807.0, 767539.0, 774952.0, 779682.1015894884, 789942.0, 108257.0, 162546.0, 165155.0, 166075.0, 167637.0, 1317.8089177069462, 1355.9254241016642, 1382.4470211915313, 1388.8888888888864, 1391.3391139208795, 17300.913495432946, 17559.82440175633, 17911.283548657906, 17959.191836367132, 18242.0, 55789.0, 56378.71810640921, 61227.08159377524, 63591.364086358546, 63688.0, 92107.0, 92160.0, 93257.0, 93716.53141734247, 95525.52237239048, 11025.29107988263, 11150.0, 11225.550977961117, 11317.434128293717, 11465.885341146482, 12250.081243906565, 12253.938730306649, 12394.0, 12622.368881556069, 12714.364281786058, 8953.552322383724, 10699.89300106989, 10755.838662419914, 10899.237053406185, 10929.071028962628, 9631.78468952131, 9866.358686685342, 9912.851307230254, 9961.452120133463, 10175.338602990782, 10197.541110650187, 10227.181825453798, 10911.890881091087, 11094.889051109385, 11149.386783726648, 10979.505922233679, 11016.17378696585, 11059.22585419013, 11094.611688590861, 11299.3785341807, 8793.988691300583, 10517.9474102629, 10859.891401086204, 10882.94558527234, 10889.074428673335], "value": 11274.959168123774, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1512.5311153542395, 1544.1352842408253, 1568.9450869219577, 1575.3541048170246, 1590.9602259943504, 10489.055984961353, 11083.002529772319, 11090.778184436307, 11096.389698566576, 11100.0, 10522.947385263082, 10741.409222492759, 10902.400367979759, 10952.123830093573, 10958.506867190976, 2349.5888219561552, 2385.892634831428, 2386.844855084414, 2388.9761102389016, 2389.0443822471066, 2284.903246441709, 2285.6800047993293, 2368.360542653491, 2370.715514138306, 2371.5138396628736, 2098.7586427560873, 2322.93031209064, 2384.3681424422543, 2385.105585405473, 2389.6296074108454, 2322.5006623576014, 2330.603797354454, 2342.9882850585723, 2354.2348736660483, 2363.161077817376, 2356.363781778927, 2361.6457531370434, 2364.18435639704, 2365.6924599802087, 2366.6450032495263, 11016.944915275373, 11107.0, 11107.500162492708, 11111.499982500807, 11124.555017799279, 5475.515823921465, 5698.458646428624, 5768.019436695772, 5777.826665200051, 5792.768289268424, 2933.589297498352, 3301.7193538549254, 3503.4744788281705, 3521.6478352164577, 3554.182538016245, 977.9168770654431, 1094.2832444748703, 1137.1414581990628, 1166.3993043582561, 1182.024829515653, 13443.731125377437, 13764.31178441074, 14050.718985620333, 15356.769648455282, 15364.846351536453, 4510.075434535912, 4651.953140618117, 4658.301254811805, 4659.603933665609, 4695.154872123051, 11824.1131915105, 11833.526658933546, 11843.46704398304, 11866.110041746904, 11877.22798018126, 4832.347633069561, 4988.625853060962, 5443.265159203536, 5694.9430505695245, 5698.31620205578, 5160.277561141444, 5172.120739474299, 5248.475152484797, 5250.422453530108, 5262.842114736564, 3741.4574886641285, 3817.9236415272094, 3822.904427389282, 3865.9124073000266, 3882.456456096149, 1015.3806178231273, 1018.6740243122277, 1024.1755386913605, 1027.825269704152, 1032.5043978890176, 918.196577994255, 931.5528546297815, 948.4167237149104, 954.9713508594754, 992.0773680477081, 6494.740210391531, 6499.935000650027, 6684.629650921549, 6758.513127112027, 6762.391384775394, 836.9372297077743, 964.3297907953905, 966.1401352795876, 976.5800705696619, 1024.7591815923251, 4037.5760545142925, 4050.1292222172374, 4065.390191471303, 4095.508538975342, 4122.938530734633, 13265.270410127536, 13776.173429594257, 13781.86218137826, 13787.379567919266, 13792.655183620489, 11262.662120136409, 13229.470821167046, 13536.187828730308, 13570.0, 13612.863871361358, 9907.455089969977, 10154.086132248132, 10158.441285729356, 10180.592776289013, 10188.184945204366, 6589.27517973013, 6807.795766127024, 6962.895556566555, 6988.475864310095, 7097.183823860322, 11791.938725514745, 12069.75860482803, 12157.878421215675, 12510.87489125097, 12528.498860045496, 9831.606735730491, 12474.68813279639, 12604.0, 12714.982801375683, 12892.871071289543, 9124.95437522808, 9403.81192376135, 9415.19970802462, 9419.670311539063, 9422.152006319464, 10033.44816035125, 10246.743831404274, 10357.17142628603, 10357.792844142925, 10368.637097701545, 8503.404761666625, 8698.956505217433, 8763.60563774619, 8793.164649358301, 8911.06433824439, 7293.52592081513, 8464.703735369232, 8477.830443390974, 8559.95720021396, 8569.357298202534, 7760.223977602194, 7817.804554886174, 7941.801454963672, 7946.046474423106, 7948.205179482005, 5735.512481439125, 5787.536997040311, 5848.385919478394, 6009.969950150221, 6012.939870601238, 5943.197668314722, 5980.37206093354, 6025.517958563392, 6098.237720284965, 6230.968845155927], "value": 5067.885401542818, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.8313259033435, 381.30602303807063, 381.7308797297905, 390.2215080913578, 391.0770581427832, 4442.355858400538, 4971.825986090487, 4981.402231732188, 5022.422421421537, 5046.467472769058, 4320.3735458358515, 4680.625549956004, 4685.180093483636, 4706.587723900171, 4975.751212439382, 3270.9182270443193, 3778.338790711628, 4148.585141485857, 4153.127843152926, 4247.235497610429, 3685.4570346460855, 4191.958080419187, 4193.980334159415, 4207.43199668044, 4237.495689030396, 3750.324941510527, 3924.960750392502, 4226.978865105685, 4232.846529335649, 4237.609912070331, 3866.732610943748, 3937.7243592948507, 4111.8560850370395, 4146.896327591834, 4247.642558380097, 3314.839806067881, 3826.655600995896, 3850.0374906273432, 3866.3233934061427, 3870.1679138985255, 4156.750594964282, 4220.725652832587, 4329.393884856123, 4562.429696287964, 4650.907036846338, 4546.499885012646, 4753.429588449409, 4773.331733557305, 4820.108279968217, 4828.830990915336, 4399.626031787302, 5126.307948426952, 5280.0, 5304.416514183436, 5325.281087053237, 3568.7145028397927, 3672.008557689409, 3880.9223815523533, 4082.408050832613, 4370.8033138508845, 4770.713757174581, 4996.625253105995, 5154.871128221825, 5388.84752419831, 5638.633488823254, 3870.967741935506, 4253.659707223414, 4457.3091170868565, 4464.598186163261, 4504.48198457175, 3665.8350374233223, 3917.4319723639915, 4257.914841703148, 4286.335617979156, 4598.563136502027, 4314.676399270067, 4799.928001079987, 4843.564079232816, 4885.706857588556, 4950.900981980412, 4313.61177494027, 4439.4006809080365, 4551.977240113844, 4901.705897646153, 5344.5724342052545, 1388.4168649167173, 1481.748102822501, 1538.1309560098732, 1541.3218183999004, 1552.9301181446863, 1068.7755571330108, 1081.0594782539083, 1148.454484120053, 1156.259993604094, 1157.9363135027654, 4756.809727610926, 5009.44896061433, 5023.497650234947, 5093.351457242287, 5110.616703747234, 4810.4467986182035, 5079.923801142986, 5099.719515426687, 5490.286262785812, 5491.862703432446, 4393.582609652078, 4689.273162659793, 4705.388299521041, 4964.503549645079, 5133.255677926754, 2419.9758002420103, 2520.7227205007434, 2831.3012192684223, 2906.2589039794716, 2916.3584011517432, 4700.529947005341, 5357.437469065692, 5526.621959683786, 5696.772129114789, 5876.266501382102, 4708.788104535304, 4887.3890763654545, 4959.247617572282, 5020.7740651670765, 5651.580793619883, 5066.695998240118, 5129.897402052013, 5293.470652934753, 5487.972560137254, 5738.0, 4803.495632958628, 4937.469384490676, 5414.908228243226, 5430.565554755689, 5437.673739575638, 4915.7542122893, 4952.1581331173065, 5081.078379931628, 5257.842264732064, 5269.762860671356, 5000.724960127228, 5077.593792496666, 5518.86202844916, 5815.5929084963645, 5844.895837498412, 4244.978775106105, 4277.775556710555, 4334.1981733379425, 4370.67219958498, 4388.398234644268, 4808.557432770225, 4885.731284779371, 5362.640438207176, 5506.64206826555, 5598.3484871962055, 4549.430446495953, 4955.2567114933045, 5046.3439752833365, 5117.8464646060675, 5334.51989320963, 4596.977015114903, 4770.356001939694, 5262.026525092717, 5264.341957255343, 5275.604329675213, 4940.75296235194, 5224.2947202127225, 5294.841154765363, 5296.0, 5312.654677445954, 4667.393238878924, 4673.696209746356, 4799.3041009052795, 5462.006367675849, 5470.945290547043, 4511.593956543927, 5244.685318880879, 5298.94701053, 5320.17537281722, 5321.574274057989], "value": 4043.1832552359383, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.9561626873568, 753.3107206905681, 758.525921299188, 820.2166930581294, 831.8419500294946, 14715.264236788158, 15955.601109972242, 16271.674566508662, 16334.754978675315, 16370.427035053775, 14333.0, 14592.927035364835, 14676.046057006288, 14706.926465367658, 14774.261286935664, 922.7600823785814, 941.7880976780239, 944.7826999790052, 946.7633091727068, 948.5636607160715, 926.754410081329, 947.9146876781089, 949.278548303291, 949.6438835436711, 963.4026903319951, 1004.4274763384874, 1005.0452070532987, 1005.2912696548915, 1006.3157053203819, 1007.7833265847842, 998.0967224661692, 1000.7197984564298, 1000.8248556502583, 1001.419176877413, 1001.6394098124742, 742.9962850185715, 743.8958545803591, 744.4528271720282, 744.56070918158, 744.9942577520359, 15566.37734490619, 15874.682506349809, 15948.0, 16307.592310192222, 16620.168991550374, 14118.576442706735, 14159.858401415957, 14245.572632820928, 14262.786058209134, 14317.35571899267, 10510.369377837278, 11749.94125029378, 11899.345535995517, 12048.216865903689, 12096.516139354504, 3620.2216523447587, 3643.1074386775144, 3662.0661731258624, 3676.099355657881, 3726.6273372662517, 96831.0316896829, 119318.0, 121266.39366803197, 123259.0, 124391.0, 11129.554817807197, 11317.981381675689, 11368.0, 11372.658820235407, 11536.423178841025, 21962.89018554897, 22196.556068878534, 22362.55274894493, 22606.09575616957, 22648.0, 7847.097583777939, 7909.960450197712, 7910.762677119696, 7912.723054693058, 7997.640106195119, 4306.698531102856, 4345.217860785026, 4347.413099231627, 4429.291313389842, 4521.276595744665, 3041.4829478988627, 3438.914027149291, 3482.6169121396624, 3488.3720930232294, 3540.8760693376125, 533.3919331961567, 618.7339444039017, 624.3413199074929, 631.3875540725489, 632.5066448170435, 658.5390226841134, 673.7843889955266, 690.775497963164, 701.6491754122939, 738.7672883041824, 9488.810223795486, 9980.500974951223, 10371.0, 10449.216308776871, 10658.946705266579, 683.8598087392073, 699.192432740186, 706.7314420520272, 728.3408515293668, 738.5753191914671, 4774.355462012653, 4906.607471402279, 4950.405951285797, 4966.15575352191, 4970.776315065831, 74589.0, 98316.50841745888, 100012.0, 101071.0, 101638.49180754197, 53429.0, 63162.0, 68054.65972670105, 87620.0, 97373.0262697378, 70085.0, 71921.0, 73909.63045184739, 80350.79473808015, 80637.59681201556, 10508.94910508943, 10965.671029869116, 11211.271267367594, 11260.38067906273, 11436.771264574496, 47382.0, 49833.0, 50023.74988125036, 51012.74493627508, 51896.0, 54150.72924635352, 69369.30630693628, 69754.65122674557, 69804.30195698181, 73629.52740945258, 12290.324032177958, 12526.498940042296, 12796.168249063781, 12835.165714228546, 13026.2835544046, 14073.788893166815, 14338.087160686677, 14598.78068874595, 14752.114873107648, 15133.621659458162, 13087.476500939856, 13891.027628065938, 14065.437382504586, 14258.001939864525, 14389.208593527037, 12879.291638959572, 13810.171389716648, 13957.651058723613, 14105.435782568582, 14267.857321427067, 12423.689407764517, 13542.187468751907, 13547.454824437276, 13550.660880544272, 14009.9850224663, 9402.576884040098, 9923.0, 9927.652532161339, 9945.104940555384, 10066.798664026825, 10427.322224055411, 11044.171687123337, 11283.435828208721, 11504.654860354203, 11506.0], "value": 7650.921545826784, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1431.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 115.0, 115.0, 115.0, 117.0, 115.0, 116.0, 116.0, 116.0, 117.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 95.0, 107.0, 171.0, 138.0, 137.0, 107.0, 109.0, 129.0, 110.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 182.0, 182.0, 184.0, 184.0, 217.0, 243.0, 183.0, 185.0], "value": 192.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 62.0, 92.0, 63.0, 60.0, 60.0, 61.0, 75.0, 60.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 68.0, 87.0, 71.0, 68.0, 68.0, 83.0, 69.0, 69.0, 85.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 87.0, 88.0, 87.0, 87.0, 86.0, 92.0, 125.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 99.0, 94.0, 97.0, 95.0, 99.0, 94.0, 97.0, 94.0, 93.0], "value": 95.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 84.0, 84.0, 86.0, 84.0, 84.0, 87.0, 86.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 60.0, 63.0, 67.0, 68.0, 68.0, 65.0, 66.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 65.0, 57.0, 66.0, 60.0, 58.0, 58.0, 60.0, 59.0, 59.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 85.0, 85.0, 82.0, 84.0, 81.0, 83.0, 83.0, 83.0, 87.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 136.0, 143.0, 139.0, 137.0, 141.0, 141.0, 144.0, 182.0, 151.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 171.0, 167.0, 165.0, 165.0, 164.0, 164.0, 175.0, 162.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 64.0, 64.0, 65.0, 64.0, 64.0, 64.0, 65.0], "value": 64.5, "unit": "score"}]}]} 02:12:16 INFO - Return code: 0 02:12:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:12:16 ERROR - # TBPL SUCCESS # 02:12:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:12:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:12:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:12:16 INFO - 2017-04-10 02:12:16.470 screenresolution[1931:25176] starting screenresolution argv=screenresolution get 02:12:16 INFO - 2017-04-10 02:12:16.473 screenresolution[1931:25176] Display 0: 1600x1200x32@60 02:12:16 INFO - 2017-04-10 02:12:16.479 screenresolution[1932:25179] starting screenresolution argv=screenresolution list 02:12:16 INFO - Available Modes on Display 0 02:12:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:12:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:12:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:12:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:12:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:12:16 INFO - Intel Iris: 02:12:16 INFO - Chipset Model: Intel Iris 02:12:16 INFO - Type: GPU 02:12:16 INFO - Bus: Built-In 02:12:16 INFO - VRAM (Dynamic, Max): 1536 MB 02:12:16 INFO - Vendor: Intel (0x8086) 02:12:16 INFO - Device ID: 0x0a2e 02:12:16 INFO - Revision ID: 0x0009 02:12:16 INFO - Displays: 02:12:16 INFO - Display: 02:12:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:12:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:12:16 INFO - Display Serial Number: 1600x1200 60 02:12:16 INFO - Main Display: Yes 02:12:16 INFO - Mirror: Off 02:12:16 INFO - Online: Yes 02:12:16 INFO - Rotation: Supported 02:12:16 INFO - Return code: 0 02:12:16 INFO - Running post-action listener: _resource_record_post_action 02:12:16 INFO - [mozharness: 2017-04-10 09:12:16.716226Z] Finished run-tests step (success) 02:12:16 INFO - Running post-run listener: _resource_record_post_run 02:12:16 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 352256; Write bytes: 510121984; Read time: 4; Write time: 1544 02:12:16 INFO - TinderboxPrint: CPU usage
53.7% 02:12:16 INFO - TinderboxPrint: I/O read bytes / time
352,256 / 4 02:12:16 INFO - TinderboxPrint: I/O write bytes / time
510,121,984 / 1,544 02:12:16 INFO - TinderboxPrint: CPU idle
2,221.3 (46.3%) 02:12:16 INFO - TinderboxPrint: CPU system
956.9 (19.9%) 02:12:16 INFO - TinderboxPrint: CPU user
1,623.3 (33.8%) 02:12:16 INFO - TinderboxPrint: Swap in / out
208,191,488 / 0 02:12:16 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205711872; Write bytes: 202582016; Read time: 15714; Write time: 432 02:12:16 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 237568; Write bytes: 296411136; Read time: 2; Write time: 1073 02:12:17 INFO - Running post-run listener: _upload_blobber_files 02:12:17 INFO - Blob upload gear active. 02:12:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:12:17 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 02:12:17 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'] 02:12:17 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 02:12:17 INFO - (blobuploader) - INFO - Open directory for files ... 02:12:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:12:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:12:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:12:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:12:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:12:18 INFO - (blobuploader) - INFO - Done attempting. 02:12:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:12:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:12:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:12:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:12:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:12:19 INFO - (blobuploader) - INFO - Done attempting. 02:12:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:12:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:12:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:12:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:12:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:12:20 INFO - (blobuploader) - INFO - Done attempting. 02:12:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:12:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:12:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:12:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:12:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:12:22 INFO - (blobuploader) - INFO - Done attempting. 02:12:22 INFO - (blobuploader) - INFO - Iteration through files over. 02:12:22 INFO - Return code: 0 02:12:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:12:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:12:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f79906a15d049951e93f5be5c1742145453dbe823d3451642599dcadd3bf1fde3f1575801f2a18627896a7a1dfc0fb09eed2c2551e1766d8b4446257c072509", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84ccfb2c91f36a3f2d3c0e314031b31e534e00bc46f18cc9da8df2b2df51a3de96dd697b349f0969ba0a09a9070d0247468f403c63d26c0fcd5c9681c839f8bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ed3debaf888b3a26be2f713ceeb1ae6f6b8395a29f8feddf82c3caac9fbc8aaa2118ca1845b7e1962348fdf3818b8c34b1915eab343cd4818eab471daa158ee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b4674672363617e16cdbfe63f28923ab3487e7cff5a9d3721670c8fd5c5bc2a47920126ae89013a0bf3892ef677b17d3f00bb2ccef258582492470f296251a8"} 02:12:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:12:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:12:22 INFO - Contents: 02:12:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f79906a15d049951e93f5be5c1742145453dbe823d3451642599dcadd3bf1fde3f1575801f2a18627896a7a1dfc0fb09eed2c2551e1766d8b4446257c072509", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84ccfb2c91f36a3f2d3c0e314031b31e534e00bc46f18cc9da8df2b2df51a3de96dd697b349f0969ba0a09a9070d0247468f403c63d26c0fcd5c9681c839f8bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ed3debaf888b3a26be2f713ceeb1ae6f6b8395a29f8feddf82c3caac9fbc8aaa2118ca1845b7e1962348fdf3818b8c34b1915eab343cd4818eab471daa158ee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b4674672363617e16cdbfe63f28923ab3487e7cff5a9d3721670c8fd5c5bc2a47920126ae89013a0bf3892ef677b17d3f00bb2ccef258582492470f296251a8"} 02:12:22 INFO - Running post-run listener: copy_logs_to_upload_dir 02:12:22 INFO - Copying logs to upload dir... 02:12:22 INFO - mkdir: /builds/slave/test/build/upload/logs 02:12:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.647594 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-10 02:12:22.539590) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 02:12:22.544805) ========= 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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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/4f79906a15d049951e93f5be5c1742145453dbe823d3451642599dcadd3bf1fde3f1575801f2a18627896a7a1dfc0fb09eed2c2551e1766d8b4446257c072509", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84ccfb2c91f36a3f2d3c0e314031b31e534e00bc46f18cc9da8df2b2df51a3de96dd697b349f0969ba0a09a9070d0247468f403c63d26c0fcd5c9681c839f8bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ed3debaf888b3a26be2f713ceeb1ae6f6b8395a29f8feddf82c3caac9fbc8aaa2118ca1845b7e1962348fdf3818b8c34b1915eab343cd4818eab471daa158ee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b4674672363617e16cdbfe63f28923ab3487e7cff5a9d3721670c8fd5c5bc2a47920126ae89013a0bf3892ef677b17d3f00bb2ccef258582492470f296251a8"} build_url:https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011079 build_url: 'https://queue.taskcluster.net/v1/task/RJMT-oc0QEWTBj0GCbmn5w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f79906a15d049951e93f5be5c1742145453dbe823d3451642599dcadd3bf1fde3f1575801f2a18627896a7a1dfc0fb09eed2c2551e1766d8b4446257c072509", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84ccfb2c91f36a3f2d3c0e314031b31e534e00bc46f18cc9da8df2b2df51a3de96dd697b349f0969ba0a09a9070d0247468f403c63d26c0fcd5c9681c839f8bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ed3debaf888b3a26be2f713ceeb1ae6f6b8395a29f8feddf82c3caac9fbc8aaa2118ca1845b7e1962348fdf3818b8c34b1915eab343cd4818eab471daa158ee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b4674672363617e16cdbfe63f28923ab3487e7cff5a9d3721670c8fd5c5bc2a47920126ae89013a0bf3892ef677b17d3f00bb2ccef258582492470f296251a8"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 02:12:22.581955) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 02:12:22.582253) ========= 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.Y2Jg7gZQXz/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.x0RQDBTEhT/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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.005413 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 02:12:22.639505) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 02:12:22.639915) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 02:12:22.640384) ========= ========= Total master_lag: 0.20 =========