builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0011 starttime: 1486584437.95 results: success (0) buildid: 20170208105120 builduid: 8e9e5fa8d8244070b7efaae9cf5d7b78 revision: c51d7c9daa243554dcee9fd28d487ae76c512bf2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-08 12:07:17.952625) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-08 12:07:17.953108) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-08 12:07:17.953425) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-08 12:07:17.980305) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 12:07:17.980734) ========= 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.czBSmHmNVQ/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.ywolUaeThX/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-08 12:07:18-- 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% 161M=0s 2017-02-08 12:07:18 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090342 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 12:07:18.096070) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 12:07:18.096427) ========= 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.czBSmHmNVQ/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.ywolUaeThX/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.028446 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 12:07:18.149085) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-08 12:07:18.149489) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c51d7c9daa243554dcee9fd28d487ae76c512bf2 --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 c51d7c9daa243554dcee9fd28d487ae76c512bf2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.czBSmHmNVQ/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.ywolUaeThX/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-08 12:07:18,282 truncating revision to first 12 chars 2017-02-08 12:07:18,282 Setting DEBUG logging. 2017-02-08 12:07:18,283 attempt 1/10 2017-02-08 12:07:18,283 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c51d7c9daa24?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-08 12:07:19,382 unpacking tar archive at: mozilla-aurora-c51d7c9daa24/testing/mozharness/ program finished with exit code 0 elapsedTime=1.367432 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-08 12:07:19.540758) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-08 12:07:19.541104) ========= script_repo_revision: c51d7c9daa243554dcee9fd28d487ae76c512bf2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-08 12:07:19.541581) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-08 12:07:19.541904) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-08 12:07:19.561747) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-08 12:07:19.562096) ========= /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.czBSmHmNVQ/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.ywolUaeThX/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 12:07:19 INFO - MultiFileLogger online at 20170208 12:07:19 in /builds/slave/test 12:07:19 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 12:07:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:07:19 INFO - {'append_to_log': False, 12:07:19 INFO - 'base_work_dir': '/builds/slave/test', 12:07:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:07:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:07:19 INFO - 'branch': 'Mozilla-Aurora', 12:07:19 INFO - 'buildbot_json_path': 'buildprops.json', 12:07:19 INFO - 'config_files': ('talos/mac_config.py',), 12:07:19 INFO - 'default_actions': ('clobber', 12:07:19 INFO - 'read-buildbot-config', 12:07:19 INFO - 'download-and-extract', 12:07:19 INFO - 'populate-webroot', 12:07:19 INFO - 'create-virtualenv', 12:07:19 INFO - 'install', 12:07:19 INFO - 'run-tests'), 12:07:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:07:19 INFO - 'download_minidump_stackwalk': True, 12:07:19 INFO - 'download_symbols': 'ondemand', 12:07:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:07:19 INFO - 'tooltool.py': '/tools/tooltool.py', 12:07:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:07:19 INFO - '/tools/misc-python/virtualenv.py')}, 12:07:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:07:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:07:19 INFO - 'installer_path': 'installer.exe', 12:07:19 INFO - 'log_level': 'info', 12:07:19 INFO - 'log_name': 'talos', 12:07:19 INFO - 'log_to_console': True, 12:07:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:07:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:07:19 INFO - 'opt_config_files': (), 12:07:19 INFO - 'pip_index': False, 12:07:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:07:19 INFO - 'cmd': ('bash', 12:07:19 INFO - '-c', 12:07:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:07:19 INFO - 'enabled': True, 12:07:19 INFO - 'halt_on_failure': False, 12:07:19 INFO - 'name': 'check_screen_resolution'},), 12:07:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:07:19 INFO - 'cmd': ('bash', 12:07:19 INFO - '-c', 12:07:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:07:19 INFO - 'enabled': True, 12:07:19 INFO - 'halt_on_failure': False, 12:07:19 INFO - 'name': 'check_screen_resolution'},), 12:07:19 INFO - 'run_cmd_checks_enabled': True, 12:07:19 INFO - 'sps_profile': False, 12:07:19 INFO - 'sps_profile_interval': 0, 12:07:19 INFO - 'suite': 'dromaeojs', 12:07:19 INFO - 'system_bits': '32', 12:07:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:07:19 INFO - 'title': 't-yosemite-r7-0011', 12:07:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:07:19 INFO - 'use_talos_json': True, 12:07:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:07:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:07:19 INFO - 'work_dir': 'build'} 12:07:19 INFO - [mozharness: 2017-02-08 20:07:19.807286Z] Running clobber step. 12:07:19 INFO - Running pre-action listener: _resource_record_pre_action 12:07:19 INFO - Running main action method: clobber 12:07:19 INFO - rmtree: /builds/slave/test/build 12:07:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:07:22 INFO - Running post-action listener: _resource_record_post_action 12:07:22 INFO - [mozharness: 2017-02-08 20:07:22.408158Z] Finished clobber step (success) 12:07:22 INFO - [mozharness: 2017-02-08 20:07:22.408421Z] Running read-buildbot-config step. 12:07:22 INFO - Running pre-action listener: _resource_record_pre_action 12:07:22 INFO - Running main action method: read_buildbot_config 12:07:22 INFO - Using buildbot properties: 12:07:22 INFO - { 12:07:22 INFO - "project": "", 12:07:22 INFO - "product": "firefox", 12:07:22 INFO - "script_repo_revision": "production", 12:07:22 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:07:22 INFO - "repository": "", 12:07:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:07:22 INFO - "buildid": "20170208105120", 12:07:22 INFO - "pgo_build": "False", 12:07:22 INFO - "basedir": "/builds/slave/test", 12:07:22 INFO - "buildnumber": 59, 12:07:22 INFO - "slavename": "t-yosemite-r7-0011", 12:07:22 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:07:22 INFO - "platform": "yosemite_r7", 12:07:22 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:07:22 INFO - "branch": "mozilla-aurora", 12:07:22 INFO - "repo_path": "releases/mozilla-aurora", 12:07:22 INFO - "revision": "c51d7c9daa243554dcee9fd28d487ae76c512bf2", 12:07:22 INFO - "stage_platform": "macosx64", 12:07:22 INFO - "builduid": "8e9e5fa8d8244070b7efaae9cf5d7b78", 12:07:22 INFO - "slavebuilddir": "test" 12:07:22 INFO - } 12:07:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 12:07:22 INFO - Running post-action listener: _resource_record_post_action 12:07:22 INFO - [mozharness: 2017-02-08 20:07:22.414157Z] Finished read-buildbot-config step (success) 12:07:22 INFO - [mozharness: 2017-02-08 20:07:22.414400Z] Running download-and-extract step. 12:07:22 INFO - Running pre-action listener: _resource_record_pre_action 12:07:22 INFO - Running main action method: download_and_extract 12:07:22 INFO - mkdir: /builds/slave/test/build/tests 12:07:22 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')]} 12:07:22 INFO - trying https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 12:07:22 INFO - Downloading https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 12:07:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 12:07:23 INFO - Downloaded 1199 bytes. 12:07:23 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 12:07:23 INFO - Using the following test package requirements: 12:07:23 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 12:07:23 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:07:23 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 12:07:23 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:07:23 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 12:07:23 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:07:23 INFO - u'jsshell-mac.zip'], 12:07:23 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:07:23 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 12:07:23 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 12:07:23 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:07:23 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 12:07:23 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:07:23 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 12:07:23 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:07:23 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 12:07:23 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:07:23 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 12:07:23 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:07:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 12:07:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:07:23 INFO - Fetch https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 12:07:24 INFO - Expected file size: 16357926 12:07:24 INFO - Obtained file size: 16357926 12:07:30 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:07:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 12:07:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:07:30 INFO - Fetch https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 12:07:31 INFO - Expected file size: 16357926 12:07:31 INFO - Obtained file size: 16357926 12:07:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 12:07:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:07:38 INFO - Fetch https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 12:07:39 INFO - Expected file size: 14403485 12:07:39 INFO - Obtained file size: 14403485 12:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:39 INFO - trying https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 12:07:39 INFO - Downloading https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 12:07:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 12:07:41 INFO - Downloaded 73815689 bytes. 12:07:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 12:07:41 INFO - mkdir: /builds/slave/test/properties 12:07:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:07:41 INFO - Writing to file /builds/slave/test/properties/build_url 12:07:41 INFO - Contents: 12:07:41 INFO - build_url:https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 12:07:42 INFO - Running post-action listener: _resource_record_post_action 12:07:42 INFO - Running post-action listener: set_extra_try_arguments 12:07:42 INFO - [mozharness: 2017-02-08 20:07:42.453501Z] Finished download-and-extract step (success) 12:07:42 INFO - [mozharness: 2017-02-08 20:07:42.453626Z] Running populate-webroot step. 12:07:42 INFO - Running pre-action listener: _resource_record_pre_action 12:07:42 INFO - Running main action method: populate_webroot 12:07:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:07:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:07:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:07:42 INFO - '--tppagecycles', 12:07:42 INFO - '7'], 12:07:42 INFO - 'tests': ['tsvgm']}}, 12:07:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:07:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:07:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:07:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:07:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:07:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:07:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:07:42 INFO - 'talos_options': ['--disable-e10s'], 12:07:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:07:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:07:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:07:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:07:42 INFO - 'talos_options': ['--disable-e10s'], 12:07:42 INFO - 'tests': ['damp', 'tps']}, 12:07:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:07:42 INFO - 'tests': ['damp', 'tps']}, 12:07:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:07:42 INFO - 'tests': ['dromaeo_dom']}, 12:07:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:07:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:07:42 INFO - 'tests': ['basic_compositor_video']}, 12:07:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:07:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:07:42 INFO - 'tests': ['a11yr', 12:07:42 INFO - 'ts_paint', 12:07:42 INFO - 'tpaint', 12:07:42 INFO - 'sessionrestore', 12:07:42 INFO - 'sessionrestore_no_auto_restore', 12:07:42 INFO - 'tabpaint']}, 12:07:42 INFO - 'other-e10s': {'tests': ['a11yr', 12:07:42 INFO - 'ts_paint', 12:07:42 INFO - 'tpaint', 12:07:42 INFO - 'sessionrestore', 12:07:42 INFO - 'sessionrestore_no_auto_restore', 12:07:42 INFO - 'tabpaint']}, 12:07:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:07:42 INFO - 'ts_paint', 12:07:42 INFO - 'tpaint', 12:07:42 INFO - 'sessionrestore', 12:07:42 INFO - 'sessionrestore_no_auto_restore', 12:07:42 INFO - 'tabpaint']}, 12:07:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:07:42 INFO - 'ts_paint', 12:07:42 INFO - 'tpaint', 12:07:42 INFO - 'sessionrestore', 12:07:42 INFO - 'sessionrestore_no_auto_restore', 12:07:42 INFO - 'tabpaint']}, 12:07:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:07:42 INFO - 'tests': ['a11yr', 12:07:42 INFO - 'ts_paint', 12:07:42 INFO - 'tpaint', 12:07:42 INFO - 'sessionrestore', 12:07:42 INFO - 'sessionrestore_no_auto_restore', 12:07:42 INFO - 'tabpaint']}, 12:07:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:07:42 INFO - 'tests': ['a11yr', 12:07:42 INFO - 'ts_paint', 12:07:42 INFO - 'tpaint', 12:07:42 INFO - 'sessionrestore', 12:07:42 INFO - 'sessionrestore_no_auto_restore', 12:07:42 INFO - 'tabpaint']}, 12:07:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:07:42 INFO - 'tests': ['tsvgx', 12:07:42 INFO - 'tsvgr_opacity', 12:07:42 INFO - 'tart', 12:07:42 INFO - 'tscrollx', 12:07:42 INFO - 'cart', 12:07:42 INFO - 'tsvg_static']}, 12:07:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:07:42 INFO - 'tsvgr_opacity', 12:07:42 INFO - 'tart', 12:07:42 INFO - 'tscrollx', 12:07:42 INFO - 'cart', 12:07:42 INFO - 'tsvg_static']}, 12:07:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:07:42 INFO - 'talos_options': ['--disable-e10s'], 12:07:42 INFO - 'tests': ['tp5o']}, 12:07:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:07:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:07:42 INFO - 'talos_options': ['--disable-e10s', 12:07:42 INFO - '--xperf_path', 12:07:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:07:42 INFO - 'tests': ['tp5n']}, 12:07:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:07:42 INFO - 'talos_options': ['--xperf_path', 12:07:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:07:42 INFO - 'tests': ['tp5n']}}, 12:07:42 INFO - 'talos.zip': {'path': '', 12:07:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:07:42 INFO - Running post-action listener: _resource_record_post_action 12:07:42 INFO - [mozharness: 2017-02-08 20:07:42.471779Z] Finished populate-webroot step (success) 12:07:42 INFO - [mozharness: 2017-02-08 20:07:42.471896Z] Running create-virtualenv step. 12:07:42 INFO - Running pre-action listener: _resource_record_pre_action 12:07:42 INFO - Running main action method: create_virtualenv 12:07:42 INFO - Creating virtualenv /builds/slave/test/build/venv 12:07:42 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 12:07:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:07:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:07:42 INFO - Using real prefix '/tools/python27' 12:07:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:07:43 INFO - Installing distribute.............................................................................................................................................................................................done. 12:07:46 INFO - Installing pip.................done. 12:07:46 INFO - Return code: 0 12:07:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:07:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:07:46 INFO - Reading from file tmpfile_stdout 12:07:46 INFO - Output received: 12:07:46 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) 12:07:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ea94b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea97768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbe9c6d8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e872d30>, '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 0x10e799db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e867be8>, '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.czBSmHmNVQ/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.ywolUaeThX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 12:07:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:07:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:07:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.czBSmHmNVQ/Render', 12:07:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:46 INFO - 'HOME': '/Users/cltbld', 12:07:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:46 INFO - 'LOGNAME': 'cltbld', 12:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:46 INFO - 'MOZ_NO_REMOTE': '1', 12:07:46 INFO - 'NO_EM_RESTART': '1', 12:07:46 INFO - 'PAGER': '/bin/cat', 12:07:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:46 INFO - 'PWD': '/builds/slave/test', 12:07:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:46 INFO - 'SHELL': '/bin/bash', 12:07:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ywolUaeThX/Listeners', 12:07:46 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 12:07:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:46 INFO - 'USER': 'cltbld', 12:07:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:46 INFO - 'XPC_FLAGS': '0x0', 12:07:46 INFO - 'XPC_SERVICE_NAME': '0', 12:07:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:46 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 12:07:46 INFO - Cleaning up... 12:07:46 INFO - Return code: 0 12:07:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ea94b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea97768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbe9c6d8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e872d30>, '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 0x10e799db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e867be8>, '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.czBSmHmNVQ/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.ywolUaeThX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 12:07:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:07:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:07:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.czBSmHmNVQ/Render', 12:07:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:46 INFO - 'HOME': '/Users/cltbld', 12:07:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:46 INFO - 'LOGNAME': 'cltbld', 12:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:46 INFO - 'MOZ_NO_REMOTE': '1', 12:07:46 INFO - 'NO_EM_RESTART': '1', 12:07:46 INFO - 'PAGER': '/bin/cat', 12:07:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:46 INFO - 'PWD': '/builds/slave/test', 12:07:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:46 INFO - 'SHELL': '/bin/bash', 12:07:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ywolUaeThX/Listeners', 12:07:46 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 12:07:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:46 INFO - 'USER': 'cltbld', 12:07:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:46 INFO - 'XPC_FLAGS': '0x0', 12:07:46 INFO - 'XPC_SERVICE_NAME': '0', 12:07:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:46 INFO - Downloading/unpacking psutil>=3.1.1 12:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:07:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:07:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:07:50 INFO - Installing collected packages: psutil 12:07:50 INFO - Running setup.py install for psutil 12:07:50 INFO - building 'psutil._psutil_osx' extension 12:07:50 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 12:07: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:07: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:07:51 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 12:07:51 INFO - building 'psutil._psutil_posix' extension 12:07:51 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 12:07:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:07:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:07:51 INFO - ^ 12:07:51 INFO - 1 warning generated. 12:07:51 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 12:07:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:07:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:07:51 INFO - Successfully installed psutil 12:07:51 INFO - Cleaning up... 12:07:51 INFO - Return code: 0 12:07:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ea94b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea97768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbe9c6d8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e872d30>, '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 0x10e799db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e867be8>, '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.czBSmHmNVQ/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.ywolUaeThX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 12:07:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:07:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:07:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.czBSmHmNVQ/Render', 12:07:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:51 INFO - 'HOME': '/Users/cltbld', 12:07:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:51 INFO - 'LOGNAME': 'cltbld', 12:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:51 INFO - 'MOZ_NO_REMOTE': '1', 12:07:51 INFO - 'NO_EM_RESTART': '1', 12:07:51 INFO - 'PAGER': '/bin/cat', 12:07:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:51 INFO - 'PWD': '/builds/slave/test', 12:07:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:51 INFO - 'SHELL': '/bin/bash', 12:07:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ywolUaeThX/Listeners', 12:07:51 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 12:07:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:51 INFO - 'USER': 'cltbld', 12:07:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:51 INFO - 'XPC_FLAGS': '0x0', 12:07:51 INFO - 'XPC_SERVICE_NAME': '0', 12:07:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:07:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:07:54 INFO - Installing collected packages: mozsystemmonitor 12:07:54 INFO - Running setup.py install for mozsystemmonitor 12:07:54 INFO - Successfully installed mozsystemmonitor 12:07:54 INFO - Cleaning up... 12:07:54 INFO - Return code: 0 12:07:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:54 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 0x10ea94b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea97768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbe9c6d8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e872d30>, '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 0x10e799db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e867be8>, '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.czBSmHmNVQ/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.ywolUaeThX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 12:07:54 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 12:07:54 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 12:07:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.czBSmHmNVQ/Render', 12:07:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:54 INFO - 'HOME': '/Users/cltbld', 12:07:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:54 INFO - 'LOGNAME': 'cltbld', 12:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:54 INFO - 'MOZ_NO_REMOTE': '1', 12:07:54 INFO - 'NO_EM_RESTART': '1', 12:07:54 INFO - 'PAGER': '/bin/cat', 12:07:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:54 INFO - 'PWD': '/builds/slave/test', 12:07:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:54 INFO - 'SHELL': '/bin/bash', 12:07:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ywolUaeThX/Listeners', 12:07:54 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 12:07:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:54 INFO - 'USER': 'cltbld', 12:07:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:54 INFO - 'XPC_FLAGS': '0x0', 12:07:54 INFO - 'XPC_SERVICE_NAME': '0', 12:07:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:55 INFO - Downloading/unpacking jsonschema==2.5.1 12:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:07:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:07:57 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 12:07:57 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 12:07:57 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 12:07:57 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 12:07:57 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 12:07:57 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 12:07:58 INFO - Downloading functools32-3.2.3-2.tar.gz 12:07:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:07:58 INFO - warning: no files found matching '*.txt' 12:07:58 INFO - no previously-included directories found matching 'build' 12:07:58 INFO - no previously-included directories found matching 'dist' 12:07:58 INFO - no previously-included directories found matching '.git*' 12:07:58 INFO - Installing collected packages: jsonschema, functools32 12:07:58 INFO - Running setup.py install for functools32 12:07:58 INFO - warning: no files found matching '*.txt' 12:07:58 INFO - no previously-included directories found matching 'build' 12:07:58 INFO - no previously-included directories found matching 'dist' 12:07:58 INFO - no previously-included directories found matching '.git*' 12:07:58 INFO - Successfully installed jsonschema functools32 12:07:58 INFO - Cleaning up... 12:07:58 INFO - Return code: 0 12:07:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:07:58 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')]} 12:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:58 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')]} 12:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:58 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 0x10ea94b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea97768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbe9c6d8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e872d30>, '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 0x10e799db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e867be8>, '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.czBSmHmNVQ/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.ywolUaeThX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 12:07:58 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 12:07:58 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 12:07:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.czBSmHmNVQ/Render', 12:07:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:58 INFO - 'HOME': '/Users/cltbld', 12:07:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:58 INFO - 'LOGNAME': 'cltbld', 12:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:58 INFO - 'MOZ_NO_REMOTE': '1', 12:07:58 INFO - 'NO_EM_RESTART': '1', 12:07:58 INFO - 'PAGER': '/bin/cat', 12:07:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:58 INFO - 'PWD': '/builds/slave/test', 12:07:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:58 INFO - 'SHELL': '/bin/bash', 12:07:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ywolUaeThX/Listeners', 12:07:58 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 12:07:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:58 INFO - 'USER': 'cltbld', 12:07:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:58 INFO - 'XPC_FLAGS': '0x0', 12:07:58 INFO - 'XPC_SERVICE_NAME': '0', 12:07:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:07:58 INFO - Cleaning up... 12:07:58 INFO - Return code: 0 12:07:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:07:58 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')]} 12:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:58 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')]} 12:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:58 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 0x10ea94b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea97768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbe9c6d8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e872d30>, '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 0x10e799db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e867be8>, '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.czBSmHmNVQ/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.ywolUaeThX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 12:07:58 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 12:07:58 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 12:07:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.czBSmHmNVQ/Render', 12:07:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:58 INFO - 'HOME': '/Users/cltbld', 12:07:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:58 INFO - 'LOGNAME': 'cltbld', 12:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:58 INFO - 'MOZ_NO_REMOTE': '1', 12:07:58 INFO - 'NO_EM_RESTART': '1', 12:07:58 INFO - 'PAGER': '/bin/cat', 12:07:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:58 INFO - 'PWD': '/builds/slave/test', 12:07:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:58 INFO - 'SHELL': '/bin/bash', 12:07:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ywolUaeThX/Listeners', 12:07:58 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 12:07:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:58 INFO - 'USER': 'cltbld', 12:07:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:58 INFO - 'XPC_FLAGS': '0x0', 12:07:58 INFO - 'XPC_SERVICE_NAME': '0', 12:07:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:59 INFO - Downloading/unpacking blobuploader==1.2.4 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:08:01 INFO - Downloading blobuploader-1.2.4.tar.gz 12:08:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:08:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:08:01 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 12:08:01 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 12:08:01 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 12:08:01 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 12:08:01 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 12:08:01 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 12:08:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:08:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:08: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 12:08: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 12:08: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 12:08: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 12:08: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 12:08: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 12:08:03 INFO - Downloading docopt-0.6.1.tar.gz 12:08:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:08:03 INFO - Installing collected packages: blobuploader, requests, docopt 12:08:03 INFO - Running setup.py install for blobuploader 12:08:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:08:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:08:03 INFO - Running setup.py install for requests 12:08:03 INFO - Running setup.py install for docopt 12:08:03 INFO - Successfully installed blobuploader requests docopt 12:08:03 INFO - Cleaning up... 12:08:04 INFO - Return code: 0 12:08:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08: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')]} 12:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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')]} 12:08:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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 0x10ea94b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea97768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbe9c6d8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e872d30>, '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 0x10e799db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e867be8>, '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.czBSmHmNVQ/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.ywolUaeThX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 12:08: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 12:08: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 12:08:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.czBSmHmNVQ/Render', 12:08:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:04 INFO - 'HOME': '/Users/cltbld', 12:08:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:04 INFO - 'LOGNAME': 'cltbld', 12:08:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:04 INFO - 'MOZ_NO_REMOTE': '1', 12:08:04 INFO - 'NO_EM_RESTART': '1', 12:08:04 INFO - 'PAGER': '/bin/cat', 12:08:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:04 INFO - 'PWD': '/builds/slave/test', 12:08:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:04 INFO - 'SHELL': '/bin/bash', 12:08:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ywolUaeThX/Listeners', 12:08:04 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 12:08:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:04 INFO - 'USER': 'cltbld', 12:08:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:04 INFO - 'XPC_FLAGS': '0x0', 12:08:04 INFO - 'XPC_SERVICE_NAME': '0', 12:08:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:04 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-slajEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:04 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-aPCn7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:04 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-KPBkug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:04 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-C2L3JC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:04 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ILSMDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:04 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Gkf8tx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:04 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ab_mNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-7GBNFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-k9lo7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-GekZ5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-RvXZvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Gdfu7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ikgTFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ipx12W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Pgkh8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-CFCSkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-2v7z2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:08:06 INFO - Running setup.py install for manifestparser 12:08:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:08:06 INFO - Running setup.py install for mozcrash 12:08:06 INFO - Running setup.py install for mozdebug 12:08:06 INFO - Running setup.py install for mozdevice 12:08:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:08:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:08:06 INFO - Running setup.py install for mozfile 12:08:06 INFO - Running setup.py install for mozhttpd 12:08:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:08:06 INFO - Running setup.py install for mozinfo 12:08:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:08:07 INFO - Running setup.py install for mozInstall 12:08:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:08:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:08:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:08:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:08:07 INFO - Running setup.py install for mozleak 12:08:07 INFO - Running setup.py install for mozlog 12:08:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:08:07 INFO - Running setup.py install for moznetwork 12:08:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:08:07 INFO - Running setup.py install for mozprocess 12:08:07 INFO - Running setup.py install for mozprofile 12:08:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:08:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:08:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:08:08 INFO - Running setup.py install for mozrunner 12:08:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:08:08 INFO - Running setup.py install for mozscreenshot 12:08:08 INFO - Running setup.py install for moztest 12:08:08 INFO - Running setup.py install for mozversion 12:08:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:08:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:08:08 INFO - Cleaning up... 12:08:08 INFO - Return code: 0 12:08:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:08 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')]} 12:08:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:08 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')]} 12:08:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:08 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 0x10ea94b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea97768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbe9c6d8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e872d30>, '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 0x10e799db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e867be8>, '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.czBSmHmNVQ/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.ywolUaeThX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 12:08:08 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 12:08:08 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 12:08:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.czBSmHmNVQ/Render', 12:08:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:08 INFO - 'HOME': '/Users/cltbld', 12:08:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:08 INFO - 'LOGNAME': 'cltbld', 12:08:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:08 INFO - 'MOZ_NO_REMOTE': '1', 12:08:08 INFO - 'NO_EM_RESTART': '1', 12:08:08 INFO - 'PAGER': '/bin/cat', 12:08:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:08 INFO - 'PWD': '/builds/slave/test', 12:08:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:08 INFO - 'SHELL': '/bin/bash', 12:08:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ywolUaeThX/Listeners', 12:08:08 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 12:08:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:08 INFO - 'USER': 'cltbld', 12:08:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:08 INFO - 'XPC_FLAGS': '0x0', 12:08:08 INFO - 'XPC_SERVICE_NAME': '0', 12:08:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:08 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-jQ4O8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08: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)) 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-1afVl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:09 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)) 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-8VF3BF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:09 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)) 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-NLxqxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:09 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)) 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-AMgHWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:09 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)) 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-mUDy_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:09 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)) 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-q05Z5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:09 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)) 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-rj3y09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:09 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)) 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-7gjCSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:09 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)) 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Fxx98I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:09 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)) 12:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-rD2ZaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:10 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)) 12:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Ep2GYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-PpCpgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:10 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)) 12:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-PrkZcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:10 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)) 12:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-locxIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:10 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)) 12:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-fUisn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:10 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)) 12:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-xKEFwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:10 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)) 12:08:10 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)) 12:08:10 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)) 12:08:10 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)) 12:08:10 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)) 12:08:10 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)) 12:08:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:08:10 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 12:08:10 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 12:08:10 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 12:08:10 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 12:08:10 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 12:08:10 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 12:08:13 INFO - Downloading blessings-1.6.tar.gz 12:08:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:08:13 INFO - Installing collected packages: blessings 12:08:13 INFO - Running setup.py install for blessings 12:08:13 INFO - Successfully installed blessings 12:08:13 INFO - Cleaning up... 12:08:13 INFO - Return code: 0 12:08:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:08:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:08:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:08:14 INFO - Reading from file tmpfile_stdout 12:08:14 INFO - Current package versions: 12:08:14 INFO - blessings == 1.6 12:08:14 INFO - blobuploader == 1.2.4 12:08:14 INFO - docopt == 0.6.1 12:08:14 INFO - functools32 == 3.2.3-2 12:08:14 INFO - jsonschema == 2.5.1 12:08:14 INFO - manifestparser == 1.1 12:08:14 INFO - mozInstall == 1.12 12:08:14 INFO - mozcrash == 1.0 12:08:14 INFO - mozdebug == 0.1 12:08:14 INFO - mozdevice == 0.49 12:08:14 INFO - mozfile == 1.2 12:08:14 INFO - mozhttpd == 0.7 12:08:14 INFO - mozinfo == 0.9 12:08:14 INFO - mozleak == 0.1 12:08:14 INFO - mozlog == 3.4 12:08:14 INFO - moznetwork == 0.27 12:08:14 INFO - mozprocess == 0.24 12:08:14 INFO - mozprofile == 0.28 12:08:14 INFO - mozrunner == 6.13 12:08:14 INFO - mozscreenshot == 0.1 12:08:14 INFO - mozsystemmonitor == 0.3 12:08:14 INFO - moztest == 0.8 12:08:14 INFO - mozversion == 1.4 12:08:14 INFO - psutil == 3.1.1 12:08:14 INFO - requests == 1.2.3 12:08:14 INFO - wsgiref == 0.1.2 12:08:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08: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')]} 12:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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')]} 12:08:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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 0x10ea94b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea97768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbe9c6d8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e872d30>, '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 0x10e799db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e867be8>, '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.czBSmHmNVQ/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.ywolUaeThX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 12:08: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 12:08: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 12:08:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.czBSmHmNVQ/Render', 12:08:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:14 INFO - 'HOME': '/Users/cltbld', 12:08:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:14 INFO - 'LOGNAME': 'cltbld', 12:08:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:14 INFO - 'MOZ_NO_REMOTE': '1', 12:08:14 INFO - 'NO_EM_RESTART': '1', 12:08:14 INFO - 'PAGER': '/bin/cat', 12:08:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:14 INFO - 'PWD': '/builds/slave/test', 12:08:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:14 INFO - 'SHELL': '/bin/bash', 12:08:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ywolUaeThX/Listeners', 12:08:14 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 12:08:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:14 INFO - 'USER': 'cltbld', 12:08:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:14 INFO - 'XPC_FLAGS': '0x0', 12:08:14 INFO - 'XPC_SERVICE_NAME': '0', 12:08:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08: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)) 12:08: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)) 12:08: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)) 12:08: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)) 12:08: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)) 12:08: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)) 12:08: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)) 12:08: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)) 12:08: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)) 12:08: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)) 12:08: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)) 12:08:15 INFO - Cleaning up... 12:08:15 INFO - Return code: 0 12:08:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:08: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')]} 12:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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')]} 12:08:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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 0x10ea94b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea97768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbe9c6d8f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e872d30>, '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 0x10e799db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e867be8>, '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.czBSmHmNVQ/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.ywolUaeThX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 12:08: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 12:08: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 12:08:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.czBSmHmNVQ/Render', 12:08:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:15 INFO - 'HOME': '/Users/cltbld', 12:08:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:15 INFO - 'LOGNAME': 'cltbld', 12:08:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:15 INFO - 'MOZ_NO_REMOTE': '1', 12:08:15 INFO - 'NO_EM_RESTART': '1', 12:08:15 INFO - 'PAGER': '/bin/cat', 12:08:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:15 INFO - 'PWD': '/builds/slave/test', 12:08:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:15 INFO - 'SHELL': '/bin/bash', 12:08:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ywolUaeThX/Listeners', 12:08:15 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 12:08:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:15 INFO - 'USER': 'cltbld', 12:08:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:15 INFO - 'XPC_FLAGS': '0x0', 12:08:15 INFO - 'XPC_SERVICE_NAME': '0', 12:08:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:08:15 INFO - Cleaning up... 12:08:15 INFO - Return code: 0 12:08:15 INFO - Running post-action listener: _resource_record_post_action 12:08:15 INFO - Running post-action listener: _start_resource_monitoring 12:08:15 INFO - Starting resource monitoring. 12:08:15 INFO - [mozharness: 2017-02-08 20:08:15.738811Z] Finished create-virtualenv step (success) 12:08:15 INFO - [mozharness: 2017-02-08 20:08:15.739722Z] Running install step. 12:08:15 INFO - Running pre-action listener: _resource_record_pre_action 12:08:15 INFO - Running main action method: install 12:08:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:08:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:08:15 INFO - Reading from file tmpfile_stdout 12:08:15 INFO - Detecting whether we're running mozinstall >=1.0... 12:08:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:08:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:08:16 INFO - Reading from file tmpfile_stdout 12:08:16 INFO - Output received: 12:08:16 INFO - Usage: mozinstall [options] installer 12:08:16 INFO - Options: 12:08:16 INFO - -h, --help show this help message and exit 12:08:16 INFO - -d DEST, --destination=DEST 12:08:16 INFO - Directory to install application into. [default: 12:08:16 INFO - "/builds/slave/test"] 12:08:16 INFO - --app=APP Application being installed. [default: firefox] 12:08:16 INFO - mkdir: /builds/slave/test/build/application 12:08:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:08:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 12:08:36 INFO - Reading from file tmpfile_stdout 12:08:36 INFO - Output received: 12:08:36 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:08:36 INFO - Running post-action listener: _resource_record_post_action 12:08:36 INFO - [mozharness: 2017-02-08 20:08:36.553075Z] Finished install step (success) 12:08:36 INFO - [mozharness: 2017-02-08 20:08:36.553228Z] Running run-tests step. 12:08:36 INFO - Running pre-action listener: _resource_record_pre_action 12:08:36 INFO - Running main action method: run_tests 12:08:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:08:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:08:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:08:36 INFO - 2017-02-08 12:08:36.567 screenresolution[1625:7344] starting screenresolution argv=screenresolution get 12:08:36 INFO - 2017-02-08 12:08:36.570 screenresolution[1625:7344] Display 0: 1600x1200x32@50 12:08:36 INFO - 2017-02-08 12:08:36.575 screenresolution[1626:7347] starting screenresolution argv=screenresolution list 12:08:36 INFO - Available Modes on Display 0 12:08:36 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 12:08:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:08:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:08:36 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 12:08:36 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 12:08:36 INFO - Graphics/Displays: 12:08:36 INFO - Intel Iris: 12:08:36 INFO - Chipset Model: Intel Iris 12:08:36 INFO - Type: GPU 12:08:36 INFO - Bus: Built-In 12:08:36 INFO - VRAM (Dynamic, Max): 1536 MB 12:08:36 INFO - Vendor: Intel (0x8086) 12:08:36 INFO - Device ID: 0x0a2e 12:08:36 INFO - Revision ID: 0x0009 12:08:36 INFO - Displays: 12:08:36 INFO - Display: 12:08:36 INFO - Resolution: 1600 x 1200 @ 50 Hz 12:08:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:08:36 INFO - Display Serial Number: 1600x1200 50 12:08:36 INFO - Main Display: Yes 12:08:36 INFO - Mirror: Off 12:08:36 INFO - Online: Yes 12:08:36 INFO - Rotation: Supported 12:08:36 INFO - Return code: 0 12:08:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:08:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:08:36 INFO - Python 2.7.3 12:08:36 INFO - Return code: 0 12:08:36 INFO - grabbing minidump binary from tooltool 12:08: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')]} 12:08: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 0x10e872d30>, '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 0x10e799db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e867be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:08: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 12:08: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 12:08: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 12:08:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:08:36 INFO - Return code: 0 12:08:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:08:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:08:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:08:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:08:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:08: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/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--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 12:08: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/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0011 --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 12:08:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.czBSmHmNVQ/Render', 12:08:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:36 INFO - 'HOME': '/Users/cltbld', 12:08:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:36 INFO - 'LOGNAME': 'cltbld', 12:08:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:08:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:08:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:36 INFO - 'MOZ_NO_REMOTE': '1', 12:08:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:08:36 INFO - 'NO_EM_RESTART': '1', 12:08:36 INFO - 'PAGER': '/bin/cat', 12:08:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:36 INFO - 'PWD': '/builds/slave/test', 12:08:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:08:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:36 INFO - 'SHELL': '/bin/bash', 12:08:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ywolUaeThX/Listeners', 12:08:36 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 12:08:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:36 INFO - 'USER': 'cltbld', 12:08:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:36 INFO - 'XPC_FLAGS': '0x0', 12:08:36 INFO - 'XPC_SERVICE_NAME': '0', 12:08:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08: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/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--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 12:08:37 INFO - mozversion application_buildid: 20170208105120 12:08:37 INFO - mozversion application_changeset: c51d7c9daa243554dcee9fd28d487ae76c512bf2 12:08:37 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:08:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:08:37 INFO - mozversion application_name: Firefox 12:08:37 INFO - mozversion application_remotingname: firefox-dev 12:08:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:08:37 INFO - mozversion application_vendor: Mozilla 12:08:37 INFO - mozversion application_version: 53.0a2 12:08:37 INFO - mozversion platform_buildid: 20170208105120 12:08:37 INFO - mozversion platform_changeset: c51d7c9daa243554dcee9fd28d487ae76c512bf2 12:08:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:08:37 INFO - mozversion platform_version: 53.0a2 12:08:37 INFO - using testdate: 1486584517 12:08:37 INFO - actual date: 1486584517 12:08:37 INFO - starting webserver on 'localhost:49248' 12:08:37 INFO - SUITE-START | Running 2 tests 12:08:37 INFO - TEST-START | dromaeo_css 12:08:37 INFO - operating with platform_type : mac_ 12:08:37 INFO - Initialising browser for dromaeo_css test... 12:08:37 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpcHdotR/profile http://localhost:49248/getInfo.html) 12:08:39 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 12:08:39 INFO - PROCESS | 1636 | colorDepth:24 12:08:39 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 12:08:39 INFO - PROCESS | 1636 | __metrics 12:08:39 INFO - PROCESS | 1636 | 1486584519707 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 12:08:39 INFO - PROCESS | 1636 | 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" 12:08:39 INFO - PROCESS | 1636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:08:40 INFO - TEST-INFO | 1636: exit 0 12:08:40 INFO - Browser initialized. 12:08:40 INFO - Running cycle 1/1 for dromaeo_css test... 12:08:40 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpcHdotR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:08:52 INFO - PROCESS | 1638 | RSS: Main: 213082112 12:08:52 INFO - PROCESS | 1638 | 12:11:56 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 12:11:58 INFO - PROCESS | 1638 | RSS: Main: 345079808 12:11:58 INFO - PROCESS | 1638 | 12:13:42 INFO - PROCESS | 1638 | ************************* 12:13:42 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 12:13:42 INFO - PROCESS | 1638 | 12:13:42 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:13:42 INFO - PROCESS | 1638 | Full stack: 12:13:42 INFO - PROCESS | 1638 | ************************* 12:15:01 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 12:15:02 INFO - PROCESS | 1638 | RSS: Main: 347000832 12:15:02 INFO - PROCESS | 1638 | 12:18:04 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 12:18:04 INFO - PROCESS | 1638 | RSS: Main: 348987392 12:18:04 INFO - PROCESS | 1638 | 12:21:07 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 12:21:08 INFO - PROCESS | 1638 | RSS: Main: 333484032 12:21:08 INFO - PROCESS | 1638 | 12:24:11 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 12:24:12 INFO - PROCESS | 1638 | RSS: Main: 308486144 12:24:12 INFO - PROCESS | 1638 | 12:27:15 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 12:27:15 INFO - PROCESS | 1638 | RSS: Main: 396201984 12:27:15 INFO - PROCESS | 1638 | 12:27:15 INFO - PROCESS | 1638 | __start_tp_report 12:27:15 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 12:27:15 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 12:27:15 INFO - PROCESS | 1638 | |i|pagename|runs| 12:27:15 INFO - PROCESS | 1638 | |0;dojo.html;3372.8313584320786;3388.3053973935343;3417.914552136198;3418.247985443203;3462.619111897691;5835.416458354162;5873.295204575452;5875.618084824494;5883.058710606309;5919.556033297498;5684.147377893318;5755.136729490579;5777.393373695764;5867.207926929863;5867.823965281048;5246.554042906348;5304.522592966632;5307.469253074681;5311.893762124755;5349.411564727877;4360.738355698652;5048.557269602763;5234.319538460014;5257.395399529046;5356.678599284036;5280.656757310762;5368.32895888014;5384.91922621159;5394.352677678684;5399.217113518537;5356;5358.946410535884;5359.839204823823;5375.865603359947;5389.838304850841;5240.213967904807;5332.92000619991;5346.652467589594;5351.2240725095035;5402.324709411324;5641.5204707599905;5702.885942281173;5709.429057094258;5762.279715035621;5912.6156799808305;6222.657753823538;6227.3772622737815;6293.716782744787;6295.716692748837;6296.244450665949;5749.655020698771;6273.215848018997;6282.748690052393;6288.119663247104;6297.905531417033;5963.46328830403;6034.909476357858;6057.061155520902;6057.112186782472;6083.969580152159;6202.596831205957;6221.160143380676;6228.377162283826;6262.342454042351;6278.968605157036;6296.6851657417865;6303.086052522451;6306.400891915261;6309.3690630936535;6317.084022816666;6148;6276.215473065867;6280.937190628127;6281.937180628135;6283.8114856554375;4972.925406118911;6153.384661533811;6209.2548894133;6214.9689251553455;6300.684965751694;6241.7191226394925;6251.249850018027;6264.874702505925;6274.560780745395;6283.08895210192;5045.798168073309;5051.696898186121;5053.140966035783;5061.620378471629;5096.770645320896;4389.034412429261;4405.537418571004;4461.57615026572;4482.260427029503;4523.36672865799;5875.706214689249;5949.286085669747;5962.07587823888;6031.698415079229;6035.39646035393;6183.072539119168;6212.689365531705;6274.780382686584;6275.780347687809;6280.120783090371;5754.108113242454;5805.94194058054;5848.181254624356;5922.082077278029;5936.347001829795;5475.260839786578;5531.834044978657;5547.583931205176;5549.833504994856;5558.555315574744;6184.381561843869;6243.719032643451;6282.874342513032;6331.493480521639;6351.396617321347;6250.593711408744;6281.3404592518955;6292.993121100602;6293.307736148929;6310;6445.2265728112925;6452;6454.967205247138;6463.450606698483;6476.417122459001;6119.563485741553;6191.628502289877;6214.875702485834;6269.467095296951;6284.371562843679;6166.938330616636;6263.3736626337;6263.655498947602;6277.058441233668;6283.02613094971;6133.969330153499;6253.374662533893;6259.061140828912;6259.937400625936;6266.686665666607;6096.420840020191;6125.601746185282;6256.186695729526;6270.0908368287255;6278.905816412849;5390.784368625211;6035.486983606443;6137.263528376623;6160.045192995093;6180.6291622502795;5637.746301416364;5929.881402371842;6254.843628909313;6284.402981716729;6301.149344838572;5458.754356053908;5868.413158684098;6128.4790792783115;6139.416755408229;6142.477889379274 12:27:15 INFO - PROCESS | 1638 | |1;ext.html;474.96200303975684;500.1572350589257;502.4874627879561;515.2425933877201;534.2760559441958;31233.063008109766;38976.415353769684;42769;42910.356344654814;45642;45002;45418.545814541845;45505;45539.31691024629;45642.77178614111;8532.57337133141;9337.579808908615;9527.523623818815;9558.330916835825;9560.665376711817;8522.318214542853;11433.885661143408;11633.127515436334;11651.883481165207;11902.702432439215;11527.481263343128;11575.305481671126;11575.479103440322;11594.710132246719;11621.709457263552;8514.957425212897;8672.349573781992;9955.900440995605;11687.824682629767;11691.12316576256;11217.775644487065;11418.257813242113;11440.485178166919;11670.474828632732;11702.824457633142;42639.36040959388;42839.14321713548;42973.57026429758;42993.57006429927;43004;3958.782266975315;4023.617756313175;4083.183363327317;4090.6931980101613;4130.648894843942;30176.8491157545;30219.395612087854;30279.545806812916;31956.881509147068;32827.6717232827;3176.7935084219685;3303.4549299365576;3335.516350129242;3377.4427219508752;3404.14896275931;481379.8965077633;486160.99999999645;486949;488972;490939;57171;67345;67537;67580.66209668921;67762.66118669375;59173.40826591679;69242.65378673075;70331.2966870335;70482.64758676174;70675.64662176656;16479.093649849132;16508.504744857673;16531.504054878373;17079.743803842954;17100.401485947932;11715;11958.40207989597;12028.398580070962;13072.084954053315;13199.604011879659;18585.81414185841;19042.714359284622;19084.80915190858;19409.126589303516;19433.125509352114;6965.199002114821;7025.262347453444;7026.297370262932;7031.542949708252;7072.434205263567;769.4575324396287;869.9608517616722;885.7121435533477;887.6582515731361;909.5861383070819;30101.849490752844;30295.848520757256;30711;30904.845475772476;31186.271575376995;948.2508818033814;1024.6618615856748;1031.6131450705984;1075.6719200643722;1158.4555259028243;15860.365585376687;15928.840711592968;15956.680866382607;16064.919675401548;16348.591285217726;632454;632520;635220;635534;636772;63553.682231588544;63864.042039369444;63932.680336598016;63974.36025639777;63984.36015639784;1416.405109853864;1443.1269082205324;1447.3776276201334;1450.9347079381241;1451.506487794155;29855.402891942475;30191.7816409816;30542.541861871647;30920.917767878018;31261;52824;53095.46904530905;53140;53297.46702532925;53334.4666553345;524847;526507;528794.3560282174;529468;530352;22903.656445153003;23502.412439689146;24492.265232043068;25106;42781;37556;42669.35995960125;44850.102997939204;45323.41368052102;47816.521834781204;36082.47422680387;36400.63599364078;36585.08537286589;37788.62211377961;38358.80820595879;36383;36741.44887826631;36750.63249367472;37220.62779372172;37793.86618401452;34954.825225873705;36245.09387265339;36530.08674783046;36544.08639784026;36722.44916326204;30923;30969.761209551365;31629.102253864843;32572.51141232931;33385.499217512246;24394.390140245927;24397.14609988642;24705.50588988174;24893.751062489144;24897.875510622333 12:27:15 INFO - PROCESS | 1638 | |2;jquery.html;390.1514206600644;397.29281878256677;400.6674460198036;407.8980254936267;426.99655808849207;14117.505887293946;20180.495487612818;20476;21507.24724634636;21535.815530145836;16405.91797041013;16743.916280418613;20526.589468210626;20662.380128596127;20813.687794683094;5548.334199896008;5854.531637469014;5866.6480011199255;5884.117382392654;5887.264091988502;5060.924086138711;5588.105903055512;5714.142878568227;5745.540356771469;5760.049591817345;5057.216131499624;5636.210930469738;5729.39841316662;5745;5758.625689330201;5515.972420137894;5683.744231509592;5706.77172913083;5718.857028574298;5753.510951569122;5014.5737612303;5085.262636917663;5093.18509038552;5097.6941383517105;5099.566536844372;24676.753232467625;25627.35931601725;25718;25922;26245.343866403302;2682.7317268273214;2739.657542807149;2741.9862900685566;2742.2595899107328;2746.6566679165103;15263;15337.539873803804;15375.769363459556;15610.531684049496;15962.680746385124;1798.379559052125;1840.098351807618;1847.113385574918;1851.703727403609;1856.3224423085476;126721;129814.3509282457;130277;133073.99999999904;133903;9735.805283894282;11468.36923969173;11577.189596728316;11580.594679186188;11750.764984700258;14450.783238251435;14488.565343039725;14519.274036298142;14523.346449409803;14536.709265814625;7282.672279747424;7583.5070720403;8144.959275203586;8314.41799074071;8332.916670833214;8551.914480855236;8608.827823443497;8642.827143457222;8662.826743465095;8790.516521591246;7040.612766297902;7160.140783106061;7162.961370601233;7168.462365322622;7178.318059784312;1939.3890924359064;1983.930562430308;1988.2146552112124;1991.731116299281;1999.9300024499073;976.345848281653;1045.325764881647;1047.251215380991;1072.7371793910543;1080.065042590952;21760.194184415595;22071.779282207295;22166.667499987514;22206.34167062273;22214.555708886055;1082.7509672775147;1087.9673609791719;1106.242224076494;1111.4942701070993;1126.4424110065406;11212.046976006968;11449.019656569071;11498.965093141658;11514.94242528782;11563.190576659721;737336.31331843;742795.2860235664;745894;746252;753688;141650;142887;143667.44988251847;143770;145218;1074.4681382715605;1084.38732116354;1087.488880226308;1097.2154909239932;1101.553870682375;18287.26850925948;19018.42944711661;19401.223951042364;19421.805781942;19509.70735438998;45735;46274.07451851011;51651.22523162231;55436;56412.7179364117;53892.46107538875;75940.24059759332;80787.7291043809;81211.37577248541;81938.77091843747;5482.479164479368;7393.334599886036;7753.10839253493;7779.883301750365;8060.314873235606;12966.351682415652;13024.674383140497;13040.41318140667;13572.04995650295;13582;10959.191733363905;11096.389698566656;11237.269577477438;11473;11711.414429278673;11027.889721103007;11227.607033753779;11251.549938002388;11512.481938312629;11626.244294120856;11358.54565817358;11409.25839820409;11465.770684586429;11496.597619083292;11625.59310424131;8044.718434854752;8223.09545949957;8271.131531189138;8304.086550479322;8453.830923381622;11099.667009989713;11138.053265472525;11191.216614836883;11367.261128026652;11577.536898523964 12:27:15 INFO - PROCESS | 1638 | |3;mootools.html;1341.6578772413034;1440.2294772296816;1441.9062760920533;1463.6267751723303;1465.040398538957;8000.039995200576;8473;8474.957625211866;8490.320774338054;8496.107908669586;7457.701691932316;7762.534247945113;7775.533467991924;8002.719904803333;8003.199680031994;2302.3323236261463;2339.7950055721317;2340.777626125524;2347.248880358286;2351;2305.873176975266;2338.871362075085;2340.578695834749;2345.7536958619357;2346.5541547105995;1932.6231384879954;2027.7769445360998;2104.4002459299104;2322.396176993977;2325.4070212095894;2291;2301.252093069759;2302.976970230293;2305.8847057647217;2317.837751357414;2252.3580779477934;2304.308707387777;2323.1520495019367;2332.6384410416244;2337.8714170720605;7856.528608283465;7927.603619819044;7937.48406353591;7945.48354356965;7957.801054973614;5031.094403007457;5181.093308670967;5193.480651934814;5194.013137503862;5208.6093542984045;3382.7970321780526;3484.9651503484893;3514.6661067198575;3547.9822600886832;3578.35589593873;1190.1133655426763;1224.088054399478;1227.061298106949;1276.4957841652588;1281.8654041325713;10707.196960227937;10842.403667798268;10853.728656783644;10854.077403420719;10858.348499090001;4442.133783912106;4632.305154226826;4703.247480403119;4709.882252943636;4716.080364328923;9580.329376943548;9588.616455341848;9589.232861371074;9593;9608.855867161998;5272.6309158358545;5278.81524146653;5287.68273903567;5291.682499050069;5297.470252974672;4236.343366778155;4359.215341238608;4668.976655116703;4702.600278976325;4757.215059515142;3880.165764360676;3903.921921561553;3967.690662081506;3991.082051128257;4004.479417675742;1275.4579303796036;1288.0404098946344;1292.1730092740654;1302.7589895869294;1304.452130105358;770.7726220764888;782.9021372328459;900.7477906186278;909.8726178335038;912;5395.595330350239;5461.644993075437;5486.478784515464;5515.090010148361;5690.231818704505;953.3040880157524;998.5834281039756;1027.4708525109575;1043.6190790361616;1046.6179844356755;3527.5943266524678;3554.680078792869;3576.946345804815;3696.611855755107;3758;10001.24990625706;10192;10192.745181370525;10193.184545236363;10195.796084078276;10026.69919902404;10036.146927511098;10072.94234105423;10097.495125243855;10154.898451015395;7476.364509016686;7823.374130069581;7833.255840695125;7949.085855126735;7958;5470.452954704498;5493.890122197614;5520.089185284543;5652.293463317085;5682.6306290091015;7863.252990965849;8873.645054198018;9083.227925626396;9242.537873106452;9411.10594493522;8720.563971801512;9211.861822072808;9394.013628569175;9446.197073248575;9474.194693451134;6547.672616369257;6740.460763138839;6742.4943129266485;7201.747938822116;7202.783916482514;7730.381569474316;8013.318867896294;8034.196580341919;8132.593370331341;8134.471259368122;7069.752558660422;7133.750318738819;7190.71237150529;7197.280271972971;7338.77983660491;7039.225685174524;7048.189458212166;7087.964560177373;7091.751788687371;7198;6356.618602883842;6361.809145725636;6362.840928976812;6413.2945376007665;6426.710798014195;4872.4883887192755;4918.926216106833;4942.92585611209;4954.925676114789;4996.775145118551;4886.3403440536495;4898.951010489992;4913.582345500529;4976.402831660224;5096.362954630671 12:27:15 INFO - PROCESS | 1638 | |4;prototype.html;322.43895621618384;324.9398861210676;345.1560933517549;347.02832070203425;361.5570925616121;4991.876827713762;5193.792248310073;5211.576616873153;5386.48828361305;5400.594955378343;4280.3151495760685;4688.906221875561;4967.428745694247;5177.922331165036;5349.251104845315;3896.1752729271334;4064.4716186895735;4069.4506241657296;4077.7145599807877;4107.856225032125;3746.340098530047;3853.8843834684862;3858.2283543291314;4065.369867670501;4065.87802365928;3987.3022221111332;4037.3540233562635;4047.372657238133;4068.3897415387783;4099.8770036898795;3599.1182160370613;3909.0227443139215;4035.334169861984;4052.4934383201953;4078.3882417637296;2858.957115643267;3127.0918046217007;3382.678645528671;3417.419038763416;3541.557305336833;4415.470143582758;4758.690685105457;5197.324347834758;5200.6619569727845;5203.16749320107;4442.8667139985855;4598.333241679971;4619.74591397473;4678.590525879175;4679.859604211845;5031.320771695848;5368.282149712094;5373.973130134364;5397.7571009304675;5609.915851262234;3307.6692330766973;3393.694567488913;3813.3517302058717;3848.826802793881;3850.3454412749898;5866.413358664099;5898.734556944948;5943.771085842809;5956.97021514894;5968.701564921824;3748.21577329483;3856.7107466940092;3864.4589757433982;3900.7464514806447;3908.785016824102;3570.8571657133944;3949.2693851636964;3952.822123004472;3972.324704800191;3993;4374.737515749001;4615.515370886076;4631.652626053059;4638.095571363552;4682.929756053593;4956.107900577858;5190.92213616796;5740.081586946152;5751.281089863767;5758.62568933018;1146.3694967767694;1262.5770366927181;1288.9108703145828;1299.9740005199842;1302.8827405533548;800.9078955920028;813.5444151275304;814.4543156085437;825.636719843279;874.191372979991;4764.92598828004;4954.497978520824;5023.2214006829;5075.289459475676;5082.5514728303;5132.948670513321;5169.276301317818;5173.741312934339;5454.699991500505;5824.922389357982;5311.468853114658;5331.200319952039;5337.01265265927;5357.5446087082255;5376.91934620981;2455.4107014316437;2772.4593704227887;2867.7132286771575;2874.9937521867178;2876.4103358811394;4916.5820905222745;5102.550775627719;5280.683159010472;5689.800856970069;5860.062390017577;4890.413150421972;5692.206954809222;5711;5716.885662286814;5725.427457254241;5138.614603904722;5478.808241711521;5712.485876271156;6061.787837425669;6078.270607527125;4599.4710608280475;5183.429822719422;5510.9448905510435;5577.776888924398;5790.363060063305;5373.623846330719;5777.595568310178;5785.6817875015595;5816.47651711348;5848.766049357978;5558.944410555842;5696.487316141402;5949.405059494016;5965.27170518426;5998.9100163496705;3886.1644746379666;3933.645971862546;3952.7628342299554;3960.9207815844097;3982.78094704794;4757.119932812441;5622;5852.7658893643775;5877.4122587742595;5909.91135132982;5599.748011339418;5601.943980560142;5646.943530564642;5657.257883248588;5661.348944871393;5626.508975121515;5653.3498647654405;5660.51885589713;5692.772289108555;5720.8569785754025;5051.21159130652;5066.771995260296;5129.28169063353;5156.896862062663;5518.172274158908;5003.049420610072;5619.775208991595;5632.436756324335;5642.266505354278;5678.971605141948;5191.428942816363;5208.151106357189;5284.943011397812;5424.891502170013;5480.561555075505 12:27:15 INFO - PROCESS | 1638 | |5;yui.html;709.7941596936882;714.6605362452832;754.5020286610838;814.2179575870083;822.21889205255;14338;14981.625459363495;15015.249237538106;15028;15178.924105379472;14154.858451415483;14309;14322.498712545063;14336.498222562212;14340.42638294467;668.2315337362024;688.0745397440435;691.8408765983827;818.1981657975188;830.8712149616789;852.8763329317245;857.3141486810526;864.3344624639025;870.8040690844541;871.485823364215;900.6487469886749;901.4005686218657;902.0798785239052;902.264654306738;903.5121034641286;896.1397058823537;897.058089006547;898.2230370729337;898.2364989758686;900.333753022765;518.2692403710787;535.807109440601;661.6856992928368;676.5365724478747;684.3601232847254;14471.348789304506;14502.782458263135;14513.709725805425;14517.274136293248;14534.854651453456;13599.728005439836;13615.59153225395;13632.93183534086;13660.975426843026;13688.65778355539;12036.338001409918;12090.637280881587;12197.45111469977;12635.557755478607;13161.670958226025;4176.18564379946;4416.823327066913;4435.445569303837;4483;4575.176468235716;102403.48798255961;113038.43480782544;113752.86247137506;114050.42974785157;114212;10202.818770018273;10587.258891877495;10799.892001080047;11135.610253641245;11260.211785175123;21227.257046003317;21524.354269371943;21549.24577639775;21954.670679939813;21963.352748543923;7145.785626431215;7185.712571497082;7222.277772222841;7254.689503663123;7301.817454563678;4072.9185416291507;4208.558101399371;4258.7657678827345;4261.296886013773;4340.045190058182;3438.9656103439147;3504.0714210734177;3554.9466757998653;3562.7327950403824;3563;655.7147640776277;661.6261812076258;666.4834753066316;671.3823282580038;675.2403546010738;630.4483576870239;641.2241188162283;644.0889258258816;652.3411354531956;652.3900153356627;8673.306135509145;9998.400095994264;9998.450085245382;10025.398476091457;10106.747331316628;655.8196495963648;658.8963486160661;669.6618207805027;672.862063277027;682.2801943949082;3576.874809381711;4895.73073480962;5279.551238144801;5331.20031995196;5513.724313784295;82474;86166.56916715502;93207.53396232975;96950.54574181393;101999.490002551;72912;89942;90390.19219615702;91653.62519562212;95684;82165.58917205496;84068.73896891551;84440.57779711185;84878.57560712157;85936.57031714801;10580.629677961235;10891.891081089405;11021.504032318404;11092.722681933015;11206.49570769301;49732;50228.74885625695;50272;50823.237651434516;51080.48919510757;70404.64797675979;70859;71461;71473.28526714875;74240.14399640053;12732.363381830686;12823.487060517475;12917.483300667867;13051;13235.933820330836;13393;13711.520096796565;14014.929925350309;14676.266186690837;14824.7776283358;11551.480183391599;13098.672533186747;13797.241151736349;14026;14294.71410571762;13192.4594563585;13240.80138797938;13599.59201223965;13703.794443083163;14162.955902720667;12954.676133096747;13195.934020329836;13545.932270338586;13547.160584997924;13744.518941837388;10773.24587278883;10892.673219803419;11137.16002419378;11180.998547729;11893.464794084113;10229.641962531276;10842;10866.239363244496;10935.890641093487;10982.560697572328 12:27:15 INFO - PROCESS | 1638 | __end_tp_report 12:27:15 INFO - PROCESS | 1638 | __start_cc_report 12:27:15 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,1409 12:27:15 INFO - PROCESS | 1638 | __end_cc_report 12:27:15 INFO - PROCESS | 1638 | __startTimestamp1486585635578__endTimestamp 12:27:15 INFO - PROCESS | 1638 | 12:27:15 INFO - PROCESS | 1638 | ------- Summary: start ------- 12:27:15 INFO - PROCESS | 1638 | Number of tests: 6 12:27:15 INFO - PROCESS | 1638 | 12:27:15 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5812.81 Median:6083.97 stddev:642.97 (10.6%) stddev-sans-first:615.79 12:27:15 INFO - PROCESS | 1638 | Values: 3372.8 3388.3 3417.9 3418.2 3462.6 5835.4 5873.3 5875.6 5883.1 5919.6 5684.1 5755.1 5777.4 5867.2 5867.8 5246.6 5304.5 5307.5 5311.9 5349.4 4360.7 5048.6 5234.3 5257.4 5356.7 5280.7 5368.3 5384.9 5394.4 5399.2 5356.0 5358.9 5359.8 5375.9 5389.8 5240.2 5332.9 5346.7 5351.2 5402.3 5641.5 5702.9 5709.4 5762.3 5912.6 6222.7 6227.4 6293.7 6295.7 6296.2 5749.7 6273.2 6282.7 6288.1 6297.9 5963.5 6034.9 6057.1 6057.1 6084.0 6202.6 6221.2 6228.4 6262.3 6279.0 6296.7 6303.1 6306.4 6309.4 6317.1 6148.0 6276.2 6280.9 6281.9 6283.8 4972.9 6153.4 6209.3 6215.0 6300.7 6241.7 6251.2 6264.9 6274.6 6283.1 5045.8 5051.7 5053.1 5061.6 5096.8 4389.0 4405.5 4461.6 4482.3 4523.4 5875.7 5949.3 5962.1 6031.7 6035.4 6183.1 6212.7 6274.8 6275.8 6280.1 5754.1 5805.9 5848.2 5922.1 5936.3 5475.3 5531.8 5547.6 5549.8 5558.6 6184.4 6243.7 6282.9 6331.5 6351.4 6250.6 6281.3 6293.0 6293.3 6310.0 6445.2 6452.0 6455.0 6463.5 6476.4 6119.6 6191.6 6214.9 6269.5 6284.4 6166.9 6263.4 6263.7 6277.1 6283.0 6134.0 6253.4 6259.1 6259.9 6266.7 6096.4 6125.6 6256.2 6270.1 6278.9 5390.8 6035.5 6137.3 6160.0 6180.6 5637.7 5929.9 6254.8 6284.4 6301.1 5458.8 5868.4 6128.5 6139.4 6142.5 12:27:15 INFO - PROCESS | 1638 | 12:27:15 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:69304.33 Median:36985.63 stddev:147597.77 (399.1%) stddev-sans-first:147921.35 12:27:15 INFO - PROCESS | 1638 | Values: 475.0 500.2 502.5 515.2 534.3 31233.1 38976.4 42769.0 42910.4 45642.0 45002.0 45418.5 45505.0 45539.3 45642.8 8532.6 9337.6 9527.5 9558.3 9560.7 8522.3 11433.9 11633.1 11651.9 11902.7 11527.5 11575.3 11575.5 11594.7 11621.7 8515.0 8672.3 9955.9 11687.8 11691.1 11217.8 11418.3 11440.5 11670.5 11702.8 42639.4 42839.1 42973.6 42993.6 43004.0 3958.8 4023.6 4083.2 4090.7 4130.6 30176.8 30219.4 30279.5 31956.9 32827.7 3176.8 3303.5 3335.5 3377.4 3404.1 481379.9 486161.0 486949.0 488972.0 490939.0 57171.0 67345.0 67537.0 67580.7 67762.7 59173.4 69242.7 70331.3 70482.6 70675.6 16479.1 16508.5 16531.5 17079.7 17100.4 11715.0 11958.4 12028.4 13072.1 13199.6 18585.8 19042.7 19084.8 19409.1 19433.1 6965.2 7025.3 7026.3 7031.5 7072.4 769.5 870.0 885.7 887.7 909.6 30101.8 30295.8 30711.0 30904.8 31186.3 948.3 1024.7 1031.6 1075.7 1158.5 15860.4 15928.8 15956.7 16064.9 16348.6 632454.0 632520.0 635220.0 635534.0 636772.0 63553.7 63864.0 63932.7 63974.4 63984.4 1416.4 1443.1 1447.4 1450.9 1451.5 29855.4 30191.8 30542.5 30920.9 31261.0 52824.0 53095.5 53140.0 53297.5 53334.5 524847.0 526507.0 528794.4 529468.0 530352.0 22903.7 23502.4 24492.3 25106.0 42781.0 37556.0 42669.4 44850.1 45323.4 47816.5 36082.5 36400.6 36585.1 37788.6 38358.8 36383.0 36741.4 36750.6 37220.6 37793.9 34954.8 36245.1 36530.1 36544.1 36722.4 30923.0 30969.8 31629.1 32572.5 33385.5 24394.4 24397.1 24705.5 24893.8 24897.9 12:27:15 INFO - PROCESS | 1638 | 12:27:15 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:39976.96 Median:21160.47 stddev:123719.22 (584.7%) stddev-sans-first:124030.57 12:27:15 INFO - PROCESS | 1638 | Values: 390.2 397.3 400.7 407.9 427.0 14117.5 20180.5 20476.0 21507.2 21535.8 16405.9 16743.9 20526.6 20662.4 20813.7 5548.3 5854.5 5866.6 5884.1 5887.3 5060.9 5588.1 5714.1 5745.5 5760.0 5057.2 5636.2 5729.4 5745.0 5758.6 5516.0 5683.7 5706.8 5718.9 5753.5 5014.6 5085.3 5093.2 5097.7 5099.6 24676.8 25627.4 25718.0 25922.0 26245.3 2682.7 2739.7 2742.0 2742.3 2746.7 15263.0 15337.5 15375.8 15610.5 15962.7 1798.4 1840.1 1847.1 1851.7 1856.3 126721.0 129814.4 130277.0 133074.0 133903.0 9735.8 11468.4 11577.2 11580.6 11750.8 14450.8 14488.6 14519.3 14523.3 14536.7 7282.7 7583.5 8145.0 8314.4 8332.9 8551.9 8608.8 8642.8 8662.8 8790.5 7040.6 7160.1 7163.0 7168.5 7178.3 1939.4 1983.9 1988.2 1991.7 1999.9 976.3 1045.3 1047.3 1072.7 1080.1 21760.2 22071.8 22166.7 22206.3 22214.6 1082.8 1088.0 1106.2 1111.5 1126.4 11212.0 11449.0 11499.0 11514.9 11563.2 737336.3 742795.3 745894.0 746252.0 753688.0 141650.0 142887.0 143667.4 143770.0 145218.0 1074.5 1084.4 1087.5 1097.2 1101.6 18287.3 19018.4 19401.2 19421.8 19509.7 45735.0 46274.1 51651.2 55436.0 56412.7 53892.5 75940.2 80787.7 81211.4 81938.8 5482.5 7393.3 7753.1 7779.9 8060.3 12966.4 13024.7 13040.4 13572.0 13582.0 10959.2 11096.4 11237.3 11473.0 11711.4 11027.9 11227.6 11251.5 11512.5 11626.2 11358.5 11409.3 11465.8 11496.6 11625.6 8044.7 8223.1 8271.1 8304.1 8453.8 11099.7 11138.1 11191.2 11367.3 11577.5 12:27:15 INFO - PROCESS | 1638 | 12:27:15 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5422.33 Median:4986.59 stddev:2948.19 (59.1%) stddev-sans-first:2940.51 12:27:15 INFO - PROCESS | 1638 | Values: 1341.7 1440.2 1441.9 1463.6 1465.0 8000.0 8473.0 8475.0 8490.3 8496.1 7457.7 7762.5 7775.5 8002.7 8003.2 2302.3 2339.8 2340.8 2347.2 2351.0 2305.9 2338.9 2340.6 2345.8 2346.6 1932.6 2027.8 2104.4 2322.4 2325.4 2291.0 2301.3 2303.0 2305.9 2317.8 2252.4 2304.3 2323.2 2332.6 2337.9 7856.5 7927.6 7937.5 7945.5 7957.8 5031.1 5181.1 5193.5 5194.0 5208.6 3382.8 3485.0 3514.7 3548.0 3578.4 1190.1 1224.1 1227.1 1276.5 1281.9 10707.2 10842.4 10853.7 10854.1 10858.3 4442.1 4632.3 4703.2 4709.9 4716.1 9580.3 9588.6 9589.2 9593.0 9608.9 5272.6 5278.8 5287.7 5291.7 5297.5 4236.3 4359.2 4669.0 4702.6 4757.2 3880.2 3903.9 3967.7 3991.1 4004.5 1275.5 1288.0 1292.2 1302.8 1304.5 770.8 782.9 900.7 909.9 912.0 5395.6 5461.6 5486.5 5515.1 5690.2 953.3 998.6 1027.5 1043.6 1046.6 3527.6 3554.7 3576.9 3696.6 3758.0 10001.2 10192.0 10192.7 10193.2 10195.8 10026.7 10036.1 10072.9 10097.5 10154.9 7476.4 7823.4 7833.3 7949.1 7958.0 5470.5 5493.9 5520.1 5652.3 5682.6 7863.3 8873.6 9083.2 9242.5 9411.1 8720.6 9211.9 9394.0 9446.2 9474.2 6547.7 6740.5 6742.5 7201.7 7202.8 7730.4 8013.3 8034.2 8132.6 8134.5 7069.8 7133.8 7190.7 7197.3 7338.8 7039.2 7048.2 7088.0 7091.8 7198.0 6356.6 6361.8 6362.8 6413.3 6426.7 4872.5 4918.9 4942.9 4954.9 4996.8 4886.3 4899.0 4913.6 4976.4 5096.4 12:27:15 INFO - PROCESS | 1638 | 12:27:15 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4524.22 Median:5115.92 stddev:1397.84 (27.3%) stddev-sans-first:1365.72 12:27:15 INFO - PROCESS | 1638 | Values: 322.4 324.9 345.2 347.0 361.6 4991.9 5193.8 5211.6 5386.5 5400.6 4280.3 4688.9 4967.4 5177.9 5349.3 3896.2 4064.5 4069.5 4077.7 4107.9 3746.3 3853.9 3858.2 4065.4 4065.9 3987.3 4037.4 4047.4 4068.4 4099.9 3599.1 3909.0 4035.3 4052.5 4078.4 2859.0 3127.1 3382.7 3417.4 3541.6 4415.5 4758.7 5197.3 5200.7 5203.2 4442.9 4598.3 4619.7 4678.6 4679.9 5031.3 5368.3 5374.0 5397.8 5609.9 3307.7 3393.7 3813.4 3848.8 3850.3 5866.4 5898.7 5943.8 5957.0 5968.7 3748.2 3856.7 3864.5 3900.7 3908.8 3570.9 3949.3 3952.8 3972.3 3993.0 4374.7 4615.5 4631.7 4638.1 4682.9 4956.1 5190.9 5740.1 5751.3 5758.6 1146.4 1262.6 1288.9 1300.0 1302.9 800.9 813.5 814.5 825.6 874.2 4764.9 4954.5 5023.2 5075.3 5082.6 5132.9 5169.3 5173.7 5454.7 5824.9 5311.5 5331.2 5337.0 5357.5 5376.9 2455.4 2772.5 2867.7 2875.0 2876.4 4916.6 5102.6 5280.7 5689.8 5860.1 4890.4 5692.2 5711.0 5716.9 5725.4 5138.6 5478.8 5712.5 6061.8 6078.3 4599.5 5183.4 5510.9 5577.8 5790.4 5373.6 5777.6 5785.7 5816.5 5848.8 5558.9 5696.5 5949.4 5965.3 5998.9 3886.2 3933.6 3952.8 3960.9 3982.8 4757.1 5622.0 5852.8 5877.4 5909.9 5599.7 5601.9 5646.9 5657.3 5661.3 5626.5 5653.3 5660.5 5692.8 5720.9 5051.2 5066.8 5129.3 5156.9 5518.2 5003.0 5619.8 5632.4 5642.3 5679.0 5191.4 5208.2 5284.9 5424.9 5480.6 12:27:15 INFO - PROCESS | 1638 | 12:27:15 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20598.29 Median:4192.37 stddev:29616.27 (706.4%) stddev-sans-first:29661.70 12:27:15 INFO - PROCESS | 1638 | Values: 709.8 714.7 754.5 814.2 822.2 14338.0 14981.6 15015.2 15028.0 15178.9 14154.9 14309.0 14322.5 14336.5 14340.4 668.2 688.1 691.8 818.2 830.9 852.9 857.3 864.3 870.8 871.5 900.6 901.4 902.1 902.3 903.5 896.1 897.1 898.2 898.2 900.3 518.3 535.8 661.7 676.5 684.4 14471.3 14502.8 14513.7 14517.3 14534.9 13599.7 13615.6 13632.9 13661.0 13688.7 12036.3 12090.6 12197.5 12635.6 13161.7 4176.2 4416.8 4435.4 4483.0 4575.2 102403.5 113038.4 113752.9 114050.4 114212.0 10202.8 10587.3 10799.9 11135.6 11260.2 21227.3 21524.4 21549.2 21954.7 21963.4 7145.8 7185.7 7222.3 7254.7 7301.8 4072.9 4208.6 4258.8 4261.3 4340.0 3439.0 3504.1 3554.9 3562.7 3563.0 655.7 661.6 666.5 671.4 675.2 630.4 641.2 644.1 652.3 652.4 8673.3 9998.4 9998.5 10025.4 10106.7 655.8 658.9 669.7 672.9 682.3 3576.9 4895.7 5279.6 5331.2 5513.7 82474.0 86166.6 93207.5 96950.5 101999.5 72912.0 89942.0 90390.2 91653.6 95684.0 82165.6 84068.7 84440.6 84878.6 85936.6 10580.6 10891.9 11021.5 11092.7 11206.5 49732.0 50228.7 50272.0 50823.2 51080.5 70404.6 70859.0 71461.0 71473.3 74240.1 12732.4 12823.5 12917.5 13051.0 13235.9 13393.0 13711.5 14014.9 14676.3 14824.8 11551.5 13098.7 13797.2 14026.0 14294.7 13192.5 13240.8 13599.6 13703.8 14163.0 12954.7 13195.9 13545.9 13547.2 13744.5 10773.2 10892.7 11137.2 11181.0 11893.5 10229.6 10842.0 10866.2 10935.9 10982.6 12:27:15 INFO - PROCESS | 1638 | -------- Summary: end -------- 12:27:15 INFO - PROCESS | 1638 | 12:27:16 INFO - TEST-INFO | 1638: exit 0 12:27:16 INFO - TEST-OK | dromaeo_css | took 1119039ms 12:27:16 INFO - TEST-START | kraken 12:27:16 INFO - operating with platform_type : mac_ 12:27:16 INFO - Initialising browser for kraken test... 12:27:16 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmplG4zCw/profile http://localhost:49248/getInfo.html) 12:27:18 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 12:27:18 INFO - PROCESS | 1647 | colorDepth:24 12:27:18 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 12:27:18 INFO - PROCESS | 1647 | __metrics 12:27:18 INFO - PROCESS | 1647 | 1486585638413 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 12:27:18 INFO - PROCESS | 1647 | 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" 12:27:18 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:27:19 INFO - TEST-INFO | 1647: exit 0 12:27:19 INFO - Browser initialized. 12:27:19 INFO - Running cycle 1/1 for kraken test... 12:27:19 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmplG4zCw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:27:31 INFO - PROCESS | 1648 | RSS: Main: 213442560 12:27:31 INFO - PROCESS | 1648 | 12:27:34 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 12:27:35 INFO - PROCESS | 1648 | RSS: Main: 266235904 12:27:35 INFO - PROCESS | 1648 | 12:27:37 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 12:27:38 INFO - PROCESS | 1648 | RSS: Main: 275542016 12:27:38 INFO - PROCESS | 1648 | 12:27:41 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 12:27:42 INFO - PROCESS | 1648 | RSS: Main: 372330496 12:27:42 INFO - PROCESS | 1648 | 12:27:44 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 12:27:44 INFO - PROCESS | 1648 | RSS: Main: 275591168 12:27:44 INFO - PROCESS | 1648 | 12:27:46 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:27:47 INFO - PROCESS | 1648 | RSS: Main: 277770240 12:27:47 INFO - PROCESS | 1648 | 12:27:50 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 12:27:50 INFO - PROCESS | 1648 | RSS: Main: 318377984 12:27:50 INFO - PROCESS | 1648 | 12:27:53 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 12:27:54 INFO - PROCESS | 1648 | RSS: Main: 318824448 12:27:54 INFO - PROCESS | 1648 | 12:27:57 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 12:27:58 INFO - PROCESS | 1648 | RSS: Main: 314241024 12:27:58 INFO - PROCESS | 1648 | 12:27:59 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:28:00 INFO - PROCESS | 1648 | RSS: Main: 295665664 12:28:00 INFO - PROCESS | 1648 | 12:28:02 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:28:02 INFO - PROCESS | 1648 | RSS: Main: 297857024 12:28:02 INFO - PROCESS | 1648 | 12:28:04 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:28:05 INFO - PROCESS | 1648 | RSS: Main: 299900928 12:28:05 INFO - PROCESS | 1648 | 12:28:08 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:28:09 INFO - PROCESS | 1648 | RSS: Main: 316403712 12:28:09 INFO - PROCESS | 1648 | 12:28:12 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:28:12 INFO - PROCESS | 1648 | RSS: Main: 310071296 12:28:12 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 12:28:14 INFO - PROCESS | 1648 | RSS: Main: 354516992 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | __start_tp_report 12:28:14 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 12:28:14 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 12:28:14 INFO - PROCESS | 1648 | |i|pagename|runs| 12:28:14 INFO - PROCESS | 1648 | |0;ai-astar;115;114;114;115;114;114;113;115;111;115 12:28:14 INFO - PROCESS | 1648 | |1;audio-beat-detection;138;109;108;140;109;111;132;131;152;108 12:28:14 INFO - PROCESS | 1648 | |2;audio-dft;185;187;183;182;185;184;183;186;187;184 12:28:14 INFO - PROCESS | 1648 | |3;audio-fft;84;73;79;110;74;59;59;58;83;78 12:28:14 INFO - PROCESS | 1648 | |4;audio-oscillator;88;70;86;70;69;69;82;68;68;82 12:28:14 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;90;86;95;87;87;86;93;88;88;85 12:28:14 INFO - PROCESS | 1648 | |6;imaging-darkroom;100;97;100;99;98;99;99;99;98;99 12:28:14 INFO - PROCESS | 1648 | |7;imaging-desaturate;132;130;84;88;84;89;86;94;139;131 12:28:14 INFO - PROCESS | 1648 | |8;json-parse-financial;54;55;55;56;57;57;57;57;57;57 12:28:14 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;65;78;77;75;72;70;73;68;71;70 12:28:14 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;85;83;87;84;83;86;80;84;81;83 12:28:14 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;138;138;136;144;121;156;135;151;185;166 12:28:14 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;164;166;166;165;165;166;166;166;165;166 12:28:14 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;66;66;66;63;69;81;80;81;64;65 12:28:14 INFO - PROCESS | 1648 | __end_tp_report 12:28:14 INFO - PROCESS | 1648 | __start_cc_report 12:28:14 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,2032 12:28:14 INFO - PROCESS | 1648 | __end_cc_report 12:28:14 INFO - PROCESS | 1648 | __startTimestamp1486585694690__endTimestamp 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | ------- Summary: start ------- 12:28:14 INFO - PROCESS | 1648 | Number of tests: 14 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.50 stddev:1.25 (1.1%) stddev-sans-first:1.27 12:28:14 INFO - PROCESS | 1648 | Values: 115.0 114.0 114.0 115.0 114.0 114.0 113.0 115.0 111.0 115.0 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:123.80 Median:131.50 stddev:16.60 (12.6%) stddev-sans-first:16.79 12:28:14 INFO - PROCESS | 1648 | Values: 138.0 109.0 108.0 140.0 109.0 111.0 132.0 131.0 152.0 108.0 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.00 stddev:1.71 (0.9%) stddev-sans-first:1.81 12:28:14 INFO - PROCESS | 1648 | Values: 185.0 187.0 183.0 182.0 185.0 184.0 183.0 186.0 187.0 184.0 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:75.70 Median:76.00 stddev:15.62 (20.6%) stddev-sans-first:16.28 12:28:14 INFO - PROCESS | 1648 | Values: 84.0 73.0 79.0 110.0 74.0 59.0 59.0 58.0 83.0 78.0 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:76.00 stddev:8.22 (10.8%) stddev-sans-first:7.29 12:28:14 INFO - PROCESS | 1648 | Values: 88.0 70.0 86.0 70.0 69.0 69.0 82.0 68.0 68.0 82.0 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:88.00 stddev:3.24 (3.7%) stddev-sans-first:3.39 12:28:14 INFO - PROCESS | 1648 | Values: 90.0 86.0 95.0 87.0 87.0 86.0 93.0 88.0 88.0 85.0 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:98.80 Median:99.00 stddev:0.92 (0.9%) stddev-sans-first:0.87 12:28:14 INFO - PROCESS | 1648 | Values: 100.0 97.0 100.0 99.0 98.0 99.0 99.0 99.0 98.0 99.0 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:105.70 Median:85.00 stddev:23.78 (28.0%) stddev-sans-first:23.24 12:28:14 INFO - PROCESS | 1648 | Values: 132.0 130.0 84.0 88.0 84.0 89.0 86.0 94.0 139.0 131.0 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:57.00 stddev:1.14 (2.0%) stddev-sans-first:0.88 12:28:14 INFO - PROCESS | 1648 | Values: 54.0 55.0 55.0 56.0 57.0 57.0 57.0 57.0 57.0 57.0 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:72.50 stddev:4.01 (5.5%) stddev-sans-first:3.39 12:28:14 INFO - PROCESS | 1648 | Values: 65.0 78.0 77.0 75.0 72.0 70.0 73.0 68.0 71.0 70.0 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:2.12 (2.5%) stddev-sans-first:2.19 12:28:14 INFO - PROCESS | 1648 | Values: 85.0 83.0 87.0 84.0 83.0 86.0 80.0 84.0 81.0 83.0 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:147.50 stddev:18.30 (12.4%) stddev-sans-first:19.12 12:28:14 INFO - PROCESS | 1648 | Values: 138.0 138.0 136.0 144.0 121.0 156.0 135.0 151.0 185.0 166.0 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.50 Median:166.00 stddev:0.71 (0.4%) stddev-sans-first:0.50 12:28:14 INFO - PROCESS | 1648 | Values: 164.0 166.0 166.0 165.0 165.0 166.0 166.0 166.0 165.0 166.0 12:28:14 INFO - PROCESS | 1648 | 12:28:14 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:67.50 stddev:7.46 (11.1%) stddev-sans-first:7.76 12:28:14 INFO - PROCESS | 1648 | Values: 66.0 66.0 66.0 63.0 69.0 81.0 80.0 81.0 64.0 65.0 12:28:14 INFO - PROCESS | 1648 | -------- Summary: end -------- 12:28:14 INFO - PROCESS | 1648 | 12:28:15 INFO - TEST-INFO | 1648: exit 0 12:28:15 INFO - TEST-OK | kraken | took 59391ms 12:28:15 INFO - SUITE-END | took 1178s 12:28:15 INFO - Completed test suite (00:19:38) 12:28:15 INFO - javascript benchmark 12:28:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3372.8313584320786, 3388.3053973935343, 3417.914552136198, 3418.247985443203, 3462.619111897691, 5835.416458354162, 5873.295204575452, 5875.618084824494, 5883.058710606309, 5919.556033297498, 5684.147377893318, 5755.136729490579, 5777.393373695764, 5867.207926929863, 5867.823965281048, 5246.554042906348, 5304.522592966632, 5307.469253074681, 5311.893762124755, 5349.411564727877, 4360.738355698652, 5048.557269602763, 5234.319538460014, 5257.395399529046, 5356.678599284036, 5280.656757310762, 5368.32895888014, 5384.91922621159, 5394.352677678684, 5399.217113518537, 5356.0, 5358.946410535884, 5359.839204823823, 5375.865603359947, 5389.838304850841, 5240.213967904807, 5332.92000619991, 5346.652467589594, 5351.2240725095035, 5402.324709411324, 5641.5204707599905, 5702.885942281173, 5709.429057094258, 5762.279715035621, 5912.6156799808305, 6222.657753823538, 6227.3772622737815, 6293.716782744787, 6295.716692748837, 6296.244450665949, 5749.655020698771, 6273.215848018997, 6282.748690052393, 6288.119663247104, 6297.905531417033, 5963.46328830403, 6034.909476357858, 6057.061155520902, 6057.112186782472, 6083.969580152159, 6202.596831205957, 6221.160143380676, 6228.377162283826, 6262.342454042351, 6278.968605157036, 6296.6851657417865, 6303.086052522451, 6306.400891915261, 6309.3690630936535, 6317.084022816666, 6148.0, 6276.215473065867, 6280.937190628127, 6281.937180628135, 6283.8114856554375, 4972.925406118911, 6153.384661533811, 6209.2548894133, 6214.9689251553455, 6300.684965751694, 6241.7191226394925, 6251.249850018027, 6264.874702505925, 6274.560780745395, 6283.08895210192, 5045.798168073309, 5051.696898186121, 5053.140966035783, 5061.620378471629, 5096.770645320896, 4389.034412429261, 4405.537418571004, 4461.57615026572, 4482.260427029503, 4523.36672865799, 5875.706214689249, 5949.286085669747, 5962.07587823888, 6031.698415079229, 6035.39646035393, 6183.072539119168, 6212.689365531705, 6274.780382686584, 6275.780347687809, 6280.120783090371, 5754.108113242454, 5805.94194058054, 5848.181254624356, 5922.082077278029, 5936.347001829795, 5475.260839786578, 5531.834044978657, 5547.583931205176, 5549.833504994856, 5558.555315574744, 6184.381561843869, 6243.719032643451, 6282.874342513032, 6331.493480521639, 6351.396617321347, 6250.593711408744, 6281.3404592518955, 6292.993121100602, 6293.307736148929, 6310.0, 6445.2265728112925, 6452.0, 6454.967205247138, 6463.450606698483, 6476.417122459001, 6119.563485741553, 6191.628502289877, 6214.875702485834, 6269.467095296951, 6284.371562843679, 6166.938330616636, 6263.3736626337, 6263.655498947602, 6277.058441233668, 6283.02613094971, 6133.969330153499, 6253.374662533893, 6259.061140828912, 6259.937400625936, 6266.686665666607, 6096.420840020191, 6125.601746185282, 6256.186695729526, 6270.0908368287255, 6278.905816412849, 5390.784368625211, 6035.486983606443, 6137.263528376623, 6160.045192995093, 6180.6291622502795, 5637.746301416364, 5929.881402371842, 6254.843628909313, 6284.402981716729, 6301.149344838572, 5458.754356053908, 5868.413158684098, 6128.4790792783115, 6139.416755408229, 6142.477889379274], "value": 5772.454953626075, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.96200303975684, 500.1572350589257, 502.4874627879561, 515.2425933877201, 534.2760559441958, 31233.063008109766, 38976.415353769684, 42769.0, 42910.356344654814, 45642.0, 45002.0, 45418.545814541845, 45505.0, 45539.31691024629, 45642.77178614111, 8532.57337133141, 9337.579808908615, 9527.523623818815, 9558.330916835825, 9560.665376711817, 8522.318214542853, 11433.885661143408, 11633.127515436334, 11651.883481165207, 11902.702432439215, 11527.481263343128, 11575.305481671126, 11575.479103440322, 11594.710132246719, 11621.709457263552, 8514.957425212897, 8672.349573781992, 9955.900440995605, 11687.824682629767, 11691.12316576256, 11217.775644487065, 11418.257813242113, 11440.485178166919, 11670.474828632732, 11702.824457633142, 42639.36040959388, 42839.14321713548, 42973.57026429758, 42993.57006429927, 43004.0, 3958.782266975315, 4023.617756313175, 4083.183363327317, 4090.6931980101613, 4130.648894843942, 30176.8491157545, 30219.395612087854, 30279.545806812916, 31956.881509147068, 32827.6717232827, 3176.7935084219685, 3303.4549299365576, 3335.516350129242, 3377.4427219508752, 3404.14896275931, 481379.8965077633, 486160.99999999645, 486949.0, 488972.0, 490939.0, 57171.0, 67345.0, 67537.0, 67580.66209668921, 67762.66118669375, 59173.40826591679, 69242.65378673075, 70331.2966870335, 70482.64758676174, 70675.64662176656, 16479.093649849132, 16508.504744857673, 16531.504054878373, 17079.743803842954, 17100.401485947932, 11715.0, 11958.40207989597, 12028.398580070962, 13072.084954053315, 13199.604011879659, 18585.81414185841, 19042.714359284622, 19084.80915190858, 19409.126589303516, 19433.125509352114, 6965.199002114821, 7025.262347453444, 7026.297370262932, 7031.542949708252, 7072.434205263567, 769.4575324396287, 869.9608517616722, 885.7121435533477, 887.6582515731361, 909.5861383070819, 30101.849490752844, 30295.848520757256, 30711.0, 30904.845475772476, 31186.271575376995, 948.2508818033814, 1024.6618615856748, 1031.6131450705984, 1075.6719200643722, 1158.4555259028243, 15860.365585376687, 15928.840711592968, 15956.680866382607, 16064.919675401548, 16348.591285217726, 632454.0, 632520.0, 635220.0, 635534.0, 636772.0, 63553.682231588544, 63864.042039369444, 63932.680336598016, 63974.36025639777, 63984.36015639784, 1416.405109853864, 1443.1269082205324, 1447.3776276201334, 1450.9347079381241, 1451.506487794155, 29855.402891942475, 30191.7816409816, 30542.541861871647, 30920.917767878018, 31261.0, 52824.0, 53095.46904530905, 53140.0, 53297.46702532925, 53334.4666553345, 524847.0, 526507.0, 528794.3560282174, 529468.0, 530352.0, 22903.656445153003, 23502.412439689146, 24492.265232043068, 25106.0, 42781.0, 37556.0, 42669.35995960125, 44850.102997939204, 45323.41368052102, 47816.521834781204, 36082.47422680387, 36400.63599364078, 36585.08537286589, 37788.62211377961, 38358.80820595879, 36383.0, 36741.44887826631, 36750.63249367472, 37220.62779372172, 37793.86618401452, 34954.825225873705, 36245.09387265339, 36530.08674783046, 36544.08639784026, 36722.44916326204, 30923.0, 30969.761209551365, 31629.102253864843, 32572.51141232931, 33385.499217512246, 24394.390140245927, 24397.14609988642, 24705.50588988174, 24893.751062489144, 24897.875510622333], "value": 20424.548740244474, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.1514206600644, 397.29281878256677, 400.6674460198036, 407.8980254936267, 426.99655808849207, 14117.505887293946, 20180.495487612818, 20476.0, 21507.24724634636, 21535.815530145836, 16405.91797041013, 16743.916280418613, 20526.589468210626, 20662.380128596127, 20813.687794683094, 5548.334199896008, 5854.531637469014, 5866.6480011199255, 5884.117382392654, 5887.264091988502, 5060.924086138711, 5588.105903055512, 5714.142878568227, 5745.540356771469, 5760.049591817345, 5057.216131499624, 5636.210930469738, 5729.39841316662, 5745.0, 5758.625689330201, 5515.972420137894, 5683.744231509592, 5706.77172913083, 5718.857028574298, 5753.510951569122, 5014.5737612303, 5085.262636917663, 5093.18509038552, 5097.6941383517105, 5099.566536844372, 24676.753232467625, 25627.35931601725, 25718.0, 25922.0, 26245.343866403302, 2682.7317268273214, 2739.657542807149, 2741.9862900685566, 2742.2595899107328, 2746.6566679165103, 15263.0, 15337.539873803804, 15375.769363459556, 15610.531684049496, 15962.680746385124, 1798.379559052125, 1840.098351807618, 1847.113385574918, 1851.703727403609, 1856.3224423085476, 126721.0, 129814.3509282457, 130277.0, 133073.99999999904, 133903.0, 9735.805283894282, 11468.36923969173, 11577.189596728316, 11580.594679186188, 11750.764984700258, 14450.783238251435, 14488.565343039725, 14519.274036298142, 14523.346449409803, 14536.709265814625, 7282.672279747424, 7583.5070720403, 8144.959275203586, 8314.41799074071, 8332.916670833214, 8551.914480855236, 8608.827823443497, 8642.827143457222, 8662.826743465095, 8790.516521591246, 7040.612766297902, 7160.140783106061, 7162.961370601233, 7168.462365322622, 7178.318059784312, 1939.3890924359064, 1983.930562430308, 1988.2146552112124, 1991.731116299281, 1999.9300024499073, 976.345848281653, 1045.325764881647, 1047.251215380991, 1072.7371793910543, 1080.065042590952, 21760.194184415595, 22071.779282207295, 22166.667499987514, 22206.34167062273, 22214.555708886055, 1082.7509672775147, 1087.9673609791719, 1106.242224076494, 1111.4942701070993, 1126.4424110065406, 11212.046976006968, 11449.019656569071, 11498.965093141658, 11514.94242528782, 11563.190576659721, 737336.31331843, 742795.2860235664, 745894.0, 746252.0, 753688.0, 141650.0, 142887.0, 143667.44988251847, 143770.0, 145218.0, 1074.4681382715605, 1084.38732116354, 1087.488880226308, 1097.2154909239932, 1101.553870682375, 18287.26850925948, 19018.42944711661, 19401.223951042364, 19421.805781942, 19509.70735438998, 45735.0, 46274.07451851011, 51651.22523162231, 55436.0, 56412.7179364117, 53892.46107538875, 75940.24059759332, 80787.7291043809, 81211.37577248541, 81938.77091843747, 5482.479164479368, 7393.334599886036, 7753.10839253493, 7779.883301750365, 8060.314873235606, 12966.351682415652, 13024.674383140497, 13040.41318140667, 13572.04995650295, 13582.0, 10959.191733363905, 11096.389698566656, 11237.269577477438, 11473.0, 11711.414429278673, 11027.889721103007, 11227.607033753779, 11251.549938002388, 11512.481938312629, 11626.244294120856, 11358.54565817358, 11409.25839820409, 11465.770684586429, 11496.597619083292, 11625.59310424131, 8044.718434854752, 8223.09545949957, 8271.131531189138, 8304.086550479322, 8453.830923381622, 11099.667009989713, 11138.053265472525, 11191.216614836883, 11367.261128026652, 11577.536898523964], "value": 10017.37613644156, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.6578772413034, 1440.2294772296816, 1441.9062760920533, 1463.6267751723303, 1465.040398538957, 8000.039995200576, 8473.0, 8474.957625211866, 8490.320774338054, 8496.107908669586, 7457.701691932316, 7762.534247945113, 7775.533467991924, 8002.719904803333, 8003.199680031994, 2302.3323236261463, 2339.7950055721317, 2340.777626125524, 2347.248880358286, 2351.0, 2305.873176975266, 2338.871362075085, 2340.578695834749, 2345.7536958619357, 2346.5541547105995, 1932.6231384879954, 2027.7769445360998, 2104.4002459299104, 2322.396176993977, 2325.4070212095894, 2291.0, 2301.252093069759, 2302.976970230293, 2305.8847057647217, 2317.837751357414, 2252.3580779477934, 2304.308707387777, 2323.1520495019367, 2332.6384410416244, 2337.8714170720605, 7856.528608283465, 7927.603619819044, 7937.48406353591, 7945.48354356965, 7957.801054973614, 5031.094403007457, 5181.093308670967, 5193.480651934814, 5194.013137503862, 5208.6093542984045, 3382.7970321780526, 3484.9651503484893, 3514.6661067198575, 3547.9822600886832, 3578.35589593873, 1190.1133655426763, 1224.088054399478, 1227.061298106949, 1276.4957841652588, 1281.8654041325713, 10707.196960227937, 10842.403667798268, 10853.728656783644, 10854.077403420719, 10858.348499090001, 4442.133783912106, 4632.305154226826, 4703.247480403119, 4709.882252943636, 4716.080364328923, 9580.329376943548, 9588.616455341848, 9589.232861371074, 9593.0, 9608.855867161998, 5272.6309158358545, 5278.81524146653, 5287.68273903567, 5291.682499050069, 5297.470252974672, 4236.343366778155, 4359.215341238608, 4668.976655116703, 4702.600278976325, 4757.215059515142, 3880.165764360676, 3903.921921561553, 3967.690662081506, 3991.082051128257, 4004.479417675742, 1275.4579303796036, 1288.0404098946344, 1292.1730092740654, 1302.7589895869294, 1304.452130105358, 770.7726220764888, 782.9021372328459, 900.7477906186278, 909.8726178335038, 912.0, 5395.595330350239, 5461.644993075437, 5486.478784515464, 5515.090010148361, 5690.231818704505, 953.3040880157524, 998.5834281039756, 1027.4708525109575, 1043.6190790361616, 1046.6179844356755, 3527.5943266524678, 3554.680078792869, 3576.946345804815, 3696.611855755107, 3758.0, 10001.24990625706, 10192.0, 10192.745181370525, 10193.184545236363, 10195.796084078276, 10026.69919902404, 10036.146927511098, 10072.94234105423, 10097.495125243855, 10154.898451015395, 7476.364509016686, 7823.374130069581, 7833.255840695125, 7949.085855126735, 7958.0, 5470.452954704498, 5493.890122197614, 5520.089185284543, 5652.293463317085, 5682.6306290091015, 7863.252990965849, 8873.645054198018, 9083.227925626396, 9242.537873106452, 9411.10594493522, 8720.563971801512, 9211.861822072808, 9394.013628569175, 9446.197073248575, 9474.194693451134, 6547.672616369257, 6740.460763138839, 6742.4943129266485, 7201.747938822116, 7202.783916482514, 7730.381569474316, 8013.318867896294, 8034.196580341919, 8132.593370331341, 8134.471259368122, 7069.752558660422, 7133.750318738819, 7190.71237150529, 7197.280271972971, 7338.77983660491, 7039.225685174524, 7048.189458212166, 7087.964560177373, 7091.751788687371, 7198.0, 6356.618602883842, 6361.809145725636, 6362.840928976812, 6413.2945376007665, 6426.710798014195, 4872.4883887192755, 4918.926216106833, 4942.92585611209, 4954.925676114789, 4996.775145118551, 4886.3403440536495, 4898.951010489992, 4913.582345500529, 4976.402831660224, 5096.362954630671], "value": 4418.864757089769, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.43895621618384, 324.9398861210676, 345.1560933517549, 347.02832070203425, 361.5570925616121, 4991.876827713762, 5193.792248310073, 5211.576616873153, 5386.48828361305, 5400.594955378343, 4280.3151495760685, 4688.906221875561, 4967.428745694247, 5177.922331165036, 5349.251104845315, 3896.1752729271334, 4064.4716186895735, 4069.4506241657296, 4077.7145599807877, 4107.856225032125, 3746.340098530047, 3853.8843834684862, 3858.2283543291314, 4065.369867670501, 4065.87802365928, 3987.3022221111332, 4037.3540233562635, 4047.372657238133, 4068.3897415387783, 4099.8770036898795, 3599.1182160370613, 3909.0227443139215, 4035.334169861984, 4052.4934383201953, 4078.3882417637296, 2858.957115643267, 3127.0918046217007, 3382.678645528671, 3417.419038763416, 3541.557305336833, 4415.470143582758, 4758.690685105457, 5197.324347834758, 5200.6619569727845, 5203.16749320107, 4442.8667139985855, 4598.333241679971, 4619.74591397473, 4678.590525879175, 4679.859604211845, 5031.320771695848, 5368.282149712094, 5373.973130134364, 5397.7571009304675, 5609.915851262234, 3307.6692330766973, 3393.694567488913, 3813.3517302058717, 3848.826802793881, 3850.3454412749898, 5866.413358664099, 5898.734556944948, 5943.771085842809, 5956.97021514894, 5968.701564921824, 3748.21577329483, 3856.7107466940092, 3864.4589757433982, 3900.7464514806447, 3908.785016824102, 3570.8571657133944, 3949.2693851636964, 3952.822123004472, 3972.324704800191, 3993.0, 4374.737515749001, 4615.515370886076, 4631.652626053059, 4638.095571363552, 4682.929756053593, 4956.107900577858, 5190.92213616796, 5740.081586946152, 5751.281089863767, 5758.62568933018, 1146.3694967767694, 1262.5770366927181, 1288.9108703145828, 1299.9740005199842, 1302.8827405533548, 800.9078955920028, 813.5444151275304, 814.4543156085437, 825.636719843279, 874.191372979991, 4764.92598828004, 4954.497978520824, 5023.2214006829, 5075.289459475676, 5082.5514728303, 5132.948670513321, 5169.276301317818, 5173.741312934339, 5454.699991500505, 5824.922389357982, 5311.468853114658, 5331.200319952039, 5337.01265265927, 5357.5446087082255, 5376.91934620981, 2455.4107014316437, 2772.4593704227887, 2867.7132286771575, 2874.9937521867178, 2876.4103358811394, 4916.5820905222745, 5102.550775627719, 5280.683159010472, 5689.800856970069, 5860.062390017577, 4890.413150421972, 5692.206954809222, 5711.0, 5716.885662286814, 5725.427457254241, 5138.614603904722, 5478.808241711521, 5712.485876271156, 6061.787837425669, 6078.270607527125, 4599.4710608280475, 5183.429822719422, 5510.9448905510435, 5577.776888924398, 5790.363060063305, 5373.623846330719, 5777.595568310178, 5785.6817875015595, 5816.47651711348, 5848.766049357978, 5558.944410555842, 5696.487316141402, 5949.405059494016, 5965.27170518426, 5998.9100163496705, 3886.1644746379666, 3933.645971862546, 3952.7628342299554, 3960.9207815844097, 3982.78094704794, 4757.119932812441, 5622.0, 5852.7658893643775, 5877.4122587742595, 5909.91135132982, 5599.748011339418, 5601.943980560142, 5646.943530564642, 5657.257883248588, 5661.348944871393, 5626.508975121515, 5653.3498647654405, 5660.51885589713, 5692.772289108555, 5720.8569785754025, 5051.21159130652, 5066.771995260296, 5129.28169063353, 5156.896862062663, 5518.172274158908, 5003.049420610072, 5619.775208991595, 5632.436756324335, 5642.266505354278, 5678.971605141948, 5191.428942816363, 5208.151106357189, 5284.943011397812, 5424.891502170013, 5480.561555075505], "value": 4078.709670624875, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.7941596936882, 714.6605362452832, 754.5020286610838, 814.2179575870083, 822.21889205255, 14338.0, 14981.625459363495, 15015.249237538106, 15028.0, 15178.924105379472, 14154.858451415483, 14309.0, 14322.498712545063, 14336.498222562212, 14340.42638294467, 668.2315337362024, 688.0745397440435, 691.8408765983827, 818.1981657975188, 830.8712149616789, 852.8763329317245, 857.3141486810526, 864.3344624639025, 870.8040690844541, 871.485823364215, 900.6487469886749, 901.4005686218657, 902.0798785239052, 902.264654306738, 903.5121034641286, 896.1397058823537, 897.058089006547, 898.2230370729337, 898.2364989758686, 900.333753022765, 518.2692403710787, 535.807109440601, 661.6856992928368, 676.5365724478747, 684.3601232847254, 14471.348789304506, 14502.782458263135, 14513.709725805425, 14517.274136293248, 14534.854651453456, 13599.728005439836, 13615.59153225395, 13632.93183534086, 13660.975426843026, 13688.65778355539, 12036.338001409918, 12090.637280881587, 12197.45111469977, 12635.557755478607, 13161.670958226025, 4176.18564379946, 4416.823327066913, 4435.445569303837, 4483.0, 4575.176468235716, 102403.48798255961, 113038.43480782544, 113752.86247137506, 114050.42974785157, 114212.0, 10202.818770018273, 10587.258891877495, 10799.892001080047, 11135.610253641245, 11260.211785175123, 21227.257046003317, 21524.354269371943, 21549.24577639775, 21954.670679939813, 21963.352748543923, 7145.785626431215, 7185.712571497082, 7222.277772222841, 7254.689503663123, 7301.817454563678, 4072.9185416291507, 4208.558101399371, 4258.7657678827345, 4261.296886013773, 4340.045190058182, 3438.9656103439147, 3504.0714210734177, 3554.9466757998653, 3562.7327950403824, 3563.0, 655.7147640776277, 661.6261812076258, 666.4834753066316, 671.3823282580038, 675.2403546010738, 630.4483576870239, 641.2241188162283, 644.0889258258816, 652.3411354531956, 652.3900153356627, 8673.306135509145, 9998.400095994264, 9998.450085245382, 10025.398476091457, 10106.747331316628, 655.8196495963648, 658.8963486160661, 669.6618207805027, 672.862063277027, 682.2801943949082, 3576.874809381711, 4895.73073480962, 5279.551238144801, 5331.20031995196, 5513.724313784295, 82474.0, 86166.56916715502, 93207.53396232975, 96950.54574181393, 101999.490002551, 72912.0, 89942.0, 90390.19219615702, 91653.62519562212, 95684.0, 82165.58917205496, 84068.73896891551, 84440.57779711185, 84878.57560712157, 85936.57031714801, 10580.629677961235, 10891.891081089405, 11021.504032318404, 11092.722681933015, 11206.49570769301, 49732.0, 50228.74885625695, 50272.0, 50823.237651434516, 51080.48919510757, 70404.64797675979, 70859.0, 71461.0, 71473.28526714875, 74240.14399640053, 12732.363381830686, 12823.487060517475, 12917.483300667867, 13051.0, 13235.933820330836, 13393.0, 13711.520096796565, 14014.929925350309, 14676.266186690837, 14824.7776283358, 11551.480183391599, 13098.672533186747, 13797.241151736349, 14026.0, 14294.71410571762, 13192.4594563585, 13240.80138797938, 13599.59201223965, 13703.794443083163, 14162.955902720667, 12954.676133096747, 13195.934020329836, 13545.932270338586, 13547.160584997924, 13744.518941837388, 10773.24587278883, 10892.673219803419, 11137.16002419378, 11180.998547729, 11893.464794084113, 10229.641962531276, 10842.0, 10866.239363244496, 10935.890641093487, 10982.560697572328], "value": 7499.882950140039, "unit": "score"}], "name": "dromaeo_css", "value": 7365.472621971865, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 113.0, 115.0, 111.0, 115.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 109.0, 108.0, 140.0, 109.0, 111.0, 132.0, 131.0, 152.0, 108.0], "value": 123.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 183.0, 182.0, 185.0, 184.0, 183.0, 186.0, 187.0, 184.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 73.0, 79.0, 110.0, 74.0, 59.0, 59.0, 58.0, 83.0, 78.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 86.0, 70.0, 69.0, 69.0, 82.0, 68.0, 68.0, 82.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 95.0, 87.0, 87.0, 86.0, 93.0, 88.0, 88.0, 85.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 97.0, 100.0, 99.0, 98.0, 99.0, 99.0, 99.0, 98.0, 99.0], "value": 98.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 130.0, 84.0, 88.0, 84.0, 89.0, 86.0, 94.0, 139.0, 131.0], "value": 105.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 56.0, 57.0, 57.0, 57.0, 57.0, 57.0, 57.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 78.0, 77.0, 75.0, 72.0, 70.0, 73.0, 68.0, 71.0, 70.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 87.0, 84.0, 83.0, 86.0, 80.0, 84.0, 81.0, 83.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 136.0, 144.0, 121.0, 156.0, 135.0, 151.0, 185.0, 166.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 166.0, 165.0, 165.0, 166.0, 166.0, 166.0, 165.0, 166.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 63.0, 69.0, 81.0, 80.0, 81.0, 64.0, 65.0], "value": 70.1, "unit": "score"}], "name": "kraken", "value": 1460.6, "alertThreshold": 2.0}]} 12:28:15 INFO - Return code: 0 12:28:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:28:15 ERROR - # TBPL SUCCESS # 12:28:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:28:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:28:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:28:16 INFO - 2017-02-08 12:28:16.012 screenresolution[1652:9712] starting screenresolution argv=screenresolution get 12:28:16 INFO - 2017-02-08 12:28:16.014 screenresolution[1652:9712] Display 0: 1600x1200x32@50 12:28:16 INFO - 2017-02-08 12:28:16.020 screenresolution[1653:9715] starting screenresolution argv=screenresolution list 12:28:16 INFO - Available Modes on Display 0 12:28:16 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 12:28:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:28:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:28:16 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 12:28:16 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 12:28:16 INFO - Graphics/Displays: 12:28:16 INFO - Intel Iris: 12:28:16 INFO - Chipset Model: Intel Iris 12:28:16 INFO - Type: GPU 12:28:16 INFO - Bus: Built-In 12:28:16 INFO - VRAM (Dynamic, Max): 1536 MB 12:28:16 INFO - Vendor: Intel (0x8086) 12:28:16 INFO - Device ID: 0x0a2e 12:28:16 INFO - Revision ID: 0x0009 12:28:16 INFO - Displays: 12:28:16 INFO - Display: 12:28:16 INFO - Resolution: 1600 x 1200 @ 50 Hz 12:28:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:28:16 INFO - Display Serial Number: 1600x1200 50 12:28:16 INFO - Main Display: Yes 12:28:16 INFO - Mirror: Off 12:28:16 INFO - Online: Yes 12:28:16 INFO - Rotation: Supported 12:28:16 INFO - Return code: 0 12:28:16 INFO - Running post-action listener: _resource_record_post_action 12:28:16 INFO - [mozharness: 2017-02-08 20:28:16.257715Z] Finished run-tests step (success) 12:28:16 INFO - Running post-run listener: _resource_record_post_run 12:28:16 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 38974464; Write bytes: 507507712; Read time: 373; Write time: 1529 12:28:16 INFO - TinderboxPrint: CPU usage
53.7% 12:28:16 INFO - TinderboxPrint: I/O read bytes / time
38,974,464 / 373 12:28:16 INFO - TinderboxPrint: I/O write bytes / time
507,507,712 / 1,529 12:28:16 INFO - TinderboxPrint: CPU idle
2,223.4 (46.3%) 12:28:16 INFO - TinderboxPrint: CPU system
959.9 (20.0%) 12:28:16 INFO - TinderboxPrint: CPU user
1,618.5 (33.7%) 12:28:16 INFO - TinderboxPrint: Swap in / out
246,251,520 / 0 12:28:16 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 199627264; Write bytes: 200275456; Read time: 15464; Write time: 376 12:28:16 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 38040576; Write bytes: 297418240; Read time: 359; Write time: 1121 12:28:16 INFO - Running post-run listener: _upload_blobber_files 12:28:16 INFO - Blob upload gear active. 12:28:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:28:16 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 12:28:16 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'] 12:28:16 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 12:28:17 INFO - (blobuploader) - INFO - Open directory for files ... 12:28:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:28:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:28:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:18 INFO - (blobuploader) - INFO - Done attempting. 12:28:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:28:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:28:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:19 INFO - (blobuploader) - INFO - Done attempting. 12:28:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:28:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:28:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:20 INFO - (blobuploader) - INFO - Done attempting. 12:28:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:28:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:28:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:22 INFO - (blobuploader) - INFO - Done attempting. 12:28:22 INFO - (blobuploader) - INFO - Iteration through files over. 12:28:22 INFO - Return code: 0 12:28:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:28:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:28:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7afd7c8be0d1ae2494ff2961b82dc01b3c34c3c15d462e11ab2137ce2c683e6828de211bbbd057d1168db8e47ac0e83a9d5a08a828468673ac7af913e11bc58", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b9f57b77fdab3ec8feca2e3d0d1f180c1be04e0784f9d489b4ddf354efed6eb378290a283769c4d770c24de780a07d808d66980f18b25d9dfd82022a7a0bc8e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da748ee9fa1230641159f0ce0066c044f7cda160f49e7586e7020c3bd5b90e2ec0a7e6de03d4bea605c506db3e1fb914a162c2ecd135a2f6507352407888b658", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7203af89cfd745d2e9b3667ade4675d6100fdf6bfe4d8a12833a5869e0b4891a44c1ad1a80c363d9dbdad773c3bb5d37a0aad7c59f666346b464d1fd14a83afd"} 12:28:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:28:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:28:22 INFO - Contents: 12:28:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7afd7c8be0d1ae2494ff2961b82dc01b3c34c3c15d462e11ab2137ce2c683e6828de211bbbd057d1168db8e47ac0e83a9d5a08a828468673ac7af913e11bc58", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b9f57b77fdab3ec8feca2e3d0d1f180c1be04e0784f9d489b4ddf354efed6eb378290a283769c4d770c24de780a07d808d66980f18b25d9dfd82022a7a0bc8e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da748ee9fa1230641159f0ce0066c044f7cda160f49e7586e7020c3bd5b90e2ec0a7e6de03d4bea605c506db3e1fb914a162c2ecd135a2f6507352407888b658", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7203af89cfd745d2e9b3667ade4675d6100fdf6bfe4d8a12833a5869e0b4891a44c1ad1a80c363d9dbdad773c3bb5d37a0aad7c59f666346b464d1fd14a83afd"} 12:28:22 INFO - Running post-run listener: copy_logs_to_upload_dir 12:28:22 INFO - Copying logs to upload dir... 12:28:22 INFO - mkdir: /builds/slave/test/build/upload/logs 12:28:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.015100 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-08 12:28:22.597631) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-08 12:28:22.600974) ========= 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.czBSmHmNVQ/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.ywolUaeThX/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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/f7afd7c8be0d1ae2494ff2961b82dc01b3c34c3c15d462e11ab2137ce2c683e6828de211bbbd057d1168db8e47ac0e83a9d5a08a828468673ac7af913e11bc58", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b9f57b77fdab3ec8feca2e3d0d1f180c1be04e0784f9d489b4ddf354efed6eb378290a283769c4d770c24de780a07d808d66980f18b25d9dfd82022a7a0bc8e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da748ee9fa1230641159f0ce0066c044f7cda160f49e7586e7020c3bd5b90e2ec0a7e6de03d4bea605c506db3e1fb914a162c2ecd135a2f6507352407888b658", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7203af89cfd745d2e9b3667ade4675d6100fdf6bfe4d8a12833a5869e0b4891a44c1ad1a80c363d9dbdad773c3bb5d37a0aad7c59f666346b464d1fd14a83afd"} build_url:https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010742 build_url: 'https://queue.taskcluster.net/v1/task/GOqTmyXHSGi_tm0SFXi6tQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7afd7c8be0d1ae2494ff2961b82dc01b3c34c3c15d462e11ab2137ce2c683e6828de211bbbd057d1168db8e47ac0e83a9d5a08a828468673ac7af913e11bc58", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b9f57b77fdab3ec8feca2e3d0d1f180c1be04e0784f9d489b4ddf354efed6eb378290a283769c4d770c24de780a07d808d66980f18b25d9dfd82022a7a0bc8e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da748ee9fa1230641159f0ce0066c044f7cda160f49e7586e7020c3bd5b90e2ec0a7e6de03d4bea605c506db3e1fb914a162c2ecd135a2f6507352407888b658", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7203af89cfd745d2e9b3667ade4675d6100fdf6bfe4d8a12833a5869e0b4891a44c1ad1a80c363d9dbdad773c3bb5d37a0aad7c59f666346b464d1fd14a83afd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-08 12:28:22.634051) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 12:28:22.634786) ========= 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.czBSmHmNVQ/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.ywolUaeThX/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.005335 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 12:28:22.689799) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 12:28:22.690208) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 12:28:22.690744) ========= ========= Total master_lag: 0.17 =========