builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0002 starttime: 1486664981.4 results: success (0) buildid: 20170209090320 builduid: c4f1b4c4e63641c0a5f45dbb83b3bb8f revision: c8eeb0d150df26360bb162db3da74ed686c8bca5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 10:29:41.397604) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 10:29:41.398074) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 10:29:41.398348) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 10:29:41.423144) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 10:29:41.423437) ========= 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.0B93grJPJa/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.GNW8MHWNpS/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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-09 10:29:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.20M=0.001s 2017-02-09 10:29:41 (8.20 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092467 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 10:29:41.539395) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 10:29:41.539728) ========= 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.0B93grJPJa/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.GNW8MHWNpS/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.020439 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 10:29:41.588580) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-02-09 10:29:41.588929) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c8eeb0d150df26360bb162db3da74ed686c8bca5 --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 c8eeb0d150df26360bb162db3da74ed686c8bca5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0B93grJPJa/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.GNW8MHWNpS/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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-09 10:29:41,678 truncating revision to first 12 chars 2017-02-09 10:29:41,678 Setting DEBUG logging. 2017-02-09 10:29:41,678 attempt 1/10 2017-02-09 10:29:41,678 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c8eeb0d150df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 10:29:43,037 unpacking tar archive at: mozilla-aurora-c8eeb0d150df/testing/mozharness/ program finished with exit code 0 elapsedTime=1.608109 ========= master_lag: 2.29 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-02-09 10:29:45.485814) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 10:29:45.486138) ========= script_repo_revision: c8eeb0d150df26360bb162db3da74ed686c8bca5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 10:29:45.486544) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 10:29:45.486829) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 10:29:45.507834) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-09 10:29:45.508178) ========= /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.0B93grJPJa/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.GNW8MHWNpS/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 10:29:45 INFO - MultiFileLogger online at 20170209 10:29:45 in /builds/slave/test 10:29:45 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 10:29:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:29:45 INFO - {'append_to_log': False, 10:29:45 INFO - 'base_work_dir': '/builds/slave/test', 10:29:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:29:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:29:45 INFO - 'branch': 'Mozilla-Aurora', 10:29:45 INFO - 'buildbot_json_path': 'buildprops.json', 10:29:45 INFO - 'config_files': ('talos/mac_config.py',), 10:29:45 INFO - 'default_actions': ('clobber', 10:29:45 INFO - 'read-buildbot-config', 10:29:45 INFO - 'download-and-extract', 10:29:45 INFO - 'populate-webroot', 10:29:45 INFO - 'create-virtualenv', 10:29:45 INFO - 'install', 10:29:45 INFO - 'run-tests'), 10:29:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:29:45 INFO - 'download_minidump_stackwalk': True, 10:29:45 INFO - 'download_symbols': 'ondemand', 10:29:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:29:45 INFO - 'tooltool.py': '/tools/tooltool.py', 10:29:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:29:45 INFO - '/tools/misc-python/virtualenv.py')}, 10:29:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:29:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:29:45 INFO - 'installer_path': 'installer.exe', 10:29:45 INFO - 'log_level': 'info', 10:29:45 INFO - 'log_name': 'talos', 10:29:45 INFO - 'log_to_console': True, 10:29:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:29:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:29:45 INFO - 'opt_config_files': (), 10:29:45 INFO - 'pip_index': False, 10:29:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:29:45 INFO - 'cmd': ('bash', 10:29:45 INFO - '-c', 10:29:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:29:45 INFO - 'enabled': True, 10:29:45 INFO - 'halt_on_failure': False, 10:29:45 INFO - 'name': 'check_screen_resolution'},), 10:29:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:29:45 INFO - 'cmd': ('bash', 10:29:45 INFO - '-c', 10:29:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:29:45 INFO - 'enabled': True, 10:29:45 INFO - 'halt_on_failure': False, 10:29:45 INFO - 'name': 'check_screen_resolution'},), 10:29:45 INFO - 'run_cmd_checks_enabled': True, 10:29:45 INFO - 'sps_profile': False, 10:29:45 INFO - 'sps_profile_interval': 0, 10:29:45 INFO - 'suite': 'dromaeojs', 10:29:45 INFO - 'system_bits': '32', 10:29:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:29:45 INFO - 'title': 't-yosemite-r7-0002', 10:29:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:29:45 INFO - 'use_talos_json': True, 10:29:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:29:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:29:45 INFO - 'work_dir': 'build'} 10:29:45 INFO - [mozharness: 2017-02-09 18:29:45.725931Z] Running clobber step. 10:29:45 INFO - Running pre-action listener: _resource_record_pre_action 10:29:45 INFO - Running main action method: clobber 10:29:45 INFO - rmtree: /builds/slave/test/build 10:29:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:29:47 INFO - Running post-action listener: _resource_record_post_action 10:29:47 INFO - [mozharness: 2017-02-09 18:29:47.884321Z] Finished clobber step (success) 10:29:47 INFO - [mozharness: 2017-02-09 18:29:47.884664Z] Running read-buildbot-config step. 10:29:47 INFO - Running pre-action listener: _resource_record_pre_action 10:29:47 INFO - Running main action method: read_buildbot_config 10:29:47 INFO - Using buildbot properties: 10:29:47 INFO - { 10:29:47 INFO - "project": "", 10:29:47 INFO - "product": "firefox", 10:29:47 INFO - "script_repo_revision": "production", 10:29:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:29:47 INFO - "repository": "", 10:29:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:29:47 INFO - "buildid": "20170209090320", 10:29:47 INFO - "pgo_build": "False", 10:29:47 INFO - "basedir": "/builds/slave/test", 10:29:47 INFO - "buildnumber": 57, 10:29:47 INFO - "slavename": "t-yosemite-r7-0002", 10:29:47 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:29:47 INFO - "platform": "yosemite_r7", 10:29:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:29:47 INFO - "branch": "mozilla-aurora", 10:29:47 INFO - "repo_path": "releases/mozilla-aurora", 10:29:47 INFO - "revision": "c8eeb0d150df26360bb162db3da74ed686c8bca5", 10:29:47 INFO - "stage_platform": "macosx64", 10:29:47 INFO - "builduid": "c4f1b4c4e63641c0a5f45dbb83b3bb8f", 10:29:47 INFO - "slavebuilddir": "test" 10:29:47 INFO - } 10:29:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 10:29:47 INFO - Running post-action listener: _resource_record_post_action 10:29:47 INFO - [mozharness: 2017-02-09 18:29:47.890500Z] Finished read-buildbot-config step (success) 10:29:47 INFO - [mozharness: 2017-02-09 18:29:47.890738Z] Running download-and-extract step. 10:29:47 INFO - Running pre-action listener: _resource_record_pre_action 10:29:47 INFO - Running main action method: download_and_extract 10:29:47 INFO - mkdir: /builds/slave/test/build/tests 10:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:47 INFO - trying https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 10:29:47 INFO - Downloading https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/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 10:29:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/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 10:29:48 INFO - Downloaded 1199 bytes. 10:29:48 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 10:29:48 INFO - Using the following test package requirements: 10:29:48 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 10:29:48 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:29:48 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 10:29:48 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:29:48 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 10:29:48 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:29:48 INFO - u'jsshell-mac.zip'], 10:29:48 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:29:48 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 10:29:48 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 10:29:48 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:29:48 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 10:29:48 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:29:48 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 10:29:48 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:29:48 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 10:29:48 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 10:29:48 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 10:29:48 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:29:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 10:29:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:29:48 INFO - Fetch https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 10:29:49 INFO - Expected file size: 16358000 10:29:49 INFO - Obtained file size: 16358000 10:29:56 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 10:29:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 10:29:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:29:56 INFO - Fetch https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 10:29:57 INFO - Expected file size: 16358000 10:29:57 INFO - Obtained file size: 16358000 10:30:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 10:30:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:30:03 INFO - Fetch https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 10:30:04 INFO - Expected file size: 14403485 10:30:04 INFO - Obtained file size: 14403485 10:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:05 INFO - trying https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 10:30:05 INFO - Downloading https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 10:30:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/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 10:30:07 INFO - Downloaded 73818463 bytes. 10:30:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 10:30:07 INFO - mkdir: /builds/slave/test/properties 10:30:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:30:07 INFO - Writing to file /builds/slave/test/properties/build_url 10:30:07 INFO - Contents: 10:30:07 INFO - build_url:https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 10:30:08 INFO - Running post-action listener: _resource_record_post_action 10:30:08 INFO - Running post-action listener: set_extra_try_arguments 10:30:08 INFO - [mozharness: 2017-02-09 18:30:08.050347Z] Finished download-and-extract step (success) 10:30:08 INFO - [mozharness: 2017-02-09 18:30:08.050458Z] Running populate-webroot step. 10:30:08 INFO - Running pre-action listener: _resource_record_pre_action 10:30:08 INFO - Running main action method: populate_webroot 10:30:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:30:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:30:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:30:08 INFO - '--tppagecycles', 10:30:08 INFO - '7'], 10:30:08 INFO - 'tests': ['tsvgm']}}, 10:30:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:30:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:30:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:30:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:30:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:30:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:30:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:30:08 INFO - 'talos_options': ['--disable-e10s'], 10:30:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:30:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:30:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:30:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:30:08 INFO - 'talos_options': ['--disable-e10s'], 10:30:08 INFO - 'tests': ['damp', 'tps']}, 10:30:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:30:08 INFO - 'tests': ['damp', 'tps']}, 10:30:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:30:08 INFO - 'tests': ['dromaeo_dom']}, 10:30:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:30:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:30:08 INFO - 'tests': ['basic_compositor_video']}, 10:30:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:30:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:30:08 INFO - 'tests': ['a11yr', 10:30:08 INFO - 'ts_paint', 10:30:08 INFO - 'tpaint', 10:30:08 INFO - 'sessionrestore', 10:30:08 INFO - 'sessionrestore_no_auto_restore', 10:30:08 INFO - 'tabpaint']}, 10:30:08 INFO - 'other-e10s': {'tests': ['a11yr', 10:30:08 INFO - 'ts_paint', 10:30:08 INFO - 'tpaint', 10:30:08 INFO - 'sessionrestore', 10:30:08 INFO - 'sessionrestore_no_auto_restore', 10:30:08 INFO - 'tabpaint']}, 10:30:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:30:08 INFO - 'ts_paint', 10:30:08 INFO - 'tpaint', 10:30:08 INFO - 'sessionrestore', 10:30:08 INFO - 'sessionrestore_no_auto_restore', 10:30:08 INFO - 'tabpaint']}, 10:30:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:30:08 INFO - 'ts_paint', 10:30:08 INFO - 'tpaint', 10:30:08 INFO - 'sessionrestore', 10:30:08 INFO - 'sessionrestore_no_auto_restore', 10:30:08 INFO - 'tabpaint']}, 10:30:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:30:08 INFO - 'tests': ['a11yr', 10:30:08 INFO - 'ts_paint', 10:30:08 INFO - 'tpaint', 10:30:08 INFO - 'sessionrestore', 10:30:08 INFO - 'sessionrestore_no_auto_restore', 10:30:08 INFO - 'tabpaint']}, 10:30:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:30:08 INFO - 'tests': ['a11yr', 10:30:08 INFO - 'ts_paint', 10:30:08 INFO - 'tpaint', 10:30:08 INFO - 'sessionrestore', 10:30:08 INFO - 'sessionrestore_no_auto_restore', 10:30:08 INFO - 'tabpaint']}, 10:30:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:30:08 INFO - 'tests': ['tsvgx', 10:30:08 INFO - 'tsvgr_opacity', 10:30:08 INFO - 'tart', 10:30:08 INFO - 'tscrollx', 10:30:08 INFO - 'cart', 10:30:08 INFO - 'tsvg_static']}, 10:30:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:30:08 INFO - 'tsvgr_opacity', 10:30:08 INFO - 'tart', 10:30:08 INFO - 'tscrollx', 10:30:08 INFO - 'cart', 10:30:08 INFO - 'tsvg_static']}, 10:30:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:30:08 INFO - 'talos_options': ['--disable-e10s'], 10:30:08 INFO - 'tests': ['tp5o']}, 10:30:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:30:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:30:08 INFO - 'talos_options': ['--disable-e10s', 10:30:08 INFO - '--xperf_path', 10:30:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:30:08 INFO - 'tests': ['tp5n']}, 10:30:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:30:08 INFO - 'talos_options': ['--xperf_path', 10:30:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:30:08 INFO - 'tests': ['tp5n']}}, 10:30:08 INFO - 'talos.zip': {'path': '', 10:30:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:30:08 INFO - Running post-action listener: _resource_record_post_action 10:30:08 INFO - [mozharness: 2017-02-09 18:30:08.069380Z] Finished populate-webroot step (success) 10:30:08 INFO - [mozharness: 2017-02-09 18:30:08.069508Z] Running create-virtualenv step. 10:30:08 INFO - Running pre-action listener: _resource_record_pre_action 10:30:08 INFO - Running main action method: create_virtualenv 10:30:08 INFO - Creating virtualenv /builds/slave/test/build/venv 10:30:08 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 10:30:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:30:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:30:08 INFO - Using real prefix '/tools/python27' 10:30:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:30:08 INFO - Installing distribute.............................................................................................................................................................................................done. 10:30:11 INFO - Installing pip.................done. 10:30:11 INFO - Return code: 0 10:30:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:30:12 INFO - Reading from file tmpfile_stdout 10:30:12 INFO - Output received: 10:30:12 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) 10:30:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:30:12 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')]} 10:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:12 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')]} 10:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:12 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 0x10f124b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f127768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda79dea9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02d30>, '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 0x10ee29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef7be8>, '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.0B93grJPJa/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.GNW8MHWNpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 10:30:12 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 10:30:12 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 10:30:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0B93grJPJa/Render', 10:30:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:12 INFO - 'HOME': '/Users/cltbld', 10:30:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:12 INFO - 'LOGNAME': 'cltbld', 10:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:12 INFO - 'MOZ_NO_REMOTE': '1', 10:30:12 INFO - 'NO_EM_RESTART': '1', 10:30:12 INFO - 'PAGER': '/bin/cat', 10:30:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:12 INFO - 'PWD': '/builds/slave/test', 10:30:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:12 INFO - 'SHELL': '/bin/bash', 10:30:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNW8MHWNpS/Listeners', 10:30:12 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 10:30:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:12 INFO - 'USER': 'cltbld', 10:30:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:12 INFO - 'XPC_FLAGS': '0x0', 10:30:12 INFO - 'XPC_SERVICE_NAME': '0', 10:30:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:12 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 10:30:12 INFO - Cleaning up... 10:30:12 INFO - Return code: 0 10:30:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:30:12 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')]} 10:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:12 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')]} 10:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:12 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 0x10f124b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f127768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda79dea9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02d30>, '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 0x10ee29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef7be8>, '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.0B93grJPJa/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.GNW8MHWNpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 10:30:12 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 10:30:12 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 10:30:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0B93grJPJa/Render', 10:30:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:12 INFO - 'HOME': '/Users/cltbld', 10:30:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:12 INFO - 'LOGNAME': 'cltbld', 10:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:12 INFO - 'MOZ_NO_REMOTE': '1', 10:30:12 INFO - 'NO_EM_RESTART': '1', 10:30:12 INFO - 'PAGER': '/bin/cat', 10:30:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:12 INFO - 'PWD': '/builds/slave/test', 10:30:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:12 INFO - 'SHELL': '/bin/bash', 10:30:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNW8MHWNpS/Listeners', 10:30:12 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 10:30:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:12 INFO - 'USER': 'cltbld', 10:30:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:12 INFO - 'XPC_FLAGS': '0x0', 10:30:12 INFO - 'XPC_SERVICE_NAME': '0', 10:30:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:12 INFO - Downloading/unpacking psutil>=3.1.1 10:30:12 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 10:30:12 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 10:30:12 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 10:30:12 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 10:30:12 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 10:30:12 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 10:30:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:30:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:30:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:30:16 INFO - Installing collected packages: psutil 10:30:16 INFO - Running setup.py install for psutil 10:30:16 INFO - building 'psutil._psutil_osx' extension 10:30:16 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 10:30:16 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 10:30:16 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 10:30:16 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 10:30:16 INFO - building 'psutil._psutil_posix' extension 10:30:16 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 10:30:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:30:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:30:16 INFO - ^ 10:30:16 INFO - 1 warning generated. 10:30:16 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 10:30:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:30:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:30:17 INFO - Successfully installed psutil 10:30:17 INFO - Cleaning up... 10:30:17 INFO - Return code: 0 10:30:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:30:17 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')]} 10:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:17 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')]} 10:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:17 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 0x10f124b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f127768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda79dea9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02d30>, '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 0x10ee29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef7be8>, '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.0B93grJPJa/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.GNW8MHWNpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 10:30:17 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 10:30:17 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 10:30:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0B93grJPJa/Render', 10:30:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:17 INFO - 'HOME': '/Users/cltbld', 10:30:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:17 INFO - 'LOGNAME': 'cltbld', 10:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:17 INFO - 'MOZ_NO_REMOTE': '1', 10:30:17 INFO - 'NO_EM_RESTART': '1', 10:30:17 INFO - 'PAGER': '/bin/cat', 10:30:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:17 INFO - 'PWD': '/builds/slave/test', 10:30:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:17 INFO - 'SHELL': '/bin/bash', 10:30:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNW8MHWNpS/Listeners', 10:30:17 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 10:30:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:17 INFO - 'USER': 'cltbld', 10:30:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:17 INFO - 'XPC_FLAGS': '0x0', 10:30:17 INFO - 'XPC_SERVICE_NAME': '0', 10:30:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:30:17 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 10:30:17 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 10:30:17 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 10:30:17 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 10:30:17 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 10:30:17 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 10:30:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:30:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:30:21 INFO - Installing collected packages: mozsystemmonitor 10:30:21 INFO - Running setup.py install for mozsystemmonitor 10:30:21 INFO - Successfully installed mozsystemmonitor 10:30:21 INFO - Cleaning up... 10:30:21 INFO - Return code: 0 10:30:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:30:21 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')]} 10:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:21 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')]} 10:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:21 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 0x10f124b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f127768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda79dea9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02d30>, '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 0x10ee29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef7be8>, '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.0B93grJPJa/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.GNW8MHWNpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 10:30:21 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 10:30:21 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 10:30:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0B93grJPJa/Render', 10:30:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:21 INFO - 'HOME': '/Users/cltbld', 10:30:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:21 INFO - 'LOGNAME': 'cltbld', 10:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:21 INFO - 'MOZ_NO_REMOTE': '1', 10:30:21 INFO - 'NO_EM_RESTART': '1', 10:30:21 INFO - 'PAGER': '/bin/cat', 10:30:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:21 INFO - 'PWD': '/builds/slave/test', 10:30:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:21 INFO - 'SHELL': '/bin/bash', 10:30:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNW8MHWNpS/Listeners', 10:30:21 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 10:30:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:21 INFO - 'USER': 'cltbld', 10:30:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:21 INFO - 'XPC_FLAGS': '0x0', 10:30:21 INFO - 'XPC_SERVICE_NAME': '0', 10:30:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:21 INFO - Downloading/unpacking jsonschema==2.5.1 10:30:21 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 10:30:21 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 10:30:21 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 10:30:21 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 10:30:21 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 10:30:21 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 10:30:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:30:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:30:24 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 10:30:24 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 10:30:24 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 10:30:24 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 10:30:24 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 10:30:24 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 10:30:24 INFO - Downloading functools32-3.2.3-2.tar.gz 10:30:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:30:24 INFO - warning: no files found matching '*.txt' 10:30:24 INFO - no previously-included directories found matching 'build' 10:30:24 INFO - no previously-included directories found matching 'dist' 10:30:24 INFO - no previously-included directories found matching '.git*' 10:30:24 INFO - Installing collected packages: jsonschema, functools32 10:30:24 INFO - Running setup.py install for functools32 10:30:24 INFO - warning: no files found matching '*.txt' 10:30:24 INFO - no previously-included directories found matching 'build' 10:30:24 INFO - no previously-included directories found matching 'dist' 10:30:24 INFO - no previously-included directories found matching '.git*' 10:30:24 INFO - Successfully installed jsonschema functools32 10:30:24 INFO - Cleaning up... 10:30:24 INFO - Return code: 0 10:30:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:24 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 0x10f124b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f127768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda79dea9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02d30>, '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 0x10ee29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef7be8>, '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.0B93grJPJa/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.GNW8MHWNpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 10:30:24 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 10:30:24 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 10:30:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0B93grJPJa/Render', 10:30:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:24 INFO - 'HOME': '/Users/cltbld', 10:30:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:24 INFO - 'LOGNAME': 'cltbld', 10:30:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:24 INFO - 'MOZ_NO_REMOTE': '1', 10:30:24 INFO - 'NO_EM_RESTART': '1', 10:30:24 INFO - 'PAGER': '/bin/cat', 10:30:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:24 INFO - 'PWD': '/builds/slave/test', 10:30:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:24 INFO - 'SHELL': '/bin/bash', 10:30:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNW8MHWNpS/Listeners', 10:30:24 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 10:30:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:24 INFO - 'USER': 'cltbld', 10:30:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:24 INFO - 'XPC_FLAGS': '0x0', 10:30:24 INFO - 'XPC_SERVICE_NAME': '0', 10:30:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:30:25 INFO - Cleaning up... 10:30:25 INFO - Return code: 0 10:30:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:30:25 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')]} 10:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:25 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')]} 10:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:25 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 0x10f124b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f127768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda79dea9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02d30>, '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 0x10ee29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef7be8>, '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.0B93grJPJa/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.GNW8MHWNpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 10:30:25 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 10:30:25 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 10:30:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0B93grJPJa/Render', 10:30:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:25 INFO - 'HOME': '/Users/cltbld', 10:30:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:25 INFO - 'LOGNAME': 'cltbld', 10:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:25 INFO - 'MOZ_NO_REMOTE': '1', 10:30:25 INFO - 'NO_EM_RESTART': '1', 10:30:25 INFO - 'PAGER': '/bin/cat', 10:30:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:25 INFO - 'PWD': '/builds/slave/test', 10:30:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:25 INFO - 'SHELL': '/bin/bash', 10:30:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNW8MHWNpS/Listeners', 10:30:25 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 10:30:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:25 INFO - 'USER': 'cltbld', 10:30:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:25 INFO - 'XPC_FLAGS': '0x0', 10:30:25 INFO - 'XPC_SERVICE_NAME': '0', 10:30:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:25 INFO - Downloading/unpacking blobuploader==1.2.4 10:30:25 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 10:30:25 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 10:30:25 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 10:30:25 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 10:30:25 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 10:30:25 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 10:30:27 INFO - Downloading blobuploader-1.2.4.tar.gz 10:30:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:30:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:30:28 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 10:30:28 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 10:30:28 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 10:30:28 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 10:30:28 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 10:30:28 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 10:30:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:30:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:30:28 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 10:30:28 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 10:30:28 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 10:30:28 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 10:30:28 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 10:30:28 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 10:30:29 INFO - Downloading docopt-0.6.1.tar.gz 10:30:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:30:29 INFO - Installing collected packages: blobuploader, requests, docopt 10:30:29 INFO - Running setup.py install for blobuploader 10:30:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:30:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:30:29 INFO - Running setup.py install for requests 10:30:29 INFO - Running setup.py install for docopt 10:30:30 INFO - Successfully installed blobuploader requests docopt 10:30:30 INFO - Cleaning up... 10:30:30 INFO - Return code: 0 10:30:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:30 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 0x10f124b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f127768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda79dea9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02d30>, '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 0x10ee29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef7be8>, '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.0B93grJPJa/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.GNW8MHWNpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 10:30:30 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 10:30:30 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 10:30:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0B93grJPJa/Render', 10:30:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:30 INFO - 'HOME': '/Users/cltbld', 10:30:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:30 INFO - 'LOGNAME': 'cltbld', 10:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:30 INFO - 'MOZ_NO_REMOTE': '1', 10:30:30 INFO - 'NO_EM_RESTART': '1', 10:30:30 INFO - 'PAGER': '/bin/cat', 10:30:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:30 INFO - 'PWD': '/builds/slave/test', 10:30:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:30 INFO - 'SHELL': '/bin/bash', 10:30:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNW8MHWNpS/Listeners', 10:30:30 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 10:30:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:30 INFO - 'USER': 'cltbld', 10:30:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:30 INFO - 'XPC_FLAGS': '0x0', 10:30:30 INFO - 'XPC_SERVICE_NAME': '0', 10:30:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:30:30 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-HRTmK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:30:30 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-BqG2kI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:30:30 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-_LdgaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:30:30 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-nrVkjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:30:30 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-mKn3Ym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:30:30 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-U_nAmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:30:31 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-2Vx2dx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:30:31 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-u36blA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:30:31 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-gR3YF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:30:31 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-AGgfLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:30:31 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-xlL5s5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:30:31 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-UkT0IA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:30:31 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-2gIiSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:30:31 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-xfHmEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:31 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-rixFTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:30:32 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-toKBk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:30:32 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-huQM_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:30:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:30:32 INFO - Running setup.py install for manifestparser 10:30:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:30:32 INFO - Running setup.py install for mozcrash 10:30:32 INFO - Running setup.py install for mozdebug 10:30:32 INFO - Running setup.py install for mozdevice 10:30:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:30:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:30:32 INFO - Running setup.py install for mozfile 10:30:32 INFO - Running setup.py install for mozhttpd 10:30:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:30:33 INFO - Running setup.py install for mozinfo 10:30:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:30:33 INFO - Running setup.py install for mozInstall 10:30:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:30:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:30:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:30:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:30:33 INFO - Running setup.py install for mozleak 10:30:33 INFO - Running setup.py install for mozlog 10:30:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:30:33 INFO - Running setup.py install for moznetwork 10:30:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:30:33 INFO - Running setup.py install for mozprocess 10:30:33 INFO - Running setup.py install for mozprofile 10:30:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:30:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:30:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:30:34 INFO - Running setup.py install for mozrunner 10:30:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:30:34 INFO - Running setup.py install for mozscreenshot 10:30:34 INFO - Running setup.py install for moztest 10:30:34 INFO - Running setup.py install for mozversion 10:30:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:30:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:30:34 INFO - Cleaning up... 10:30:34 INFO - Return code: 0 10:30:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:30:34 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')]} 10:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:34 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')]} 10:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:34 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 0x10f124b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f127768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda79dea9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02d30>, '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 0x10ee29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef7be8>, '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.0B93grJPJa/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.GNW8MHWNpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 10:30:34 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 10:30:34 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 10:30:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0B93grJPJa/Render', 10:30:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:34 INFO - 'HOME': '/Users/cltbld', 10:30:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:34 INFO - 'LOGNAME': 'cltbld', 10:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:34 INFO - 'MOZ_NO_REMOTE': '1', 10:30:34 INFO - 'NO_EM_RESTART': '1', 10:30:34 INFO - 'PAGER': '/bin/cat', 10:30:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:34 INFO - 'PWD': '/builds/slave/test', 10:30:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:34 INFO - 'SHELL': '/bin/bash', 10:30:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNW8MHWNpS/Listeners', 10:30:34 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 10:30:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:34 INFO - 'USER': 'cltbld', 10:30:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:34 INFO - 'XPC_FLAGS': '0x0', 10:30:34 INFO - 'XPC_SERVICE_NAME': '0', 10:30:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:30:35 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-OCy1w1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:30:35 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)) 10:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:30:35 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-7ssedW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:30:35 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)) 10:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:30:35 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-1MkOHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:30:35 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)) 10:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:30:35 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-YHzveh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:30:35 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)) 10:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:30:35 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-xtS7IC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:30:35 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)) 10:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:30:35 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-XLQaAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:30:35 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)) 10:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:30:35 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-FtZ6ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:30:35 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)) 10:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:30:35 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-KDFSur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:30:35 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)) 10:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:30:35 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-efwWLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:30:35 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)) 10:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:30:35 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-N38zal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:30:36 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)) 10:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:30:36 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-wHWMcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:30:36 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)) 10:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:30:36 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-10aEYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:30:36 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)) 10:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:30:36 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-gePToQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:30:36 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)) 10:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:30:36 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Z1pEVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:30:36 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)) 10:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:36 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-CklKuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:36 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)) 10:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:30:36 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-PStNp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:30:36 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)) 10:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:30:36 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-IWBOIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:30:36 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)) 10:30:36 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)) 10:30:36 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)) 10:30:36 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)) 10:30:36 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)) 10:30:36 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)) 10:30:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:30:36 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 10:30:36 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 10:30:36 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 10:30:36 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 10:30:36 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 10:30:36 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 10:30:39 INFO - Downloading blessings-1.6.tar.gz 10:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:30:39 INFO - Installing collected packages: blessings 10:30:39 INFO - Running setup.py install for blessings 10:30:39 INFO - Successfully installed blessings 10:30:39 INFO - Cleaning up... 10:30:39 INFO - Return code: 0 10:30:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:30:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:30:40 INFO - Reading from file tmpfile_stdout 10:30:40 INFO - Current package versions: 10:30:40 INFO - blessings == 1.6 10:30:40 INFO - blobuploader == 1.2.4 10:30:40 INFO - docopt == 0.6.1 10:30:40 INFO - functools32 == 3.2.3-2 10:30:40 INFO - jsonschema == 2.5.1 10:30:40 INFO - manifestparser == 1.1 10:30:40 INFO - mozInstall == 1.12 10:30:40 INFO - mozcrash == 1.0 10:30:40 INFO - mozdebug == 0.1 10:30:40 INFO - mozdevice == 0.49 10:30:40 INFO - mozfile == 1.2 10:30:40 INFO - mozhttpd == 0.7 10:30:40 INFO - mozinfo == 0.9 10:30:40 INFO - mozleak == 0.1 10:30:40 INFO - mozlog == 3.4 10:30:40 INFO - moznetwork == 0.27 10:30:40 INFO - mozprocess == 0.24 10:30:40 INFO - mozprofile == 0.28 10:30:40 INFO - mozrunner == 6.13 10:30:40 INFO - mozscreenshot == 0.1 10:30:40 INFO - mozsystemmonitor == 0.3 10:30:40 INFO - moztest == 0.8 10:30:40 INFO - mozversion == 1.4 10:30:40 INFO - psutil == 3.1.1 10:30:40 INFO - requests == 1.2.3 10:30:40 INFO - wsgiref == 0.1.2 10:30:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:40 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 0x10f124b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f127768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda79dea9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02d30>, '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 0x10ee29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef7be8>, '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.0B93grJPJa/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.GNW8MHWNpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 10:30:40 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 10:30:40 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 10:30:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0B93grJPJa/Render', 10:30:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:40 INFO - 'HOME': '/Users/cltbld', 10:30:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:40 INFO - 'LOGNAME': 'cltbld', 10:30:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:40 INFO - 'MOZ_NO_REMOTE': '1', 10:30:40 INFO - 'NO_EM_RESTART': '1', 10:30:40 INFO - 'PAGER': '/bin/cat', 10:30:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:40 INFO - 'PWD': '/builds/slave/test', 10:30:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:40 INFO - 'SHELL': '/bin/bash', 10:30:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNW8MHWNpS/Listeners', 10:30:40 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 10:30:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:40 INFO - 'USER': 'cltbld', 10:30:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:40 INFO - 'XPC_FLAGS': '0x0', 10:30:40 INFO - 'XPC_SERVICE_NAME': '0', 10:30:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:41 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)) 10:30:41 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)) 10:30:41 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)) 10:30:41 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)) 10:30:41 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)) 10:30:41 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)) 10:30:41 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)) 10:30:41 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)) 10:30:41 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)) 10:30:41 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)) 10:30:41 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)) 10:30:41 INFO - Cleaning up... 10:30:41 INFO - Return code: 0 10:30:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:30:41 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')]} 10:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:41 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')]} 10:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:41 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 0x10f124b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f127768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda79dea9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02d30>, '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 0x10ee29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef7be8>, '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.0B93grJPJa/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.GNW8MHWNpS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 10:30:41 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 10:30:41 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 10:30:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0B93grJPJa/Render', 10:30:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:41 INFO - 'HOME': '/Users/cltbld', 10:30:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:41 INFO - 'LOGNAME': 'cltbld', 10:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:41 INFO - 'MOZ_NO_REMOTE': '1', 10:30:41 INFO - 'NO_EM_RESTART': '1', 10:30:41 INFO - 'PAGER': '/bin/cat', 10:30:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:41 INFO - 'PWD': '/builds/slave/test', 10:30:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:41 INFO - 'SHELL': '/bin/bash', 10:30:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNW8MHWNpS/Listeners', 10:30:41 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 10:30:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:41 INFO - 'USER': 'cltbld', 10:30:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:41 INFO - 'XPC_FLAGS': '0x0', 10:30:41 INFO - 'XPC_SERVICE_NAME': '0', 10:30:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:30:41 INFO - Cleaning up... 10:30:41 INFO - Return code: 0 10:30:41 INFO - Running post-action listener: _resource_record_post_action 10:30:41 INFO - Running post-action listener: _start_resource_monitoring 10:30:41 INFO - Starting resource monitoring. 10:30:41 INFO - [mozharness: 2017-02-09 18:30:41.677612Z] Finished create-virtualenv step (success) 10:30:41 INFO - [mozharness: 2017-02-09 18:30:41.678494Z] Running install step. 10:30:41 INFO - Running pre-action listener: _resource_record_pre_action 10:30:41 INFO - Running main action method: install 10:30:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:30:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:30:41 INFO - Reading from file tmpfile_stdout 10:30:41 INFO - Detecting whether we're running mozinstall >=1.0... 10:30:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:30:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:30:42 INFO - Reading from file tmpfile_stdout 10:30:42 INFO - Output received: 10:30:42 INFO - Usage: mozinstall [options] installer 10:30:42 INFO - Options: 10:30:42 INFO - -h, --help show this help message and exit 10:30:42 INFO - -d DEST, --destination=DEST 10:30:42 INFO - Directory to install application into. [default: 10:30:42 INFO - "/builds/slave/test"] 10:30:42 INFO - --app=APP Application being installed. [default: firefox] 10:30:42 INFO - mkdir: /builds/slave/test/build/application 10:30:42 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'] 10:30:42 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 10:31:01 INFO - Reading from file tmpfile_stdout 10:31:01 INFO - Output received: 10:31:01 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:31:01 INFO - Running post-action listener: _resource_record_post_action 10:31:01 INFO - [mozharness: 2017-02-09 18:31:01.868021Z] Finished install step (success) 10:31:01 INFO - [mozharness: 2017-02-09 18:31:01.868168Z] Running run-tests step. 10:31:01 INFO - Running pre-action listener: _resource_record_pre_action 10:31:01 INFO - Running main action method: run_tests 10:31:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:31:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:31:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:31:01 INFO - 2017-02-09 10:31:01.882 screenresolution[1963:10335] starting screenresolution argv=screenresolution get 10:31:01 INFO - 2017-02-09 10:31:01.885 screenresolution[1963:10335] Display 0: 1600x1200x32@60 10:31:01 INFO - 2017-02-09 10:31:01.891 screenresolution[1964:10338] starting screenresolution argv=screenresolution list 10:31:01 INFO - Available Modes on Display 0 10:31:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:31:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:31:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:31:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:31:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:31:02 INFO - Intel Iris: 10:31:02 INFO - Chipset Model: Intel Iris 10:31:02 INFO - Type: GPU 10:31:02 INFO - Bus: Built-In 10:31:02 INFO - VRAM (Dynamic, Max): 1536 MB 10:31:02 INFO - Vendor: Intel (0x8086) 10:31:02 INFO - Device ID: 0x0a2e 10:31:02 INFO - Revision ID: 0x0009 10:31:02 INFO - Displays: 10:31:02 INFO - Display: 10:31:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:31:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:31:02 INFO - Display Serial Number: 1600x1200 60 10:31:02 INFO - Main Display: Yes 10:31:02 INFO - Mirror: Off 10:31:02 INFO - Online: Yes 10:31:02 INFO - Rotation: Supported 10:31:02 INFO - Return code: 0 10:31:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:31:02 INFO - Python 2.7.3 10:31:02 INFO - Return code: 0 10:31:02 INFO - grabbing minidump binary from tooltool 10:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:02 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 0x10ef02d30>, '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 0x10ee29db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:31:02 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 10:31:02 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 10:31:02 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 10:31:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:31:02 INFO - Return code: 0 10:31:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:31:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:31:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:31:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:31:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:31:02 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/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0002', '--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 10:31:02 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/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0002 --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 10:31:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0B93grJPJa/Render', 10:31:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:02 INFO - 'HOME': '/Users/cltbld', 10:31:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:02 INFO - 'LOGNAME': 'cltbld', 10:31:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:31:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:02 INFO - 'MOZ_NO_REMOTE': '1', 10:31:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:31:02 INFO - 'NO_EM_RESTART': '1', 10:31:02 INFO - 'PAGER': '/bin/cat', 10:31:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:02 INFO - 'PWD': '/builds/slave/test', 10:31:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:31:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:02 INFO - 'SHELL': '/bin/bash', 10:31:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GNW8MHWNpS/Listeners', 10:31:02 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 10:31:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:02 INFO - 'USER': 'cltbld', 10:31:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:02 INFO - 'XPC_FLAGS': '0x0', 10:31:02 INFO - 'XPC_SERVICE_NAME': '0', 10:31:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:02 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/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0002', '--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 10:31:02 INFO - mozversion application_buildid: 20170209090320 10:31:02 INFO - mozversion application_changeset: c8eeb0d150df26360bb162db3da74ed686c8bca5 10:31:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:31:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:31:02 INFO - mozversion application_name: Firefox 10:31:02 INFO - mozversion application_remotingname: firefox-dev 10:31:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:31:02 INFO - mozversion application_vendor: Mozilla 10:31:02 INFO - mozversion application_version: 53.0a2 10:31:02 INFO - mozversion platform_buildid: 20170209090320 10:31:02 INFO - mozversion platform_changeset: c8eeb0d150df26360bb162db3da74ed686c8bca5 10:31:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:31:02 INFO - mozversion platform_version: 53.0a2 10:31:02 INFO - using testdate: 1486665062 10:31:02 INFO - actual date: 1486665062 10:31:02 INFO - starting webserver on 'localhost:49350' 10:31:02 INFO - SUITE-START | Running 2 tests 10:31:02 INFO - TEST-START | dromaeo_css 10:31:02 INFO - operating with platform_type : mac_ 10:31:02 INFO - Initialising browser for dromaeo_css test... 10:31:02 INFO - TEST-INFO | started process 1974 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmp8XEX0Q/profile http://localhost:49350/getInfo.html) 10:31:04 INFO - PROCESS | 1974 | __metrics Screen width/height:1600/1200 10:31:04 INFO - PROCESS | 1974 | colorDepth:24 10:31:04 INFO - PROCESS | 1974 | Browser inner width/height: 1024/689 10:31:04 INFO - PROCESS | 1974 | __metrics 10:31:05 INFO - PROCESS | 1974 | 1486665065013 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 10:31:05 INFO - PROCESS | 1974 | 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" 10:31:05 INFO - PROCESS | 1974 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:31:05 INFO - TEST-INFO | 1974: exit 0 10:31:05 INFO - Browser initialized. 10:31:05 INFO - Running cycle 1/1 for dromaeo_css test... 10:31:05 INFO - TEST-INFO | started process 1976 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmp8XEX0Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:31:17 INFO - PROCESS | 1976 | RSS: Main: 212844544 10:31:17 INFO - PROCESS | 1976 | 10:34:21 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49350/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49350/tests/dromaeo/cssquery-ext.html) 10:34:22 INFO - PROCESS | 1976 | RSS: Main: 338485248 10:34:22 INFO - PROCESS | 1976 | 10:36:07 INFO - PROCESS | 1976 | ************************* 10:36:07 INFO - PROCESS | 1976 | A coding exception was thrown and uncaught in a Task. 10:36:07 INFO - PROCESS | 1976 | 10:36:07 ERROR - PROCESS | 1976 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:36:07 INFO - PROCESS | 1976 | Full stack: 10:36:07 INFO - PROCESS | 1976 | ************************* 10:37:25 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49350/tests/dromaeo/cssquery-ext.html (next: http://localhost:49350/tests/dromaeo/cssquery-jquery.html) 10:37:26 INFO - PROCESS | 1976 | RSS: Main: 348237824 10:37:26 INFO - PROCESS | 1976 | 10:40:28 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49350/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49350/tests/dromaeo/cssquery-mootools.html) 10:40:29 INFO - PROCESS | 1976 | RSS: Main: 317566976 10:40:29 INFO - PROCESS | 1976 | 10:43:31 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49350/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49350/tests/dromaeo/cssquery-prototype.html) 10:43:32 INFO - PROCESS | 1976 | RSS: Main: 324866048 10:43:32 INFO - PROCESS | 1976 | 10:46:35 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49350/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49350/tests/dromaeo/cssquery-yui.html) 10:46:36 INFO - PROCESS | 1976 | RSS: Main: 312758272 10:46:36 INFO - PROCESS | 1976 | 10:49:39 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49350/tests/dromaeo/cssquery-yui.html (next: http://localhost:49350/tests/dromaeo/cssquery-dojo.html) 10:49:39 INFO - PROCESS | 1976 | RSS: Main: 418254848 10:49:39 INFO - PROCESS | 1976 | 10:49:39 INFO - PROCESS | 1976 | __start_tp_report 10:49:39 INFO - PROCESS | 1976 | _x_x_mozilla_page_load 10:49:39 INFO - PROCESS | 1976 | _x_x_mozilla_page_load_details 10:49:39 INFO - PROCESS | 1976 | |i|pagename|runs| 10:49:39 INFO - PROCESS | 1976 | |0;dojo.html;2805.5090359187143;3283.7516065077252;3329.1510664780476;3341.599008119023;3377.105067157204;5687.601867869251;5692.943070569293;5705.315362156547;5725.713714314284;5740.454656807602;5659.264295641561;5752.482276595106;5755.740991655375;5760.395158508359;5766.192733017381;5161.509656582628;5187.299714538527;5207.375114986207;5225.137852254393;5256.8160114396005;5087.643864929457;5097.260897169908;5260.421353651095;5286.04851126797;5337.813176538822;5137.229415587654;5138.6146039047035;5170.586353091744;5173.508516690908;5252.343457067866;5206.817761378353;5259.842204733826;5260.289860868772;5261.657992230493;5263.552598029172;4389.604935555815;5177.249298851683;5179.456157103487;5186.351706036746;5245;5479.863003424946;5524.558035357162;5544.833654990316;5560.8331750047155;5661.801836935688;6158.599691020114;6175.258968923713;6182.474489668383;6200.534959878028;6209.96895015522;6164.537659675542;6188.381161883821;6212.81361559154;6232.626042437469;6253.968730156365;5609.747561359749;5796.594238403271;5818.1272809078555;5847.152162936435;5879.970600147015;6116.618888358023;6223.96888015566;6229.53278504114;6237.4698150656795;6259.874802503924;5281.947180528223;5914.905742437662;6114.419130182626;6129.817282374534;6146.661933593606;6127.080937859357;6256.468200203034;6263.624182548971;6264.185655864709;6284.12022316876;4915.754212289371;6023.518118550505;6027.578069535178;6113.263474096239;6213.627182369072;5103.209002604603;6261.154744109578;6261.9373806262265;6269.561130720897;6278.466330361879;4250.787460626956;4998.775055122528;5002.649814513022;5006.048850718352;5015.27278544609;4310.676699247569;4358.825646974149;4443.555644435531;4456.799444025027;4461.598456138898;5681.204631351614;5720.141978703228;5739.483446489836;5775.335836378787;5780.595358324961;6092.177556029879;6286.308506064329;6296.496280297565;6336.239651241881;6355.491560675135;5604.047311956896;5641.435856414326;5647.491725744702;5740.196372507852;5741.913871291934;4884.804607815648;5427.348718153847;5471.316085489314;5478.91781623276;5479.04116779562;6259.061140828912;6275.811725648238;6340.841478963062;6366.6180029198395;6373.012183111625;6177.938220617736;6311.0217916223055;6314.842128946813;6323.9683801580695;6337.176167098248;6331.746730130743;6339.1125242467015;6341.556091073765;6357.618542887441;6357.682115894279;6010.579259451796;6031.396860313934;6221.906671400023;6232.937670623236;6242.157308763441;5123.538881500683;6108.75564977396;6163.599366041193;6183.041628547582;6224.315325314303;6006.729697163551;6100.359462256399;6130.478909292582;6138;6148.292946311231;5870.119482077723;6109.511239100772;6116;6132.448079672851;6142.938570614237;5628.380878103487;5667.4615911488345;5677.716114194356;5711.171880077449;5777.537796976148;4832.226843704991;5579.77245006098;5767.019606666793;5794.956907756476;5827.5629327801435;5703.458171473703;5714.057180565326;5720.48515633595;5726.627769194989;5739.167820666064 10:49:39 INFO - PROCESS | 1976 | |1;ext.html;489.0683248411775;490.32090554581913;494.127864319476;499.59782375187984;502.76872638586235;29994;38819.80590097049;40253.59746402535;40261.59738402619;40294.798526007326;41549;41953;41989.7900510497;41991.370129448085;42432.57567424325;8320;9216.17054465098;9409.106134917172;9423.293253006052;9472.857907131398;8366.456180348288;11088.944555277212;11331.150163737711;11337.433128343591;11523.942380288128;11262.605808796694;11329.376884271362;11376.829347559793;11422.771544569103;11422.942885285562;8430.45202061864;8632.784180395478;11169.888301117047;11318.20772545922;11411.771764564744;11014.44927753609;11254.268472549258;11277.323360598308;11318.037966772834;11326.20716549842;39533.01167470836;39927.8003609983;39936;39958.200835983116;39998.200035999114;3728.738988270822;3806.2958352704836;3816.866409675648;3884.553276373225;3904.863329783472;28575.85712071447;29138.271543211376;30424;30911.84544077288;31420.05739827786;3195.936081278385;3216.2924156685726;3226.322472280824;3245.269814291789;3255.283837555734;497227;504349;504412;504478;505576;58487.415125848194;58498.7075064622;60822.087668685;60914.78170436566;61255.38744612586;52268.95462090737;63318.68340658267;63632;63711.6814415925;63738;15594.908356414942;15839.524814255361;15860.52418427449;15864.841351586567;15883.682326353408;10734.946325268324;11020.669379918616;11029.062529685067;12148.21036632616;12238.449269782881;16862.747058794128;17188.968661880328;17197.398091066752;17205.91397043007;17687.38094166698;6098.35967223444;6129.774045190979;6235.220597425322;6242.909759668993;6252.468540174136;695.9898439250263;829.4152622401193;837.3050368194384;850.9361797865184;863.119617989647;23444;29288.560671589945;29354.559681604795;29883.850580746956;30016;789.72754399732;1013.7668336282696;1049.7375656085978;1072.9195310351754;1085.1969542538623;12846.421911014028;14271.215083170318;14711;15191.772123418157;15641.843581564266;611250;614769;615665;616466;617881;58351.124733129036;58434.415655843746;58533.70733146392;58648.82702345929;58923.70538147282;1305.3603734170379;1343.743599734232;1352.2218282958272;1359.3543067043076;1360.8843248324004;28782.568261476514;28920.421591568473;29213;29928.102156935325;30054.699453006066;50284;50486;50558;50741;50754.477365679086;529262;531392;532107;532464;534043;22045.66931496061;24506;24892.75107248977;25128.874355628104;25133.24600261995;34112.31775364429;35531.64468355283;40876.77369678915;41643;42097;35057.298854023284;35279;35407.645923541466;35563.288734224654;35643.28713425665;35023.29953400969;35276.47085293671;35311.82344088263;35466.8226658865;35659.46510802392;34171;34638.65361346455;34680;34865.65134348624;35348;29780.255493612833;31459.37081258408;31738.04785856428;31822.52266216055;31847;23383.88308058449;23521.64717529273;23711.881440592686;24048.87975560181;24136.396590085387 10:49:39 INFO - PROCESS | 1976 | |2;jquery.html;339.28580338387695;365.10549154571305;386.8046636448593;398.9481367422235;415.99168016639646;14006.94947878908;14713.632159196011;18304.861104169384;20096;20410.489737756565;15363.92318038411;18598.256069757193;19040.952747598876;19313.130909109088;19385.612287754237;5221.530062294383;5659.009673307155;5718.799842005531;5737.7991770288045;5744.080947048473;4077.3476243800997;4179.519355274137;5603.103503439451;5706.28671416073;5715.885682286373;4322.351647252922;4394.07724377881;6421.7752378666755;6461.418472337488;6472.449841763456;6148.538859585549;6309.810705678837;6431.196100487439;6442.613443193423;6451.7096730647;5589.217509548666;5593.636413633101;5594.888102237933;5602.38373778884;5610.719464026783;24230.515389692107;24518.754812452004;24938.5012299753;25145.994160233568;25320.746792532023;2648.602709593557;2674.8930042798265;2695.3396417877548;2700.5949107633814;2716.8641567921527;14897;15258.237088145659;15358.616034599112;15420.92289538545;15965.680686386208;1595.9760603590955;1797.91010449477;1843.834054935049;1847.5196448923243;1867.6358110168524;126009;127328;131465.342673286;133247.33376333152;133285;22372.328830134797;22703.20538781168;23090.30729078097;23534.176303829285;23536;31007.534886976715;32161.356772864412;36862;37484.43773343402;38494.84515464541;8154.510729356258;9890.91199968003;9965.152961998167;10624.681259562225;10891.564337426415;8687.522186279815;8712.564371781385;8732.95633521841;8840.381173317806;8861.512616806009;8571;10370.792584148276;10427.217958653131;10500.377369807557;10552.47237638115;3931.567527571965;3975.4036894465485;3995.2233197927744;4002.939955900664;4003.8598649047144;801.551131366425;821.2731732416735;828.0973738624807;834.4284165346756;847.6185716427632;19923.800761992483;20481.692774608393;20641.071151798205;20652.690209646866;20738.377848664262;846.6824940647257;848.1772680499905;853.906070332263;862.0259107208814;872.821071680304;10098.141657959155;10523.894761052445;10545.683629491128;10546.894531054591;10585.788284234271;711933;732394;750085.4991450125;768394;791214;137339;148088;155803;158641;162839;927.79124696944;938.2119020023214;964.5755867418313;969.2633598465077;998;17809.643807123524;17978.820211797716;18478.630427391647;18516.537086572942;18527.53681157982;41317.98147680727;50295.99408011892;54492.727536362065;60414.69792651009;60525.39474605198;85818;86007.56996214979;86160.56919715361;86569.56715216384;89917;7244.601546914971;7327.560346379234;7660.69357225727;8053.718119865776;8059.274665279919;11805.704857378634;11958.402079896145;12171.208871423329;12202.75594488123;12362.690932726753;9746.415215087118;10373.636922707668;10502.317349372266;10671.46642667848;10686.931306869561;8485.660573576988;10200.4389758564;10595.841062383915;10941;11224.887751122711;10253.846192306972;10899.78200435971;10955.780884382428;11122;11493.48279327449;8034.156413576724;8253.79365515867;8297.294729948022;8344.415890887822;8388.077311495852;7671.61641917913;10701.571937122739;10875.456227188768;10978.011978921615;10993.890061099606 10:49:39 INFO - PROCESS | 1976 | |3;mootools.html;1306.7255876265983;1313.3761463304932;1314.3428285857071;1321.286505287145;1329.5479536957432;7921.841563168733;8207.630656620451;8332;8335.208155225258;8346.123657016016;7273.527220730662;7488.176300606929;7496.737614183505;7909.169537198598;8146.26683598476;2259.89830457629;2318.6869772580653;2323.9419014524688;2324.4537533679654;2326.3718795925174;2295.735990361113;2319.35058183708;2337.123578658003;2337.146941366406;2340.789328960393;1627.4385337058695;2067.390119914629;2226.3209721035078;2286.4055345610177;2291.415688999311;2291.0950174680993;2310.7920287174234;2311.4452531392512;2315.131825565413;2318.038014224095;2017.6469117904455;2177.8366622503218;2238.205437069833;2300.815934725218;2308.4921317310163;8290.129536398652;8344.207300306462;8344.499330040217;8347.582620868932;8348.457350272212;4971.527704868032;5122.743862806845;5131.563817075553;5135.12702840518;5139.331886854722;3230.660780618048;3413.6927676509235;3431.2965842002577;3497.7376696747847;3504.54440922681;1163.9010684091863;1184.2775906696909;1236.5479620224344;1238.8389509363815;1247.095855504761;10587.15302775776;10602.204834637432;10610.045095941403;10627.52176152075;10636.521356538975;4383.583559561836;4616.676832621752;4665;4667.556582124693;4680.531946805292;9310.441373517611;9314.254859611216;9320.533973301308;9322.440653560809;9331.253499720005;5116.9744151279;5145.562627176657;5148.6910785353;5151.6136289778415;5151.819686311036;4423.601875831127;4586.036932244199;4649.18639238132;4758.0246049559755;4762.9523704763205;3736.271427071695;3803.4865293185067;3819.560750513671;3875.6318149775725;3898.922021559553;1255.12768625806;1257.7861763500227;1260.1431026901612;1272.6182145356356;1273.8917192038596;807.6123460738761;830.2444775254489;844.607257625195;866.7659731872359;878.1438097854541;5195.350581177353;5346.251524786533;5374.1938709193155;5464.863378415572;5554.000279949567;520.924291338385;539.7544117426562;545.6453305351474;553.6899336371645;554.3984776517443;3465.5841299043773;3517.9120521987156;3598.6401359863803;3618.9819050904575;3619.7285203609836;9606.183474404614;9975.351602145838;9982.301238913205;9988.250881183794;9993.300468967247;9747.853782193273;9915.454649994319;9936.254780891462;9941.80116397668;9950.054744799232;7374.299441553044;7604.163542010375;7613.895985082132;7726.420518461138;7751.534907905544;5248.76380562868;5329.658850098967;5345.679259244458;5405.378381486179;5457.754401052041;8959.596818143329;8966.82066358656;9026.593803279;9040;9174.495402752913;8346.666133354598;8675;8838.779030524289;9243.260539156985;9467.005964373917;6481.4166724994975;6710.932890671226;6996.650167491503;7004.4046256068805;7071.7878463646175;7621.12357078943;7812.3750099993;7860.882086768589;7889.250521200477;7889.526628402315;6883.242843287134;6895.5173137879865;6922.5154239202775;7017.157941047107;7081.7167313308955;6853.485988550979;6862.828429289308;6941.965290173517;6956.339147780953;7051.189113252041;6214.223222097238;6231.781887633911;6246.781362652286;6336.651484168415;6337.176167098248;4707.81168753246;4804.279358096173;4848.333416620774;4860.878478038077;4984.302197692254;4038.7172897896867;4821.228603423436;4887.218045112765;4951.8266860659705;4977.879977005145 10:49:39 INFO - PROCESS | 1976 | |4;prototype.html;355.42243853737676;355.47744815121763;360.5835260274382;372.44691632925117;372.47853005791904;4648.513045520647;5323.787048518059;5348;5381.838544843651;5381.892362152755;5012.799488020475;5137.743112844361;5143;5170.242117679997;5252.579793616511;4023.7786921719294;4027.4562934003975;4127.731697439672;4129.2154490646835;4194.643455306303;4147.274227010276;4164.8338465229635;4188.570629035167;4217.40956266123;4238.830446782125;3260.5490556702275;3934.390169523728;4057.2696914555368;4128.071183983595;4136.503619565657;3239.4330992076293;3592.5868525119686;4105.0763578194965;4148.792560371984;4154.065335299563;3262.983685081583;3653.7442379033478;3684.889453316405;3690.7785532867847;3707.330721880269;5053.545180933734;5146;5175.818846340398;5211.895762084775;5221.869453263661;4766.713997160146;4775.450823155347;4801.4718380978065;4803.7357945313;4821.975890120562;5114.744262786846;5552.750126244328;5579.637323573996;5605.309451463279;5645.165321270562;3952.664023558001;4094.1811637672586;4106.507219133723;4106.897327566835;4111.48606424197;4902.240152776325;5179.715115668637;5305.44292849253;5347.999999999961;5796.362654991968;4162.542120366757;4169.374593810952;4170.791460426966;4176.0395109124465;4182.519010313792;3763.5483741950593;3901.6293452122004;4173.914782156661;4180.4708151568475;4276.2089013532595;4269.929832616358;4446.799894004777;4616.630669546428;4682.765861706901;4784.210605250164;5278.709670968134;5603.523700485504;5609;5628.240187574625;5642.633228840113;1303.6089173247988;1372.4715984346099;1381.8134551835374;1387.882030027459;1388.687545302319;825.1336097098023;884.5046773806561;912.2473958983865;914.3553794574809;978.9657361992296;4092.6725861930972;4972.8508144755715;4978.751062446863;5018.946021335488;5085.821996230115;4750.904981900343;4848.539388758062;5170.844874653766;5271.551918087006;5471.699056551928;4586.908261834811;4654.790534425959;4855.587275081587;4942;5270.420253772082;2412.915547955813;2721.877515511807;2797.412543365916;2892.815923977426;2914.591957126004;4829.643397838468;5012.674176178537;5304.9920515102;5375.709829640936;5845.937073733714;5113.181890897438;5195.428502864682;5270.655982724381;5347.331583552056;5698.772049118072;4914.40503129575;5221.738913054332;5522.7573795895605;5596.300462442195;5770.307563092372;4689.695169813951;5089.949100508948;5433.972830135824;5508.421615730291;5575.916361254665;5073.644844860972;5144.614153938394;5208.609354298518;5231.633785634969;5444.782208711766;4564.7032942858605;5440.143924439701;5577.581681373832;5591.750269794904;5680.6875621839545;3096.504559270506;3406.591209054929;3448.5516882805073;3601.819909004592;3679.889603311905;4623.768811559476;4800.639952003544;5376.704281264411;5417.349918009864;5417.756200970887;5214.608904332266;5217.4260831307765;5226.268322434938;5251.921221181762;5263.499967503081;4740.525947405232;5147;5173.922391164211;5293.391260005149;5322.54758345545;4668.439787225555;4963.812815607771;5038.722870242172;5227.2420499028185;5325.618232422517;4561.452625684939;5202.947970520247;5539.473749993744;5580.102765059899;5592.944070559405;4190.459062092527;4600.148972440109;4737.4788773235605;5313.54834839043;5389 10:49:39 INFO - PROCESS | 1976 | |5;yui.html;617.1637431280617;636.8726254749051;705.0552259971637;715.7959981405301;724.3046675191814;14183.361748721307;15038.548843534683;15056.623584410381;15076.020058696176;15093.094414335133;13370.465181392758;13496.865031349684;13500.392482338293;13516.459341626347;13583.185008899472;683.634255673215;703.2931903437038;879.9516026618534;891.3270480786997;895.6909866096187;658.1805651963313;696.0950764006797;699.6816448515939;873.3449912565576;883.0286684646901;881.6341218394359;923.5059243304834;924.7272054743867;933.2906990687093;936.9953150234273;933.4306073295285;933.8459154239542;934.8317302885478;935.3546053223288;935.3966691483989;696.989545156823;698.1133959870987;701.2776839854957;701.3162166887295;702.761061239176;13970.091944023607;14311.35598898052;14333.78499322511;14444.638884027983;14463.78304325436;13500.594982150551;13654.04421690482;13837.377318020714;13940.024198306122;14012.649683757885;10725.356478611307;10791.352518848817;10990.340579565252;11112.055475284611;11322.490487928064;3500.8732392972415;3512.3853325667906;3617.113807117247;3802.5056742623283;3806.619338066199;98771.50614246883;99351;102036.95926081511;103214;104576;8904;10457.006584374472;10529.315594486334;10554.419506927039;10672.359658420519;20729.89635051815;20996.370108896757;21128.260510882046;21169.15323387078;21196.894015529822;7036.718531258794;7070.92929070713;7103.056451652998;7168.605726685082;7185.53294035886;4065.97967010169;4097.1396006838895;4107.199096176277;4113.85601503946;4137.668986481074;3279.983600082032;3330.900072997814;3360.1263671445113;3398.4222682144095;3400.3369342978376;621.6518749500294;622.6108682073703;624.2664868779166;625.5777350288537;635.9777407790705;620.8975519039399;623.3921926121994;628.6479571440008;653.5555822041058;665.9400653941168;9185.402948808307;9937.354071985297;10002.149817265614;10123.7469063274;10272;639.2616527910271;645.7029766307458;669.6852479334705;684.7603338831369;696.2828286864537;4755.738434386073;4789;4794.328793968847;4891.633127515451;4899.24061770419;86768;90737.54631226802;91533.7116572091;98158.52762208572;98305;77360;79356.60321698355;80831;86701;87027.56486217528;72528.54942901219;73957.63021184859;74466.8829967551;77819;80118.59940700259;10361.74095647615;10600.681979540626;10760.62337818204;10873.510692019036;10878.184136189975;40211.79894100511;49819.50180498149;51265;53977.19034214412;54375.45624543704;68012.97980530396;68548.6572567134;70411.29588704047;71310.64344678243;71382.64308678423;12005.63983080509;12108.394580270775;12195.329256890956;12221;12569.685757856127;11658.125640576822;11687.357195353998;12473.251604903735;13557;13985.510507132201;12759.808602870778;13019.349032548524;13614.455421783017;14163.22102284343;14290.214038227998;11296.887031129583;13306.733865322833;13673.495915449492;13678.658033549242;14279.357428915517;12691.873081269068;13149;13296.667583310495;13838.100523465942;13842.377093031038;8775.780605484913;10890.401027943222;11017.614383496539;11231.15766317513;11486.770264594828;8386.916130838857;8690.956545217234;10469.895301047196;10640.733981650521;11109.055730263019 10:49:39 INFO - PROCESS | 1976 | __end_tp_report 10:49:39 INFO - PROCESS | 1976 | __start_cc_report 10:49:39 INFO - PROCESS | 1976 | _x_x_mozilla_cycle_collect,1283 10:49:39 INFO - PROCESS | 1976 | __end_cc_report 10:49:39 INFO - PROCESS | 1976 | __startTimestamp1486666179511__endTimestamp 10:49:39 INFO - PROCESS | 1976 | 10:49:39 INFO - PROCESS | 1976 | ------- Summary: start ------- 10:49:39 INFO - PROCESS | 1976 | Number of tests: 6 10:49:39 INFO - PROCESS | 1976 | 10:49:39 INFO - PROCESS | 1976 | [#0] dojo.html Cycles:165 Average:5669.16 Median:5760.40 stddev:666.89 (11.6%) stddev-sans-first:629.97 10:49:39 INFO - PROCESS | 1976 | Values: 2805.5 3283.8 3329.2 3341.6 3377.1 5687.6 5692.9 5705.3 5725.7 5740.5 5659.3 5752.5 5755.7 5760.4 5766.2 5161.5 5187.3 5207.4 5225.1 5256.8 5087.6 5097.3 5260.4 5286.0 5337.8 5137.2 5138.6 5170.6 5173.5 5252.3 5206.8 5259.8 5260.3 5261.7 5263.6 4389.6 5177.2 5179.5 5186.4 5245.0 5479.9 5524.6 5544.8 5560.8 5661.8 6158.6 6175.3 6182.5 6200.5 6210.0 6164.5 6188.4 6212.8 6232.6 6254.0 5609.7 5796.6 5818.1 5847.2 5880.0 6116.6 6224.0 6229.5 6237.5 6259.9 5281.9 5914.9 6114.4 6129.8 6146.7 6127.1 6256.5 6263.6 6264.2 6284.1 4915.8 6023.5 6027.6 6113.3 6213.6 5103.2 6261.2 6261.9 6269.6 6278.5 4250.8 4998.8 5002.6 5006.0 5015.3 4310.7 4358.8 4443.6 4456.8 4461.6 5681.2 5720.1 5739.5 5775.3 5780.6 6092.2 6286.3 6296.5 6336.2 6355.5 5604.0 5641.4 5647.5 5740.2 5741.9 4884.8 5427.3 5471.3 5478.9 5479.0 6259.1 6275.8 6340.8 6366.6 6373.0 6177.9 6311.0 6314.8 6324.0 6337.2 6331.7 6339.1 6341.6 6357.6 6357.7 6010.6 6031.4 6221.9 6232.9 6242.2 5123.5 6108.8 6163.6 6183.0 6224.3 6006.7 6100.4 6130.5 6138.0 6148.3 5870.1 6109.5 6116.0 6132.4 6142.9 5628.4 5667.5 5677.7 5711.2 5777.5 4832.2 5579.8 5767.0 5795.0 5827.6 5703.5 5714.1 5720.5 5726.6 5739.2 10:49:39 INFO - PROCESS | 1976 | 10:49:39 INFO - PROCESS | 1976 | [#1] ext.html Cycles:180 Average:67798.68 Median:35295.41 stddev:147488.58 (417.9%) stddev-sans-first:147815.74 10:49:39 INFO - PROCESS | 1976 | Values: 489.1 490.3 494.1 499.6 502.8 29994.0 38819.8 40253.6 40261.6 40294.8 41549.0 41953.0 41989.8 41991.4 42432.6 8320.0 9216.2 9409.1 9423.3 9472.9 8366.5 11088.9 11331.2 11337.4 11523.9 11262.6 11329.4 11376.8 11422.8 11422.9 8430.5 8632.8 11169.9 11318.2 11411.8 11014.4 11254.3 11277.3 11318.0 11326.2 39533.0 39927.8 39936.0 39958.2 39998.2 3728.7 3806.3 3816.9 3884.6 3904.9 28575.9 29138.3 30424.0 30911.8 31420.1 3195.9 3216.3 3226.3 3245.3 3255.3 497227.0 504349.0 504412.0 504478.0 505576.0 58487.4 58498.7 60822.1 60914.8 61255.4 52269.0 63318.7 63632.0 63711.7 63738.0 15594.9 15839.5 15860.5 15864.8 15883.7 10734.9 11020.7 11029.1 12148.2 12238.4 16862.7 17189.0 17197.4 17205.9 17687.4 6098.4 6129.8 6235.2 6242.9 6252.5 696.0 829.4 837.3 850.9 863.1 23444.0 29288.6 29354.6 29883.9 30016.0 789.7 1013.8 1049.7 1072.9 1085.2 12846.4 14271.2 14711.0 15191.8 15641.8 611250.0 614769.0 615665.0 616466.0 617881.0 58351.1 58434.4 58533.7 58648.8 58923.7 1305.4 1343.7 1352.2 1359.4 1360.9 28782.6 28920.4 29213.0 29928.1 30054.7 50284.0 50486.0 50558.0 50741.0 50754.5 529262.0 531392.0 532107.0 532464.0 534043.0 22045.7 24506.0 24892.8 25128.9 25133.2 34112.3 35531.6 40876.8 41643.0 42097.0 35057.3 35279.0 35407.6 35563.3 35643.3 35023.3 35276.5 35311.8 35466.8 35659.5 34171.0 34638.7 34680.0 34865.7 35348.0 29780.3 31459.4 31738.0 31822.5 31847.0 23383.9 23521.6 23711.9 24048.9 24136.4 10:49:39 INFO - PROCESS | 1976 | 10:49:39 INFO - PROCESS | 1976 | [#2] jquery.html Cycles:180 Average:41550.67 Median:352.20 stddev:124894.49 (35461.7%) stddev-sans-first:125206.51 10:49:39 INFO - PROCESS | 1976 | Values: 339.3 365.1 386.8 398.9 416.0 14006.9 14713.6 18304.9 20096.0 20410.5 15363.9 18598.3 19041.0 19313.1 19385.6 5221.5 5659.0 5718.8 5737.8 5744.1 4077.3 4179.5 5603.1 5706.3 5715.9 4322.4 4394.1 6421.8 6461.4 6472.4 6148.5 6309.8 6431.2 6442.6 6451.7 5589.2 5593.6 5594.9 5602.4 5610.7 24230.5 24518.8 24938.5 25146.0 25320.7 2648.6 2674.9 2695.3 2700.6 2716.9 14897.0 15258.2 15358.6 15420.9 15965.7 1596.0 1797.9 1843.8 1847.5 1867.6 126009.0 127328.0 131465.3 133247.3 133285.0 22372.3 22703.2 23090.3 23534.2 23536.0 31007.5 32161.4 36862.0 37484.4 38494.8 8154.5 9890.9 9965.2 10624.7 10891.6 8687.5 8712.6 8733.0 8840.4 8861.5 8571.0 10370.8 10427.2 10500.4 10552.5 3931.6 3975.4 3995.2 4002.9 4003.9 801.6 821.3 828.1 834.4 847.6 19923.8 20481.7 20641.1 20652.7 20738.4 846.7 848.2 853.9 862.0 872.8 10098.1 10523.9 10545.7 10546.9 10585.8 711933.0 732394.0 750085.5 768394.0 791214.0 137339.0 148088.0 155803.0 158641.0 162839.0 927.8 938.2 964.6 969.3 998.0 17809.6 17978.8 18478.6 18516.5 18527.5 41318.0 50296.0 54492.7 60414.7 60525.4 85818.0 86007.6 86160.6 86569.6 89917.0 7244.6 7327.6 7660.7 8053.7 8059.3 11805.7 11958.4 12171.2 12202.8 12362.7 9746.4 10373.6 10502.3 10671.5 10686.9 8485.7 10200.4 10595.8 10941.0 11224.9 10253.8 10899.8 10955.8 11122.0 11493.5 8034.2 8253.8 8297.3 8344.4 8388.1 7671.6 10701.6 10875.5 10978.0 10993.9 10:49:39 INFO - PROCESS | 1976 | 10:49:39 INFO - PROCESS | 1976 | [#3] mootools.html Cycles:180 Average:5316.91 Median:2858.14 stddev:2925.50 (102.4%) stddev-sans-first:2918.18 10:49:39 INFO - PROCESS | 1976 | Values: 1306.7 1313.4 1314.3 1321.3 1329.5 7921.8 8207.6 8332.0 8335.2 8346.1 7273.5 7488.2 7496.7 7909.2 8146.3 2259.9 2318.7 2323.9 2324.5 2326.4 2295.7 2319.4 2337.1 2337.1 2340.8 1627.4 2067.4 2226.3 2286.4 2291.4 2291.1 2310.8 2311.4 2315.1 2318.0 2017.6 2177.8 2238.2 2300.8 2308.5 8290.1 8344.2 8344.5 8347.6 8348.5 4971.5 5122.7 5131.6 5135.1 5139.3 3230.7 3413.7 3431.3 3497.7 3504.5 1163.9 1184.3 1236.5 1238.8 1247.1 10587.2 10602.2 10610.0 10627.5 10636.5 4383.6 4616.7 4665.0 4667.6 4680.5 9310.4 9314.3 9320.5 9322.4 9331.3 5117.0 5145.6 5148.7 5151.6 5151.8 4423.6 4586.0 4649.2 4758.0 4763.0 3736.3 3803.5 3819.6 3875.6 3898.9 1255.1 1257.8 1260.1 1272.6 1273.9 807.6 830.2 844.6 866.8 878.1 5195.4 5346.3 5374.2 5464.9 5554.0 520.9 539.8 545.6 553.7 554.4 3465.6 3517.9 3598.6 3619.0 3619.7 9606.2 9975.4 9982.3 9988.3 9993.3 9747.9 9915.5 9936.3 9941.8 9950.1 7374.3 7604.2 7613.9 7726.4 7751.5 5248.8 5329.7 5345.7 5405.4 5457.8 8959.6 8966.8 9026.6 9040.0 9174.5 8346.7 8675.0 8838.8 9243.3 9467.0 6481.4 6710.9 6996.7 7004.4 7071.8 7621.1 7812.4 7860.9 7889.3 7889.5 6883.2 6895.5 6922.5 7017.2 7081.7 6853.5 6862.8 6942.0 6956.3 7051.2 6214.2 6231.8 6246.8 6336.7 6337.2 4707.8 4804.3 4848.3 4860.9 4984.3 4038.7 4821.2 4887.2 4951.8 4977.9 10:49:39 INFO - PROCESS | 1976 | 10:49:39 INFO - PROCESS | 1976 | [#4] prototype.html Cycles:180 Average:4426.23 Median:4952.91 stddev:1287.94 (26.0%) stddev-sans-first:1254.79 10:49:39 INFO - PROCESS | 1976 | Values: 355.4 355.5 360.6 372.4 372.5 4648.5 5323.8 5348.0 5381.8 5381.9 5012.8 5137.7 5143.0 5170.2 5252.6 4023.8 4027.5 4127.7 4129.2 4194.6 4147.3 4164.8 4188.6 4217.4 4238.8 3260.5 3934.4 4057.3 4128.1 4136.5 3239.4 3592.6 4105.1 4148.8 4154.1 3263.0 3653.7 3684.9 3690.8 3707.3 5053.5 5146.0 5175.8 5211.9 5221.9 4766.7 4775.5 4801.5 4803.7 4822.0 5114.7 5552.8 5579.6 5605.3 5645.2 3952.7 4094.2 4106.5 4106.9 4111.5 4902.2 5179.7 5305.4 5348.0 5796.4 4162.5 4169.4 4170.8 4176.0 4182.5 3763.5 3901.6 4173.9 4180.5 4276.2 4269.9 4446.8 4616.6 4682.8 4784.2 5278.7 5603.5 5609.0 5628.2 5642.6 1303.6 1372.5 1381.8 1387.9 1388.7 825.1 884.5 912.2 914.4 979.0 4092.7 4972.9 4978.8 5018.9 5085.8 4750.9 4848.5 5170.8 5271.6 5471.7 4586.9 4654.8 4855.6 4942.0 5270.4 2412.9 2721.9 2797.4 2892.8 2914.6 4829.6 5012.7 5305.0 5375.7 5845.9 5113.2 5195.4 5270.7 5347.3 5698.8 4914.4 5221.7 5522.8 5596.3 5770.3 4689.7 5089.9 5434.0 5508.4 5575.9 5073.6 5144.6 5208.6 5231.6 5444.8 4564.7 5440.1 5577.6 5591.8 5680.7 3096.5 3406.6 3448.6 3601.8 3679.9 4623.8 4800.6 5376.7 5417.3 5417.8 5214.6 5217.4 5226.3 5251.9 5263.5 4740.5 5147.0 5173.9 5293.4 5322.5 4668.4 4963.8 5038.7 5227.2 5325.6 4561.5 5202.9 5539.5 5580.1 5592.9 4190.5 4600.1 4737.5 5313.5 5389.0 10:49:39 INFO - PROCESS | 1976 | 10:49:39 INFO - PROCESS | 1976 | [#5] yui.html Cycles:180 Average:19647.63 Median:4110.53 stddev:27928.64 (679.4%) stddev-sans-first:27970.43 10:49:39 INFO - PROCESS | 1976 | Values: 617.2 636.9 705.1 715.8 724.3 14183.4 15038.5 15056.6 15076.0 15093.1 13370.5 13496.9 13500.4 13516.5 13583.2 683.6 703.3 880.0 891.3 895.7 658.2 696.1 699.7 873.3 883.0 881.6 923.5 924.7 933.3 937.0 933.4 933.8 934.8 935.4 935.4 697.0 698.1 701.3 701.3 702.8 13970.1 14311.4 14333.8 14444.6 14463.8 13500.6 13654.0 13837.4 13940.0 14012.6 10725.4 10791.4 10990.3 11112.1 11322.5 3500.9 3512.4 3617.1 3802.5 3806.6 98771.5 99351.0 102037.0 103214.0 104576.0 8904.0 10457.0 10529.3 10554.4 10672.4 20729.9 20996.4 21128.3 21169.2 21196.9 7036.7 7070.9 7103.1 7168.6 7185.5 4066.0 4097.1 4107.2 4113.9 4137.7 3280.0 3330.9 3360.1 3398.4 3400.3 621.7 622.6 624.3 625.6 636.0 620.9 623.4 628.6 653.6 665.9 9185.4 9937.4 10002.1 10123.7 10272.0 639.3 645.7 669.7 684.8 696.3 4755.7 4789.0 4794.3 4891.6 4899.2 86768.0 90737.5 91533.7 98158.5 98305.0 77360.0 79356.6 80831.0 86701.0 87027.6 72528.5 73957.6 74466.9 77819.0 80118.6 10361.7 10600.7 10760.6 10873.5 10878.2 40211.8 49819.5 51265.0 53977.2 54375.5 68013.0 68548.7 70411.3 71310.6 71382.6 12005.6 12108.4 12195.3 12221.0 12569.7 11658.1 11687.4 12473.3 13557.0 13985.5 12759.8 13019.3 13614.5 14163.2 14290.2 11296.9 13306.7 13673.5 13678.7 14279.4 12691.9 13149.0 13296.7 13838.1 13842.4 8775.8 10890.4 11017.6 11231.2 11486.8 8386.9 8691.0 10469.9 10640.7 11109.1 10:49:39 INFO - PROCESS | 1976 | -------- Summary: end -------- 10:49:39 INFO - PROCESS | 1976 | 10:49:40 INFO - TEST-INFO | 1976: exit 0 10:49:40 INFO - TEST-OK | dromaeo_css | took 1117677ms 10:49:40 INFO - TEST-START | kraken 10:49:40 INFO - operating with platform_type : mac_ 10:49:40 INFO - Initialising browser for kraken test... 10:49:40 INFO - TEST-INFO | started process 1979 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpctXx9n/profile http://localhost:49350/getInfo.html) 10:49:42 INFO - PROCESS | 1979 | __metrics Screen width/height:1600/1200 10:49:42 INFO - PROCESS | 1979 | colorDepth:24 10:49:42 INFO - PROCESS | 1979 | Browser inner width/height: 1024/689 10:49:42 INFO - PROCESS | 1979 | __metrics 10:49:42 INFO - PROCESS | 1979 | 1486666182382 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 10:49:42 INFO - PROCESS | 1979 | 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" 10:49:42 INFO - PROCESS | 1979 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:49:43 INFO - TEST-INFO | 1979: exit 0 10:49:43 INFO - Browser initialized. 10:49:43 INFO - Running cycle 1/1 for kraken test... 10:49:43 INFO - TEST-INFO | started process 1980 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpctXx9n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:49:55 INFO - PROCESS | 1980 | RSS: Main: 212738048 10:49:55 INFO - PROCESS | 1980 | 10:49:57 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49350/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49350/tests/kraken/driver.html?testName=audio-beat-detection) 10:49:58 INFO - PROCESS | 1980 | RSS: Main: 265678848 10:49:58 INFO - PROCESS | 1980 | 10:50:01 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49350/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49350/tests/kraken/driver.html?testName=audio-dft) 10:50:01 INFO - PROCESS | 1980 | RSS: Main: 275271680 10:50:01 INFO - PROCESS | 1980 | 10:50:05 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49350/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49350/tests/kraken/driver.html?testName=audio-fft) 10:50:05 INFO - PROCESS | 1980 | RSS: Main: 372326400 10:50:05 INFO - PROCESS | 1980 | 10:50:07 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49350/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49350/tests/kraken/driver.html?testName=audio-oscillator) 10:50:08 INFO - PROCESS | 1980 | RSS: Main: 275365888 10:50:08 INFO - PROCESS | 1980 | 10:50:10 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49350/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49350/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:50:11 INFO - PROCESS | 1980 | RSS: Main: 278032384 10:50:11 INFO - PROCESS | 1980 | 10:50:13 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49350/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49350/tests/kraken/driver.html?testName=imaging-darkroom) 10:50:14 INFO - PROCESS | 1980 | RSS: Main: 317972480 10:50:14 INFO - PROCESS | 1980 | 10:50:17 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49350/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49350/tests/kraken/driver.html?testName=imaging-desaturate) 10:50:17 INFO - PROCESS | 1980 | RSS: Main: 318697472 10:50:17 INFO - PROCESS | 1980 | 10:50:20 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49350/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49350/tests/kraken/driver.html?testName=json-parse-financial) 10:50:21 INFO - PROCESS | 1980 | RSS: Main: 316915712 10:50:21 INFO - PROCESS | 1980 | 10:50:22 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49350/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49350/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:50:23 INFO - PROCESS | 1980 | RSS: Main: 294440960 10:50:23 INFO - PROCESS | 1980 | 10:50:25 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49350/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49350/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:50:26 INFO - PROCESS | 1980 | RSS: Main: 297459712 10:50:26 INFO - PROCESS | 1980 | 10:50:28 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49350/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49350/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:50:28 INFO - PROCESS | 1980 | RSS: Main: 300855296 10:50:28 INFO - PROCESS | 1980 | 10:50:31 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49350/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49350/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:50:32 INFO - PROCESS | 1980 | RSS: Main: 316968960 10:50:32 INFO - PROCESS | 1980 | 10:50:35 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49350/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49350/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:50:35 INFO - PROCESS | 1980 | RSS: Main: 311029760 10:50:35 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49350/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49350/tests/kraken/driver.html?testName=ai-astar) 10:50:37 INFO - PROCESS | 1980 | RSS: Main: 355581952 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | __start_tp_report 10:50:37 INFO - PROCESS | 1980 | _x_x_mozilla_page_load 10:50:37 INFO - PROCESS | 1980 | _x_x_mozilla_page_load_details 10:50:37 INFO - PROCESS | 1980 | |i|pagename|runs| 10:50:37 INFO - PROCESS | 1980 | |0;ai-astar;116;116;115;116;114;114;114;115;115;114 10:50:37 INFO - PROCESS | 1980 | |1;audio-beat-detection;139;109;107;139;110;110;108;106;128;108 10:50:37 INFO - PROCESS | 1980 | |2;audio-dft;187;185;183;184;183;194;258;216;185;182 10:50:37 INFO - PROCESS | 1980 | |3;audio-fft;82;58;60;88;60;59;70;80;95;82 10:50:37 INFO - PROCESS | 1980 | |4;audio-oscillator;90;70;86;68;67;69;80;66;68;82 10:50:37 INFO - PROCESS | 1980 | |5;imaging-gaussian-blur;88;85;87;85;86;83;88;85;88;83 10:50:37 INFO - PROCESS | 1980 | |6;imaging-darkroom;94;94;94;95;93;97;93;96;95;94 10:50:37 INFO - PROCESS | 1980 | |7;imaging-desaturate;85;84;84;87;87;84;85;88;89;86 10:50:37 INFO - PROCESS | 1980 | |8;json-parse-financial;58;55;53;54;55;55;55;57;57;57 10:50:37 INFO - PROCESS | 1980 | |9;json-stringify-tinderbox;68;73;77;75;75;70;69;69;71;71 10:50:37 INFO - PROCESS | 1980 | |10;stanford-crypto-aes;83;83;84;84;83;85;82;83;86;84 10:50:37 INFO - PROCESS | 1980 | |11;stanford-crypto-ccm;138;135;159;146;127;142;157;138;201;180 10:50:37 INFO - PROCESS | 1980 | |12;stanford-crypto-pbkdf2;166;166;169;169;169;165;165;166;166;169 10:50:37 INFO - PROCESS | 1980 | |13;stanford-crypto-sha256-iterative;66;65;66;66;65;65;64;65;63;63 10:50:37 INFO - PROCESS | 1980 | __end_tp_report 10:50:37 INFO - PROCESS | 1980 | __start_cc_report 10:50:37 INFO - PROCESS | 1980 | _x_x_mozilla_cycle_collect,1990 10:50:37 INFO - PROCESS | 1980 | __end_cc_report 10:50:37 INFO - PROCESS | 1980 | __startTimestamp1486666237927__endTimestamp 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | ------- Summary: start ------- 10:50:37 INFO - PROCESS | 1980 | Number of tests: 14 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | [#0] ai-astar Cycles:10 Average:114.90 Median:115.00 stddev:0.88 (0.8%) stddev-sans-first:0.83 10:50:37 INFO - PROCESS | 1980 | Values: 116.0 116.0 115.0 116.0 114.0 114.0 114.0 115.0 115.0 114.0 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | [#1] audio-beat-detection Cycles:10 Average:116.40 Median:110.00 stddev:13.46 (12.2%) stddev-sans-first:11.53 10:50:37 INFO - PROCESS | 1980 | Values: 139.0 109.0 107.0 139.0 110.0 110.0 108.0 106.0 128.0 108.0 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | [#2] audio-dft Cycles:10 Average:195.70 Median:186.00 stddev:24.15 (13.0%) stddev-sans-first:25.41 10:50:37 INFO - PROCESS | 1980 | Values: 187.0 185.0 183.0 184.0 183.0 194.0 258.0 216.0 185.0 182.0 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | [#3] audio-fft Cycles:10 Average:73.40 Median:81.00 stddev:13.69 (16.9%) stddev-sans-first:14.16 10:50:37 INFO - PROCESS | 1980 | Values: 82.0 58.0 60.0 88.0 60.0 59.0 70.0 80.0 95.0 82.0 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | [#4] audio-oscillator Cycles:10 Average:74.60 Median:75.00 stddev:8.96 (11.9%) stddev-sans-first:7.57 10:50:37 INFO - PROCESS | 1980 | Values: 90.0 70.0 86.0 68.0 67.0 69.0 80.0 66.0 68.0 82.0 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:86.50 stddev:1.93 (2.2%) stddev-sans-first:1.88 10:50:37 INFO - PROCESS | 1980 | Values: 88.0 85.0 87.0 85.0 86.0 83.0 88.0 85.0 88.0 83.0 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.50 stddev:1.27 (1.3%) stddev-sans-first:1.33 10:50:37 INFO - PROCESS | 1980 | Values: 94.0 94.0 94.0 95.0 93.0 97.0 93.0 96.0 95.0 94.0 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | [#7] imaging-desaturate Cycles:10 Average:85.90 Median:86.50 stddev:1.79 (2.1%) stddev-sans-first:1.87 10:50:37 INFO - PROCESS | 1980 | Values: 85.0 84.0 84.0 87.0 87.0 84.0 85.0 88.0 89.0 86.0 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:1.58 (2.8%) stddev-sans-first:1.41 10:50:37 INFO - PROCESS | 1980 | Values: 58.0 55.0 53.0 54.0 55.0 55.0 55.0 57.0 57.0 57.0 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:72.00 stddev:3.05 (4.2%) stddev-sans-first:2.91 10:50:37 INFO - PROCESS | 1980 | Values: 68.0 73.0 77.0 75.0 75.0 70.0 69.0 69.0 71.0 71.0 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.00 stddev:1.16 (1.4%) stddev-sans-first:1.20 10:50:37 INFO - PROCESS | 1980 | Values: 83.0 83.0 84.0 84.0 83.0 85.0 82.0 83.0 86.0 84.0 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | [#11] stanford-crypto-ccm Cycles:10 Average:152.30 Median:151.50 stddev:22.85 (15.1%) stddev-sans-first:23.65 10:50:37 INFO - PROCESS | 1980 | Values: 138.0 135.0 159.0 146.0 127.0 142.0 157.0 138.0 201.0 180.0 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:167.50 stddev:1.76 (1.1%) stddev-sans-first:1.83 10:50:37 INFO - PROCESS | 1980 | Values: 166.0 166.0 169.0 169.0 169.0 165.0 165.0 166.0 166.0 169.0 10:50:37 INFO - PROCESS | 1980 | 10:50:37 INFO - PROCESS | 1980 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.00 stddev:1.14 (1.7%) stddev-sans-first:1.12 10:50:37 INFO - PROCESS | 1980 | Values: 66.0 65.0 66.0 66.0 65.0 65.0 64.0 65.0 63.0 63.0 10:50:37 INFO - PROCESS | 1980 | -------- Summary: end -------- 10:50:37 INFO - PROCESS | 1980 | 10:50:38 INFO - TEST-INFO | 1980: exit 0 10:50:38 INFO - TEST-OK | kraken | took 58701ms 10:50:38 INFO - SUITE-END | took 1176s 10:50:39 INFO - Completed test suite (00:19:36) 10:50:39 INFO - javascript benchmark 10:50:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2805.5090359187143, 3283.7516065077252, 3329.1510664780476, 3341.599008119023, 3377.105067157204, 5687.601867869251, 5692.943070569293, 5705.315362156547, 5725.713714314284, 5740.454656807602, 5659.264295641561, 5752.482276595106, 5755.740991655375, 5760.395158508359, 5766.192733017381, 5161.509656582628, 5187.299714538527, 5207.375114986207, 5225.137852254393, 5256.8160114396005, 5087.643864929457, 5097.260897169908, 5260.421353651095, 5286.04851126797, 5337.813176538822, 5137.229415587654, 5138.6146039047035, 5170.586353091744, 5173.508516690908, 5252.343457067866, 5206.817761378353, 5259.842204733826, 5260.289860868772, 5261.657992230493, 5263.552598029172, 4389.604935555815, 5177.249298851683, 5179.456157103487, 5186.351706036746, 5245.0, 5479.863003424946, 5524.558035357162, 5544.833654990316, 5560.8331750047155, 5661.801836935688, 6158.599691020114, 6175.258968923713, 6182.474489668383, 6200.534959878028, 6209.96895015522, 6164.537659675542, 6188.381161883821, 6212.81361559154, 6232.626042437469, 6253.968730156365, 5609.747561359749, 5796.594238403271, 5818.1272809078555, 5847.152162936435, 5879.970600147015, 6116.618888358023, 6223.96888015566, 6229.53278504114, 6237.4698150656795, 6259.874802503924, 5281.947180528223, 5914.905742437662, 6114.419130182626, 6129.817282374534, 6146.661933593606, 6127.080937859357, 6256.468200203034, 6263.624182548971, 6264.185655864709, 6284.12022316876, 4915.754212289371, 6023.518118550505, 6027.578069535178, 6113.263474096239, 6213.627182369072, 5103.209002604603, 6261.154744109578, 6261.9373806262265, 6269.561130720897, 6278.466330361879, 4250.787460626956, 4998.775055122528, 5002.649814513022, 5006.048850718352, 5015.27278544609, 4310.676699247569, 4358.825646974149, 4443.555644435531, 4456.799444025027, 4461.598456138898, 5681.204631351614, 5720.141978703228, 5739.483446489836, 5775.335836378787, 5780.595358324961, 6092.177556029879, 6286.308506064329, 6296.496280297565, 6336.239651241881, 6355.491560675135, 5604.047311956896, 5641.435856414326, 5647.491725744702, 5740.196372507852, 5741.913871291934, 4884.804607815648, 5427.348718153847, 5471.316085489314, 5478.91781623276, 5479.04116779562, 6259.061140828912, 6275.811725648238, 6340.841478963062, 6366.6180029198395, 6373.012183111625, 6177.938220617736, 6311.0217916223055, 6314.842128946813, 6323.9683801580695, 6337.176167098248, 6331.746730130743, 6339.1125242467015, 6341.556091073765, 6357.618542887441, 6357.682115894279, 6010.579259451796, 6031.396860313934, 6221.906671400023, 6232.937670623236, 6242.157308763441, 5123.538881500683, 6108.75564977396, 6163.599366041193, 6183.041628547582, 6224.315325314303, 6006.729697163551, 6100.359462256399, 6130.478909292582, 6138.0, 6148.292946311231, 5870.119482077723, 6109.511239100772, 6116.0, 6132.448079672851, 6142.938570614237, 5628.380878103487, 5667.4615911488345, 5677.716114194356, 5711.171880077449, 5777.537796976148, 4832.226843704991, 5579.77245006098, 5767.019606666793, 5794.956907756476, 5827.5629327801435, 5703.458171473703, 5714.057180565326, 5720.48515633595, 5726.627769194989, 5739.167820666064], "value": 5626.250318790824, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.0683248411775, 490.32090554581913, 494.127864319476, 499.59782375187984, 502.76872638586235, 29994.0, 38819.80590097049, 40253.59746402535, 40261.59738402619, 40294.798526007326, 41549.0, 41953.0, 41989.7900510497, 41991.370129448085, 42432.57567424325, 8320.0, 9216.17054465098, 9409.106134917172, 9423.293253006052, 9472.857907131398, 8366.456180348288, 11088.944555277212, 11331.150163737711, 11337.433128343591, 11523.942380288128, 11262.605808796694, 11329.376884271362, 11376.829347559793, 11422.771544569103, 11422.942885285562, 8430.45202061864, 8632.784180395478, 11169.888301117047, 11318.20772545922, 11411.771764564744, 11014.44927753609, 11254.268472549258, 11277.323360598308, 11318.037966772834, 11326.20716549842, 39533.01167470836, 39927.8003609983, 39936.0, 39958.200835983116, 39998.200035999114, 3728.738988270822, 3806.2958352704836, 3816.866409675648, 3884.553276373225, 3904.863329783472, 28575.85712071447, 29138.271543211376, 30424.0, 30911.84544077288, 31420.05739827786, 3195.936081278385, 3216.2924156685726, 3226.322472280824, 3245.269814291789, 3255.283837555734, 497227.0, 504349.0, 504412.0, 504478.0, 505576.0, 58487.415125848194, 58498.7075064622, 60822.087668685, 60914.78170436566, 61255.38744612586, 52268.95462090737, 63318.68340658267, 63632.0, 63711.6814415925, 63738.0, 15594.908356414942, 15839.524814255361, 15860.52418427449, 15864.841351586567, 15883.682326353408, 10734.946325268324, 11020.669379918616, 11029.062529685067, 12148.21036632616, 12238.449269782881, 16862.747058794128, 17188.968661880328, 17197.398091066752, 17205.91397043007, 17687.38094166698, 6098.35967223444, 6129.774045190979, 6235.220597425322, 6242.909759668993, 6252.468540174136, 695.9898439250263, 829.4152622401193, 837.3050368194384, 850.9361797865184, 863.119617989647, 23444.0, 29288.560671589945, 29354.559681604795, 29883.850580746956, 30016.0, 789.72754399732, 1013.7668336282696, 1049.7375656085978, 1072.9195310351754, 1085.1969542538623, 12846.421911014028, 14271.215083170318, 14711.0, 15191.772123418157, 15641.843581564266, 611250.0, 614769.0, 615665.0, 616466.0, 617881.0, 58351.124733129036, 58434.415655843746, 58533.70733146392, 58648.82702345929, 58923.70538147282, 1305.3603734170379, 1343.743599734232, 1352.2218282958272, 1359.3543067043076, 1360.8843248324004, 28782.568261476514, 28920.421591568473, 29213.0, 29928.102156935325, 30054.699453006066, 50284.0, 50486.0, 50558.0, 50741.0, 50754.477365679086, 529262.0, 531392.0, 532107.0, 532464.0, 534043.0, 22045.66931496061, 24506.0, 24892.75107248977, 25128.874355628104, 25133.24600261995, 34112.31775364429, 35531.64468355283, 40876.77369678915, 41643.0, 42097.0, 35057.298854023284, 35279.0, 35407.645923541466, 35563.288734224654, 35643.28713425665, 35023.29953400969, 35276.47085293671, 35311.82344088263, 35466.8226658865, 35659.46510802392, 34171.0, 34638.65361346455, 34680.0, 34865.65134348624, 35348.0, 29780.255493612833, 31459.37081258408, 31738.04785856428, 31822.52266216055, 31847.0, 23383.88308058449, 23521.64717529273, 23711.881440592686, 24048.87975560181, 24136.396590085387], "value": 19357.563399693354, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.28580338387695, 365.10549154571305, 386.8046636448593, 398.9481367422235, 415.99168016639646, 14006.94947878908, 14713.632159196011, 18304.861104169384, 20096.0, 20410.489737756565, 15363.92318038411, 18598.256069757193, 19040.952747598876, 19313.130909109088, 19385.612287754237, 5221.530062294383, 5659.009673307155, 5718.799842005531, 5737.7991770288045, 5744.080947048473, 4077.3476243800997, 4179.519355274137, 5603.103503439451, 5706.28671416073, 5715.885682286373, 4322.351647252922, 4394.07724377881, 6421.7752378666755, 6461.418472337488, 6472.449841763456, 6148.538859585549, 6309.810705678837, 6431.196100487439, 6442.613443193423, 6451.7096730647, 5589.217509548666, 5593.636413633101, 5594.888102237933, 5602.38373778884, 5610.719464026783, 24230.515389692107, 24518.754812452004, 24938.5012299753, 25145.994160233568, 25320.746792532023, 2648.602709593557, 2674.8930042798265, 2695.3396417877548, 2700.5949107633814, 2716.8641567921527, 14897.0, 15258.237088145659, 15358.616034599112, 15420.92289538545, 15965.680686386208, 1595.9760603590955, 1797.91010449477, 1843.834054935049, 1847.5196448923243, 1867.6358110168524, 126009.0, 127328.0, 131465.342673286, 133247.33376333152, 133285.0, 22372.328830134797, 22703.20538781168, 23090.30729078097, 23534.176303829285, 23536.0, 31007.534886976715, 32161.356772864412, 36862.0, 37484.43773343402, 38494.84515464541, 8154.510729356258, 9890.91199968003, 9965.152961998167, 10624.681259562225, 10891.564337426415, 8687.522186279815, 8712.564371781385, 8732.95633521841, 8840.381173317806, 8861.512616806009, 8571.0, 10370.792584148276, 10427.217958653131, 10500.377369807557, 10552.47237638115, 3931.567527571965, 3975.4036894465485, 3995.2233197927744, 4002.939955900664, 4003.8598649047144, 801.551131366425, 821.2731732416735, 828.0973738624807, 834.4284165346756, 847.6185716427632, 19923.800761992483, 20481.692774608393, 20641.071151798205, 20652.690209646866, 20738.377848664262, 846.6824940647257, 848.1772680499905, 853.906070332263, 862.0259107208814, 872.821071680304, 10098.141657959155, 10523.894761052445, 10545.683629491128, 10546.894531054591, 10585.788284234271, 711933.0, 732394.0, 750085.4991450125, 768394.0, 791214.0, 137339.0, 148088.0, 155803.0, 158641.0, 162839.0, 927.79124696944, 938.2119020023214, 964.5755867418313, 969.2633598465077, 998.0, 17809.643807123524, 17978.820211797716, 18478.630427391647, 18516.537086572942, 18527.53681157982, 41317.98147680727, 50295.99408011892, 54492.727536362065, 60414.69792651009, 60525.39474605198, 85818.0, 86007.56996214979, 86160.56919715361, 86569.56715216384, 89917.0, 7244.601546914971, 7327.560346379234, 7660.69357225727, 8053.718119865776, 8059.274665279919, 11805.704857378634, 11958.402079896145, 12171.208871423329, 12202.75594488123, 12362.690932726753, 9746.415215087118, 10373.636922707668, 10502.317349372266, 10671.46642667848, 10686.931306869561, 8485.660573576988, 10200.4389758564, 10595.841062383915, 10941.0, 11224.887751122711, 10253.846192306972, 10899.78200435971, 10955.780884382428, 11122.0, 11493.48279327449, 8034.156413576724, 8253.79365515867, 8297.294729948022, 8344.415890887822, 8388.077311495852, 7671.61641917913, 10701.571937122739, 10875.456227188768, 10978.011978921615, 10993.890061099606], "value": 10517.654053940672, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1306.7255876265983, 1313.3761463304932, 1314.3428285857071, 1321.286505287145, 1329.5479536957432, 7921.841563168733, 8207.630656620451, 8332.0, 8335.208155225258, 8346.123657016016, 7273.527220730662, 7488.176300606929, 7496.737614183505, 7909.169537198598, 8146.26683598476, 2259.89830457629, 2318.6869772580653, 2323.9419014524688, 2324.4537533679654, 2326.3718795925174, 2295.735990361113, 2319.35058183708, 2337.123578658003, 2337.146941366406, 2340.789328960393, 1627.4385337058695, 2067.390119914629, 2226.3209721035078, 2286.4055345610177, 2291.415688999311, 2291.0950174680993, 2310.7920287174234, 2311.4452531392512, 2315.131825565413, 2318.038014224095, 2017.6469117904455, 2177.8366622503218, 2238.205437069833, 2300.815934725218, 2308.4921317310163, 8290.129536398652, 8344.207300306462, 8344.499330040217, 8347.582620868932, 8348.457350272212, 4971.527704868032, 5122.743862806845, 5131.563817075553, 5135.12702840518, 5139.331886854722, 3230.660780618048, 3413.6927676509235, 3431.2965842002577, 3497.7376696747847, 3504.54440922681, 1163.9010684091863, 1184.2775906696909, 1236.5479620224344, 1238.8389509363815, 1247.095855504761, 10587.15302775776, 10602.204834637432, 10610.045095941403, 10627.52176152075, 10636.521356538975, 4383.583559561836, 4616.676832621752, 4665.0, 4667.556582124693, 4680.531946805292, 9310.441373517611, 9314.254859611216, 9320.533973301308, 9322.440653560809, 9331.253499720005, 5116.9744151279, 5145.562627176657, 5148.6910785353, 5151.6136289778415, 5151.819686311036, 4423.601875831127, 4586.036932244199, 4649.18639238132, 4758.0246049559755, 4762.9523704763205, 3736.271427071695, 3803.4865293185067, 3819.560750513671, 3875.6318149775725, 3898.922021559553, 1255.12768625806, 1257.7861763500227, 1260.1431026901612, 1272.6182145356356, 1273.8917192038596, 807.6123460738761, 830.2444775254489, 844.607257625195, 866.7659731872359, 878.1438097854541, 5195.350581177353, 5346.251524786533, 5374.1938709193155, 5464.863378415572, 5554.000279949567, 520.924291338385, 539.7544117426562, 545.6453305351474, 553.6899336371645, 554.3984776517443, 3465.5841299043773, 3517.9120521987156, 3598.6401359863803, 3618.9819050904575, 3619.7285203609836, 9606.183474404614, 9975.351602145838, 9982.301238913205, 9988.250881183794, 9993.300468967247, 9747.853782193273, 9915.454649994319, 9936.254780891462, 9941.80116397668, 9950.054744799232, 7374.299441553044, 7604.163542010375, 7613.895985082132, 7726.420518461138, 7751.534907905544, 5248.76380562868, 5329.658850098967, 5345.679259244458, 5405.378381486179, 5457.754401052041, 8959.596818143329, 8966.82066358656, 9026.593803279, 9040.0, 9174.495402752913, 8346.666133354598, 8675.0, 8838.779030524289, 9243.260539156985, 9467.005964373917, 6481.4166724994975, 6710.932890671226, 6996.650167491503, 7004.4046256068805, 7071.7878463646175, 7621.12357078943, 7812.3750099993, 7860.882086768589, 7889.250521200477, 7889.526628402315, 6883.242843287134, 6895.5173137879865, 6922.5154239202775, 7017.157941047107, 7081.7167313308955, 6853.485988550979, 6862.828429289308, 6941.965290173517, 6956.339147780953, 7051.189113252041, 6214.223222097238, 6231.781887633911, 6246.781362652286, 6336.651484168415, 6337.176167098248, 4707.81168753246, 4804.279358096173, 4848.333416620774, 4860.878478038077, 4984.302197692254, 4038.7172897896867, 4821.228603423436, 4887.218045112765, 4951.8266860659705, 4977.879977005145], "value": 4263.243456272226, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.42243853737676, 355.47744815121763, 360.5835260274382, 372.44691632925117, 372.47853005791904, 4648.513045520647, 5323.787048518059, 5348.0, 5381.838544843651, 5381.892362152755, 5012.799488020475, 5137.743112844361, 5143.0, 5170.242117679997, 5252.579793616511, 4023.7786921719294, 4027.4562934003975, 4127.731697439672, 4129.2154490646835, 4194.643455306303, 4147.274227010276, 4164.8338465229635, 4188.570629035167, 4217.40956266123, 4238.830446782125, 3260.5490556702275, 3934.390169523728, 4057.2696914555368, 4128.071183983595, 4136.503619565657, 3239.4330992076293, 3592.5868525119686, 4105.0763578194965, 4148.792560371984, 4154.065335299563, 3262.983685081583, 3653.7442379033478, 3684.889453316405, 3690.7785532867847, 3707.330721880269, 5053.545180933734, 5146.0, 5175.818846340398, 5211.895762084775, 5221.869453263661, 4766.713997160146, 4775.450823155347, 4801.4718380978065, 4803.7357945313, 4821.975890120562, 5114.744262786846, 5552.750126244328, 5579.637323573996, 5605.309451463279, 5645.165321270562, 3952.664023558001, 4094.1811637672586, 4106.507219133723, 4106.897327566835, 4111.48606424197, 4902.240152776325, 5179.715115668637, 5305.44292849253, 5347.999999999961, 5796.362654991968, 4162.542120366757, 4169.374593810952, 4170.791460426966, 4176.0395109124465, 4182.519010313792, 3763.5483741950593, 3901.6293452122004, 4173.914782156661, 4180.4708151568475, 4276.2089013532595, 4269.929832616358, 4446.799894004777, 4616.630669546428, 4682.765861706901, 4784.210605250164, 5278.709670968134, 5603.523700485504, 5609.0, 5628.240187574625, 5642.633228840113, 1303.6089173247988, 1372.4715984346099, 1381.8134551835374, 1387.882030027459, 1388.687545302319, 825.1336097098023, 884.5046773806561, 912.2473958983865, 914.3553794574809, 978.9657361992296, 4092.6725861930972, 4972.8508144755715, 4978.751062446863, 5018.946021335488, 5085.821996230115, 4750.904981900343, 4848.539388758062, 5170.844874653766, 5271.551918087006, 5471.699056551928, 4586.908261834811, 4654.790534425959, 4855.587275081587, 4942.0, 5270.420253772082, 2412.915547955813, 2721.877515511807, 2797.412543365916, 2892.815923977426, 2914.591957126004, 4829.643397838468, 5012.674176178537, 5304.9920515102, 5375.709829640936, 5845.937073733714, 5113.181890897438, 5195.428502864682, 5270.655982724381, 5347.331583552056, 5698.772049118072, 4914.40503129575, 5221.738913054332, 5522.7573795895605, 5596.300462442195, 5770.307563092372, 4689.695169813951, 5089.949100508948, 5433.972830135824, 5508.421615730291, 5575.916361254665, 5073.644844860972, 5144.614153938394, 5208.609354298518, 5231.633785634969, 5444.782208711766, 4564.7032942858605, 5440.143924439701, 5577.581681373832, 5591.750269794904, 5680.6875621839545, 3096.504559270506, 3406.591209054929, 3448.5516882805073, 3601.819909004592, 3679.889603311905, 4623.768811559476, 4800.639952003544, 5376.704281264411, 5417.349918009864, 5417.756200970887, 5214.608904332266, 5217.4260831307765, 5226.268322434938, 5251.921221181762, 5263.499967503081, 4740.525947405232, 5147.0, 5173.922391164211, 5293.391260005149, 5322.54758345545, 4668.439787225555, 4963.812815607771, 5038.722870242172, 5227.2420499028185, 5325.618232422517, 4561.452625684939, 5202.947970520247, 5539.473749993744, 5580.102765059899, 5592.944070559405, 4190.459062092527, 4600.148972440109, 4737.4788773235605, 5313.54834839043, 5389.0], "value": 4034.1473726560052, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.1637431280617, 636.8726254749051, 705.0552259971637, 715.7959981405301, 724.3046675191814, 14183.361748721307, 15038.548843534683, 15056.623584410381, 15076.020058696176, 15093.094414335133, 13370.465181392758, 13496.865031349684, 13500.392482338293, 13516.459341626347, 13583.185008899472, 683.634255673215, 703.2931903437038, 879.9516026618534, 891.3270480786997, 895.6909866096187, 658.1805651963313, 696.0950764006797, 699.6816448515939, 873.3449912565576, 883.0286684646901, 881.6341218394359, 923.5059243304834, 924.7272054743867, 933.2906990687093, 936.9953150234273, 933.4306073295285, 933.8459154239542, 934.8317302885478, 935.3546053223288, 935.3966691483989, 696.989545156823, 698.1133959870987, 701.2776839854957, 701.3162166887295, 702.761061239176, 13970.091944023607, 14311.35598898052, 14333.78499322511, 14444.638884027983, 14463.78304325436, 13500.594982150551, 13654.04421690482, 13837.377318020714, 13940.024198306122, 14012.649683757885, 10725.356478611307, 10791.352518848817, 10990.340579565252, 11112.055475284611, 11322.490487928064, 3500.8732392972415, 3512.3853325667906, 3617.113807117247, 3802.5056742623283, 3806.619338066199, 98771.50614246883, 99351.0, 102036.95926081511, 103214.0, 104576.0, 8904.0, 10457.006584374472, 10529.315594486334, 10554.419506927039, 10672.359658420519, 20729.89635051815, 20996.370108896757, 21128.260510882046, 21169.15323387078, 21196.894015529822, 7036.718531258794, 7070.92929070713, 7103.056451652998, 7168.605726685082, 7185.53294035886, 4065.97967010169, 4097.1396006838895, 4107.199096176277, 4113.85601503946, 4137.668986481074, 3279.983600082032, 3330.900072997814, 3360.1263671445113, 3398.4222682144095, 3400.3369342978376, 621.6518749500294, 622.6108682073703, 624.2664868779166, 625.5777350288537, 635.9777407790705, 620.8975519039399, 623.3921926121994, 628.6479571440008, 653.5555822041058, 665.9400653941168, 9185.402948808307, 9937.354071985297, 10002.149817265614, 10123.7469063274, 10272.0, 639.2616527910271, 645.7029766307458, 669.6852479334705, 684.7603338831369, 696.2828286864537, 4755.738434386073, 4789.0, 4794.328793968847, 4891.633127515451, 4899.24061770419, 86768.0, 90737.54631226802, 91533.7116572091, 98158.52762208572, 98305.0, 77360.0, 79356.60321698355, 80831.0, 86701.0, 87027.56486217528, 72528.54942901219, 73957.63021184859, 74466.8829967551, 77819.0, 80118.59940700259, 10361.74095647615, 10600.681979540626, 10760.62337818204, 10873.510692019036, 10878.184136189975, 40211.79894100511, 49819.50180498149, 51265.0, 53977.19034214412, 54375.45624543704, 68012.97980530396, 68548.6572567134, 70411.29588704047, 71310.64344678243, 71382.64308678423, 12005.63983080509, 12108.394580270775, 12195.329256890956, 12221.0, 12569.685757856127, 11658.125640576822, 11687.357195353998, 12473.251604903735, 13557.0, 13985.510507132201, 12759.808602870778, 13019.349032548524, 13614.455421783017, 14163.22102284343, 14290.214038227998, 11296.887031129583, 13306.733865322833, 13673.495915449492, 13678.658033549242, 14279.357428915517, 12691.873081269068, 13149.0, 13296.667583310495, 13838.100523465942, 13842.377093031038, 8775.780605484913, 10890.401027943222, 11017.614383496539, 11231.15766317513, 11486.770264594828, 8386.916130838857, 8690.956545217234, 10469.895301047196, 10640.733981650521, 11109.055730263019], "value": 7250.27816951972, "unit": "score"}], "name": "dromaeo_css", "value": 7230.153515537106, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 115.0, 116.0, 114.0, 114.0, 114.0, 115.0, 115.0, 114.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 109.0, 107.0, 139.0, 110.0, 110.0, 108.0, 106.0, 128.0, 108.0], "value": 116.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 183.0, 184.0, 183.0, 194.0, 258.0, 216.0, 185.0, 182.0], "value": 195.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 60.0, 88.0, 60.0, 59.0, 70.0, 80.0, 95.0, 82.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 86.0, 68.0, 67.0, 69.0, 80.0, 66.0, 68.0, 82.0], "value": 74.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 85.0, 86.0, 83.0, 88.0, 85.0, 88.0, 83.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 93.0, 97.0, 93.0, 96.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 87.0, 87.0, 84.0, 85.0, 88.0, 89.0, 86.0], "value": 85.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 53.0, 54.0, 55.0, 55.0, 55.0, 57.0, 57.0, 57.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 77.0, 75.0, 75.0, 70.0, 69.0, 69.0, 71.0, 71.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 84.0, 83.0, 85.0, 82.0, 83.0, 86.0, 84.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 159.0, 146.0, 127.0, 142.0, 157.0, 138.0, 201.0, 180.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 169.0, 169.0, 169.0, 165.0, 165.0, 166.0, 166.0, 169.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 66.0, 65.0, 65.0, 64.0, 65.0, 63.0, 63.0], "value": 64.8, "unit": "score"}], "name": "kraken", "value": 1436.3999999999999, "alertThreshold": 2.0}]} 10:50:39 INFO - Return code: 0 10:50:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:50:39 ERROR - # TBPL SUCCESS # 10:50:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:50:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:50:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:50:39 INFO - 2017-02-09 10:50:39.233 screenresolution[1984:12452] starting screenresolution argv=screenresolution get 10:50:39 INFO - 2017-02-09 10:50:39.236 screenresolution[1984:12452] Display 0: 1600x1200x32@60 10:50:39 INFO - 2017-02-09 10:50:39.243 screenresolution[1985:12455] starting screenresolution argv=screenresolution list 10:50:39 INFO - Available Modes on Display 0 10:50:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:50:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:50:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:50:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:50:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:50:39 INFO - Intel Iris: 10:50:39 INFO - Chipset Model: Intel Iris 10:50:39 INFO - Type: GPU 10:50:39 INFO - Bus: Built-In 10:50:39 INFO - VRAM (Dynamic, Max): 1536 MB 10:50:39 INFO - Vendor: Intel (0x8086) 10:50:39 INFO - Device ID: 0x0a2e 10:50:39 INFO - Revision ID: 0x0009 10:50:39 INFO - Displays: 10:50:39 INFO - Display: 10:50:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:50:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:50:39 INFO - Display Serial Number: 1600x1200 60 10:50:39 INFO - Main Display: Yes 10:50:39 INFO - Mirror: Off 10:50:39 INFO - Online: Yes 10:50:39 INFO - Rotation: Supported 10:50:39 INFO - Return code: 0 10:50:39 INFO - Running post-action listener: _resource_record_post_action 10:50:39 INFO - [mozharness: 2017-02-09 18:50:39.481833Z] Finished run-tests step (success) 10:50:39 INFO - Running post-run listener: _resource_record_post_run 10:50:39 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 335872; Write bytes: 507981312; Read time: 7; Write time: 1672 10:50:39 INFO - TinderboxPrint: CPU usage
53.5% 10:50:39 INFO - TinderboxPrint: I/O read bytes / time
335,872 / 7 10:50:39 INFO - TinderboxPrint: I/O write bytes / time
507,981,312 / 1,672 10:50:39 INFO - TinderboxPrint: CPU idle
2,224.8 (46.4%) 10:50:39 INFO - TinderboxPrint: CPU system
957.1 (20.0%) 10:50:39 INFO - TinderboxPrint: CPU user
1,607.8 (33.6%) 10:50:39 INFO - TinderboxPrint: Swap in / out
208,052,224 / 0 10:50:39 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207274496; Write bytes: 199827456; Read time: 15924; Write time: 405 10:50:39 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 196608; Write bytes: 298143232; Read time: 5; Write time: 1236 10:50:39 INFO - Running post-run listener: _upload_blobber_files 10:50:39 INFO - Blob upload gear active. 10:50:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:50:39 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 10:50:39 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'] 10:50:39 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 10:50:40 INFO - (blobuploader) - INFO - Open directory for files ... 10:50:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:50:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:50:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:41 INFO - (blobuploader) - INFO - Done attempting. 10:50:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:50:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:50:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:42 INFO - (blobuploader) - INFO - Done attempting. 10:50:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:50:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:50:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:43 INFO - (blobuploader) - INFO - Done attempting. 10:50:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:50:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:50:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:45 INFO - (blobuploader) - INFO - Done attempting. 10:50:45 INFO - (blobuploader) - INFO - Iteration through files over. 10:50:45 INFO - Return code: 0 10:50:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:50:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:50:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/21b57ba242df7f128eaeaadb33d4d59ed01ac97b3f3f569a518dff2e5ddfe0009168e93e44458eb3ace807184529360bdae640e156521ec819168583cc78ce89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1615b80ba70c01b0d800a50a4be1d22f54c757edde8624a128649dcb2efc34b95ba1c2a185c75a8c09c11ab58422d18d431628a97dfd0bfe152b25e687797a96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae0a42fe91daec5c8de8068589e136ba0e5646c4ba45d15e84f1b649e83b63c582998c7e7902d637c31ed4a6406871fdc5ee62035507f966ceb422f33b389e06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6bee28b8a3b1cdcf9a3565828be8c2616882f7ccb70654da731c71dae767372e27dcf0fd07dee9bfceee9d4b7818c467a89a58657920ce2a3eab8463741c73b"} 10:50:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:50:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:50:45 INFO - Contents: 10:50:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/21b57ba242df7f128eaeaadb33d4d59ed01ac97b3f3f569a518dff2e5ddfe0009168e93e44458eb3ace807184529360bdae640e156521ec819168583cc78ce89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1615b80ba70c01b0d800a50a4be1d22f54c757edde8624a128649dcb2efc34b95ba1c2a185c75a8c09c11ab58422d18d431628a97dfd0bfe152b25e687797a96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae0a42fe91daec5c8de8068589e136ba0e5646c4ba45d15e84f1b649e83b63c582998c7e7902d637c31ed4a6406871fdc5ee62035507f966ceb422f33b389e06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6bee28b8a3b1cdcf9a3565828be8c2616882f7ccb70654da731c71dae767372e27dcf0fd07dee9bfceee9d4b7818c467a89a58657920ce2a3eab8463741c73b"} 10:50:45 INFO - Running post-run listener: copy_logs_to_upload_dir 10:50:45 INFO - Copying logs to upload dir... 10:50:45 INFO - mkdir: /builds/slave/test/build/upload/logs 10:50:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.222966 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-09 10:50:45.770181) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 10:50:45.773918) ========= 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.0B93grJPJa/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.GNW8MHWNpS/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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/21b57ba242df7f128eaeaadb33d4d59ed01ac97b3f3f569a518dff2e5ddfe0009168e93e44458eb3ace807184529360bdae640e156521ec819168583cc78ce89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1615b80ba70c01b0d800a50a4be1d22f54c757edde8624a128649dcb2efc34b95ba1c2a185c75a8c09c11ab58422d18d431628a97dfd0bfe152b25e687797a96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae0a42fe91daec5c8de8068589e136ba0e5646c4ba45d15e84f1b649e83b63c582998c7e7902d637c31ed4a6406871fdc5ee62035507f966ceb422f33b389e06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6bee28b8a3b1cdcf9a3565828be8c2616882f7ccb70654da731c71dae767372e27dcf0fd07dee9bfceee9d4b7818c467a89a58657920ce2a3eab8463741c73b"} build_url:https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011263 build_url: 'https://queue.taskcluster.net/v1/task/cfmMfyPPRX6EQqt_iZ3SsA/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/21b57ba242df7f128eaeaadb33d4d59ed01ac97b3f3f569a518dff2e5ddfe0009168e93e44458eb3ace807184529360bdae640e156521ec819168583cc78ce89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1615b80ba70c01b0d800a50a4be1d22f54c757edde8624a128649dcb2efc34b95ba1c2a185c75a8c09c11ab58422d18d431628a97dfd0bfe152b25e687797a96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ae0a42fe91daec5c8de8068589e136ba0e5646c4ba45d15e84f1b649e83b63c582998c7e7902d637c31ed4a6406871fdc5ee62035507f966ceb422f33b389e06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6bee28b8a3b1cdcf9a3565828be8c2616882f7ccb70654da731c71dae767372e27dcf0fd07dee9bfceee9d4b7818c467a89a58657920ce2a3eab8463741c73b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 10:50:45.808272) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 10:50:45.808578) ========= 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.0B93grJPJa/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.GNW8MHWNpS/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.005391 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 10:50:45.857065) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 10:50:45.857347) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 10:50:45.857637) ========= ========= Total master_lag: 2.45 =========