builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0126 starttime: 1479749238.96 results: success (0) buildid: 20161121065108 builduid: 3a3c776276db4b78930048a5ec81a327 revision: c58b40f5e8b398c225df819b28314c57181273dd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-21 09:27:18.955400) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-21 09:27:18.955859) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-11-21 09:27:18.956141) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-11-21 09:27:22.181646) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-21 09:27:22.182040) ========= 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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 --2016-11-21 09:27:24-- 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% 14.2M=0.001s 2016-11-21 09:27:24 (14.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121980 ========= master_lag: 2.20 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-21 09:27:24.503911) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 09:27:24.510262) ========= 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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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.021134 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 09:27:24.571737) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-11-21 09:27:24.572168) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c58b40f5e8b398c225df819b28314c57181273dd --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 c58b40f5e8b398c225df819b28314c57181273dd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RSsDeuGSbb/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.1eoT9DUw5H/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 2016-11-21 09:27:24,651 truncating revision to first 12 chars 2016-11-21 09:27:24,651 Setting DEBUG logging. 2016-11-21 09:27:24,651 attempt 1/10 2016-11-21 09:27:24,651 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c58b40f5e8b3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-21 09:27:25,965 unpacking tar archive at: mozilla-aurora-c58b40f5e8b3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.567417 ========= master_lag: 1.55 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-11-21 09:27:27.689710) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-21 09:27:27.690056) ========= script_repo_revision: c58b40f5e8b398c225df819b28314c57181273dd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-21 09:27:27.690507) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-11-21 09:27:27.690788) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-11-21 09:27:29.279597) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-11-21 09:27:29.279936) ========= /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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:27:29 INFO - MultiFileLogger online at 20161121 09:27:29 in /builds/slave/test 09:27:29 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 09:27:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:27:29 INFO - {'append_to_log': False, 09:27:29 INFO - 'base_work_dir': '/builds/slave/test', 09:27:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:27:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:27:29 INFO - 'branch': 'Mozilla-Aurora', 09:27:29 INFO - 'buildbot_json_path': 'buildprops.json', 09:27:29 INFO - 'config_files': ('talos/mac_config.py',), 09:27:29 INFO - 'default_actions': ('clobber', 09:27:29 INFO - 'read-buildbot-config', 09:27:29 INFO - 'download-and-extract', 09:27:29 INFO - 'populate-webroot', 09:27:29 INFO - 'create-virtualenv', 09:27:29 INFO - 'install', 09:27:29 INFO - 'run-tests'), 09:27:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:27:29 INFO - 'download_minidump_stackwalk': True, 09:27:29 INFO - 'download_symbols': 'ondemand', 09:27:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:27:29 INFO - 'tooltool.py': '/tools/tooltool.py', 09:27:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:27:29 INFO - '/tools/misc-python/virtualenv.py')}, 09:27:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:27:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:27:29 INFO - 'installer_path': 'installer.exe', 09:27:29 INFO - 'log_level': 'info', 09:27:29 INFO - 'log_name': 'talos', 09:27:29 INFO - 'log_to_console': True, 09:27:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:27:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:27:29 INFO - 'opt_config_files': (), 09:27:29 INFO - 'pip_index': False, 09:27:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:27:29 INFO - 'cmd': ('bash', 09:27:29 INFO - '-c', 09:27:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:27:29 INFO - 'enabled': True, 09:27:29 INFO - 'halt_on_failure': False, 09:27:29 INFO - 'name': 'check_screen_resolution'},), 09:27:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:27:29 INFO - 'cmd': ('bash', 09:27:29 INFO - '-c', 09:27:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:27:29 INFO - 'enabled': True, 09:27:29 INFO - 'halt_on_failure': False, 09:27:29 INFO - 'name': 'check_screen_resolution'},), 09:27:29 INFO - 'run_cmd_checks_enabled': True, 09:27:29 INFO - 'sps_profile': False, 09:27:29 INFO - 'sps_profile_interval': 0, 09:27:29 INFO - 'suite': 'dromaeojs', 09:27:29 INFO - 'system_bits': '32', 09:27:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:27:29 INFO - 'title': 't-yosemite-r7-0126', 09:27:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:27:29 INFO - 'use_talos_json': True, 09:27:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:27:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:27:29 INFO - 'work_dir': 'build'} 09:27:29 INFO - [mozharness: 2016-11-21 17:27:29.659791Z] Running clobber step. 09:27:29 INFO - Running pre-action listener: _resource_record_pre_action 09:27:29 INFO - Running main action method: clobber 09:27:29 INFO - rmtree: /builds/slave/test/build 09:27:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:27:29 INFO - Running post-action listener: _resource_record_post_action 09:27:29 INFO - [mozharness: 2016-11-21 17:27:29.830824Z] Finished clobber step (success) 09:27:29 INFO - [mozharness: 2016-11-21 17:27:29.830954Z] Running read-buildbot-config step. 09:27:29 INFO - Running pre-action listener: _resource_record_pre_action 09:27:29 INFO - Running main action method: read_buildbot_config 09:27:29 INFO - Using buildbot properties: 09:27:29 INFO - { 09:27:29 INFO - "project": "", 09:27:29 INFO - "product": "firefox", 09:27:29 INFO - "script_repo_revision": "production", 09:27:29 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:27:29 INFO - "repository": "", 09:27:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:27:29 INFO - "buildid": "20161121065108", 09:27:29 INFO - "pgo_build": "False", 09:27:29 INFO - "basedir": "/builds/slave/test", 09:27:29 INFO - "buildnumber": 1, 09:27:29 INFO - "slavename": "t-yosemite-r7-0126", 09:27:29 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:27:29 INFO - "platform": "yosemite_r7", 09:27:29 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:27:29 INFO - "branch": "mozilla-aurora", 09:27:29 INFO - "repo_path": "releases/mozilla-aurora", 09:27:29 INFO - "revision": "c58b40f5e8b398c225df819b28314c57181273dd", 09:27:29 INFO - "stage_platform": "macosx64", 09:27:29 INFO - "builduid": "3a3c776276db4b78930048a5ec81a327", 09:27:29 INFO - "slavebuilddir": "test" 09:27:29 INFO - } 09:27:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:27:29 INFO - Running post-action listener: _resource_record_post_action 09:27:29 INFO - [mozharness: 2016-11-21 17:27:29.834088Z] Finished read-buildbot-config step (success) 09:27:29 INFO - [mozharness: 2016-11-21 17:27:29.834193Z] Running download-and-extract step. 09:27:29 INFO - Running pre-action listener: _resource_record_pre_action 09:27:29 INFO - Running main action method: download_and_extract 09:27:29 INFO - mkdir: /builds/slave/test/build/tests 09:27:29 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')]} 09:27:29 INFO - trying https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:27:29 INFO - Downloading https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:27:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 09:27:30 INFO - Downloaded 1199 bytes. 09:27:30 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:27:30 INFO - Using the following test package requirements: 09:27:30 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:27:30 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:27:30 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:27:30 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:27:30 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:27:30 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:27:30 INFO - u'jsshell-mac.zip'], 09:27:30 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:27:30 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:27:30 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:27:30 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:27:30 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:27:30 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:27:30 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:27:30 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:27:30 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:27:30 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:27:30 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:27:30 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:27:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:27:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:27:30 INFO - Fetch https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:27:31 INFO - Http code: 200 09:27:31 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 09:27:31 INFO - x-amz-cf-id: l4r_K86U7Nm5zKpGQQfrQY88oYaB5c8FrrObTQ7OMWICvzASmPGmdQ== 09:27:31 INFO - x-amz-version-id: URz_du25IDxtX1gJ8vkRLMLGRHzg5A6c 09:27:31 INFO - Expected file size: 16274870 09:27:31 INFO - Obtained file size: 16274870 09:27:38 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:27:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:27:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:27:38 INFO - Fetch https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:27:38 INFO - Http code: 200 09:27:38 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 09:27:39 INFO - x-amz-cf-id: ioMozkqJBu0BP9kaozzATj_09xTS1eedjUPyN6mEhykiaOOVRxlTtQ== 09:27:39 INFO - x-amz-version-id: URz_du25IDxtX1gJ8vkRLMLGRHzg5A6c 09:27:39 INFO - Expected file size: 16274870 09:27:39 INFO - Obtained file size: 16274870 09:27:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:27:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:27:45 INFO - Fetch https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:27:46 INFO - Http code: 200 09:27:46 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 09:27:46 INFO - x-amz-cf-id: VSzYjexC3F3XA6KJnPMmPAuGrkLyjAUeqqU1ERWWE-eowqxDRkV4Xg== 09:27:46 INFO - x-amz-version-id: BPBpyaTqqP80WRffeNZ5A7nRAC.atDT_ 09:27:46 INFO - Expected file size: 14409038 09:27:46 INFO - Obtained file size: 14409038 09:27: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')]} 09:27:47 INFO - trying https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:27:47 INFO - Downloading https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:27:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 09:27:49 INFO - Downloaded 114502188 bytes. 09:27:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:27:49 INFO - mkdir: /builds/slave/test/properties 09:27:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:27:49 INFO - Writing to file /builds/slave/test/properties/build_url 09:27:49 INFO - Contents: 09:27:49 INFO - build_url:https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:27:50 INFO - Running post-action listener: _resource_record_post_action 09:27:50 INFO - Running post-action listener: set_extra_try_arguments 09:27:50 INFO - [mozharness: 2016-11-21 17:27:50.495251Z] Finished download-and-extract step (success) 09:27:50 INFO - [mozharness: 2016-11-21 17:27:50.495401Z] Running populate-webroot step. 09:27:50 INFO - Running pre-action listener: _resource_record_pre_action 09:27:50 INFO - Running main action method: populate_webroot 09:27:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:27:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:27:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:27:50 INFO - '--tppagecycles', 09:27:50 INFO - '7'], 09:27:50 INFO - 'tests': ['tsvgm']}}, 09:27:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:27:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:27:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:27:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:27:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:27:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:27:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:50 INFO - 'talos_options': ['--disable-e10s'], 09:27:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:50 INFO - 'talos_options': ['--disable-e10s'], 09:27:50 INFO - 'tests': ['damp', 'tps']}, 09:27:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:50 INFO - 'tests': ['damp', 'tps']}, 09:27:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:27:50 INFO - 'tests': ['dromaeo_dom']}, 09:27:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:27:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:27:50 INFO - 'tests': ['basic_compositor_video']}, 09:27:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:27:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:27:50 INFO - 'tests': ['a11yr', 09:27:50 INFO - 'ts_paint', 09:27:50 INFO - 'tpaint', 09:27:50 INFO - 'sessionrestore', 09:27:50 INFO - 'sessionrestore_no_auto_restore', 09:27:50 INFO - 'tabpaint']}, 09:27:50 INFO - 'other-e10s': {'tests': ['a11yr', 09:27:50 INFO - 'ts_paint', 09:27:50 INFO - 'tpaint', 09:27:50 INFO - 'sessionrestore', 09:27:50 INFO - 'sessionrestore_no_auto_restore', 09:27:50 INFO - 'tabpaint']}, 09:27:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:27:50 INFO - 'ts_paint', 09:27:50 INFO - 'tpaint', 09:27:50 INFO - 'sessionrestore', 09:27:50 INFO - 'sessionrestore_no_auto_restore', 09:27:50 INFO - 'tabpaint']}, 09:27:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:27:50 INFO - 'ts_paint', 09:27:50 INFO - 'tpaint', 09:27:50 INFO - 'sessionrestore', 09:27:50 INFO - 'sessionrestore_no_auto_restore', 09:27:50 INFO - 'tabpaint']}, 09:27:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:27:50 INFO - 'tests': ['a11yr', 09:27:50 INFO - 'ts_paint', 09:27:50 INFO - 'tpaint', 09:27:50 INFO - 'sessionrestore', 09:27:50 INFO - 'sessionrestore_no_auto_restore', 09:27:50 INFO - 'tabpaint']}, 09:27:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:27:50 INFO - 'tests': ['a11yr', 09:27:50 INFO - 'ts_paint', 09:27:50 INFO - 'tpaint', 09:27:50 INFO - 'sessionrestore', 09:27:50 INFO - 'sessionrestore_no_auto_restore', 09:27:50 INFO - 'tabpaint']}, 09:27:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:27:50 INFO - 'tests': ['tsvgx', 09:27:50 INFO - 'tsvgr_opacity', 09:27:50 INFO - 'tart', 09:27:50 INFO - 'tscrollx', 09:27:50 INFO - 'cart']}, 09:27:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:27:50 INFO - 'tsvgr_opacity', 09:27:50 INFO - 'tart', 09:27:50 INFO - 'tscrollx', 09:27:50 INFO - 'cart']}, 09:27:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:50 INFO - 'talos_options': ['--disable-e10s'], 09:27:50 INFO - 'tests': ['tp5o']}, 09:27:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:50 INFO - 'tests': ['tp5o']}, 09:27:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:50 INFO - 'talos_options': ['--disable-e10s', 09:27:50 INFO - '--xperf_path', 09:27:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:50 INFO - 'tests': ['tp5n']}, 09:27:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:50 INFO - 'talos_options': ['--xperf_path', 09:27:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:50 INFO - 'tests': ['tp5n']}}, 09:27:50 INFO - 'talos.zip': {'path': '', 09:27:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:27:50 INFO - Running post-action listener: _resource_record_post_action 09:27:50 INFO - [mozharness: 2016-11-21 17:27:50.514079Z] Finished populate-webroot step (success) 09:27:50 INFO - [mozharness: 2016-11-21 17:27:50.514224Z] Running create-virtualenv step. 09:27:50 INFO - Running pre-action listener: _resource_record_pre_action 09:27:50 INFO - Running main action method: create_virtualenv 09:27:50 INFO - Creating virtualenv /builds/slave/test/build/venv 09:27:50 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 09:27:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:27:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:27:50 INFO - Using real prefix '/tools/python27' 09:27:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:27:51 INFO - Installing distribute.............................................................................................................................................................................................done. 09:27:54 INFO - Installing pip.................done. 09:27:54 INFO - Return code: 0 09:27:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:27:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:27:54 INFO - Reading from file tmpfile_stdout 09:27:54 INFO - Output received: 09:27:54 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) 09:27:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105741490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5fbc7d950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105555d30>, '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 0x1053bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10554db20>, '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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:27:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:27:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:27:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSsDeuGSbb/Render', 09:27:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:54 INFO - 'HOME': '/Users/cltbld', 09:27:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:54 INFO - 'LOGNAME': 'cltbld', 09:27:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:54 INFO - 'MOZ_NO_REMOTE': '1', 09:27:54 INFO - 'NO_EM_RESTART': '1', 09:27:54 INFO - 'PAGER': '/bin/cat', 09:27:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:54 INFO - 'PWD': '/builds/slave/test', 09:27:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:54 INFO - 'SHELL': '/bin/bash', 09:27:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1eoT9DUw5H/Listeners', 09:27:54 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:27:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:54 INFO - 'USER': 'cltbld', 09:27:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:54 INFO - 'XPC_FLAGS': '0x0', 09:27:54 INFO - 'XPC_SERVICE_NAME': '0', 09:27:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:54 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 09:27:54 INFO - Cleaning up... 09:27:54 INFO - Return code: 0 09:27:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105741490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5fbc7d950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105555d30>, '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 0x1053bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10554db20>, '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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:27:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:27:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:27:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSsDeuGSbb/Render', 09:27:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:54 INFO - 'HOME': '/Users/cltbld', 09:27:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:54 INFO - 'LOGNAME': 'cltbld', 09:27:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:54 INFO - 'MOZ_NO_REMOTE': '1', 09:27:54 INFO - 'NO_EM_RESTART': '1', 09:27:54 INFO - 'PAGER': '/bin/cat', 09:27:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:54 INFO - 'PWD': '/builds/slave/test', 09:27:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:54 INFO - 'SHELL': '/bin/bash', 09:27:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1eoT9DUw5H/Listeners', 09:27:54 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:27:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:54 INFO - 'USER': 'cltbld', 09:27:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:54 INFO - 'XPC_FLAGS': '0x0', 09:27:54 INFO - 'XPC_SERVICE_NAME': '0', 09:27:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:55 INFO - Downloading/unpacking psutil>=3.1.1 09:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:27:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:58 INFO - Installing collected packages: psutil 09:27:58 INFO - Running setup.py install for psutil 09:27:58 INFO - building 'psutil._psutil_osx' extension 09:27:58 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 09:27:58 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 09:27:58 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 09:27:58 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 09:27:59 INFO - building 'psutil._psutil_posix' extension 09:27:59 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 09:27:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:27:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:27:59 INFO - ^ 09:27:59 INFO - 1 warning generated. 09:27:59 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 09:27:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:59 INFO - Successfully installed psutil 09:27:59 INFO - Cleaning up... 09:27:59 INFO - Return code: 0 09:27:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105741490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5fbc7d950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105555d30>, '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 0x1053bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10554db20>, '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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:27:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:27:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:27:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSsDeuGSbb/Render', 09:27:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:59 INFO - 'HOME': '/Users/cltbld', 09:27:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:59 INFO - 'LOGNAME': 'cltbld', 09:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:59 INFO - 'MOZ_NO_REMOTE': '1', 09:27:59 INFO - 'NO_EM_RESTART': '1', 09:27:59 INFO - 'PAGER': '/bin/cat', 09:27:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:59 INFO - 'PWD': '/builds/slave/test', 09:27:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:59 INFO - 'SHELL': '/bin/bash', 09:27:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1eoT9DUw5H/Listeners', 09:27:59 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:27:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:59 INFO - 'USER': 'cltbld', 09:27:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:59 INFO - 'XPC_FLAGS': '0x0', 09:27:59 INFO - 'XPC_SERVICE_NAME': '0', 09:27:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:28:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:28:02 INFO - Installing collected packages: mozsystemmonitor 09:28:02 INFO - Running setup.py install for mozsystemmonitor 09:28:02 INFO - Successfully installed mozsystemmonitor 09:28:02 INFO - Cleaning up... 09:28:02 INFO - Return code: 0 09:28:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:28: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')]} 09:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28: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')]} 09:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:02 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 0x105741490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5fbc7d950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105555d30>, '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 0x1053bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10554db20>, '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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:28:02 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 09:28:02 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 09:28:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSsDeuGSbb/Render', 09:28:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:02 INFO - 'HOME': '/Users/cltbld', 09:28:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:02 INFO - 'LOGNAME': 'cltbld', 09:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:02 INFO - 'MOZ_NO_REMOTE': '1', 09:28:02 INFO - 'NO_EM_RESTART': '1', 09:28:02 INFO - 'PAGER': '/bin/cat', 09:28:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:02 INFO - 'PWD': '/builds/slave/test', 09:28:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:02 INFO - 'SHELL': '/bin/bash', 09:28:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1eoT9DUw5H/Listeners', 09:28:02 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:28:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:02 INFO - 'USER': 'cltbld', 09:28:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:02 INFO - 'XPC_FLAGS': '0x0', 09:28:02 INFO - 'XPC_SERVICE_NAME': '0', 09:28:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:02 INFO - Downloading/unpacking jsonschema==2.5.1 09:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:28:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:28:05 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 09:28:05 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 09:28:05 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 09:28:05 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 09:28:05 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 09:28:05 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 09:28:05 INFO - Downloading functools32-3.2.3-2.tar.gz 09:28:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:28:05 INFO - warning: no files found matching '*.txt' 09:28:05 INFO - no previously-included directories found matching 'build' 09:28:05 INFO - no previously-included directories found matching 'dist' 09:28:05 INFO - no previously-included directories found matching '.git*' 09:28:05 INFO - Installing collected packages: jsonschema, functools32 09:28:05 INFO - Running setup.py install for functools32 09:28:05 INFO - warning: no files found matching '*.txt' 09:28:05 INFO - no previously-included directories found matching 'build' 09:28:05 INFO - no previously-included directories found matching 'dist' 09:28:05 INFO - no previously-included directories found matching '.git*' 09:28:05 INFO - Successfully installed jsonschema functools32 09:28:05 INFO - Cleaning up... 09:28:06 INFO - Return code: 0 09:28:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105741490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5fbc7d950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105555d30>, '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 0x1053bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10554db20>, '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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:28:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:28:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSsDeuGSbb/Render', 09:28:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:06 INFO - 'HOME': '/Users/cltbld', 09:28:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:06 INFO - 'LOGNAME': 'cltbld', 09:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:06 INFO - 'MOZ_NO_REMOTE': '1', 09:28:06 INFO - 'NO_EM_RESTART': '1', 09:28:06 INFO - 'PAGER': '/bin/cat', 09:28:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:06 INFO - 'PWD': '/builds/slave/test', 09:28:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:06 INFO - 'SHELL': '/bin/bash', 09:28:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1eoT9DUw5H/Listeners', 09:28:06 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:28:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:06 INFO - 'USER': 'cltbld', 09:28:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:06 INFO - 'XPC_FLAGS': '0x0', 09:28:06 INFO - 'XPC_SERVICE_NAME': '0', 09:28:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:28:06 INFO - Cleaning up... 09:28:06 INFO - Return code: 0 09:28:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:07 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 0x105741490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5fbc7d950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105555d30>, '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 0x1053bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10554db20>, '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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:28:07 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 09:28:07 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 09:28:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSsDeuGSbb/Render', 09:28:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:07 INFO - 'HOME': '/Users/cltbld', 09:28:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:07 INFO - 'LOGNAME': 'cltbld', 09:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:07 INFO - 'MOZ_NO_REMOTE': '1', 09:28:07 INFO - 'NO_EM_RESTART': '1', 09:28:07 INFO - 'PAGER': '/bin/cat', 09:28:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:07 INFO - 'PWD': '/builds/slave/test', 09:28:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:07 INFO - 'SHELL': '/bin/bash', 09:28:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1eoT9DUw5H/Listeners', 09:28:07 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:28:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:07 INFO - 'USER': 'cltbld', 09:28:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:07 INFO - 'XPC_FLAGS': '0x0', 09:28:07 INFO - 'XPC_SERVICE_NAME': '0', 09:28:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:07 INFO - Downloading/unpacking blobuploader==1.2.4 09:28:07 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 09:28:07 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 09:28:07 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 09:28:07 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 09:28:07 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 09:28:07 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 09:28:09 INFO - Downloading blobuploader-1.2.4.tar.gz 09:28:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:28:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:28:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:11 INFO - Downloading docopt-0.6.1.tar.gz 09:28:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:28:11 INFO - Installing collected packages: blobuploader, requests, docopt 09:28:11 INFO - Running setup.py install for blobuploader 09:28:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:28:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:28:11 INFO - Running setup.py install for requests 09:28:11 INFO - Running setup.py install for docopt 09:28:12 INFO - Successfully installed blobuploader requests docopt 09:28:12 INFO - Cleaning up... 09:28:12 INFO - Return code: 0 09:28:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:28: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')]} 09:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28: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')]} 09:28:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:12 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 0x105741490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5fbc7d950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105555d30>, '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 0x1053bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10554db20>, '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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:28:12 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 09:28:12 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 09:28:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSsDeuGSbb/Render', 09:28:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:12 INFO - 'HOME': '/Users/cltbld', 09:28:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:12 INFO - 'LOGNAME': 'cltbld', 09:28:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:12 INFO - 'MOZ_NO_REMOTE': '1', 09:28:12 INFO - 'NO_EM_RESTART': '1', 09:28:12 INFO - 'PAGER': '/bin/cat', 09:28:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:12 INFO - 'PWD': '/builds/slave/test', 09:28:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:12 INFO - 'SHELL': '/bin/bash', 09:28:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1eoT9DUw5H/Listeners', 09:28:12 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:28:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:12 INFO - 'USER': 'cltbld', 09:28:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:12 INFO - 'XPC_FLAGS': '0x0', 09:28:12 INFO - 'XPC_SERVICE_NAME': '0', 09:28:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:28:12 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-U4oqzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:28:12 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-eFi9zZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:28:12 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-yWeu9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:28:12 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-m8Wdzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:28:12 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-cpm8PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:28:12 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-8Pvt7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:28:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-xrdznP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:28:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-QOx3gK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:28:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-3KsiPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:28:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-IJdmUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:28:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-PjNN4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:28:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-IFGbWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:28:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-vDA_VC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:28:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-kdk3W8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-QxLqsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:28:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-SclDa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:28:14 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-cmVZaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:28:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:28:14 INFO - Running setup.py install for manifestparser 09:28:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:28:14 INFO - Running setup.py install for mozcrash 09:28:14 INFO - Running setup.py install for mozdebug 09:28:14 INFO - Running setup.py install for mozdevice 09:28:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:28:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:28:14 INFO - Running setup.py install for mozfile 09:28:14 INFO - Running setup.py install for mozhttpd 09:28:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:28:15 INFO - Running setup.py install for mozinfo 09:28:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:28:15 INFO - Running setup.py install for mozInstall 09:28:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:28:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:28:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:28:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:28:15 INFO - Running setup.py install for mozleak 09:28:15 INFO - Running setup.py install for mozlog 09:28:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:28:15 INFO - Running setup.py install for moznetwork 09:28:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:28:15 INFO - Running setup.py install for mozprocess 09:28:16 INFO - Running setup.py install for mozprofile 09:28:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:28:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:28:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:28:16 INFO - Running setup.py install for mozrunner 09:28:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:28:16 INFO - Running setup.py install for mozscreenshot 09:28:16 INFO - Running setup.py install for moztest 09:28:16 INFO - Running setup.py install for mozversion 09:28:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:28:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:28:16 INFO - Cleaning up... 09:28:16 INFO - Return code: 0 09:28:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:28:16 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')]} 09:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:16 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')]} 09:28:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:16 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 0x105741490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5fbc7d950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105555d30>, '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 0x1053bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10554db20>, '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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:28:16 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 09:28:16 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 09:28:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSsDeuGSbb/Render', 09:28:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:16 INFO - 'HOME': '/Users/cltbld', 09:28:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:16 INFO - 'LOGNAME': 'cltbld', 09:28:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:16 INFO - 'MOZ_NO_REMOTE': '1', 09:28:16 INFO - 'NO_EM_RESTART': '1', 09:28:16 INFO - 'PAGER': '/bin/cat', 09:28:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:16 INFO - 'PWD': '/builds/slave/test', 09:28:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:16 INFO - 'SHELL': '/bin/bash', 09:28:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1eoT9DUw5H/Listeners', 09:28:16 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:28:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:16 INFO - 'USER': 'cltbld', 09:28:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:16 INFO - 'XPC_FLAGS': '0x0', 09:28:16 INFO - 'XPC_SERVICE_NAME': '0', 09:28:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:28:17 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-YFzexE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:28:17 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)) 09:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:28:17 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-eXltdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:28:17 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)) 09:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:28:17 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-2nSSiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:28:17 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)) 09:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:28:17 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-6U0xxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:28:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 09:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:28:17 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-vYi38u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:28:17 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)) 09:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:28:17 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-Yb0yGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:28:17 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)) 09:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:28:17 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-p4Hp_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:28:17 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)) 09:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:28:17 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-noSBPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:28:18 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)) 09:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:28:18 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-6V93Zz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:28:18 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)) 09:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:28:18 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-dNQkrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:28:18 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)) 09:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:28:18 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-xmGNif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:28:18 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)) 09:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:28:18 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-O3fOOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:28:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 09:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:28:18 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-vKvVnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:28:18 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)) 09:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:28:18 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-n2ZjME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:28:18 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)) 09:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:18 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-NW8j3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:18 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)) 09:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:28:18 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-YQI9Ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:28:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 09:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:28:18 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-tTv2fS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:28:18 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)) 09:28:18 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)) 09:28:18 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)) 09:28:18 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)) 09:28:18 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:28:18 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:28:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:28:18 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 09:28:18 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 09:28:18 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 09:28:18 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 09:28:18 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 09:28:18 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 09:28:21 INFO - Downloading blessings-1.6.tar.gz 09:28:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:28:21 INFO - Installing collected packages: blessings 09:28:21 INFO - Running setup.py install for blessings 09:28:21 INFO - Successfully installed blessings 09:28:21 INFO - Cleaning up... 09:28:21 INFO - Return code: 0 09:28:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:28:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:28:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:28:22 INFO - Reading from file tmpfile_stdout 09:28:22 INFO - Current package versions: 09:28:22 INFO - blessings == 1.6 09:28:22 INFO - blobuploader == 1.2.4 09:28:22 INFO - docopt == 0.6.1 09:28:22 INFO - functools32 == 3.2.3-2 09:28:22 INFO - jsonschema == 2.5.1 09:28:22 INFO - manifestparser == 1.1 09:28:22 INFO - mozInstall == 1.12 09:28:22 INFO - mozcrash == 1.0 09:28:22 INFO - mozdebug == 0.1 09:28:22 INFO - mozdevice == 0.48 09:28:22 INFO - mozfile == 1.2 09:28:22 INFO - mozhttpd == 0.7 09:28:22 INFO - mozinfo == 0.9 09:28:22 INFO - mozleak == 0.1 09:28:22 INFO - mozlog == 3.4 09:28:22 INFO - moznetwork == 0.27 09:28:22 INFO - mozprocess == 0.23 09:28:22 INFO - mozprofile == 0.28 09:28:22 INFO - mozrunner == 6.13 09:28:22 INFO - mozscreenshot == 0.1 09:28:22 INFO - mozsystemmonitor == 0.3 09:28:22 INFO - moztest == 0.7 09:28:22 INFO - mozversion == 1.4 09:28:22 INFO - psutil == 3.1.1 09:28:22 INFO - requests == 1.2.3 09:28:22 INFO - wsgiref == 0.1.2 09:28:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:28:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:22 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 0x105741490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5fbc7d950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105555d30>, '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 0x1053bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10554db20>, '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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:28:22 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 09:28:22 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 09:28:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSsDeuGSbb/Render', 09:28:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:22 INFO - 'HOME': '/Users/cltbld', 09:28:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:22 INFO - 'LOGNAME': 'cltbld', 09:28:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:22 INFO - 'MOZ_NO_REMOTE': '1', 09:28:22 INFO - 'NO_EM_RESTART': '1', 09:28:22 INFO - 'PAGER': '/bin/cat', 09:28:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:22 INFO - 'PWD': '/builds/slave/test', 09:28:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:22 INFO - 'SHELL': '/bin/bash', 09:28:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1eoT9DUw5H/Listeners', 09:28:22 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:28:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:22 INFO - 'USER': 'cltbld', 09:28:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:22 INFO - 'XPC_FLAGS': '0x0', 09:28:22 INFO - 'XPC_SERVICE_NAME': '0', 09:28:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:22 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)) 09:28:22 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)) 09:28:22 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)) 09:28:22 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)) 09:28:22 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)) 09:28:22 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)) 09:28:22 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)) 09:28:22 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)) 09:28:22 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)) 09:28:22 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)) 09:28:22 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)) 09:28:22 INFO - Cleaning up... 09:28:22 INFO - Return code: 0 09:28:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:28:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:22 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 0x105741490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5fbc7d950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105555d30>, '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 0x1053bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10554db20>, '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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:28:22 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 09:28:22 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 09:28:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSsDeuGSbb/Render', 09:28:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:22 INFO - 'HOME': '/Users/cltbld', 09:28:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:22 INFO - 'LOGNAME': 'cltbld', 09:28:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:22 INFO - 'MOZ_NO_REMOTE': '1', 09:28:22 INFO - 'NO_EM_RESTART': '1', 09:28:22 INFO - 'PAGER': '/bin/cat', 09:28:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:22 INFO - 'PWD': '/builds/slave/test', 09:28:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:22 INFO - 'SHELL': '/bin/bash', 09:28:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1eoT9DUw5H/Listeners', 09:28:22 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:28:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:22 INFO - 'USER': 'cltbld', 09:28:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:22 INFO - 'XPC_FLAGS': '0x0', 09:28:22 INFO - 'XPC_SERVICE_NAME': '0', 09:28:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:28:22 INFO - Cleaning up... 09:28:22 INFO - Return code: 0 09:28:22 INFO - Running post-action listener: _resource_record_post_action 09:28:22 INFO - Running post-action listener: _start_resource_monitoring 09:28:22 INFO - Starting resource monitoring. 09:28:22 INFO - [mozharness: 2016-11-21 17:28:22.702715Z] Finished create-virtualenv step (success) 09:28:22 INFO - [mozharness: 2016-11-21 17:28:22.703777Z] Running install step. 09:28:22 INFO - Running pre-action listener: _resource_record_pre_action 09:28:22 INFO - Running main action method: install 09:28:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:28:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:28:22 INFO - Reading from file tmpfile_stdout 09:28:22 INFO - Detecting whether we're running mozinstall >=1.0... 09:28:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:28:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:28:23 INFO - Reading from file tmpfile_stdout 09:28:23 INFO - Output received: 09:28:23 INFO - Usage: mozinstall [options] installer 09:28:23 INFO - Options: 09:28:23 INFO - -h, --help show this help message and exit 09:28:23 INFO - -d DEST, --destination=DEST 09:28:23 INFO - Directory to install application into. [default: 09:28:23 INFO - "/builds/slave/test"] 09:28:23 INFO - --app=APP Application being installed. [default: firefox] 09:28:23 INFO - mkdir: /builds/slave/test/build/application 09:28:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:28:55 INFO - Reading from file tmpfile_stdout 09:28:55 INFO - Output received: 09:28:55 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:28:55 INFO - Running post-action listener: _resource_record_post_action 09:28:55 INFO - [mozharness: 2016-11-21 17:28:55.373188Z] Finished install step (success) 09:28:55 INFO - [mozharness: 2016-11-21 17:28:55.373334Z] Running run-tests step. 09:28:55 INFO - Running pre-action listener: _resource_record_pre_action 09:28:55 INFO - Running main action method: run_tests 09:28:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:28:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:28:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:28:55 INFO - 2016-11-21 09:28:55.387 screenresolution[2048:22353] starting screenresolution argv=screenresolution get 09:28:55 INFO - 2016-11-21 09:28:55.390 screenresolution[2048:22353] Display 0: 1600x1200x32@60 09:28:55 INFO - 2016-11-21 09:28:55.396 screenresolution[2049:22355] starting screenresolution argv=screenresolution list 09:28:55 INFO - Available Modes on Display 0 09:28:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:28:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:28:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:28:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:28:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:28:55 INFO - Intel Iris: 09:28:55 INFO - Chipset Model: Intel Iris 09:28:55 INFO - Type: GPU 09:28:55 INFO - Bus: Built-In 09:28:55 INFO - VRAM (Dynamic, Max): 1536 MB 09:28:55 INFO - Vendor: Intel (0x8086) 09:28:55 INFO - Device ID: 0x0a2e 09:28:55 INFO - Revision ID: 0x0009 09:28:55 INFO - Displays: 09:28:55 INFO - Display: 09:28:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:28:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:28:55 INFO - Display Serial Number: 1600x1200 60 09:28:55 INFO - Main Display: Yes 09:28:55 INFO - Mirror: Off 09:28:55 INFO - Online: Yes 09:28:55 INFO - Rotation: Supported 09:28:55 INFO - Return code: 0 09:28:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:28:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:28:55 INFO - Python 2.7.3 09:28:55 INFO - Return code: 0 09:28:55 INFO - grabbing minidump binary from tooltool 09:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:55 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 0x105555d30>, '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 0x1053bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10554db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:28:55 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 09:28:55 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 09:28:55 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 09:28:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:28:55 INFO - Return code: 0 09:28:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:28:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:28:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:28:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:28:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:28:55 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/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0126', '--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 09:28:55 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/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0126 --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 09:28:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RSsDeuGSbb/Render', 09:28:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:55 INFO - 'HOME': '/Users/cltbld', 09:28:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:55 INFO - 'LOGNAME': 'cltbld', 09:28:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:28:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:28:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:55 INFO - 'MOZ_NO_REMOTE': '1', 09:28:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:28:55 INFO - 'NO_EM_RESTART': '1', 09:28:55 INFO - 'PAGER': '/bin/cat', 09:28:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:55 INFO - 'PWD': '/builds/slave/test', 09:28:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:28:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:55 INFO - 'SHELL': '/bin/bash', 09:28:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1eoT9DUw5H/Listeners', 09:28:55 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:28:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:55 INFO - 'USER': 'cltbld', 09:28:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:55 INFO - 'XPC_FLAGS': '0x0', 09:28:55 INFO - 'XPC_SERVICE_NAME': '0', 09:28:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:55 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/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0126', '--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 09:28:55 INFO - mozversion application_buildid: 20161121065108 09:28:55 INFO - mozversion application_changeset: c58b40f5e8b398c225df819b28314c57181273dd 09:28:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:28:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:28:55 INFO - mozversion application_name: Firefox 09:28:55 INFO - mozversion application_remotingname: firefox-dev 09:28:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:28:55 INFO - mozversion application_vendor: Mozilla 09:28:55 INFO - mozversion application_version: 52.0a2 09:28:55 INFO - mozversion platform_buildid: 20161121065108 09:28:55 INFO - mozversion platform_changeset: c58b40f5e8b398c225df819b28314c57181273dd 09:28:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:28:55 INFO - mozversion platform_version: 52.0a2 09:28:55 INFO - using testdate: 1479749335 09:28:55 INFO - actual date: 1479749335 09:28:55 INFO - starting webserver on 'localhost:49303' 09:28:55 INFO - SUITE-START | Running 2 tests 09:28:55 INFO - TEST-START | dromaeo_css 09:28:55 INFO - operating with platform_type : mac_ 09:28:55 INFO - Initialising browser for dromaeo_css test... 09:28:55 INFO - TEST-INFO | started process 2059 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/tmpTKA8G7/profile http://localhost:49303/getInfo.html) 09:28:57 ERROR - PROCESS | 2059 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 09:28:58 INFO - PROCESS | 2059 | __metrics Screen width/height:1600/1200 09:28:58 INFO - PROCESS | 2059 | colorDepth:24 09:28:58 INFO - PROCESS | 2059 | Browser inner width/height: 1024/689 09:28:58 INFO - PROCESS | 2059 | __metrics 09:28:58 INFO - PROCESS | 2059 | 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" 09:28:58 INFO - PROCESS | 2059 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:28:59 INFO - TEST-INFO | 2059: exit 0 09:28:59 INFO - Browser initialized. 09:28:59 INFO - Running cycle 1/1 for dromaeo_css test... 09:28:59 INFO - TEST-INFO | started process 2062 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/tmpTKA8G7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:29:11 INFO - PROCESS | 2062 | RSS: Main: 217567232 09:29:11 INFO - PROCESS | 2062 | 09:30:02 INFO - PROCESS | 2062 | 1479749402123 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:32:16 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49303/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49303/tests/dromaeo/cssquery-ext.html) 09:32:18 INFO - PROCESS | 2062 | RSS: Main: 347664384 09:32:18 INFO - PROCESS | 2062 | 09:35:21 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49303/tests/dromaeo/cssquery-ext.html (next: http://localhost:49303/tests/dromaeo/cssquery-jquery.html) 09:35:21 INFO - PROCESS | 2062 | RSS: Main: 348221440 09:35:21 INFO - PROCESS | 2062 | 09:38:24 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49303/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49303/tests/dromaeo/cssquery-mootools.html) 09:38:24 INFO - PROCESS | 2062 | RSS: Main: 319082496 09:38:24 INFO - PROCESS | 2062 | 09:41:27 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49303/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49303/tests/dromaeo/cssquery-prototype.html) 09:41:27 INFO - PROCESS | 2062 | RSS: Main: 337399808 09:41:27 INFO - PROCESS | 2062 | 09:44:31 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49303/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49303/tests/dromaeo/cssquery-yui.html) 09:44:31 INFO - PROCESS | 2062 | RSS: Main: 310128640 09:44:31 INFO - PROCESS | 2062 | 09:47:34 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:49303/tests/dromaeo/cssquery-yui.html (next: http://localhost:49303/tests/dromaeo/cssquery-dojo.html) 09:47:35 INFO - PROCESS | 2062 | RSS: Main: 389251072 09:47:35 INFO - PROCESS | 2062 | 09:47:35 INFO - PROCESS | 2062 | __start_tp_report 09:47:35 INFO - PROCESS | 2062 | _x_x_mozilla_page_load 09:47:35 INFO - PROCESS | 2062 | _x_x_mozilla_page_load_details 09:47:35 INFO - PROCESS | 2062 | |i|pagename|runs| 09:47:35 INFO - PROCESS | 2062 | |0;dojo.html;3287.9013629591113;3293.1602441377445;3295.5386245925574;3308.454105072665;3361.226917808904;5650.6044576879585;5652.745626446809;5671.574631902603;5684.118961560955;5705.771769129235;5578.832635020945;5649.1526271059365;5668.858278543038;5682.801101961432;5686.346070201928;5138.306328645641;5150.510701483353;5169.198774190007;5196.1166601677805;5232.529072383504;4220.788960551976;5131.820386286481;5274.55166310864;5274.947250527484;5304.469553044684;5165.870853228662;5215.139501982168;5224.973875130619;5233.371995360562;5236.21456781482;5202.791888324481;5225.947740522584;5231.843044708665;5232.947670523322;5233.058049551079;5093.210552364352;5194.506521880416;5205.323307969978;5206.271122042917;5238.580913526908;5701.059325211377;5704.401037891044;5715.3141623005085;5715.428457154293;5716.342620598602;6099.634021958696;6103.908441373339;6107;6130.754769809202;6135.987562052256;6169.722362493699;6185.855616710923;6196.442320191204;6202.131701561785;6212.534059945522;5671.432856714337;5673.461021202979;5674.404187560288;5728.97135514324;5731.627444216113;6359.36406359365;6361.872762544724;6374.872502550016;6386.680665966683;6394.008928616072;4984.700917944935;5888.116782482662;5911.940880591225;5918.674472903946;6006.879862402815;6115.270764408881;6191.659458729813;6195.876082478325;6206.689665516707;6234.719437625317;4960.70235785854;6186.288576813634;6189.96905015472;6205.844853878689;6208.50331973441;6039.577229593974;6092.908606370908;6121.081837724377;6142.723577439026;6224.37756224374;4866.124097662455;4890.902181956412;4893.975530122327;4914.926276105862;4941.48114447985;4378.737275763465;4402;4411.955880441219;4416.955830441654;4426.092651006536;5541.556675465953;5681.971590142023;5702.173184888168;5726.627769194989;5748.913766293509;6108.08378743192;6127.1393886820315;6166.475849552839;6190.473809726133;6197.442230199304;5709.771609135588;5724.16999535065;5725.141228815628;5725.370209276976;5751.85620359486;5321.281626980385;5359;5362.570994320445;5376.973115134478;5388.218708287355;6118.265808103056;6231.189945307081;6243.250809902841;6269.874602508015;6294.464970477561;6206.06908963641;6285.528585356025;6293.590916590407;6298.685065746786;6301.6219026858535;6332.335104814112;6340.4293613575;6343.873122537616;6355.491560675043;6359.459445946961;5959.821205363846;5992.550558708027;6066.636001839904;6129.908051379322;6181.474574661204;5993.340732519332;6148.077788331786;6185.8144255672405;6255.437010669061;6278.7488500461295;4986.177280748636;6045.818625441244;6053.909191362045;6057.363976782551;6060.969695151673;5865.061590145727;6005.549583781321;6019.127226552213;6035.5473339500595;6041.546883983807;5774.884502310014;5813.6221145625395;5841.18223448726;5864.736086876186;5941.910871336847;6068.423499767515;6077.4226448487325;6079.635221886701;6085.90453718335;6102.786402475892;5696.772129114955;5764.13537969308;5764.279465066867;5798.420157984167;5862.296524416926 09:47:35 INFO - PROCESS | 2062 | |1;ext.html;464.70723444230134;464.79548998440686;470.4378267969776;516.9586433085351;520.4691214960742;23579.292621221368;31231.531527027113;31394.529082063786;38073.42889856654;39401;41058.17883642333;41415.378769318406;41438.79280603601;41442.7927860361;41551.79224103883;9188.724338269845;9365.391249568791;9370.25037996962;9371.6719914803;9372.95313523435;8016.15830337812;8625.051244363114;8632.438891472035;8706.564671766419;8715.215630593277;11090.778184436307;11369.886301137007;11394.715132121722;11550.422478876064;11556.826647600292;11253.887461125407;11331.716707082307;11352.262102963363;11355.829662555068;11380.943095284554;8142.715004974857;8673.176048275403;10031.247656425725;11213.495392707347;11293.153013523937;37883.62116378829;38720.22559548821;38918.41622375666;39787.40318895219;39923.8003809982;3227.80633162011;3260.8206548639814;3275.967240327614;3282.1302354875975;3323.4848598467274;29272;29784.255393615116;29993.850030749705;30127.69872301271;30154.84922575395;3143.591333126679;3172.159377764894;3185.3310804731036;3210.0530343548708;3313;492677;493578;497951;498190;501982.4900875509;52996.735016324674;54265.72867135718;58047.129293060636;58859;59554.404455954886;61820.763584728054;62060.75878482405;62084.37915620786;62143;62261.377386226464;15722.29249683767;15889.205539722967;15908.284127214303;15909.045457272601;16051.678966420606;12266.693332666755;12382.690432739073;12383.814242786366;12386.690332741753;12399;14971.850281497263;15242.466513671969;15268.465603703817;16590.17049147538;17112.40106596288;5699.8860022800145;6715.321169707573;6733.93266067333;6783.762568310184;6881.8967715484305;859.5014891362995;993.6820217530321;994.683758135846;1004.2167109654335;1011.939283642969;29026.564601530994;29127.417451650846;29270.975515856844;29664.258393540335;29800.254993625334;1051.1695760349392;1065.3767545985531;1090.8527348807968;1111.488715191005;1149.287441786098;14819.259037048105;15224.46714364992;15292.00601960869;15603.141827199386;15629.921850390903;626199.8690006521;630979.2255193656;631423;631663;631825;48492.51507484951;49482.25776613354;55112;55323.723381382835;56292.87414251692;1319.8548159702425;1324.5496531179444;1365.9795103073263;1371.4445649511963;1371.979420308716;29193;29845.55231671483;29949;30293.476471497524;30643;40345;48463.75768121137;48934.510654893;48957.51042489529;48971.775705607644;497924;581481.0925945343;582920;583879;587203;38567.42148867825;39114.60885391109;39282.82151535458;39362.606373935894;39403.80298098491;44469.55530444784;44629.77685111554;45261;45290.09419811651;45308;36741.63258367382;36745;37687.62312376951;39005.414918776805;39211.01972450712;36581.63418365889;37399.62600373961;37877;38630;38676.613233868426;36100.81949590235;36439.27121457609;37169.81415092907;37515.43726844045;38565.22869542537;31747.36505269928;33266.83366583152;33894;33946.49080263847;33992.83003585065;25200;25214;25265.621015684414;25478.23565293044;25857 09:47:35 INFO - PROCESS | 2062 | |2;jquery.html;351.47279081377934;372.96643302102814;375.43443839190587;383.98272077756496;404.9473568436103;14780.926095369521;14846.331915063822;15812.288447019882;20089.899550502265;20646.793532064676;16971.49085527433;18326.35857744979;19727.21091156352;20203.39389818308;20462.283820066263;5413.404525502201;5960.5529585281065;5973.4623883850445;5979.910301345483;5988.850278743044;4601.976990115045;5837.09525023622;5882.911756323637;5889.352171261158;5904.88190236195;5673.290838645169;5830.970845145768;5842.094475356326;5844.503217226541;5853.824385268449;4844.660873738822;5825.067989121741;5887.234659494238;5890.764369425217;5891.116332550109;4881.7551524361415;5058.038972595232;5165.328507294028;5171.7672704728375;5189.091908915962;25235;25828.87085564579;26005.219843404728;26330.868345658342;26408.471830563474;2229.7034054923292;2253.549290141978;2361.504084142332;2620.9606855897177;2894.421115776853;15097.698046039128;15240.695186096216;15341.92329038359;16061.678766424722;16140.75788863168;1728.567858035491;1729.3746351791262;1786.3747688308988;1843.6681397348473;1982.058522201957;119095.4045229777;129686.70313296936;129872;131737;132493;10209.74475638115;10990.560377584967;11398.601048963246;11615.36115513638;11724.88275117255;14921.776173357192;14934.92532537352;14942.551723448314;14959.326830292663;14967.850321496864;8388.916110838814;8521.914780852112;8527.65889364431;8552.657893684183;8654.004789449167;6903.551269167488;8390.286825619769;8422.873656895152;8571.057183709787;8589.398742088119;4444.400005999149;4510.075434535912;4540.432445944257;4563.794629241625;4581.427321584802;1862.2029741299712;1920.7310976463027;1922.3175772600805;1922.4905400068992;1963.2932144427994;713.5504632081755;818.8536049530704;863.6977058029765;879.9120087991278;919.4023884475012;20317.898410508147;21464.141434342764;21556.784432155477;21577.568448631253;21674.674879876813;715.9964200178965;930.251147825993;947.8009617980164;948.5067764762285;972.4651441707033;10734.892651073545;11196.664100076849;11237.269577477438;11291.717707057225;11296.322220666787;589141;591514;596762;607412;607659;124378.26865194058;128133.35933320274;131872;133485.99771003443;134645;818.8730746734146;933.0995589256536;964.4165280005495;965.5558443116245;966.6665000574791;18603.883766974024;18977.810221897606;19023.52441188981;19057.237710491427;19102.426927192206;54193.645158871346;54302.58464797016;55378.615534611956;55579.722101389234;55962.440375595725;78419.38010406548;78862.42275154579;78862.6056869712;79682;79963.60018199871;7047.753328633473;7912.564808935564;7993.200679931961;8238.382121340803;8388.370872184489;12227.388630568257;12700.237985720887;12776.425060872098;13083.084184107021;13187.934060329637;10660;10884.292520986111;11038.337699738042;11202.607908723156;11391.316521008766;10229.28395012342;10934.234603577674;11058.944705276423;11394.829077564009;11407.25852819564;9063.954680226556;9367.5784589695;11109.277896936674;11275.64692236904;11631.88368116342;7332.120145582564;7341.449391295568;7382.335589796944;7520.811979700551;7577.659005344883;8801;11167.888321116685;11190.048845847869;11525;11564.94217528907 09:47:35 INFO - PROCESS | 2062 | |3;mootools.html;1318.5582829752032;1474.3218119664953;1496.7156240314341;1665.4254282272611;1843.410108765194;7540.472166948316;7952.125266220711;8318.126596707341;8547.914520854789;8558.400911936167;8481.618327175293;8516.787080322994;8535;8541.615627296771;8545.87181192281;2350.929472115835;2383.6067048936907;2385.7375688674233;2388.8088952883727;2389.1160270699866;2366.7633236676365;2405.915792947247;2408.0608562660577;2411.9035238590436;2422.479166979108;2348.8003519700846;2359.7994170495444;2382.332946774906;2382.9046838126455;2386.140989243871;2288.8054515366216;2319.826013049028;2349.1425629645228;2350.083467447697;2357.8349515533746;1975.7530308711412;2342.636891281854;2361.2089949866813;2391.306521108894;2395.377201927494;8401.789955251106;8448.746537603882;8513.318934485289;8523.403361764678;8528;5059.3169922060415;5257.7108259045735;5269.104252277084;5300.602454815866;5311.336082989626;3504.439289713659;3567.839447224881;3656.780593164419;3656.963430365689;3666.5233519642543;909.9772505687345;911.5305617606938;912.361347057056;1063.075481334195;1083.756154865149;10977.725556861062;11050.889491105067;11138.443077846156;11165.330080195214;11166.553337866475;4359.891002724958;4452.4211852458975;4525.208088584484;4543.795529201194;4555.430571178603;8809.735707928772;8815.515146666867;8817.779555511164;8818.470891746516;8831;5165.638405311592;5165.793368265227;5168.8966220675375;5176.7152806596;5191.402988656278;4176.248275310473;4346.891327716769;4467.195904737114;4470.977645111753;4759.9524004759505;3690.114372550622;3704.4628528863705;3765.378712512405;3795.146092129304;3807.8286477108595;1147.6327575175865;1155.6879642496478;1156.0578128824982;1165.5337864854125;1171.9531218751324;778.3695206882443;860.1269711243093;872.912708729122;879.8416285068621;901.8782464367358;5361;5511.641743286674;5546.195801608744;5718.142278658234;5823.068309070529;430.694207112947;448.7509432265059;448.8092560661771;448.89002194461915;452.22443509381566;3693.7229707772026;3725.5715592706642;3759.191773768653;3771.9622803771613;3889.5915928827067;9927.801443971224;10213.642522511676;10270.845937310947;10294.948525257476;10306.278560500694;9633.662821801203;9950.751231219278;10255.641052563275;10263.435511046964;10275.280730348804;6907.929270963009;7769.339606133542;7820;7991.320737737357;7992.640331185111;5084.3898732152375;5300.125479296027;5651.660900345993;5696;5864.413558644102;9527.237820974167;9676.612935482503;9684.806303874024;9715.36850104741;9727.173190278905;7171.641417928979;8353.916460835557;8882.60028298741;9249.491277979776;9548.18840398574;6728.091707619409;6898.1377327834025;7054.224035356004;7108.253633368422;7162.212156662658;7786.805329866572;7949.761507154794;8016.158303378062;8107.432479726598;8107.878381824396;7086;7196.532225405332;7238.384737297389;7252.637368131678;7332.486725929134;7100.502964792415;7143.714251429885;7144.1784194818265;7155.141383034069;7198.20819709821;6639.402453779183;6754.8649027020165;6762.256151823394;6846.554973926679;6849.8972515411315;4924.8768780780765;4987.9002419950675;4992;5073.340465739578;5078.009788091286;4922.680025798311;4950.529699678525;5002.474740152377;5075.7969681212335;5175.353080864892 09:47:35 INFO - PROCESS | 2062 | |4;prototype.html;294.16163932791545;298.113113487375;306.81131104370814;311.6089307918562;313.7505682982029;5231.973840130799;5270.307252145789;5274.26160337553;5307.681539107656;5346.545543628791;4492.303692927593;4551.544845515455;5102.3111879896205;5216.22648299578;5245.816396426126;4027.1542975974935;4038.798060096998;4047.927299265697;4050.2507036198326;4060.9796951015205;3044.710752478522;3646;4093.140440507497;4107.938380924288;4110.6094920982605;3943.073377756225;3966.3852102924093;4013.0970531630296;4028.6777057835448;4044.8786536403954;3958.802059896994;3995.441777706697;3997.400389941503;4028.295048366524;4048.67610591152;3503.982480087583;3516.841742121611;3529.347070791886;3534.6288639692975;3539.6460353964653;4192.769397683157;4766.475687674353;5084.593232541425;5146.228065790124;5151.9484805152215;4326.394304797331;4491.9775401123115;4556.2937744649635;4586.770661466913;4646.186917289461;4521.253993091136;5265.86835329116;5360.328175199961;5439.619226654136;5473.480019398151;3105.8757649693985;3287.621923478807;3354.1446931032674;3579.3378225028455;3810.428435734662;5266.183741520071;5395.29861118056;5568.385168301188;5695.977927835642;5707.115397113413;3685.9174566369916;3734.8897872701837;3741.8316175772156;3757.6242375762754;3780.6597406233573;3911.393733971239;3919.294526985113;3923.725339226227;3925.823337949799;3938.428927805452;3425.5955058425925;3776.9235767806595;4500.369948207253;4590.862274131781;4625.907481850344;5370.2213179089595;5404.972975135099;5428.945710542923;5486.286782718221;5504.0643090674685;1259.7480503899073;1265.5380786013038;1274.6928060211048;1287.8518970318346;1293.5770652282415;716.9677364518609;799.8040480082301;831.4637059096849;882.4308321132834;891.7636826241052;4225.68307376948;4281.19581674004;4331.826726930951;4423.871912662315;4751.168545504523;4471.6869819113;4709.740964246999;5232.2674825524455;5267.341582302212;5288;4634.652401069933;4634.86095417138;4773.546513081252;4833.299171620095;4859.635527335464;2286.6227072532847;2751.614773931651;2760.4755096531594;2790.706975767556;2797.6223209866816;4939.506049395103;5357.19642053695;5374.840153452651;5394.514493695587;5441.374241962146;4989.750512474362;5425.361316074778;5449.983506102688;5456.590755693339;5457.951394959726;4651.767411629473;4879.512048795092;5041.59667226621;5398.973005134949;5449;4507.977460112678;5062.569681577107;5204.531592156724;5219.478052194902;5335.199720042025;4278.957210427856;4458;4842.668266226816;4910.779014944264;5322.627416080992;4819.939613285072;5153.948460515497;5299.655522391032;5527.917081243865;5682.318121825408;3686.557613086447;3694.8522059117336;3783.24335132969;3793.1844653399653;3830.8084595770656;5588.060417405801;5622.465865742748;5640.576956728344;5673.468163595889;5691.658500489983;4785;4846.20225976288;4943.703377797344;5446.809361672323;5448.482394172547;4569.428821397325;4717.09898412467;5029.29589857413;5054.418741844588;5267.894642107213;4702.506236845082;5348.384935732285;5357.732113394392;5365.624406291522;5369.48989845964;4420.801063952065;5255.474452554714;5303.7878484860175;5410.945890541044;5488.1340344283135;5347.839564813062;5367.677939323653;5379.772136717938;5382.3003009610065;5433.429489903503 09:47:35 INFO - PROCESS | 2062 | |5;yui.html;576.6477610004538;684.3567046975843;701.2496628607389;715.3847690985751;741.3809469093306;12052.638420847377;13817;14509.49216777413;14509.854901450983;14533.200673962929;12854.48582056719;12999.220046797198;13016.21902685837;13020.348982550882;13022.218666879993;630.385374260095;650.9153810004716;789.0964845252197;806.5523634382915;809.3363441977581;780.254856611937;802.1216767639455;808.5310519898475;814;814.816666250095;854.6196942360652;855.3029281135854;857.7555396711938;857.8241460500614;858.3776761847641;669.7689297192461;851.833892390984;852.7186028610544;854.4488804720983;856.3577317012241;510.3161763237243;608.4797498139116;625.368377938281;630.7886857902608;631.7946667333089;13734;13793;13793;13795.10331828428;13798.448062077605;12581.056420768478;12748.936255318757;12755.107142500028;12843.29361885096;13020.804687929689;11927.284362938251;12042.879571204263;12067.517299308018;12073.879261207363;12134.27194368332;4242.596953289432;4243.681723870722;4297.333913243452;4340.066885619607;4443.133588950156;69674.3032569673;82105;84706.15293847045;90713.63929541063;93495.53252233763;8883.955580222058;8988;9148.496832674135;9311.72064838056;9372.765680857896;15873.761893571605;19533.902330488258;19571.902140489492;19692.90153549223;19806.504837378892;7153.105861767279;7268.23683513234;7324.121105467271;7454.776356709307;7469.514481558681;3985.6811455083525;4093.406456063854;4096.487939007624;4106.527749308808;4217.261979153636;3144.685531446837;3229.6608856070243;3234.433974054531;3238.886638967672;3248.123006788201;626.37988391493;630.7287866217479;639.7632875835877;644.8291202831253;646.7703965092419;573.082889523448;594.8364199845078;602.2231321595093;605.2192671453863;608.9451949324493;8136.389770767216;8842;9728.270379721549;9886.30795844284;9962.452065136487;556.899758043548;593.851537115721;622.327885883245;631.5989346764837;667.6995352091482;4142.668586513072;4296.183725092223;4305.440292761921;4327.480702315742;4337.349397590387;94512;96157;97236;105159.4742026285;107679;84865;88539;89508.5524572373;92258.53870730604;93972;71252;78505.21494784979;80187.59906200432;80304.59847700724;81814;8542.27390671775;8682.869756953776;8696.869546956927;8733.737987860375;8907.777305567413;45097.54902450933;46879.828004300165;47838.76080619574;55064.44935550753;55610.44389556053;71550.64224678843;72541.6372918132;72908.27091729015;76208;76355.61822190853;12188.695282617651;12205.633830985085;12482.126251162332;12569.93715031419;12730;13730;14298.499552515612;14472.348744306742;14741.778873317124;14821.555353339418;12813.295268760308;12884.549040783528;13067.869321307046;13114.147580407243;13491.86508134906;12229.694257643274;12282.201656892274;13086.934565327112;13201;13860.514881979083;9927.851082233628;12301.630951071482;12827.037972151938;13118.409671564612;13683.384247708678;10000.199984001409;11095.334279943228;11136.832947505633;11307.434628268718;11522.76954460923;9546.713598592054;10640.787184256425;11374.94312528432;11424.257423267461;11693.356865372487 09:47:35 INFO - PROCESS | 2062 | __end_tp_report 09:47:35 INFO - PROCESS | 2062 | __start_cc_report 09:47:35 INFO - PROCESS | 2062 | _x_x_mozilla_cycle_collect,1443 09:47:35 INFO - PROCESS | 2062 | __end_cc_report 09:47:35 INFO - PROCESS | 2062 | __startTimestamp1479750455153__endTimestamp 09:47:35 INFO - PROCESS | 2062 | 09:47:35 INFO - PROCESS | 2062 | ------- Summary: start ------- 09:47:35 INFO - PROCESS | 2062 | Number of tests: 6 09:47:35 INFO - PROCESS | 2062 | 09:47:35 INFO - PROCESS | 2062 | [#0] dojo.html Cycles:165 Average:5680.25 Median:5798.42 stddev:643.80 (11.1%) stddev-sans-first:617.82 09:47:35 INFO - PROCESS | 2062 | Values: 3287.9 3293.2 3295.5 3308.5 3361.2 5650.6 5652.7 5671.6 5684.1 5705.8 5578.8 5649.2 5668.9 5682.8 5686.3 5138.3 5150.5 5169.2 5196.1 5232.5 4220.8 5131.8 5274.6 5274.9 5304.5 5165.9 5215.1 5225.0 5233.4 5236.2 5202.8 5225.9 5231.8 5232.9 5233.1 5093.2 5194.5 5205.3 5206.3 5238.6 5701.1 5704.4 5715.3 5715.4 5716.3 6099.6 6103.9 6107.0 6130.8 6136.0 6169.7 6185.9 6196.4 6202.1 6212.5 5671.4 5673.5 5674.4 5729.0 5731.6 6359.4 6361.9 6374.9 6386.7 6394.0 4984.7 5888.1 5911.9 5918.7 6006.9 6115.3 6191.7 6195.9 6206.7 6234.7 4960.7 6186.3 6190.0 6205.8 6208.5 6039.6 6092.9 6121.1 6142.7 6224.4 4866.1 4890.9 4894.0 4914.9 4941.5 4378.7 4402.0 4412.0 4417.0 4426.1 5541.6 5682.0 5702.2 5726.6 5748.9 6108.1 6127.1 6166.5 6190.5 6197.4 5709.8 5724.2 5725.1 5725.4 5751.9 5321.3 5359.0 5362.6 5377.0 5388.2 6118.3 6231.2 6243.3 6269.9 6294.5 6206.1 6285.5 6293.6 6298.7 6301.6 6332.3 6340.4 6343.9 6355.5 6359.5 5959.8 5992.6 6066.6 6129.9 6181.5 5993.3 6148.1 6185.8 6255.4 6278.7 4986.2 6045.8 6053.9 6057.4 6061.0 5865.1 6005.5 6019.1 6035.5 6041.5 5774.9 5813.6 5841.2 5864.7 5941.9 6068.4 6077.4 6079.6 6085.9 6102.8 5696.8 5764.1 5764.3 5798.4 5862.3 09:47:35 INFO - PROCESS | 2062 | 09:47:35 INFO - PROCESS | 2062 | [#1] ext.html Cycles:180 Average:69385.28 Median:37601.53 stddev:151666.02 (403.4%) stddev-sans-first:152003.20 09:47:35 INFO - PROCESS | 2062 | Values: 464.7 464.8 470.4 517.0 520.5 23579.3 31231.5 31394.5 38073.4 39401.0 41058.2 41415.4 41438.8 41442.8 41551.8 9188.7 9365.4 9370.3 9371.7 9373.0 8016.2 8625.1 8632.4 8706.6 8715.2 11090.8 11369.9 11394.7 11550.4 11556.8 11253.9 11331.7 11352.3 11355.8 11380.9 8142.7 8673.2 10031.2 11213.5 11293.2 37883.6 38720.2 38918.4 39787.4 39923.8 3227.8 3260.8 3276.0 3282.1 3323.5 29272.0 29784.3 29993.9 30127.7 30154.8 3143.6 3172.2 3185.3 3210.1 3313.0 492677.0 493578.0 497951.0 498190.0 501982.5 52996.7 54265.7 58047.1 58859.0 59554.4 61820.8 62060.8 62084.4 62143.0 62261.4 15722.3 15889.2 15908.3 15909.0 16051.7 12266.7 12382.7 12383.8 12386.7 12399.0 14971.9 15242.5 15268.5 16590.2 17112.4 5699.9 6715.3 6733.9 6783.8 6881.9 859.5 993.7 994.7 1004.2 1011.9 29026.6 29127.4 29271.0 29664.3 29800.3 1051.2 1065.4 1090.9 1111.5 1149.3 14819.3 15224.5 15292.0 15603.1 15629.9 626199.9 630979.2 631423.0 631663.0 631825.0 48492.5 49482.3 55112.0 55323.7 56292.9 1319.9 1324.5 1366.0 1371.4 1372.0 29193.0 29845.6 29949.0 30293.5 30643.0 40345.0 48463.8 48934.5 48957.5 48971.8 497924.0 581481.1 582920.0 583879.0 587203.0 38567.4 39114.6 39282.8 39362.6 39403.8 44469.6 44629.8 45261.0 45290.1 45308.0 36741.6 36745.0 37687.6 39005.4 39211.0 36581.6 37399.6 37877.0 38630.0 38676.6 36100.8 36439.3 37169.8 37515.4 38565.2 31747.4 33266.8 33894.0 33946.5 33992.8 25200.0 25214.0 25265.6 25478.2 25857.0 09:47:35 INFO - PROCESS | 2062 | 09:47:35 INFO - PROCESS | 2062 | [#2] jquery.html Cycles:180 Average:35481.95 Median:14651.45 stddev:100251.69 (684.2%) stddev-sans-first:100498.22 09:47:35 INFO - PROCESS | 2062 | Values: 351.5 373.0 375.4 384.0 404.9 14780.9 14846.3 15812.3 20089.9 20646.8 16971.5 18326.4 19727.2 20203.4 20462.3 5413.4 5960.6 5973.5 5979.9 5988.9 4602.0 5837.1 5882.9 5889.4 5904.9 5673.3 5831.0 5842.1 5844.5 5853.8 4844.7 5825.1 5887.2 5890.8 5891.1 4881.8 5058.0 5165.3 5171.8 5189.1 25235.0 25828.9 26005.2 26330.9 26408.5 2229.7 2253.5 2361.5 2621.0 2894.4 15097.7 15240.7 15341.9 16061.7 16140.8 1728.6 1729.4 1786.4 1843.7 1982.1 119095.4 129686.7 129872.0 131737.0 132493.0 10209.7 10990.6 11398.6 11615.4 11724.9 14921.8 14934.9 14942.6 14959.3 14967.9 8388.9 8521.9 8527.7 8552.7 8654.0 6903.6 8390.3 8422.9 8571.1 8589.4 4444.4 4510.1 4540.4 4563.8 4581.4 1862.2 1920.7 1922.3 1922.5 1963.3 713.6 818.9 863.7 879.9 919.4 20317.9 21464.1 21556.8 21577.6 21674.7 716.0 930.3 947.8 948.5 972.5 10734.9 11196.7 11237.3 11291.7 11296.3 589141.0 591514.0 596762.0 607412.0 607659.0 124378.3 128133.4 131872.0 133486.0 134645.0 818.9 933.1 964.4 965.6 966.7 18603.9 18977.8 19023.5 19057.2 19102.4 54193.6 54302.6 55378.6 55579.7 55962.4 78419.4 78862.4 78862.6 79682.0 79963.6 7047.8 7912.6 7993.2 8238.4 8388.4 12227.4 12700.2 12776.4 13083.1 13187.9 10660.0 10884.3 11038.3 11202.6 11391.3 10229.3 10934.2 11058.9 11394.8 11407.3 9064.0 9367.6 11109.3 11275.6 11631.9 7332.1 7341.4 7382.3 7520.8 7577.7 8801.0 11167.9 11190.0 11525.0 11564.9 09:47:35 INFO - PROCESS | 2062 | 09:47:35 INFO - PROCESS | 2062 | [#3] mootools.html Cycles:180 Average:5442.21 Median:5165.72 stddev:3006.02 (58.2%) stddev-sans-first:2998.47 09:47:35 INFO - PROCESS | 2062 | Values: 1318.6 1474.3 1496.7 1665.4 1843.4 7540.5 7952.1 8318.1 8547.9 8558.4 8481.6 8516.8 8535.0 8541.6 8545.9 2350.9 2383.6 2385.7 2388.8 2389.1 2366.8 2405.9 2408.1 2411.9 2422.5 2348.8 2359.8 2382.3 2382.9 2386.1 2288.8 2319.8 2349.1 2350.1 2357.8 1975.8 2342.6 2361.2 2391.3 2395.4 8401.8 8448.7 8513.3 8523.4 8528.0 5059.3 5257.7 5269.1 5300.6 5311.3 3504.4 3567.8 3656.8 3657.0 3666.5 910.0 911.5 912.4 1063.1 1083.8 10977.7 11050.9 11138.4 11165.3 11166.6 4359.9 4452.4 4525.2 4543.8 4555.4 8809.7 8815.5 8817.8 8818.5 8831.0 5165.6 5165.8 5168.9 5176.7 5191.4 4176.2 4346.9 4467.2 4471.0 4760.0 3690.1 3704.5 3765.4 3795.1 3807.8 1147.6 1155.7 1156.1 1165.5 1172.0 778.4 860.1 872.9 879.8 901.9 5361.0 5511.6 5546.2 5718.1 5823.1 430.7 448.8 448.8 448.9 452.2 3693.7 3725.6 3759.2 3772.0 3889.6 9927.8 10213.6 10270.8 10294.9 10306.3 9633.7 9950.8 10255.6 10263.4 10275.3 6907.9 7769.3 7820.0 7991.3 7992.6 5084.4 5300.1 5651.7 5696.0 5864.4 9527.2 9676.6 9684.8 9715.4 9727.2 7171.6 8353.9 8882.6 9249.5 9548.2 6728.1 6898.1 7054.2 7108.3 7162.2 7786.8 7949.8 8016.2 8107.4 8107.9 7086.0 7196.5 7238.4 7252.6 7332.5 7100.5 7143.7 7144.2 7155.1 7198.2 6639.4 6754.9 6762.3 6846.6 6849.9 4924.9 4987.9 4992.0 5073.3 5078.0 4922.7 4950.5 5002.5 5075.8 5175.4 09:47:35 INFO - PROCESS | 2062 | 09:47:35 INFO - PROCESS | 2062 | [#4] prototype.html Cycles:180 Average:4325.81 Median:4758.82 stddev:1300.57 (27.3%) stddev-sans-first:1268.53 09:47:35 INFO - PROCESS | 2062 | Values: 294.2 298.1 306.8 311.6 313.8 5232.0 5270.3 5274.3 5307.7 5346.5 4492.3 4551.5 5102.3 5216.2 5245.8 4027.2 4038.8 4047.9 4050.3 4061.0 3044.7 3646.0 4093.1 4107.9 4110.6 3943.1 3966.4 4013.1 4028.7 4044.9 3958.8 3995.4 3997.4 4028.3 4048.7 3504.0 3516.8 3529.3 3534.6 3539.6 4192.8 4766.5 5084.6 5146.2 5151.9 4326.4 4492.0 4556.3 4586.8 4646.2 4521.3 5265.9 5360.3 5439.6 5473.5 3105.9 3287.6 3354.1 3579.3 3810.4 5266.2 5395.3 5568.4 5696.0 5707.1 3685.9 3734.9 3741.8 3757.6 3780.7 3911.4 3919.3 3923.7 3925.8 3938.4 3425.6 3776.9 4500.4 4590.9 4625.9 5370.2 5405.0 5428.9 5486.3 5504.1 1259.7 1265.5 1274.7 1287.9 1293.6 717.0 799.8 831.5 882.4 891.8 4225.7 4281.2 4331.8 4423.9 4751.2 4471.7 4709.7 5232.3 5267.3 5288.0 4634.7 4634.9 4773.5 4833.3 4859.6 2286.6 2751.6 2760.5 2790.7 2797.6 4939.5 5357.2 5374.8 5394.5 5441.4 4989.8 5425.4 5450.0 5456.6 5458.0 4651.8 4879.5 5041.6 5399.0 5449.0 4508.0 5062.6 5204.5 5219.5 5335.2 4279.0 4458.0 4842.7 4910.8 5322.6 4819.9 5153.9 5299.7 5527.9 5682.3 3686.6 3694.9 3783.2 3793.2 3830.8 5588.1 5622.5 5640.6 5673.5 5691.7 4785.0 4846.2 4943.7 5446.8 5448.5 4569.4 4717.1 5029.3 5054.4 5267.9 4702.5 5348.4 5357.7 5365.6 5369.5 4420.8 5255.5 5303.8 5410.9 5488.1 5347.8 5367.7 5379.8 5382.3 5433.4 09:47:35 INFO - PROCESS | 2062 | 09:47:35 INFO - PROCESS | 2062 | [#5] yui.html Cycles:180 Average:19579.48 Median:28083.67 stddev:28130.56 (100.2%) stddev-sans-first:28173.29 09:47:35 INFO - PROCESS | 2062 | Values: 576.6 684.4 701.2 715.4 741.4 12052.6 13817.0 14509.5 14509.9 14533.2 12854.5 12999.2 13016.2 13020.3 13022.2 630.4 650.9 789.1 806.6 809.3 780.3 802.1 808.5 814.0 814.8 854.6 855.3 857.8 857.8 858.4 669.8 851.8 852.7 854.4 856.4 510.3 608.5 625.4 630.8 631.8 13734.0 13793.0 13793.0 13795.1 13798.4 12581.1 12748.9 12755.1 12843.3 13020.8 11927.3 12042.9 12067.5 12073.9 12134.3 4242.6 4243.7 4297.3 4340.1 4443.1 69674.3 82105.0 84706.2 90713.6 93495.5 8884.0 8988.0 9148.5 9311.7 9372.8 15873.8 19533.9 19571.9 19692.9 19806.5 7153.1 7268.2 7324.1 7454.8 7469.5 3985.7 4093.4 4096.5 4106.5 4217.3 3144.7 3229.7 3234.4 3238.9 3248.1 626.4 630.7 639.8 644.8 646.8 573.1 594.8 602.2 605.2 608.9 8136.4 8842.0 9728.3 9886.3 9962.5 556.9 593.9 622.3 631.6 667.7 4142.7 4296.2 4305.4 4327.5 4337.3 94512.0 96157.0 97236.0 105159.5 107679.0 84865.0 88539.0 89508.6 92258.5 93972.0 71252.0 78505.2 80187.6 80304.6 81814.0 8542.3 8682.9 8696.9 8733.7 8907.8 45097.5 46879.8 47838.8 55064.4 55610.4 71550.6 72541.6 72908.3 76208.0 76355.6 12188.7 12205.6 12482.1 12569.9 12730.0 13730.0 14298.5 14472.3 14741.8 14821.6 12813.3 12884.5 13067.9 13114.1 13491.9 12229.7 12282.2 13086.9 13201.0 13860.5 9927.9 12301.6 12827.0 13118.4 13683.4 10000.2 11095.3 11136.8 11307.4 11522.8 9546.7 10640.8 11374.9 11424.3 11693.4 09:47:35 INFO - PROCESS | 2062 | -------- Summary: end -------- 09:47:35 INFO - PROCESS | 2062 | 09:47:35 INFO - TEST-INFO | 2062: exit 0 09:47:35 INFO - TEST-OK | dromaeo_css | took 1119795ms 09:47:35 INFO - TEST-START | kraken 09:47:35 INFO - operating with platform_type : mac_ 09:47:35 INFO - Initialising browser for kraken test... 09:47:35 INFO - TEST-INFO | started process 2066 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/tmpPg1IAW/profile http://localhost:49303/getInfo.html) 09:47:37 ERROR - PROCESS | 2066 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 09:47:38 INFO - PROCESS | 2066 | __metrics Screen width/height:1600/1200 09:47:38 INFO - PROCESS | 2066 | colorDepth:24 09:47:38 INFO - PROCESS | 2066 | Browser inner width/height: 1024/689 09:47:38 INFO - PROCESS | 2066 | __metrics 09:47:38 INFO - PROCESS | 2066 | 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" 09:47:38 INFO - PROCESS | 2066 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:47:38 INFO - TEST-INFO | 2066: exit 0 09:47:38 INFO - Browser initialized. 09:47:38 INFO - Running cycle 1/1 for kraken test... 09:47:38 INFO - TEST-INFO | started process 2067 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/tmpPg1IAW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:47:51 INFO - PROCESS | 2067 | RSS: Main: 217292800 09:47:51 INFO - PROCESS | 2067 | 09:47:53 INFO - PROCESS | 2067 | Cycle 1(1): loaded http://localhost:49303/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49303/tests/kraken/driver.html?testName=audio-beat-detection) 09:47:54 INFO - PROCESS | 2067 | RSS: Main: 264781824 09:47:54 INFO - PROCESS | 2067 | 09:47:57 INFO - PROCESS | 2067 | Cycle 1(1): loaded http://localhost:49303/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49303/tests/kraken/driver.html?testName=audio-dft) 09:47:57 INFO - PROCESS | 2067 | RSS: Main: 277577728 09:47:57 INFO - PROCESS | 2067 | 09:48:00 INFO - PROCESS | 2067 | Cycle 1(1): loaded http://localhost:49303/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49303/tests/kraken/driver.html?testName=audio-fft) 09:48:01 INFO - PROCESS | 2067 | RSS: Main: 374476800 09:48:01 INFO - PROCESS | 2067 | 09:48:03 INFO - PROCESS | 2067 | Cycle 1(1): loaded http://localhost:49303/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49303/tests/kraken/driver.html?testName=audio-oscillator) 09:48:04 INFO - PROCESS | 2067 | RSS: Main: 277770240 09:48:04 INFO - PROCESS | 2067 | 09:48:06 INFO - PROCESS | 2067 | Cycle 1(1): loaded http://localhost:49303/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49303/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:48:06 INFO - PROCESS | 2067 | RSS: Main: 280379392 09:48:06 INFO - PROCESS | 2067 | 09:48:09 INFO - PROCESS | 2067 | Cycle 1(1): loaded http://localhost:49303/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49303/tests/kraken/driver.html?testName=imaging-darkroom) 09:48:10 INFO - PROCESS | 2067 | RSS: Main: 320135168 09:48:10 INFO - PROCESS | 2067 | 09:48:13 INFO - PROCESS | 2067 | Cycle 1(1): loaded http://localhost:49303/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49303/tests/kraken/driver.html?testName=imaging-desaturate) 09:48:13 INFO - PROCESS | 2067 | RSS: Main: 319016960 09:48:13 INFO - PROCESS | 2067 | 09:48:16 INFO - PROCESS | 2067 | Cycle 1(1): loaded http://localhost:49303/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49303/tests/kraken/driver.html?testName=json-parse-financial) 09:48:17 INFO - PROCESS | 2067 | RSS: Main: 316710912 09:48:17 INFO - PROCESS | 2067 | 09:48:18 INFO - PROCESS | 2067 | Cycle 1(1): loaded http://localhost:49303/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49303/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:48:19 INFO - PROCESS | 2067 | RSS: Main: 295817216 09:48:19 INFO - PROCESS | 2067 | 09:48:21 INFO - PROCESS | 2067 | Cycle 1(1): loaded http://localhost:49303/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49303/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:48:21 INFO - PROCESS | 2067 | RSS: Main: 296189952 09:48:21 INFO - PROCESS | 2067 | 09:48:23 INFO - PROCESS | 2067 | Cycle 1(1): loaded http://localhost:49303/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49303/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:48:24 INFO - PROCESS | 2067 | RSS: Main: 299425792 09:48:24 INFO - PROCESS | 2067 | 09:48:27 INFO - PROCESS | 2067 | Cycle 1(1): loaded http://localhost:49303/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49303/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:48:28 INFO - PROCESS | 2067 | RSS: Main: 308994048 09:48:28 INFO - PROCESS | 2067 | 09:48:31 INFO - PROCESS | 2067 | Cycle 1(1): loaded http://localhost:49303/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49303/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:48:31 INFO - PROCESS | 2067 | RSS: Main: 309174272 09:48:31 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | Cycle 1(1): loaded http://localhost:49303/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49303/tests/kraken/driver.html?testName=ai-astar) 09:48:33 INFO - PROCESS | 2067 | RSS: Main: 352428032 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | __start_tp_report 09:48:33 INFO - PROCESS | 2067 | _x_x_mozilla_page_load 09:48:33 INFO - PROCESS | 2067 | _x_x_mozilla_page_load_details 09:48:33 INFO - PROCESS | 2067 | |i|pagename|runs| 09:48:33 INFO - PROCESS | 2067 | |0;ai-astar;104;116;115;115;116;114;115;115;115;116 09:48:33 INFO - PROCESS | 2067 | |1;audio-beat-detection;142;112;111;143;117;111;113;107;143;113 09:48:33 INFO - PROCESS | 2067 | |2;audio-dft;186;183;181;184;183;183;183;184;181;187 09:48:33 INFO - PROCESS | 2067 | |3;audio-fft;81;59;63;106;83;84;69;59;83;58 09:48:33 INFO - PROCESS | 2067 | |4;audio-oscillator;88;70;94;70;69;71;89;67;70;92 09:48:33 INFO - PROCESS | 2067 | |5;imaging-gaussian-blur;88;85;87;85;87;84;88;84;92;85 09:48:33 INFO - PROCESS | 2067 | |6;imaging-darkroom;98;97;97;97;96;116;167;97;96;97 09:48:33 INFO - PROCESS | 2067 | |7;imaging-desaturate;81;81;80;78;81;81;85;83;81;83 09:48:33 INFO - PROCESS | 2067 | |8;json-parse-financial;55;54;53;54;57;56;58;56;56;57 09:48:33 INFO - PROCESS | 2067 | |9;json-stringify-tinderbox;64;72;68;76;72;68;69;71;70;71 09:48:33 INFO - PROCESS | 2067 | |10;stanford-crypto-aes;82;87;86;83;82;92;83;82;84;85 09:48:33 INFO - PROCESS | 2067 | |11;stanford-crypto-ccm;147;146;145;144;139;155;162;159;185;166 09:48:33 INFO - PROCESS | 2067 | |12;stanford-crypto-pbkdf2;166;166;167;167;168;166;170;167;167;167 09:48:33 INFO - PROCESS | 2067 | |13;stanford-crypto-sha256-iterative;67;67;67;67;66;68;66;79;74;66 09:48:33 INFO - PROCESS | 2067 | __end_tp_report 09:48:33 INFO - PROCESS | 2067 | __start_cc_report 09:48:33 INFO - PROCESS | 2067 | _x_x_mozilla_cycle_collect,2049 09:48:33 INFO - PROCESS | 2067 | __end_cc_report 09:48:33 INFO - PROCESS | 2067 | __startTimestamp1479750513849__endTimestamp 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | ------- Summary: start ------- 09:48:33 INFO - PROCESS | 2067 | Number of tests: 14 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.00 stddev:3.60 (3.1%) stddev-sans-first:0.67 09:48:33 INFO - PROCESS | 2067 | Values: 104.0 116.0 115.0 115.0 116.0 114.0 115.0 115.0 115.0 116.0 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | [#1] audio-beat-detection Cycles:10 Average:121.20 Median:115.00 stddev:15.02 (13.1%) stddev-sans-first:13.91 09:48:33 INFO - PROCESS | 2067 | Values: 142.0 112.0 111.0 143.0 117.0 111.0 113.0 107.0 143.0 113.0 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | [#2] audio-dft Cycles:10 Average:183.50 Median:183.50 stddev:1.90 (1.0%) stddev-sans-first:1.79 09:48:33 INFO - PROCESS | 2067 | Values: 186.0 183.0 181.0 184.0 183.0 183.0 183.0 184.0 181.0 187.0 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | [#3] audio-fft Cycles:10 Average:74.50 Median:75.00 stddev:15.58 (20.8%) stddev-sans-first:16.35 09:48:33 INFO - PROCESS | 2067 | Values: 81.0 59.0 63.0 106.0 83.0 84.0 69.0 59.0 83.0 58.0 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:79.50 stddev:11.14 (14.0%) stddev-sans-first:11.21 09:48:33 INFO - PROCESS | 2067 | Values: 88.0 70.0 94.0 70.0 69.0 71.0 89.0 67.0 70.0 92.0 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.00 stddev:2.46 (2.8%) stddev-sans-first:2.55 09:48:33 INFO - PROCESS | 2067 | Values: 88.0 85.0 87.0 85.0 87.0 84.0 88.0 84.0 92.0 85.0 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | [#6] imaging-darkroom Cycles:10 Average:105.80 Median:97.00 stddev:22.33 (23.0%) stddev-sans-first:23.51 09:48:33 INFO - PROCESS | 2067 | Values: 98.0 97.0 97.0 97.0 96.0 116.0 167.0 97.0 96.0 97.0 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | [#7] imaging-desaturate Cycles:10 Average:81.40 Median:81.00 stddev:1.90 (2.3%) stddev-sans-first:2.01 09:48:33 INFO - PROCESS | 2067 | Values: 81.0 81.0 80.0 78.0 81.0 81.0 85.0 83.0 81.0 83.0 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:1.58 (2.8%) stddev-sans-first:1.66 09:48:33 INFO - PROCESS | 2067 | Values: 55.0 54.0 53.0 54.0 57.0 56.0 58.0 56.0 56.0 57.0 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:71.00 stddev:3.18 (4.5%) stddev-sans-first:2.49 09:48:33 INFO - PROCESS | 2067 | Values: 64.0 72.0 68.0 76.0 72.0 68.0 69.0 71.0 70.0 71.0 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:84.50 stddev:3.13 (3.7%) stddev-sans-first:3.18 09:48:33 INFO - PROCESS | 2067 | Values: 82.0 87.0 86.0 83.0 82.0 92.0 83.0 82.0 84.0 85.0 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | [#11] stanford-crypto-ccm Cycles:10 Average:154.80 Median:157.00 stddev:13.77 (8.8%) stddev-sans-first:14.32 09:48:33 INFO - PROCESS | 2067 | Values: 147.0 146.0 145.0 144.0 139.0 155.0 162.0 159.0 185.0 166.0 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.10 Median:167.00 stddev:1.20 (0.7%) stddev-sans-first:1.20 09:48:33 INFO - PROCESS | 2067 | Values: 166.0 166.0 167.0 167.0 168.0 166.0 170.0 167.0 167.0 167.0 09:48:33 INFO - PROCESS | 2067 | 09:48:33 INFO - PROCESS | 2067 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:67.00 stddev:4.32 (6.5%) stddev-sans-first:4.54 09:48:33 INFO - PROCESS | 2067 | Values: 67.0 67.0 67.0 67.0 66.0 68.0 66.0 79.0 74.0 66.0 09:48:33 INFO - PROCESS | 2067 | -------- Summary: end -------- 09:48:33 INFO - PROCESS | 2067 | 09:48:34 INFO - TEST-INFO | 2067: exit 0 09:48:34 INFO - TEST-OK | kraken | took 58957ms 09:48:34 INFO - SUITE-END | took 1178s 09:48:35 INFO - Completed test suite (00:19:39) 09:48:35 INFO - javascript benchmark 09:48:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3287.9013629591113, 3293.1602441377445, 3295.5386245925574, 3308.454105072665, 3361.226917808904, 5650.6044576879585, 5652.745626446809, 5671.574631902603, 5684.118961560955, 5705.771769129235, 5578.832635020945, 5649.1526271059365, 5668.858278543038, 5682.801101961432, 5686.346070201928, 5138.306328645641, 5150.510701483353, 5169.198774190007, 5196.1166601677805, 5232.529072383504, 4220.788960551976, 5131.820386286481, 5274.55166310864, 5274.947250527484, 5304.469553044684, 5165.870853228662, 5215.139501982168, 5224.973875130619, 5233.371995360562, 5236.21456781482, 5202.791888324481, 5225.947740522584, 5231.843044708665, 5232.947670523322, 5233.058049551079, 5093.210552364352, 5194.506521880416, 5205.323307969978, 5206.271122042917, 5238.580913526908, 5701.059325211377, 5704.401037891044, 5715.3141623005085, 5715.428457154293, 5716.342620598602, 6099.634021958696, 6103.908441373339, 6107.0, 6130.754769809202, 6135.987562052256, 6169.722362493699, 6185.855616710923, 6196.442320191204, 6202.131701561785, 6212.534059945522, 5671.432856714337, 5673.461021202979, 5674.404187560288, 5728.97135514324, 5731.627444216113, 6359.36406359365, 6361.872762544724, 6374.872502550016, 6386.680665966683, 6394.008928616072, 4984.700917944935, 5888.116782482662, 5911.940880591225, 5918.674472903946, 6006.879862402815, 6115.270764408881, 6191.659458729813, 6195.876082478325, 6206.689665516707, 6234.719437625317, 4960.70235785854, 6186.288576813634, 6189.96905015472, 6205.844853878689, 6208.50331973441, 6039.577229593974, 6092.908606370908, 6121.081837724377, 6142.723577439026, 6224.37756224374, 4866.124097662455, 4890.902181956412, 4893.975530122327, 4914.926276105862, 4941.48114447985, 4378.737275763465, 4402.0, 4411.955880441219, 4416.955830441654, 4426.092651006536, 5541.556675465953, 5681.971590142023, 5702.173184888168, 5726.627769194989, 5748.913766293509, 6108.08378743192, 6127.1393886820315, 6166.475849552839, 6190.473809726133, 6197.442230199304, 5709.771609135588, 5724.16999535065, 5725.141228815628, 5725.370209276976, 5751.85620359486, 5321.281626980385, 5359.0, 5362.570994320445, 5376.973115134478, 5388.218708287355, 6118.265808103056, 6231.189945307081, 6243.250809902841, 6269.874602508015, 6294.464970477561, 6206.06908963641, 6285.528585356025, 6293.590916590407, 6298.685065746786, 6301.6219026858535, 6332.335104814112, 6340.4293613575, 6343.873122537616, 6355.491560675043, 6359.459445946961, 5959.821205363846, 5992.550558708027, 6066.636001839904, 6129.908051379322, 6181.474574661204, 5993.340732519332, 6148.077788331786, 6185.8144255672405, 6255.437010669061, 6278.7488500461295, 4986.177280748636, 6045.818625441244, 6053.909191362045, 6057.363976782551, 6060.969695151673, 5865.061590145727, 6005.549583781321, 6019.127226552213, 6035.5473339500595, 6041.546883983807, 5774.884502310014, 5813.6221145625395, 5841.18223448726, 5864.736086876186, 5941.910871336847, 6068.423499767515, 6077.4226448487325, 6079.635221886701, 6085.90453718335, 6102.786402475892, 5696.772129114955, 5764.13537969308, 5764.279465066867, 5798.420157984167, 5862.296524416926], "value": 5639.47620617711, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.70723444230134, 464.79548998440686, 470.4378267969776, 516.9586433085351, 520.4691214960742, 23579.292621221368, 31231.531527027113, 31394.529082063786, 38073.42889856654, 39401.0, 41058.17883642333, 41415.378769318406, 41438.79280603601, 41442.7927860361, 41551.79224103883, 9188.724338269845, 9365.391249568791, 9370.25037996962, 9371.6719914803, 9372.95313523435, 8016.15830337812, 8625.051244363114, 8632.438891472035, 8706.564671766419, 8715.215630593277, 11090.778184436307, 11369.886301137007, 11394.715132121722, 11550.422478876064, 11556.826647600292, 11253.887461125407, 11331.716707082307, 11352.262102963363, 11355.829662555068, 11380.943095284554, 8142.715004974857, 8673.176048275403, 10031.247656425725, 11213.495392707347, 11293.153013523937, 37883.62116378829, 38720.22559548821, 38918.41622375666, 39787.40318895219, 39923.8003809982, 3227.80633162011, 3260.8206548639814, 3275.967240327614, 3282.1302354875975, 3323.4848598467274, 29272.0, 29784.255393615116, 29993.850030749705, 30127.69872301271, 30154.84922575395, 3143.591333126679, 3172.159377764894, 3185.3310804731036, 3210.0530343548708, 3313.0, 492677.0, 493578.0, 497951.0, 498190.0, 501982.4900875509, 52996.735016324674, 54265.72867135718, 58047.129293060636, 58859.0, 59554.404455954886, 61820.763584728054, 62060.75878482405, 62084.37915620786, 62143.0, 62261.377386226464, 15722.29249683767, 15889.205539722967, 15908.284127214303, 15909.045457272601, 16051.678966420606, 12266.693332666755, 12382.690432739073, 12383.814242786366, 12386.690332741753, 12399.0, 14971.850281497263, 15242.466513671969, 15268.465603703817, 16590.17049147538, 17112.40106596288, 5699.8860022800145, 6715.321169707573, 6733.93266067333, 6783.762568310184, 6881.8967715484305, 859.5014891362995, 993.6820217530321, 994.683758135846, 1004.2167109654335, 1011.939283642969, 29026.564601530994, 29127.417451650846, 29270.975515856844, 29664.258393540335, 29800.254993625334, 1051.1695760349392, 1065.3767545985531, 1090.8527348807968, 1111.488715191005, 1149.287441786098, 14819.259037048105, 15224.46714364992, 15292.00601960869, 15603.141827199386, 15629.921850390903, 626199.8690006521, 630979.2255193656, 631423.0, 631663.0, 631825.0, 48492.51507484951, 49482.25776613354, 55112.0, 55323.723381382835, 56292.87414251692, 1319.8548159702425, 1324.5496531179444, 1365.9795103073263, 1371.4445649511963, 1371.979420308716, 29193.0, 29845.55231671483, 29949.0, 30293.476471497524, 30643.0, 40345.0, 48463.75768121137, 48934.510654893, 48957.51042489529, 48971.775705607644, 497924.0, 581481.0925945343, 582920.0, 583879.0, 587203.0, 38567.42148867825, 39114.60885391109, 39282.82151535458, 39362.606373935894, 39403.80298098491, 44469.55530444784, 44629.77685111554, 45261.0, 45290.09419811651, 45308.0, 36741.63258367382, 36745.0, 37687.62312376951, 39005.414918776805, 39211.01972450712, 36581.63418365889, 37399.62600373961, 37877.0, 38630.0, 38676.613233868426, 36100.81949590235, 36439.27121457609, 37169.81415092907, 37515.43726844045, 38565.22869542537, 31747.36505269928, 33266.83366583152, 33894.0, 33946.49080263847, 33992.83003585065, 25200.0, 25214.0, 25265.621015684414, 25478.23565293044, 25857.0], "value": 19708.68224726638, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.47279081377934, 372.96643302102814, 375.43443839190587, 383.98272077756496, 404.9473568436103, 14780.926095369521, 14846.331915063822, 15812.288447019882, 20089.899550502265, 20646.793532064676, 16971.49085527433, 18326.35857744979, 19727.21091156352, 20203.39389818308, 20462.283820066263, 5413.404525502201, 5960.5529585281065, 5973.4623883850445, 5979.910301345483, 5988.850278743044, 4601.976990115045, 5837.09525023622, 5882.911756323637, 5889.352171261158, 5904.88190236195, 5673.290838645169, 5830.970845145768, 5842.094475356326, 5844.503217226541, 5853.824385268449, 4844.660873738822, 5825.067989121741, 5887.234659494238, 5890.764369425217, 5891.116332550109, 4881.7551524361415, 5058.038972595232, 5165.328507294028, 5171.7672704728375, 5189.091908915962, 25235.0, 25828.87085564579, 26005.219843404728, 26330.868345658342, 26408.471830563474, 2229.7034054923292, 2253.549290141978, 2361.504084142332, 2620.9606855897177, 2894.421115776853, 15097.698046039128, 15240.695186096216, 15341.92329038359, 16061.678766424722, 16140.75788863168, 1728.567858035491, 1729.3746351791262, 1786.3747688308988, 1843.6681397348473, 1982.058522201957, 119095.4045229777, 129686.70313296936, 129872.0, 131737.0, 132493.0, 10209.74475638115, 10990.560377584967, 11398.601048963246, 11615.36115513638, 11724.88275117255, 14921.776173357192, 14934.92532537352, 14942.551723448314, 14959.326830292663, 14967.850321496864, 8388.916110838814, 8521.914780852112, 8527.65889364431, 8552.657893684183, 8654.004789449167, 6903.551269167488, 8390.286825619769, 8422.873656895152, 8571.057183709787, 8589.398742088119, 4444.400005999149, 4510.075434535912, 4540.432445944257, 4563.794629241625, 4581.427321584802, 1862.2029741299712, 1920.7310976463027, 1922.3175772600805, 1922.4905400068992, 1963.2932144427994, 713.5504632081755, 818.8536049530704, 863.6977058029765, 879.9120087991278, 919.4023884475012, 20317.898410508147, 21464.141434342764, 21556.784432155477, 21577.568448631253, 21674.674879876813, 715.9964200178965, 930.251147825993, 947.8009617980164, 948.5067764762285, 972.4651441707033, 10734.892651073545, 11196.664100076849, 11237.269577477438, 11291.717707057225, 11296.322220666787, 589141.0, 591514.0, 596762.0, 607412.0, 607659.0, 124378.26865194058, 128133.35933320274, 131872.0, 133485.99771003443, 134645.0, 818.8730746734146, 933.0995589256536, 964.4165280005495, 965.5558443116245, 966.6665000574791, 18603.883766974024, 18977.810221897606, 19023.52441188981, 19057.237710491427, 19102.426927192206, 54193.645158871346, 54302.58464797016, 55378.615534611956, 55579.722101389234, 55962.440375595725, 78419.38010406548, 78862.42275154579, 78862.6056869712, 79682.0, 79963.60018199871, 7047.753328633473, 7912.564808935564, 7993.200679931961, 8238.382121340803, 8388.370872184489, 12227.388630568257, 12700.237985720887, 12776.425060872098, 13083.084184107021, 13187.934060329637, 10660.0, 10884.292520986111, 11038.337699738042, 11202.607908723156, 11391.316521008766, 10229.28395012342, 10934.234603577674, 11058.944705276423, 11394.829077564009, 11407.25852819564, 9063.954680226556, 9367.5784589695, 11109.277896936674, 11275.64692236904, 11631.88368116342, 7332.120145582564, 7341.449391295568, 7382.335589796944, 7520.811979700551, 7577.659005344883, 8801.0, 11167.888321116685, 11190.048845847869, 11525.0, 11564.94217528907], "value": 9540.769564621352, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.5582829752032, 1474.3218119664953, 1496.7156240314341, 1665.4254282272611, 1843.410108765194, 7540.472166948316, 7952.125266220711, 8318.126596707341, 8547.914520854789, 8558.400911936167, 8481.618327175293, 8516.787080322994, 8535.0, 8541.615627296771, 8545.87181192281, 2350.929472115835, 2383.6067048936907, 2385.7375688674233, 2388.8088952883727, 2389.1160270699866, 2366.7633236676365, 2405.915792947247, 2408.0608562660577, 2411.9035238590436, 2422.479166979108, 2348.8003519700846, 2359.7994170495444, 2382.332946774906, 2382.9046838126455, 2386.140989243871, 2288.8054515366216, 2319.826013049028, 2349.1425629645228, 2350.083467447697, 2357.8349515533746, 1975.7530308711412, 2342.636891281854, 2361.2089949866813, 2391.306521108894, 2395.377201927494, 8401.789955251106, 8448.746537603882, 8513.318934485289, 8523.403361764678, 8528.0, 5059.3169922060415, 5257.7108259045735, 5269.104252277084, 5300.602454815866, 5311.336082989626, 3504.439289713659, 3567.839447224881, 3656.780593164419, 3656.963430365689, 3666.5233519642543, 909.9772505687345, 911.5305617606938, 912.361347057056, 1063.075481334195, 1083.756154865149, 10977.725556861062, 11050.889491105067, 11138.443077846156, 11165.330080195214, 11166.553337866475, 4359.891002724958, 4452.4211852458975, 4525.208088584484, 4543.795529201194, 4555.430571178603, 8809.735707928772, 8815.515146666867, 8817.779555511164, 8818.470891746516, 8831.0, 5165.638405311592, 5165.793368265227, 5168.8966220675375, 5176.7152806596, 5191.402988656278, 4176.248275310473, 4346.891327716769, 4467.195904737114, 4470.977645111753, 4759.9524004759505, 3690.114372550622, 3704.4628528863705, 3765.378712512405, 3795.146092129304, 3807.8286477108595, 1147.6327575175865, 1155.6879642496478, 1156.0578128824982, 1165.5337864854125, 1171.9531218751324, 778.3695206882443, 860.1269711243093, 872.912708729122, 879.8416285068621, 901.8782464367358, 5361.0, 5511.641743286674, 5546.195801608744, 5718.142278658234, 5823.068309070529, 430.694207112947, 448.7509432265059, 448.8092560661771, 448.89002194461915, 452.22443509381566, 3693.7229707772026, 3725.5715592706642, 3759.191773768653, 3771.9622803771613, 3889.5915928827067, 9927.801443971224, 10213.642522511676, 10270.845937310947, 10294.948525257476, 10306.278560500694, 9633.662821801203, 9950.751231219278, 10255.641052563275, 10263.435511046964, 10275.280730348804, 6907.929270963009, 7769.339606133542, 7820.0, 7991.320737737357, 7992.640331185111, 5084.3898732152375, 5300.125479296027, 5651.660900345993, 5696.0, 5864.413558644102, 9527.237820974167, 9676.612935482503, 9684.806303874024, 9715.36850104741, 9727.173190278905, 7171.641417928979, 8353.916460835557, 8882.60028298741, 9249.491277979776, 9548.18840398574, 6728.091707619409, 6898.1377327834025, 7054.224035356004, 7108.253633368422, 7162.212156662658, 7786.805329866572, 7949.761507154794, 8016.158303378062, 8107.432479726598, 8107.878381824396, 7086.0, 7196.532225405332, 7238.384737297389, 7252.637368131678, 7332.486725929134, 7100.502964792415, 7143.714251429885, 7144.1784194818265, 7155.141383034069, 7198.20819709821, 6639.402453779183, 6754.8649027020165, 6762.256151823394, 6846.554973926679, 6849.8972515411315, 4924.8768780780765, 4987.9002419950675, 4992.0, 5073.340465739578, 5078.009788091286, 4922.680025798311, 4950.529699678525, 5002.474740152377, 5075.7969681212335, 5175.353080864892], "value": 4325.51481041368, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.16163932791545, 298.113113487375, 306.81131104370814, 311.6089307918562, 313.7505682982029, 5231.973840130799, 5270.307252145789, 5274.26160337553, 5307.681539107656, 5346.545543628791, 4492.303692927593, 4551.544845515455, 5102.3111879896205, 5216.22648299578, 5245.816396426126, 4027.1542975974935, 4038.798060096998, 4047.927299265697, 4050.2507036198326, 4060.9796951015205, 3044.710752478522, 3646.0, 4093.140440507497, 4107.938380924288, 4110.6094920982605, 3943.073377756225, 3966.3852102924093, 4013.0970531630296, 4028.6777057835448, 4044.8786536403954, 3958.802059896994, 3995.441777706697, 3997.400389941503, 4028.295048366524, 4048.67610591152, 3503.982480087583, 3516.841742121611, 3529.347070791886, 3534.6288639692975, 3539.6460353964653, 4192.769397683157, 4766.475687674353, 5084.593232541425, 5146.228065790124, 5151.9484805152215, 4326.394304797331, 4491.9775401123115, 4556.2937744649635, 4586.770661466913, 4646.186917289461, 4521.253993091136, 5265.86835329116, 5360.328175199961, 5439.619226654136, 5473.480019398151, 3105.8757649693985, 3287.621923478807, 3354.1446931032674, 3579.3378225028455, 3810.428435734662, 5266.183741520071, 5395.29861118056, 5568.385168301188, 5695.977927835642, 5707.115397113413, 3685.9174566369916, 3734.8897872701837, 3741.8316175772156, 3757.6242375762754, 3780.6597406233573, 3911.393733971239, 3919.294526985113, 3923.725339226227, 3925.823337949799, 3938.428927805452, 3425.5955058425925, 3776.9235767806595, 4500.369948207253, 4590.862274131781, 4625.907481850344, 5370.2213179089595, 5404.972975135099, 5428.945710542923, 5486.286782718221, 5504.0643090674685, 1259.7480503899073, 1265.5380786013038, 1274.6928060211048, 1287.8518970318346, 1293.5770652282415, 716.9677364518609, 799.8040480082301, 831.4637059096849, 882.4308321132834, 891.7636826241052, 4225.68307376948, 4281.19581674004, 4331.826726930951, 4423.871912662315, 4751.168545504523, 4471.6869819113, 4709.740964246999, 5232.2674825524455, 5267.341582302212, 5288.0, 4634.652401069933, 4634.86095417138, 4773.546513081252, 4833.299171620095, 4859.635527335464, 2286.6227072532847, 2751.614773931651, 2760.4755096531594, 2790.706975767556, 2797.6223209866816, 4939.506049395103, 5357.19642053695, 5374.840153452651, 5394.514493695587, 5441.374241962146, 4989.750512474362, 5425.361316074778, 5449.983506102688, 5456.590755693339, 5457.951394959726, 4651.767411629473, 4879.512048795092, 5041.59667226621, 5398.973005134949, 5449.0, 4507.977460112678, 5062.569681577107, 5204.531592156724, 5219.478052194902, 5335.199720042025, 4278.957210427856, 4458.0, 4842.668266226816, 4910.779014944264, 5322.627416080992, 4819.939613285072, 5153.948460515497, 5299.655522391032, 5527.917081243865, 5682.318121825408, 3686.557613086447, 3694.8522059117336, 3783.24335132969, 3793.1844653399653, 3830.8084595770656, 5588.060417405801, 5622.465865742748, 5640.576956728344, 5673.468163595889, 5691.658500489983, 4785.0, 4846.20225976288, 4943.703377797344, 5446.809361672323, 5448.482394172547, 4569.428821397325, 4717.09898412467, 5029.29589857413, 5054.418741844588, 5267.894642107213, 4702.506236845082, 5348.384935732285, 5357.732113394392, 5365.624406291522, 5369.48989845964, 4420.801063952065, 5255.474452554714, 5303.7878484860175, 5410.945890541044, 5488.1340344283135, 5347.839564813062, 5367.677939323653, 5379.772136717938, 5382.3003009610065, 5433.429489903503], "value": 3911.4411696838893, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.6477610004538, 684.3567046975843, 701.2496628607389, 715.3847690985751, 741.3809469093306, 12052.638420847377, 13817.0, 14509.49216777413, 14509.854901450983, 14533.200673962929, 12854.48582056719, 12999.220046797198, 13016.21902685837, 13020.348982550882, 13022.218666879993, 630.385374260095, 650.9153810004716, 789.0964845252197, 806.5523634382915, 809.3363441977581, 780.254856611937, 802.1216767639455, 808.5310519898475, 814.0, 814.816666250095, 854.6196942360652, 855.3029281135854, 857.7555396711938, 857.8241460500614, 858.3776761847641, 669.7689297192461, 851.833892390984, 852.7186028610544, 854.4488804720983, 856.3577317012241, 510.3161763237243, 608.4797498139116, 625.368377938281, 630.7886857902608, 631.7946667333089, 13734.0, 13793.0, 13793.0, 13795.10331828428, 13798.448062077605, 12581.056420768478, 12748.936255318757, 12755.107142500028, 12843.29361885096, 13020.804687929689, 11927.284362938251, 12042.879571204263, 12067.517299308018, 12073.879261207363, 12134.27194368332, 4242.596953289432, 4243.681723870722, 4297.333913243452, 4340.066885619607, 4443.133588950156, 69674.3032569673, 82105.0, 84706.15293847045, 90713.63929541063, 93495.53252233763, 8883.955580222058, 8988.0, 9148.496832674135, 9311.72064838056, 9372.765680857896, 15873.761893571605, 19533.902330488258, 19571.902140489492, 19692.90153549223, 19806.504837378892, 7153.105861767279, 7268.23683513234, 7324.121105467271, 7454.776356709307, 7469.514481558681, 3985.6811455083525, 4093.406456063854, 4096.487939007624, 4106.527749308808, 4217.261979153636, 3144.685531446837, 3229.6608856070243, 3234.433974054531, 3238.886638967672, 3248.123006788201, 626.37988391493, 630.7287866217479, 639.7632875835877, 644.8291202831253, 646.7703965092419, 573.082889523448, 594.8364199845078, 602.2231321595093, 605.2192671453863, 608.9451949324493, 8136.389770767216, 8842.0, 9728.270379721549, 9886.30795844284, 9962.452065136487, 556.899758043548, 593.851537115721, 622.327885883245, 631.5989346764837, 667.6995352091482, 4142.668586513072, 4296.183725092223, 4305.440292761921, 4327.480702315742, 4337.349397590387, 94512.0, 96157.0, 97236.0, 105159.4742026285, 107679.0, 84865.0, 88539.0, 89508.5524572373, 92258.53870730604, 93972.0, 71252.0, 78505.21494784979, 80187.59906200432, 80304.59847700724, 81814.0, 8542.27390671775, 8682.869756953776, 8696.869546956927, 8733.737987860375, 8907.777305567413, 45097.54902450933, 46879.828004300165, 47838.76080619574, 55064.44935550753, 55610.44389556053, 71550.64224678843, 72541.6372918132, 72908.27091729015, 76208.0, 76355.61822190853, 12188.695282617651, 12205.633830985085, 12482.126251162332, 12569.93715031419, 12730.0, 13730.0, 14298.499552515612, 14472.348744306742, 14741.778873317124, 14821.555353339418, 12813.295268760308, 12884.549040783528, 13067.869321307046, 13114.147580407243, 13491.86508134906, 12229.694257643274, 12282.201656892274, 13086.934565327112, 13201.0, 13860.514881979083, 9927.851082233628, 12301.630951071482, 12827.037972151938, 13118.409671564612, 13683.384247708678, 10000.199984001409, 11095.334279943228, 11136.832947505633, 11307.434628268718, 11522.76954460923, 9546.713598592054, 10640.787184256425, 11374.94312528432, 11424.257423267461, 11693.356865372487], "value": 7072.235295730375, "unit": "score"}], "name": "dromaeo_css", "value": 7088.866752972304, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 115.0, 115.0, 116.0, 114.0, 115.0, 115.0, 115.0, 116.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 111.0, 143.0, 117.0, 111.0, 113.0, 107.0, 143.0, 113.0], "value": 121.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 181.0, 184.0, 183.0, 183.0, 183.0, 184.0, 181.0, 187.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 63.0, 106.0, 83.0, 84.0, 69.0, 59.0, 83.0, 58.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 94.0, 70.0, 69.0, 71.0, 89.0, 67.0, 70.0, 92.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 85.0, 87.0, 84.0, 88.0, 84.0, 92.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 97.0, 97.0, 96.0, 116.0, 167.0, 97.0, 96.0, 97.0], "value": 105.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 80.0, 78.0, 81.0, 81.0, 85.0, 83.0, 81.0, 83.0], "value": 81.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 54.0, 57.0, 56.0, 58.0, 56.0, 56.0, 57.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 68.0, 76.0, 72.0, 68.0, 69.0, 71.0, 70.0, 71.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 87.0, 86.0, 83.0, 82.0, 92.0, 83.0, 82.0, 84.0, 85.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 145.0, 144.0, 139.0, 155.0, 162.0, 159.0, 185.0, 166.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 167.0, 167.0, 168.0, 166.0, 170.0, 167.0, 167.0, 167.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 66.0, 68.0, 66.0, 79.0, 74.0, 66.0], "value": 68.7, "unit": "score"}], "name": "kraken", "value": 1445.8999999999999, "alertThreshold": 2.0}]} 09:48:35 INFO - Return code: 0 09:48:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:48:35 ERROR - # TBPL SUCCESS # 09:48:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:48:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:48:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:48:35 INFO - 2016-11-21 09:48:35.157 screenresolution[2072:24381] starting screenresolution argv=screenresolution get 09:48:35 INFO - 2016-11-21 09:48:35.160 screenresolution[2072:24381] Display 0: 1600x1200x32@60 09:48:35 INFO - 2016-11-21 09:48:35.166 screenresolution[2073:24384] starting screenresolution argv=screenresolution list 09:48:35 INFO - Available Modes on Display 0 09:48:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:48:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:48:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:48:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:48:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:48:35 INFO - Intel Iris: 09:48:35 INFO - Chipset Model: Intel Iris 09:48:35 INFO - Type: GPU 09:48:35 INFO - Bus: Built-In 09:48:35 INFO - VRAM (Dynamic, Max): 1536 MB 09:48:35 INFO - Vendor: Intel (0x8086) 09:48:35 INFO - Device ID: 0x0a2e 09:48:35 INFO - Revision ID: 0x0009 09:48:35 INFO - Displays: 09:48:35 INFO - Display: 09:48:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:48:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:48:35 INFO - Display Serial Number: 1600x1200 60 09:48:35 INFO - Main Display: Yes 09:48:35 INFO - Mirror: Off 09:48:35 INFO - Online: Yes 09:48:35 INFO - Rotation: Supported 09:48:35 INFO - Return code: 0 09:48:35 INFO - Running post-action listener: _resource_record_post_action 09:48:35 INFO - [mozharness: 2016-11-21 17:48:35.402044Z] Finished run-tests step (success) 09:48:35 INFO - Running post-run listener: _resource_record_post_run 09:48:35 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 32005120; Write bytes: 652233728; Read time: 293; Write time: 2012 09:48:35 INFO - TinderboxPrint: CPU usage
53.2% 09:48:35 INFO - TinderboxPrint: I/O read bytes / time
32,005,120 / 293 09:48:35 INFO - TinderboxPrint: I/O write bytes / time
652,233,728 / 2,012 09:48:35 INFO - TinderboxPrint: CPU idle
2,267.7 (46.8%) 09:48:35 INFO - TinderboxPrint: CPU system
966.0 (19.9%) 09:48:35 INFO - TinderboxPrint: CPU user
1,616.0 (33.3%) 09:48:35 INFO - TinderboxPrint: Swap in / out
379,985,920 / 0 09:48:35 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 346167808; Write bytes: 345782272; Read time: 27041; Write time: 681 09:48:35 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 31140864; Write bytes: 295564288; Read time: 289; Write time: 1303 09:48:35 INFO - Running post-run listener: _upload_blobber_files 09:48:35 INFO - Blob upload gear active. 09:48:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:48:35 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 09:48:35 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'] 09:48:35 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 09:48:36 INFO - (blobuploader) - INFO - Open directory for files ... 09:48:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:48:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:48:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:37 INFO - (blobuploader) - INFO - Done attempting. 09:48:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:48:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:48:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:38 INFO - (blobuploader) - INFO - Done attempting. 09:48:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:48:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:48:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:40 INFO - (blobuploader) - INFO - Done attempting. 09:48:40 INFO - (blobuploader) - INFO - Iteration through files over. 09:48:40 INFO - Return code: 0 09:48:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:48:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:48:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb65f384cb30baaa2f7aa34600ca46bc52471aef7418f6b833a07c0aece458f72d218018899eff8bbfb52025dfa0511f5becafd090f2cbc0873b6f84c03aead2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5c04f071187ad3cb3a249ae8dc63864e4a3379db8596cdc801e0778d9151d2cae4b1d94abc74bde5c90aa0e8ce59624ebe9a784d0205a1add25b44217c244e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d883a7bf3a0c9931a1b05bf0fc5a09e6c008f9b7131aeb13ee057a5bea0fbece0b313f8c7403337ec693003e593f5b9a4e497222edf4ffee8ba0173f0754fe9a"} 09:48:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:48:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:48:40 INFO - Contents: 09:48:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb65f384cb30baaa2f7aa34600ca46bc52471aef7418f6b833a07c0aece458f72d218018899eff8bbfb52025dfa0511f5becafd090f2cbc0873b6f84c03aead2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5c04f071187ad3cb3a249ae8dc63864e4a3379db8596cdc801e0778d9151d2cae4b1d94abc74bde5c90aa0e8ce59624ebe9a784d0205a1add25b44217c244e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d883a7bf3a0c9931a1b05bf0fc5a09e6c008f9b7131aeb13ee057a5bea0fbece0b313f8c7403337ec693003e593f5b9a4e497222edf4ffee8ba0173f0754fe9a"} 09:48:40 INFO - Running post-run listener: copy_logs_to_upload_dir 09:48:40 INFO - Copying logs to upload dir... 09:48:40 INFO - mkdir: /builds/slave/test/build/upload/logs 09:48:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.274679 ========= master_lag: 0.23 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-11-21 09:48:40.788863) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-21 09:48:40.793654) ========= 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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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/cb65f384cb30baaa2f7aa34600ca46bc52471aef7418f6b833a07c0aece458f72d218018899eff8bbfb52025dfa0511f5becafd090f2cbc0873b6f84c03aead2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5c04f071187ad3cb3a249ae8dc63864e4a3379db8596cdc801e0778d9151d2cae4b1d94abc74bde5c90aa0e8ce59624ebe9a784d0205a1add25b44217c244e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d883a7bf3a0c9931a1b05bf0fc5a09e6c008f9b7131aeb13ee057a5bea0fbece0b313f8c7403337ec693003e593f5b9a4e497222edf4ffee8ba0173f0754fe9a"} build_url:https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011053 build_url: 'https://queue.taskcluster.net/v1/task/FTXeMJrYQleTzTM2M_IUuQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb65f384cb30baaa2f7aa34600ca46bc52471aef7418f6b833a07c0aece458f72d218018899eff8bbfb52025dfa0511f5becafd090f2cbc0873b6f84c03aead2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5c04f071187ad3cb3a249ae8dc63864e4a3379db8596cdc801e0778d9151d2cae4b1d94abc74bde5c90aa0e8ce59624ebe9a784d0205a1add25b44217c244e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d883a7bf3a0c9931a1b05bf0fc5a09e6c008f9b7131aeb13ee057a5bea0fbece0b313f8c7403337ec693003e593f5b9a4e497222edf4ffee8ba0173f0754fe9a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-21 09:48:40.829501) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 09:48:40.829809) ========= 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.RSsDeuGSbb/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.1eoT9DUw5H/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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.005331 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 09:48:40.877840) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-21 09:48:40.878140) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-21 09:48:40.881518) ========= ========= Total master_lag: 4.09 =========