builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0020 starttime: 1477515052.56 results: success (0) buildid: 20161026075526 builduid: 1b6e62fa11104b93a83fb80681645e95 revision: d26ac63f1b81c3fce35448a7c502e95e0b5c56c0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 13:50:52.560743) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 13:50:52.561096) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 13:50:52.561363) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 13:50:52.639310) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 13:50:52.639583) ========= 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.YpGAeUzLNB/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.9vPMwS83Cb/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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-10-26 13:50:52-- 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% 171M=0s 2016-10-26 13:50:52 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.112973 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 13:50:52.777643) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 13:50:52.777927) ========= 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.YpGAeUzLNB/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.9vPMwS83Cb/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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.026617 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 13:50:52.826661) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 13:50:52.826984) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d26ac63f1b81c3fce35448a7c502e95e0b5c56c0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d26ac63f1b81c3fce35448a7c502e95e0b5c56c0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YpGAeUzLNB/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.9vPMwS83Cb/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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-10-26 13:50:52,901 truncating revision to first 12 chars 2016-10-26 13:50:52,901 Setting DEBUG logging. 2016-10-26 13:50:52,902 attempt 1/10 2016-10-26 13:50:52,902 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d26ac63f1b81?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-26 13:50:54,476 unpacking tar archive at: mozilla-central-d26ac63f1b81/testing/mozharness/ program finished with exit code 0 elapsedTime=1.838968 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 13:50:54.687546) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 13:50:54.687851) ========= script_repo_revision: d26ac63f1b81c3fce35448a7c502e95e0b5c56c0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 13:50:54.691280) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 13:50:54.691556) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 13:50:54.717853) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-10-26 13:50:54.718219) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YpGAeUzLNB/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.9vPMwS83Cb/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 13:50:54 INFO - MultiFileLogger online at 20161026 13:50:54 in /builds/slave/test 13:50:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 13:50:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:50:54 INFO - {'append_to_log': False, 13:50:54 INFO - 'base_work_dir': '/builds/slave/test', 13:50:54 INFO - 'blob_upload_branch': 'Firefox', 13:50:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:50:54 INFO - 'branch': 'Firefox', 13:50:54 INFO - 'buildbot_json_path': 'buildprops.json', 13:50:54 INFO - 'config_files': ('talos/mac_config.py',), 13:50:54 INFO - 'default_actions': ('clobber', 13:50:54 INFO - 'read-buildbot-config', 13:50:54 INFO - 'download-and-extract', 13:50:54 INFO - 'populate-webroot', 13:50:54 INFO - 'create-virtualenv', 13:50:54 INFO - 'install', 13:50:54 INFO - 'run-tests'), 13:50:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:50:54 INFO - 'download_minidump_stackwalk': True, 13:50:54 INFO - 'download_symbols': 'ondemand', 13:50:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:50:54 INFO - 'tooltool.py': '/tools/tooltool.py', 13:50:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:50:54 INFO - '/tools/misc-python/virtualenv.py')}, 13:50:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:50:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:50:54 INFO - 'installer_path': 'installer.exe', 13:50:54 INFO - 'log_level': 'info', 13:50:54 INFO - 'log_name': 'talos', 13:50:54 INFO - 'log_to_console': True, 13:50:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:50:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:50:54 INFO - 'opt_config_files': (), 13:50:54 INFO - 'pip_index': False, 13:50:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:50:54 INFO - 'cmd': ('bash', 13:50:54 INFO - '-c', 13:50:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:50:54 INFO - 'enabled': True, 13:50:54 INFO - 'halt_on_failure': False, 13:50:54 INFO - 'name': 'check_screen_resolution'},), 13:50:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:50:54 INFO - 'cmd': ('bash', 13:50:54 INFO - '-c', 13:50:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:50:54 INFO - 'enabled': True, 13:50:54 INFO - 'halt_on_failure': False, 13:50:54 INFO - 'name': 'check_screen_resolution'},), 13:50:54 INFO - 'run_cmd_checks_enabled': True, 13:50:54 INFO - 'sps_profile': False, 13:50:54 INFO - 'sps_profile_interval': 0, 13:50:54 INFO - 'suite': 'dromaeojs', 13:50:54 INFO - 'system_bits': '32', 13:50:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:50:54 INFO - 'title': 't-yosemite-r7-0020', 13:50:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:50:54 INFO - 'use_talos_json': True, 13:50:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:50:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:50:54 INFO - 'work_dir': 'build'} 13:50:54 INFO - [mozharness: 2016-10-26 20:50:54.925837Z] Running clobber step. 13:50:54 INFO - Running pre-action listener: _resource_record_pre_action 13:50:54 INFO - Running main action method: clobber 13:50:54 INFO - rmtree: /builds/slave/test/build 13:50:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:50:57 INFO - Running post-action listener: _resource_record_post_action 13:50:57 INFO - [mozharness: 2016-10-26 20:50:57.108944Z] Finished clobber step (success) 13:50:57 INFO - [mozharness: 2016-10-26 20:50:57.109085Z] Running read-buildbot-config step. 13:50:57 INFO - Running pre-action listener: _resource_record_pre_action 13:50:57 INFO - Running main action method: read_buildbot_config 13:50:57 INFO - Using buildbot properties: 13:50:57 INFO - { 13:50:57 INFO - "project": "", 13:50:57 INFO - "product": "firefox", 13:50:57 INFO - "script_repo_revision": "production", 13:50:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 13:50:57 INFO - "repository": "", 13:50:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 13:50:57 INFO - "buildid": "20161026075526", 13:50:57 INFO - "pgo_build": "False", 13:50:57 INFO - "basedir": "/builds/slave/test", 13:50:57 INFO - "buildnumber": 10, 13:50:57 INFO - "slavename": "t-yosemite-r7-0020", 13:50:57 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 13:50:57 INFO - "platform": "yosemite_r7", 13:50:57 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 13:50:57 INFO - "branch": "mozilla-central", 13:50:57 INFO - "repo_path": "mozilla-central", 13:50:57 INFO - "revision": "d26ac63f1b81c3fce35448a7c502e95e0b5c56c0", 13:50:57 INFO - "stage_platform": "macosx64", 13:50:57 INFO - "builduid": "1b6e62fa11104b93a83fb80681645e95", 13:50:57 INFO - "slavebuilddir": "test" 13:50:57 INFO - } 13:50:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 13:50:57 INFO - Running post-action listener: _resource_record_post_action 13:50:57 INFO - [mozharness: 2016-10-26 20:50:57.112390Z] Finished read-buildbot-config step (success) 13:50:57 INFO - [mozharness: 2016-10-26 20:50:57.112503Z] Running download-and-extract step. 13:50:57 INFO - Running pre-action listener: _resource_record_pre_action 13:50:57 INFO - Running main action method: download_and_extract 13:50:57 INFO - mkdir: /builds/slave/test/build/tests 13:50:57 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')]} 13:50:57 INFO - trying https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 13:50:57 INFO - Downloading https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 13:50:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 13:50:58 INFO - Downloaded 1199 bytes. 13:50:58 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 13:50:58 INFO - Using the following test package requirements: 13:50:58 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 13:50:58 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:50:58 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 13:50:58 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:50:58 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 13:50:58 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:50:58 INFO - u'jsshell-mac.zip'], 13:50:58 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:50:58 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 13:50:58 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 13:50:58 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:50:58 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 13:50:58 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:50:58 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 13:50:58 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:50:58 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 13:50:58 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:50:58 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 13:50:58 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:50:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 13:50:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:50:58 INFO - Fetch https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 13:50:59 INFO - Http code: 200 13:50:59 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 13:50:59 INFO - x-amz-cf-id: PhtqOEjnJsZlC3mYysPjvNmNFLvIXl27FeVNEat75tvor6om5-QPRA== 13:50:59 INFO - x-amz-version-id: oYs0S0BWLue4d5yNfzrDOVXSMP0of8m4 13:50:59 INFO - Expected file size: 16442708 13:50:59 INFO - Obtained file size: 16442708 13:51:06 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:51:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 13:51:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:51:06 INFO - Fetch https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 13:51:06 INFO - Http code: 200 13:51:06 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 13:51:06 INFO - x-amz-cf-id: ZsQl5vg261Jl_Ocm6BBJ4JPxmx8nVjVqJD6LIPVy_nhVRRtQ8BwRgQ== 13:51:06 INFO - x-amz-version-id: oYs0S0BWLue4d5yNfzrDOVXSMP0of8m4 13:51:06 INFO - Expected file size: 16442708 13:51:06 INFO - Obtained file size: 16442708 13:51:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 13:51:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:51:13 INFO - Fetch https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 13:51:13 INFO - Http code: 200 13:51:13 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 13:51:13 INFO - x-amz-cf-id: agGiTQYFcDoxEzsfRXaQDCvmvkfKwDf-4xReLBc0jjAs-MRdRr89YQ== 13:51:13 INFO - x-amz-version-id: 0bXUQ92dp_r4joro14ZW7j22G0F3vbr6 13:51:14 INFO - Expected file size: 14409028 13:51:14 INFO - Obtained file size: 14409028 13:51:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:51:14 INFO - trying https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:51:14 INFO - Downloading https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 13:51:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 13:51:17 INFO - Downloaded 111730824 bytes. 13:51:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:51:17 INFO - mkdir: /builds/slave/test/properties 13:51:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:51:17 INFO - Writing to file /builds/slave/test/properties/build_url 13:51:17 INFO - Contents: 13:51:17 INFO - build_url:https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:51:18 INFO - Running post-action listener: _resource_record_post_action 13:51:18 INFO - Running post-action listener: set_extra_try_arguments 13:51:18 INFO - [mozharness: 2016-10-26 20:51:18.220268Z] Finished download-and-extract step (success) 13:51:18 INFO - [mozharness: 2016-10-26 20:51:18.220423Z] Running populate-webroot step. 13:51:18 INFO - Running pre-action listener: _resource_record_pre_action 13:51:18 INFO - Running main action method: populate_webroot 13:51:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:51:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:51:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:51:18 INFO - '--tppagecycles', 13:51:18 INFO - '7'], 13:51:18 INFO - 'tests': ['tsvgm']}}, 13:51:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:51:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:51:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:51:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:51:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:51:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:51:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:18 INFO - 'talos_options': ['--disable-e10s'], 13:51:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:51:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:51:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:18 INFO - 'talos_options': ['--disable-e10s'], 13:51:18 INFO - 'tests': ['damp', 'tps']}, 13:51:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:18 INFO - 'tests': ['damp', 'tps']}, 13:51:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:51:18 INFO - 'tests': ['dromaeo_dom']}, 13:51:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:51:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:51:18 INFO - 'tests': ['basic_compositor_video']}, 13:51:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:51:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:51:18 INFO - 'tests': ['a11yr', 13:51:18 INFO - 'ts_paint', 13:51:18 INFO - 'tpaint', 13:51:18 INFO - 'sessionrestore', 13:51:18 INFO - 'sessionrestore_no_auto_restore', 13:51:18 INFO - 'tabpaint']}, 13:51:18 INFO - 'other-e10s': {'tests': ['a11yr', 13:51:18 INFO - 'ts_paint', 13:51:18 INFO - 'tpaint', 13:51:18 INFO - 'sessionrestore', 13:51:18 INFO - 'sessionrestore_no_auto_restore', 13:51:18 INFO - 'tabpaint']}, 13:51:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:51:18 INFO - 'ts_paint', 13:51:18 INFO - 'tpaint', 13:51:18 INFO - 'sessionrestore', 13:51:18 INFO - 'sessionrestore_no_auto_restore', 13:51:18 INFO - 'tabpaint']}, 13:51:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:51:18 INFO - 'ts_paint', 13:51:18 INFO - 'tpaint', 13:51:18 INFO - 'sessionrestore', 13:51:18 INFO - 'sessionrestore_no_auto_restore', 13:51:18 INFO - 'tabpaint']}, 13:51:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:51:18 INFO - 'tests': ['a11yr', 13:51:18 INFO - 'ts_paint', 13:51:18 INFO - 'tpaint', 13:51:18 INFO - 'sessionrestore', 13:51:18 INFO - 'sessionrestore_no_auto_restore', 13:51:18 INFO - 'tabpaint']}, 13:51:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:51:18 INFO - 'tests': ['a11yr', 13:51:18 INFO - 'ts_paint', 13:51:18 INFO - 'tpaint', 13:51:18 INFO - 'sessionrestore', 13:51:18 INFO - 'sessionrestore_no_auto_restore', 13:51:18 INFO - 'tabpaint']}, 13:51:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:51:18 INFO - 'tests': ['tsvgx', 13:51:18 INFO - 'tsvgr_opacity', 13:51:18 INFO - 'tart', 13:51:18 INFO - 'tscrollx', 13:51:18 INFO - 'cart']}, 13:51:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:51:18 INFO - 'tsvgr_opacity', 13:51:18 INFO - 'tart', 13:51:18 INFO - 'tscrollx', 13:51:18 INFO - 'cart']}, 13:51:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:18 INFO - 'talos_options': ['--disable-e10s'], 13:51:18 INFO - 'tests': ['tp5o']}, 13:51:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:18 INFO - 'tests': ['tp5o']}, 13:51:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:18 INFO - 'talos_options': ['--disable-e10s', 13:51:18 INFO - '--xperf_path', 13:51:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:51:18 INFO - 'tests': ['tp5n']}, 13:51:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:51:18 INFO - 'talos_options': ['--xperf_path', 13:51:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:51:18 INFO - 'tests': ['tp5n']}}, 13:51:18 INFO - 'talos.zip': {'path': '', 13:51:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:51:18 INFO - Running post-action listener: _resource_record_post_action 13:51:18 INFO - [mozharness: 2016-10-26 20:51:18.239530Z] Finished populate-webroot step (success) 13:51:18 INFO - [mozharness: 2016-10-26 20:51:18.239619Z] Running create-virtualenv step. 13:51:18 INFO - Running pre-action listener: _resource_record_pre_action 13:51:18 INFO - Running main action method: create_virtualenv 13:51:18 INFO - Creating virtualenv /builds/slave/test/build/venv 13:51:18 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 13:51:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:51:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:51:18 INFO - Using real prefix '/tools/python27' 13:51:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:51:19 INFO - Installing distribute.............................................................................................................................................................................................done. 13:51:21 INFO - Installing pip.................done. 13:51:21 INFO - Return code: 0 13:51:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:51:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:51:22 INFO - Reading from file tmpfile_stdout 13:51:22 INFO - Output received: 13:51:22 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) 13:51:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:51: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')]} 13:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51: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')]} 13:51:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108869490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9fb5d68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867dd30>, '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 0x1084a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108675b20>, '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.YpGAeUzLNB/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.9vPMwS83Cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 13:51: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', 'pip>=1.5'] in /builds/slave/test/build 13:51: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 pip>=1.5 13:51:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpGAeUzLNB/Render', 13:51:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:51:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:51:22 INFO - 'HOME': '/Users/cltbld', 13:51:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:51:22 INFO - 'LOGNAME': 'cltbld', 13:51:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:22 INFO - 'MOZ_NO_REMOTE': '1', 13:51:22 INFO - 'NO_EM_RESTART': '1', 13:51:22 INFO - 'PAGER': '/bin/cat', 13:51:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:51:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:22 INFO - 'PWD': '/builds/slave/test', 13:51:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:51:22 INFO - 'SHELL': '/bin/bash', 13:51:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9vPMwS83Cb/Listeners', 13:51:22 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 13:51:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:51:22 INFO - 'USER': 'cltbld', 13:51:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:51:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:51:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:22 INFO - 'XPC_FLAGS': '0x0', 13:51:22 INFO - 'XPC_SERVICE_NAME': '0', 13:51:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:51:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:22 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 13:51:22 INFO - Cleaning up... 13:51:22 INFO - Return code: 0 13:51:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:51: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')]} 13:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51: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')]} 13:51:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51: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', '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 0x108869490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9fb5d68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867dd30>, '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 0x1084a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108675b20>, '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.YpGAeUzLNB/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.9vPMwS83Cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 13:51: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', 'psutil>=3.1.1'] in /builds/slave/test/build 13:51: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 psutil>=3.1.1 13:51:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpGAeUzLNB/Render', 13:51:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:51:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:51:22 INFO - 'HOME': '/Users/cltbld', 13:51:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:51:22 INFO - 'LOGNAME': 'cltbld', 13:51:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:22 INFO - 'MOZ_NO_REMOTE': '1', 13:51:22 INFO - 'NO_EM_RESTART': '1', 13:51:22 INFO - 'PAGER': '/bin/cat', 13:51:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:51:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:22 INFO - 'PWD': '/builds/slave/test', 13:51:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:51:22 INFO - 'SHELL': '/bin/bash', 13:51:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9vPMwS83Cb/Listeners', 13:51:22 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 13:51:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:51:22 INFO - 'USER': 'cltbld', 13:51:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:51:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:51:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:22 INFO - 'XPC_FLAGS': '0x0', 13:51:22 INFO - 'XPC_SERVICE_NAME': '0', 13:51:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:51:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:22 INFO - Downloading/unpacking psutil>=3.1.1 13:51:22 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 13:51:22 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 13:51:22 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 13:51:22 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 13:51:22 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 13:51:22 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 13:51:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:51:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:51:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:51:26 INFO - Installing collected packages: psutil 13:51:26 INFO - Running setup.py install for psutil 13:51:26 INFO - building 'psutil._psutil_osx' extension 13:51:26 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 13:51:27 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 13:51:27 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 13:51:27 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 13:51:27 INFO - building 'psutil._psutil_posix' extension 13:51:27 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 13:51:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:51:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:51:27 INFO - ^ 13:51:27 INFO - 1 warning generated. 13:51:27 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 13:51:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:51:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:51:27 INFO - Successfully installed psutil 13:51:27 INFO - Cleaning up... 13:51:27 INFO - Return code: 0 13:51:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:51:27 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')]} 13:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:27 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')]} 13:51:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:27 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 0x108869490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9fb5d68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867dd30>, '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 0x1084a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108675b20>, '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.YpGAeUzLNB/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.9vPMwS83Cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 13:51:27 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 13:51:27 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 13:51:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpGAeUzLNB/Render', 13:51:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:51:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:51:27 INFO - 'HOME': '/Users/cltbld', 13:51:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:51:27 INFO - 'LOGNAME': 'cltbld', 13:51:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:27 INFO - 'MOZ_NO_REMOTE': '1', 13:51:27 INFO - 'NO_EM_RESTART': '1', 13:51:27 INFO - 'PAGER': '/bin/cat', 13:51:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:51:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:27 INFO - 'PWD': '/builds/slave/test', 13:51:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:51:27 INFO - 'SHELL': '/bin/bash', 13:51:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9vPMwS83Cb/Listeners', 13:51:27 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 13:51:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:51:27 INFO - 'USER': 'cltbld', 13:51:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:51:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:51:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:27 INFO - 'XPC_FLAGS': '0x0', 13:51:27 INFO - 'XPC_SERVICE_NAME': '0', 13:51:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:51:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:51:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:51:30 INFO - Installing collected packages: mozsystemmonitor 13:51:30 INFO - Running setup.py install for mozsystemmonitor 13:51:30 INFO - Successfully installed mozsystemmonitor 13:51:30 INFO - Cleaning up... 13:51:30 INFO - Return code: 0 13:51:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:51:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:51:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108869490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9fb5d68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867dd30>, '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 0x1084a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108675b20>, '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.YpGAeUzLNB/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.9vPMwS83Cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 13:51:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:51:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:51:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpGAeUzLNB/Render', 13:51:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:51:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:51:30 INFO - 'HOME': '/Users/cltbld', 13:51:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:51:30 INFO - 'LOGNAME': 'cltbld', 13:51:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:30 INFO - 'MOZ_NO_REMOTE': '1', 13:51:30 INFO - 'NO_EM_RESTART': '1', 13:51:30 INFO - 'PAGER': '/bin/cat', 13:51:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:51:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:30 INFO - 'PWD': '/builds/slave/test', 13:51:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:51:30 INFO - 'SHELL': '/bin/bash', 13:51:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9vPMwS83Cb/Listeners', 13:51:30 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 13:51:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:51:30 INFO - 'USER': 'cltbld', 13:51:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:51:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:51:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:30 INFO - 'XPC_FLAGS': '0x0', 13:51:30 INFO - 'XPC_SERVICE_NAME': '0', 13:51:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:51:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:31 INFO - Downloading/unpacking jsonschema==2.5.1 13:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:51:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:51:33 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 13:51:33 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 13:51:33 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 13:51:33 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 13:51:33 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 13:51:33 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 13:51:34 INFO - Downloading functools32-3.2.3-2.tar.gz 13:51:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:51:34 INFO - warning: no files found matching '*.txt' 13:51:34 INFO - no previously-included directories found matching 'build' 13:51:34 INFO - no previously-included directories found matching 'dist' 13:51:34 INFO - no previously-included directories found matching '.git*' 13:51:34 INFO - Installing collected packages: jsonschema, functools32 13:51:34 INFO - Running setup.py install for functools32 13:51:34 INFO - warning: no files found matching '*.txt' 13:51:34 INFO - no previously-included directories found matching 'build' 13:51:34 INFO - no previously-included directories found matching 'dist' 13:51:34 INFO - no previously-included directories found matching '.git*' 13:51:34 INFO - Successfully installed jsonschema functools32 13:51:34 INFO - Cleaning up... 13:51:34 INFO - Return code: 0 13:51:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:51:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:34 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 0x108869490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9fb5d68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867dd30>, '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 0x1084a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108675b20>, '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.YpGAeUzLNB/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.9vPMwS83Cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 13:51:34 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 13:51:34 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 13:51:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpGAeUzLNB/Render', 13:51:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:51:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:51:34 INFO - 'HOME': '/Users/cltbld', 13:51:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:51:34 INFO - 'LOGNAME': 'cltbld', 13:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:34 INFO - 'MOZ_NO_REMOTE': '1', 13:51:34 INFO - 'NO_EM_RESTART': '1', 13:51:34 INFO - 'PAGER': '/bin/cat', 13:51:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:34 INFO - 'PWD': '/builds/slave/test', 13:51:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:51:34 INFO - 'SHELL': '/bin/bash', 13:51:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9vPMwS83Cb/Listeners', 13:51:34 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 13:51:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:51:34 INFO - 'USER': 'cltbld', 13:51:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:51:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:34 INFO - 'XPC_FLAGS': '0x0', 13:51:34 INFO - 'XPC_SERVICE_NAME': '0', 13:51:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:51:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:51:34 INFO - Cleaning up... 13:51:34 INFO - Return code: 0 13:51:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:51:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:34 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 0x108869490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9fb5d68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867dd30>, '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 0x1084a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108675b20>, '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.YpGAeUzLNB/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.9vPMwS83Cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 13:51:34 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 13:51:34 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 13:51:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpGAeUzLNB/Render', 13:51:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:51:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:51:34 INFO - 'HOME': '/Users/cltbld', 13:51:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:51:34 INFO - 'LOGNAME': 'cltbld', 13:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:34 INFO - 'MOZ_NO_REMOTE': '1', 13:51:34 INFO - 'NO_EM_RESTART': '1', 13:51:34 INFO - 'PAGER': '/bin/cat', 13:51:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:34 INFO - 'PWD': '/builds/slave/test', 13:51:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:51:34 INFO - 'SHELL': '/bin/bash', 13:51:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9vPMwS83Cb/Listeners', 13:51:34 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 13:51:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:51:34 INFO - 'USER': 'cltbld', 13:51:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:51:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:34 INFO - 'XPC_FLAGS': '0x0', 13:51:34 INFO - 'XPC_SERVICE_NAME': '0', 13:51:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:51:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:34 INFO - Downloading/unpacking blobuploader==1.2.4 13:51:34 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 13:51:34 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 13:51:34 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 13:51:34 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 13:51:34 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 13:51:34 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 13:51:37 INFO - Downloading blobuploader-1.2.4.tar.gz 13:51:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:51:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:51:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:51:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:51:38 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 13:51:38 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 13:51:38 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 13:51:38 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 13:51:38 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 13:51:38 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 13:51:38 INFO - Downloading docopt-0.6.1.tar.gz 13:51:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:51:38 INFO - Installing collected packages: blobuploader, requests, docopt 13:51:38 INFO - Running setup.py install for blobuploader 13:51:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:51:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:51:38 INFO - Running setup.py install for requests 13:51:39 INFO - Running setup.py install for docopt 13:51:39 INFO - Successfully installed blobuploader requests docopt 13:51:39 INFO - Cleaning up... 13:51:39 INFO - Return code: 0 13:51:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:51:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:39 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 0x108869490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9fb5d68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867dd30>, '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 0x1084a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108675b20>, '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.YpGAeUzLNB/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.9vPMwS83Cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 13:51:39 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 13:51:39 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 13:51:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpGAeUzLNB/Render', 13:51:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:51:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:51:39 INFO - 'HOME': '/Users/cltbld', 13:51:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:51:39 INFO - 'LOGNAME': 'cltbld', 13:51:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:39 INFO - 'MOZ_NO_REMOTE': '1', 13:51:39 INFO - 'NO_EM_RESTART': '1', 13:51:39 INFO - 'PAGER': '/bin/cat', 13:51:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:51:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:39 INFO - 'PWD': '/builds/slave/test', 13:51:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:51:39 INFO - 'SHELL': '/bin/bash', 13:51:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9vPMwS83Cb/Listeners', 13:51:39 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 13:51:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:51:39 INFO - 'USER': 'cltbld', 13:51:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:51:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:51:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:39 INFO - 'XPC_FLAGS': '0x0', 13:51:39 INFO - 'XPC_SERVICE_NAME': '0', 13:51:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:51:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:51:39 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-hVdhWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:51:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-lNxZtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:51:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-bHalpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:51:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-BVErKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:51:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-0q_wMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:51:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-mz4KpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:51:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-qmwMcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:51:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-quMQER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:51:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-gPtJnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:51:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-bl2YFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:51:40 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-hAziMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:51:41 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-Q6fGPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:51:41 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-asDOQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:51:41 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-SrnU0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:51:41 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-8GlxKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:51:41 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-YfHtBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:51:41 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-uS22oM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:51:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:51:41 INFO - Running setup.py install for manifestparser 13:51:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:51:41 INFO - Running setup.py install for mozcrash 13:51:41 INFO - Running setup.py install for mozdebug 13:51:42 INFO - Running setup.py install for mozdevice 13:51:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:51:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:51:42 INFO - Running setup.py install for mozfile 13:51:42 INFO - Running setup.py install for mozhttpd 13:51:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:51:42 INFO - Running setup.py install for mozinfo 13:51:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:51:42 INFO - Running setup.py install for mozInstall 13:51:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:51:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:51:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:51:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:51:42 INFO - Running setup.py install for mozleak 13:51:42 INFO - Running setup.py install for mozlog 13:51:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:51:43 INFO - Running setup.py install for moznetwork 13:51:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:51:43 INFO - Running setup.py install for mozprocess 13:51:43 INFO - Running setup.py install for mozprofile 13:51:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:51:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:51:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:51:43 INFO - Running setup.py install for mozrunner 13:51:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:51:43 INFO - Running setup.py install for mozscreenshot 13:51:43 INFO - Running setup.py install for moztest 13:51:44 INFO - Running setup.py install for mozversion 13:51:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:51:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:51:44 INFO - Cleaning up... 13:51:44 INFO - Return code: 0 13:51:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:51:44 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')]} 13:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:44 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')]} 13:51:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:44 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 0x108869490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9fb5d68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867dd30>, '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 0x1084a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108675b20>, '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.YpGAeUzLNB/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.9vPMwS83Cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 13:51:44 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 13:51:44 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 13:51:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpGAeUzLNB/Render', 13:51:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:51:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:51:44 INFO - 'HOME': '/Users/cltbld', 13:51:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:51:44 INFO - 'LOGNAME': 'cltbld', 13:51:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:44 INFO - 'MOZ_NO_REMOTE': '1', 13:51:44 INFO - 'NO_EM_RESTART': '1', 13:51:44 INFO - 'PAGER': '/bin/cat', 13:51:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:51:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:44 INFO - 'PWD': '/builds/slave/test', 13:51:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:51:44 INFO - 'SHELL': '/bin/bash', 13:51:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9vPMwS83Cb/Listeners', 13:51:44 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 13:51:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:51:44 INFO - 'USER': 'cltbld', 13:51:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:51:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:51:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:44 INFO - 'XPC_FLAGS': '0x0', 13:51:44 INFO - 'XPC_SERVICE_NAME': '0', 13:51:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:51:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:51:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-4xbESn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:51:44 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)) 13:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:51:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-COScS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 13:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:51:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-peNtP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:51:44 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)) 13:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:51:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-fLCH9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:51:44 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)) 13:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:51:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-kLYAaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:51:45 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)) 13:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:51:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-UMwlqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:51:45 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)) 13:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:51:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-i95MWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:51:45 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)) 13:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:51:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-RZ6wNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:51:45 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)) 13:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:51:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-uejGPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:51:45 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)) 13:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:51:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-cIHxh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:51:45 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)) 13:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:51:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-_yN3nw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:51:45 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)) 13:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:51:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-eRSgLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:51:45 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)) 13:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:51:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-zcNyFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:51:45 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)) 13:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:51:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-b9iRuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 13:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:51:46 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-mhq7Hy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:51:46 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)) 13:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:51:46 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-v0CTIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:51:46 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)) 13:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:51:46 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-huKmZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:51:46 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)) 13:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:51:46 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)) 13:51:46 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)) 13:51:46 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)) 13:51:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:51:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:51:48 INFO - Downloading blessings-1.6.tar.gz 13:51:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:51:49 INFO - Installing collected packages: blessings 13:51:49 INFO - Running setup.py install for blessings 13:51:49 INFO - Successfully installed blessings 13:51:49 INFO - Cleaning up... 13:51:49 INFO - Return code: 0 13:51:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:51:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:51:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:51:49 INFO - Reading from file tmpfile_stdout 13:51:49 INFO - Current package versions: 13:51:49 INFO - blessings == 1.6 13:51:49 INFO - blobuploader == 1.2.4 13:51:49 INFO - docopt == 0.6.1 13:51:49 INFO - functools32 == 3.2.3-2 13:51:49 INFO - jsonschema == 2.5.1 13:51:49 INFO - manifestparser == 1.1 13:51:49 INFO - mozInstall == 1.12 13:51:49 INFO - mozcrash == 0.17 13:51:49 INFO - mozdebug == 0.1 13:51:49 INFO - mozdevice == 0.48 13:51:49 INFO - mozfile == 1.2 13:51:49 INFO - mozhttpd == 0.7 13:51:49 INFO - mozinfo == 0.9 13:51:49 INFO - mozleak == 0.1 13:51:49 INFO - mozlog == 3.4 13:51:49 INFO - moznetwork == 0.27 13:51:49 INFO - mozprocess == 0.23 13:51:49 INFO - mozprofile == 0.28 13:51:49 INFO - mozrunner == 6.12 13:51:49 INFO - mozscreenshot == 0.1 13:51:49 INFO - mozsystemmonitor == 0.3 13:51:49 INFO - moztest == 0.7 13:51:49 INFO - mozversion == 1.4 13:51:49 INFO - psutil == 3.1.1 13:51:49 INFO - requests == 1.2.3 13:51:49 INFO - wsgiref == 0.1.2 13:51:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:51:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:49 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 0x108869490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9fb5d68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867dd30>, '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 0x1084a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108675b20>, '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.YpGAeUzLNB/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.9vPMwS83Cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 13:51:49 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 13:51:49 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 13:51:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpGAeUzLNB/Render', 13:51:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:51:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:51:49 INFO - 'HOME': '/Users/cltbld', 13:51:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:51:49 INFO - 'LOGNAME': 'cltbld', 13:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:49 INFO - 'MOZ_NO_REMOTE': '1', 13:51:49 INFO - 'NO_EM_RESTART': '1', 13:51:49 INFO - 'PAGER': '/bin/cat', 13:51:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:49 INFO - 'PWD': '/builds/slave/test', 13:51:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:51:49 INFO - 'SHELL': '/bin/bash', 13:51:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9vPMwS83Cb/Listeners', 13:51:49 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 13:51:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:51:49 INFO - 'USER': 'cltbld', 13:51:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:51:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:49 INFO - 'XPC_FLAGS': '0x0', 13:51:49 INFO - 'XPC_SERVICE_NAME': '0', 13:51:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:51:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:49 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)) 13:51:49 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)) 13:51:49 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)) 13:51:49 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)) 13:51:49 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)) 13:51:49 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)) 13:51:49 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)) 13:51:49 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)) 13:51:49 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)) 13:51:49 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)) 13:51:49 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)) 13:51:49 INFO - Cleaning up... 13:51:49 INFO - Return code: 0 13:51:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:51:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:51:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:51:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:51:49 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 0x108869490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9fb5d68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10867dd30>, '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 0x1084a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108675b20>, '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.YpGAeUzLNB/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.9vPMwS83Cb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 13:51:49 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 13:51:49 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 13:51:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpGAeUzLNB/Render', 13:51:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:51:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:51:49 INFO - 'HOME': '/Users/cltbld', 13:51:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:51:49 INFO - 'LOGNAME': 'cltbld', 13:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:51:49 INFO - 'MOZ_NO_REMOTE': '1', 13:51:49 INFO - 'NO_EM_RESTART': '1', 13:51:49 INFO - 'PAGER': '/bin/cat', 13:51:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:51:49 INFO - 'PWD': '/builds/slave/test', 13:51:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:51:49 INFO - 'SHELL': '/bin/bash', 13:51:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9vPMwS83Cb/Listeners', 13:51:49 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 13:51:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:51:49 INFO - 'USER': 'cltbld', 13:51:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:51:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:51:49 INFO - 'XPC_FLAGS': '0x0', 13:51:49 INFO - 'XPC_SERVICE_NAME': '0', 13:51:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:51:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:51:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:51:50 INFO - Cleaning up... 13:51:50 INFO - Return code: 0 13:51:50 INFO - Running post-action listener: _resource_record_post_action 13:51:50 INFO - Running post-action listener: _start_resource_monitoring 13:51:50 INFO - Starting resource monitoring. 13:51:50 INFO - [mozharness: 2016-10-26 20:51:50.165417Z] Finished create-virtualenv step (success) 13:51:50 INFO - [mozharness: 2016-10-26 20:51:50.166414Z] Running install step. 13:51:50 INFO - Running pre-action listener: _resource_record_pre_action 13:51:50 INFO - Running main action method: install 13:51:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:51:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:51:50 INFO - Reading from file tmpfile_stdout 13:51:50 INFO - Detecting whether we're running mozinstall >=1.0... 13:51:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:51:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:51:50 INFO - Reading from file tmpfile_stdout 13:51:50 INFO - Output received: 13:51:50 INFO - Usage: mozinstall [options] installer 13:51:50 INFO - Options: 13:51:50 INFO - -h, --help show this help message and exit 13:51:50 INFO - -d DEST, --destination=DEST 13:51:50 INFO - Directory to install application into. [default: 13:51:50 INFO - "/builds/slave/test"] 13:51:50 INFO - --app=APP Application being installed. [default: firefox] 13:51:50 INFO - mkdir: /builds/slave/test/build/application 13:51:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:51:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:52:24 INFO - Reading from file tmpfile_stdout 13:52:24 INFO - Output received: 13:52:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:52:24 INFO - Running post-action listener: _resource_record_post_action 13:52:24 INFO - [mozharness: 2016-10-26 20:52:24.911875Z] Finished install step (success) 13:52:24 INFO - [mozharness: 2016-10-26 20:52:24.912011Z] Running run-tests step. 13:52:24 INFO - Running pre-action listener: _resource_record_pre_action 13:52:24 INFO - Running main action method: run_tests 13:52:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:52:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:52:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:52:24 INFO - 2016-10-26 13:52:24.926 screenresolution[1636:8086] starting screenresolution argv=screenresolution get 13:52:24 INFO - 2016-10-26 13:52:24.929 screenresolution[1636:8086] Display 0: 1600x1200x32@60 13:52:24 INFO - 2016-10-26 13:52:24.935 screenresolution[1637:8089] starting screenresolution argv=screenresolution list 13:52:24 INFO - Available Modes on Display 0 13:52:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:52:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:52:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:52:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:52:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:52:25 INFO - Intel Iris: 13:52:25 INFO - Chipset Model: Intel Iris 13:52:25 INFO - Type: GPU 13:52:25 INFO - Bus: Built-In 13:52:25 INFO - VRAM (Dynamic, Max): 1536 MB 13:52:25 INFO - Vendor: Intel (0x8086) 13:52:25 INFO - Device ID: 0x0a2e 13:52:25 INFO - Revision ID: 0x0009 13:52:25 INFO - Displays: 13:52:25 INFO - Display: 13:52:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:52:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:52:25 INFO - Display Serial Number: 1600x1200 60 13:52:25 INFO - Main Display: Yes 13:52:25 INFO - Mirror: Off 13:52:25 INFO - Online: Yes 13:52:25 INFO - Rotation: Supported 13:52:25 INFO - Return code: 0 13:52:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:52:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:52:25 INFO - Python 2.7.3 13:52:25 INFO - Return code: 0 13:52:25 INFO - grabbing minidump binary from tooltool 13:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:52:25 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 0x10867dd30>, '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 0x1084a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108675b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:52:25 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 13:52:25 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 13:52:25 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 13:52:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:52:25 INFO - Return code: 0 13:52:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:52:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:52:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:52:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:52:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:52:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0020', '--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 13:52:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0020 --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 13:52:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpGAeUzLNB/Render', 13:52:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:52:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:52:25 INFO - 'HOME': '/Users/cltbld', 13:52:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:52:25 INFO - 'LOGNAME': 'cltbld', 13:52:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:52:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:52:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:52:25 INFO - 'MOZ_NO_REMOTE': '1', 13:52:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:52:25 INFO - 'NO_EM_RESTART': '1', 13:52:25 INFO - 'PAGER': '/bin/cat', 13:52:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:52:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:52:25 INFO - 'PWD': '/builds/slave/test', 13:52:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:52:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:52:25 INFO - 'SHELL': '/bin/bash', 13:52:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9vPMwS83Cb/Listeners', 13:52:25 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 13:52:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:52:25 INFO - 'USER': 'cltbld', 13:52:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:52:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:52:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:52:25 INFO - 'XPC_FLAGS': '0x0', 13:52:25 INFO - 'XPC_SERVICE_NAME': '0', 13:52:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:52:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0020', '--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 13:52:25 INFO - mozversion application_buildid: 20161026075526 13:52:25 INFO - mozversion application_changeset: d26ac63f1b81c3fce35448a7c502e95e0b5c56c0 13:52:25 INFO - mozversion application_display_name: Nightly 13:52:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:52:25 INFO - mozversion application_name: Firefox 13:52:25 INFO - mozversion application_remotingname: firefox 13:52:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:52:25 INFO - mozversion application_vendor: Mozilla 13:52:25 INFO - mozversion application_version: 52.0a1 13:52:25 INFO - mozversion platform_buildid: 20161026075526 13:52:25 INFO - mozversion platform_changeset: d26ac63f1b81c3fce35448a7c502e95e0b5c56c0 13:52:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:52:25 INFO - mozversion platform_version: 52.0a1 13:52:25 INFO - using testdate: 1477515145 13:52:25 INFO - actual date: 1477515145 13:52:25 INFO - starting webserver on 'localhost:49266' 13:52:25 INFO - SUITE-START | Running 2 tests 13:52:25 INFO - TEST-START | dromaeo_css 13:52:25 INFO - operating with platform_type : mac_ 13:52:25 INFO - Initialising browser for dromaeo_css test... 13:52:25 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpxvvo1R/profile http://localhost:49266/getInfo.html) 13:52:27 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 13:52:27 INFO - PROCESS | 1647 | colorDepth:24 13:52:27 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 13:52:27 INFO - PROCESS | 1647 | __metrics 13:52:27 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:52:27 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:52:28 INFO - TEST-INFO | 1647: exit 0 13:52:28 INFO - Browser initialized. 13:52:28 INFO - Running cycle 1/1 for dromaeo_css test... 13:52:28 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpxvvo1R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:52:40 INFO - PROCESS | 1649 | RSS: Main: 231047168 13:52:40 INFO - PROCESS | 1649 | 13:53:32 INFO - PROCESS | 1649 | 1477515212095 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] 13:55:46 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 13:55:47 INFO - PROCESS | 1649 | RSS: Main: 419745792 13:55:47 INFO - PROCESS | 1649 | 13:58:50 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 13:58:51 INFO - PROCESS | 1649 | RSS: Main: 369610752 13:58:51 INFO - PROCESS | 1649 | 14:01:53 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 14:01:54 INFO - PROCESS | 1649 | RSS: Main: 336150528 14:01:54 INFO - PROCESS | 1649 | 14:04:56 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 14:04:57 INFO - PROCESS | 1649 | RSS: Main: 353873920 14:04:57 INFO - PROCESS | 1649 | 14:08:00 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 14:08:01 INFO - PROCESS | 1649 | RSS: Main: 336502784 14:08:01 INFO - PROCESS | 1649 | 14:11:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 14:11:04 INFO - PROCESS | 1649 | RSS: Main: 476065792 14:11:04 INFO - PROCESS | 1649 | 14:11:04 INFO - PROCESS | 1649 | __start_tp_report 14:11:04 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 14:11:04 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 14:11:04 INFO - PROCESS | 1649 | |i|pagename|runs| 14:11:04 INFO - PROCESS | 1649 | |0;dojo.html;3332.5667663203785;3350.262942152727;3360.1935535471493;3386;3403.4384326586114;5621.269234999455;5706.029974904266;5769.076947688365;5785.363610002902;5817.592768506201;5652;5713.257276554042;5721.141828725683;5728.398518155585;5738;5094.235864620318;5138.563222126124;5139.794408223666;5222.817201397952;5242.056429842627;4567.931481027787;5295.417504074549;5326.520613144816;5331.120365139747;5389.865253368677;5153.6392452528335;5298.09932311508;5307.310049693554;5309.123994540934;5400.621956463049;5182.974085129588;5230.947690523084;5236.3978142513715;5243.475652434726;5246.213068039787;5154.149565321717;5184.248283998799;5209.374875014984;5210.557102646279;5219.973900130513;5716.371199168088;5720.427957204288;5721.742521586539;5727.398623144593;5732.197492351074;6106.642687876637;6146.508279337642;6147.569670123094;6154.26148862135;6167.845803854894;6100.479656792151;6145.770845830852;6168.87662246757;6181.629102253879;6197;4849.587785038311;5734.512566431817;5793.304803423532;5807.09989951554;5815.41845815415;6168.228971378578;6232.8130156095385;6261.7182226799905;6280.654563998933;6296.653684047421;6120.357362476965;6154.3845615438995;6156.384361563808;6166.229221347332;6170.10533472644;6125.540584456184;6133.785317513865;6143.419763699257;6149.569530132848;6168.136460895478;6146.354632763496;6193.84515387119;6200.937990620127;6205.410486003822;6210.130581718563;6201.162843016225;6201.6899155042065;6206.627602343874;6210.441060304594;6213.875722485525;4840.878978025577;4876.341693871353;4894.975525122351;4917.655764096479;4948.282499037619;4338.82644694215;4435.467743870691;4453.933191002137;4455.443069616298;4487.102579484116;5810.389909059572;5816.040353341736;5829.679367634821;5831.241938547962;5840.970795145997;6133.1510485532735;6181.907271390933;6188.8762224755255;6199.783007594712;6199.938000619936;5803.970980145073;5808.535317174616;5813.912791308134;5820.912686309709;5823.88352232953;5321.122014867581;5367.007103685831;5395.406505284415;5441.673499589958;5449.291592093082;6263.561550691408;6304.905426418607;6321.557490975588;6330.683465826598;6368.87262254743;6236.002239641635;6237.750489980532;6245.281792593728;6247.344028877084;6256.843578910563;6189.071639253968;6238.407351301619;6250.718717657625;6264.498840092872;6304.6847657617855;6152.723127459365;6158.076288556573;6182.721777519932;6183.938160618516;6184.567080304336;6181.227346581677;6191.597546159485;6217.875642487215;6218.9378106218355;6222.0355844844125;5066.873328166826;6016.368281330398;6064.02975523914;6103.908441373472;6104.610739188311;4753.405824271966;6105.114758359924;6131.570790044654;6144.109104179958;6156.5998210116195;6006.039033754579;6017.097435384728;6017.669028203491;6025.457708806229;6037.0642550404755;5977.342492325927;6041.546883983807;6057.121717351048;6079.574429790049;6126.816195514141;5944.673042982505;5951.732172052355;5953.077273022688;5955.791547295824;5967.194428752063 14:11:04 INFO - PROCESS | 1649 | |1;ext.html;501.75915560530933;510.3263691926657;511.3327108123897;539.8353502181833;552.2930648769576;30890.91881784138;40798.79600601996;41772;41891.37162942554;43440.78279608605;42699.359509607384;43091.56908430915;43101;43149.7842510787;43258.13483730324;9485.668001619944;9663.95168024159;9666.709998700015;9669.854952175723;9679.516024198761;9504.002079781627;9614.182794462478;9618.711438656852;9620.56707448163;9627.614895404175;9485.430874147572;9531.904680953172;9541.522923853814;9542.952285238563;9543.618255269781;9580.329376943617;9617.471039092847;9628.855567166498;9632.56653450593;9647;9439.433633981984;9502.239820814319;9516.048395160498;9518.809623807485;9532.332736708433;40574.39138412926;40670.186596268206;40829.79585102056;40882.38676419856;40903.97740056492;3340.3486320167576;3352.329534093167;3387.5257463955068;3447.4311738563356;3818.8281527331337;30578.54132188019;30963.380732385227;30999.38001239985;31133.221669458217;31710.841445792856;3078.553609726577;3201.743860491155;3208.951865722016;3211.084840820355;3243.5728279557156;445889;446441;447435.7628211871;449425;451834;58131.41868581345;58134.70932645394;59638.70180649156;59653.40346596565;60022;34833.65166348304;35098.47352289667;35575.82212088923;62749.05876411858;63288.68355658192;16063.437779677655;16255.349786008665;16405.83594164043;16433.178341082898;16463.670726585402;12515.74968500643;12582.622521324374;12585.811212831815;12617.93691031539;12625.87374126247;19443.22227110928;19468.22127114928;19469.902650486656;19474.610507790047;19475.104979003972;7314.793059145181;7687.500312479671;7921.989643935268;8079.798005049921;8102.918970810217;1014.1430491234895;1024.7489365105596;1067.1836045425257;1079.1852151625471;1121.4112590889686;23038.884805575864;23752.64371034436;28076.157715268575;28739.425211495654;30114.54828177579;995.1938929467154;1024.1806554756165;1062.2935747727674;1113.0483436661539;1136.7669627726295;12688.936555317165;14541.200233987232;15281.006734562217;15351.692966140838;15779.763303550455;621617;627049;627532;627637;628515;55475.89048219094;56094;56166.43833561612;56200;56255.87488250212;1249.6688377579949;1292.7091404434116;1350.2371160294401;1375.6698392385756;1399.839018512884;26116.8694156528;29341.706582933897;30873.691263087083;30931.906809318887;31404.214894627818;48104.27843582279;48680;48698.75650621724;48825;48870.51129488659;493662;494623;495334;495616;495748;22737.772622273566;25585.744142558335;25701.3574660635;26306.4738705221;26338.60492092582;45711.85720357018;45903.3114503276;46057.539424605326;46398.84002899955;46777.59667209989;35859.10352241215;37507.43738843974;38254.42618360782;39008.804955975036;39189.60810391974;36739;36956.81521592483;38453;38497.80751096339;38508;36581.817090914374;37070.59041162316;37313.62686373101;37548.812255938545;37660.43509347417;32281.3856473033;34805;34950.82524587361;35683.82158089193;35729.1067723309;20791.388418220115;25027.624585631595;25407.87296063508;25603.487930241663;25721.241570309554 14:11:04 INFO - PROCESS | 1649 | |2;jquery.html;331.86559443425415;360.0198906016905;360.8610684886318;369.8243334416153;386.3991493228033;19371.031448427577;20082.799172008275;20924.476888077807;21013.579728405442;21128.577428451423;14623.78064329036;19799.109040093193;19994;20054.1978320867;20454.897725511353;5385.192221166827;5574.526165275936;5581;5581.246531718227;5616.297962754656;5918.437748413915;6091.908621370683;6093.664848433335;6093.78671746489;6101.267847858263;5341.599380046493;5420.783168673248;6037.456628903398;6058.091286307067;6062.575619706622;5402.216678581623;5456.945430545723;5459.235707001023;5465.453454654523;5468.48049435303;5038.7984480620735;5270.209468579706;5295.841124766263;5297.523222909918;5352.437994010651;13295.108939522284;13339.666508337272;13425.194488330731;13441.798373024412;25343.746562534325;2092.0794850265834;2121.16214095433;2121.8514703970727;2182.9126834926583;2216.2797090945505;9845.212383009342;9865.654702085392;9936.90063099367;10249.795004099951;10301.587936482532;1772.273367919147;1821.134960893565;1834.092124398418;1860.5906700525863;1892.4198294424173;81274.59362703208;106345;126286;126783;127167;11206.159538034517;11369.204155709187;11529;11535.480903359368;11723.706907327385;14745;14751.557453276204;14770.483033094006;14771.40914363435;14784.852151478563;8209.58952052395;8481.915180848237;8482.787930301793;8584.141585841366;8626.18051285127;8334.708285209988;8342.499450033018;8368.665253389918;8540.44487108336;8568;4438.245498265303;4514.074614703978;4518.954810451919;4577.3362862384765;4621.976890115528;1798.1998010885031;1877.6150889067708;1942.8348590369976;1948.1720268885667;1948.4509814697349;701.1200942816732;748.296601194875;792.338397438134;917.977050573741;961.8076384723083;19500.024998750006;20679.27622533234;21416.571668566852;21453;21605.67591486129;857.6226460357423;889.4885440871525;924.6671198368582;964.6527250189926;1006.642641862143;10716.981886720749;11475.598354057567;11572.884271157349;11594.420278986017;11618.419079046183;570028;575544;589165;590036;590296;68592.01163659264;120140;122743.38628306802;132396.3380183093;138907;896.050186801992;905.1943770044666;922.6540047482194;924.4761800285124;927.958241879117;19406.932618706105;19714.802851971872;19777.802221977596;19838.90080549588;19875;54836.35490935278;55193.44806551883;56079.71960140336;56305.71847140738;56570;75226.62386688251;80694.59652701698;81489.59255203686;81652;82632.58683706544;6485.3514648534765;7914.841703165789;7944.285014348505;7965.044194696674;8328.417010809186;10510.579576817148;12965.481380745043;13125.409356578786;13394.531191408254;13406.32968351598;8132.593370331341;11120.777584448428;11265.986061254527;11422.45796817419;11554.884451155382;11133.109351251718;11188.10495160369;11224.887751122384;11364.374959377314;11627;11405.258658187191;11528.423578821194;11727.706807329885;11807.586734464596;11955.282683039046;7375.631218438949;7497.737579184703;7545.132309784445;7648.617569121633;7701.4608977371045;9268.490233037248;9709;10198.643047493302;10996;11326.150538709466 14:11:04 INFO - PROCESS | 1649 | |3;mootools.html;1538.0771537077756;1564.8043994500688;1794.9192286347113;1872.9719054214181;1949.4054313434397;8275.917240827597;8690.174433428814;8695.087015863339;8722.738317850475;8723.345749068822;7683.731069412558;7921.247481489264;7939.1663875293125;8284.502929824215;8511.233988940994;2289.5878741826423;2319.524497478021;2326.302109367183;2327.359976006603;2329.557384097016;2332.4518738096535;2355.1756885090167;2368.3250273672006;2370.0401337458356;2375.869327187004;2305.965410518844;2319.663648770927;2341.203990643181;2342.4378149244144;2346.9178578749747;2304.2050492580033;2306.5156317173414;2330.8368414211013;2331.3821837213154;2333.8482998605036;2012.175008246612;2327.290176496168;2343.824213184001;2348.635961425982;2349.917752878658;8549.914500854975;8567.82864342716;8648.956755216246;8670.609822558;8681.609327580274;5111.361079865017;5251.396089449724;5253.789848406097;5263.526282634543;5274.657147285453;3282.376348493802;3303.9174020649434;3531.346700860349;3589.4436362363876;3591.515145455356;1014.3964341216964;1044.1281529922448;1045.2474218562622;1049.5749221565202;1058.4601853054921;11197.048250898684;11201.215914885961;11374.886251137548;11379.431028448627;11391.487383067783;4447.955520444754;4599.402077729874;4701.2242979908615;4708.952910470852;4716.481187069419;9667.419954802734;9671.468069256258;9679.08048735369;9703.805923881624;9711.46586937711;5252.44849290819;5258.158694608845;5262.894742105136;5275.235090911873;5284.04887120322;4257.9561810331215;4601.355810186509;4823.541763532458;4826.251930950708;4836;3710.348894741814;3744.1388480649057;3803.3914573668635;3849.3840985442193;3860.0542866997203;1222.1689251309017;1230.1757822259108;1230.2434003088035;1243.1422318600137;1245.3586403002462;789.5065584009993;866.2463656618656;900.963961441535;931.8960806715983;959.64972784933;5063.316452278968;5663.377028526858;5675.858103547362;5860.974329492322;6000.519958403317;421.62475396896787;426.78020819278095;430.53502217605535;431.019430794944;433.0343334364385;3811.7331786774657;3838.328292548849;3941.7043721720984;3957.821898014539;3992.500937382827;10133.543990520444;10384.636537721144;10533.157347412189;10553.577856885639;10555.050045495942;10495.737606559896;10508.894911050791;10510.264281500222;10524.789504210026;10544.525496352682;7739.303462688273;7953.483023603448;8088.676452941934;8137.023557173177;8140.59297035134;5665.546756259407;5682.943170568242;5696.886062278814;5715;5793.449622285876;9516.809663806824;9742.805143896941;9756.170725488413;9762.463064531235;9771.560279787285;8127.512349259064;9252.49111298885;9350;9658.90341096608;9682;6827.590344579341;7044.330788575077;7050.612216327946;7218.783436496913;7382.483226174117;7971.242731940688;8047.396445266511;8186.140455252113;8290.088090310182;8303.584820758817;7032.613206273704;7072.469564782765;7319.34125928669;7374.33630973215;7383.2985866342615;7087.7164913402885;7136.714531418893;7225.1329840419485;7294.307040831112;7382.446316526175;6790.320967903368;6934.133233345832;6936.7572134975035;6947.44420446353;6952.235254121942;4821.300911367902;4862.367892173995;4914.238293064581;4996.400431948189;5021.422536408214;4830.541098595628;4903.436104847846;5039.168537191469;5135.563477104488;5171.612129090408 14:11:04 INFO - PROCESS | 1649 | |4;prototype.html;289.7146310883779;298.9641243050834;308.0112837790692;308.96601373848875;313.5077927653582;4807.6153907687385;5349.960779611393;5365.973170134149;5414.593905457092;5422.508810077231;4794.2329227323635;4859.979404325096;5024.246363045545;5083.339165908426;5274.5780337573;3870.612938706128;4009.739366941154;4024.678025757932;4028.811500607313;4045.9595404045876;4035.57626449223;4044.1911617676583;4060.776657283848;4063.878083657495;4070.9185816283657;3825.5218097737784;3968.5634580196156;3969.384745364473;3997.1206334606486;4003.837927519136;3367.4948757686298;3967.789824103633;3969.233691007503;3972.82122304497;4007.258657148422;3423.828808559587;3425.794452332868;3430.9313813723834;3445.552078229814;3448.4654878493875;4479.70088674284;4870.269459581055;5122.257272695476;5139.280500729901;5159.2003239497935;4463.933041004388;4503.819847206108;4510.052888893337;4541.477730061019;4555.339475775994;5484.698341591211;5511.255980442629;5513.719336994482;5520.2059330717675;5549.722513874331;3328;3756.8067142928567;3761.492198553215;3771.585125636178;3814.237152569497;4799.592034677022;5231.8168864089575;5281.788728450857;5430.728463576845;5719.914201286984;3209.935801283961;3281.7702760806515;3659.432787917877;3814.6948244140403;3819.904502387407;3861.4207868819904;3862.227554489057;3865.903352416156;3876.127871228951;3881.6608270146753;4321.395004699345;4403.899025243689;4422.425084738963;4533.602405666498;4537.4328208973875;5455.645383050089;5563.276774019351;5611.859703507445;5621.8594535136945;5646.741303478641;1239.5599562155485;1296.5656505070717;1324.973500529984;1329.4682127149217;1329.8005299205004;741.9649588823669;797.5613412623034;804.4730701390598;811.5130921447085;816.6529225079317;3901.3809269152985;3989.3018721723583;4014.558398576154;4301.827926882952;4305.720128191658;4037.5962403759977;4163.292240319153;4164.250434921682;4733.881652958704;4867.712175277614;3824.1395685970433;3898.707596930185;4250.193667691219;4271.487421509439;4308.763018033976;2272.204958083129;2510.5230006298743;2511.9073203156686;2524.3941454050896;2553.327570441364;4048.8380464781667;4461.166889558692;4625.306204069417;4917.311576379309;4917.975410122926;4453.724424552466;4536.228841097021;4803.1114243864295;4843.782029808667;4875.7074575525585;3959.742616729903;4054.8986275343354;4713.690291757014;5663.320401551922;6028.4272994065495;5347.598930080182;5794.739236734273;5804.709764511673;5850.414958504286;5853.970730146322;5134.717590532557;5502;5749.770009199585;5755.762511060142;5773.53811695055;5354.839354819362;5404.972975135256;5535.972320138535;5898.587098903035;5980.19267398913;3444.844981975867;3725.8882233533036;3737.215184811111;3744.344739670601;3827.3302172120325;5741.311042674905;5749.655020698771;5774.624649397776;5811.476967072815;5833.666533386509;4870.659053866196;5299.91579573044;5576.609637325349;5610.5231055360755;5618.494335509824;4452.154090722753;4900.534449227318;5464.80873169453;5635.774569017193;5661.556303142837;5500.642458240201;5531.391546929754;5534.667919924817;5560.332760068818;5567.359753628384;5156.871078223075;5243.449437808975;5538.6399884007415;5582.441755824385;5587.435518054951;4603.079384123122;4973.129702302155;5171.793128274978;5488.807891723771;5491.313585801775 14:11:04 INFO - PROCESS | 1649 | |5;yui.html;605.7637521366668;731.9853602927939;758.2947858491603;788.5268838696779;799.2992444979744;14344;14389.568312950601;14974;15010.549683509485;15033.924830375847;13497;13627.795583066238;13654.180749155057;13658.863411365883;13659.317034148278;698.9999999999975;717.347214035228;718.2063819479479;870.5777697816554;876.5748611923212;880.5773228850156;886.5079880666227;891.5765011619461;894.2532984957568;897.4884315939917;944.1031020530489;945.5603144537788;945.7210123013728;946.1720994130136;947.7535840681403;942.048530983708;942.3121121581285;942.5522876633613;943.8678584998106;944.8771659684265;544.4936209325326;561.357245953381;678.2132726037772;694.8262934266434;700.4886432904009;12074.396280185956;14385.352659130363;14420.49528266516;14441.78337324941;14442.566722998326;12840.357982100857;12967.805482917764;13123.737525249537;13142.802857957138;13146.342682865818;10235.692929212135;11916.46375913086;11953;12006;12530.997520198362;4337.804798784062;4375.299952007664;4421.6904816662845;4491.461024677027;4571.542845715435;84267;84514.57742711308;84550.15449845546;85773.71339429913;85777.57111214467;10347.793044139074;10362.844557331646;10387.84418233727;10478.37129772216;10498.71912031281;19799.30702425437;20023.195592837314;20055.09752061161;20093.196272149242;20262.89868550648;7513.71760878324;7583.62081895903;7658.081030276291;7692.73075442368;7898.842023159505;3971.1660551284554;4169.437125987953;4222.683298752606;4269.637080848101;4396.098799746044;3009.277773334384;3381.917786308407;3485.5120283160377;3490.127468132967;3498.755087143926;636.7707625254905;641.7433026789322;649.5810202419508;654.2933631677777;662.1491383572087;598.8173607049891;624.9312575616678;629.3266205160526;630.6121735132951;631.5673763472037;8230.259276665129;8677.6528938843;8688.218060374596;8713.695020674246;8751.387402881737;577.5956830218863;596.7762089216544;611.6788685939828;621.1676353686082;634.2357459261578;4061.2283666103344;4215.831366745296;4219.177260434247;4288.699791014661;4332.545082766327;91298;116479.5168434845;117691.8230817698;118333;122685;67865;67885.98171027438;78335.38310785808;92805.60791588132;101346.4932675332;80213;81112;81250.18749812545;82206.58896705478;82483;9538.23694104456;9697.369670971364;9701.854472183064;9719.271054670786;9724.562394692115;51619;52479;53017.4698253028;54191.18713219384;55045.348639540876;54981.72509137429;64571.67714161399;67867;72343.91484127638;72479;12496.375181241083;12581.308028058545;12699.492020319083;12749.617511474671;12771.763576933785;13972.02195846281;14317.498887538886;14351.497697580535;14377.424903004181;14393.712125757636;12077.51689932393;12991.870081299066;13185.472581096648;13189.802152967904;13503.527376541773;12544.121911466109;12944.288064156563;12996.610101696964;13146.539871104465;13674.658133546742;12729.490820367082;12928.224306541637;13028.41372138275;13067.708411289452;13355.3990070449;10795.244332896622;11004.504797284302;11027.393493357942;11131.276467029616;11502.506905769147;10677.679669609925;11074.501647426046;11320.490577923849;11352.545898164311;11359.659210223706 14:11:04 INFO - PROCESS | 1649 | __end_tp_report 14:11:04 INFO - PROCESS | 1649 | __start_cc_report 14:11:04 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1809 14:11:04 INFO - PROCESS | 1649 | __end_cc_report 14:11:04 INFO - PROCESS | 1649 | __startTimestamp1477516264752__endTimestamp 14:11:04 INFO - PROCESS | 1649 | 14:11:04 INFO - PROCESS | 1649 | ------- Summary: start ------- 14:11:04 INFO - PROCESS | 1649 | Number of tests: 6 14:11:04 INFO - PROCESS | 1649 | 14:11:04 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5725.81 Median:5977.34 stddev:638.92 (10.7%) stddev-sans-first:612.67 14:11:04 INFO - PROCESS | 1649 | Values: 3332.6 3350.3 3360.2 3386.0 3403.4 5621.3 5706.0 5769.1 5785.4 5817.6 5652.0 5713.3 5721.1 5728.4 5738.0 5094.2 5138.6 5139.8 5222.8 5242.1 4567.9 5295.4 5326.5 5331.1 5389.9 5153.6 5298.1 5307.3 5309.1 5400.6 5183.0 5230.9 5236.4 5243.5 5246.2 5154.1 5184.2 5209.4 5210.6 5220.0 5716.4 5720.4 5721.7 5727.4 5732.2 6106.6 6146.5 6147.6 6154.3 6167.8 6100.5 6145.8 6168.9 6181.6 6197.0 4849.6 5734.5 5793.3 5807.1 5815.4 6168.2 6232.8 6261.7 6280.7 6296.7 6120.4 6154.4 6156.4 6166.2 6170.1 6125.5 6133.8 6143.4 6149.6 6168.1 6146.4 6193.8 6200.9 6205.4 6210.1 6201.2 6201.7 6206.6 6210.4 6213.9 4840.9 4876.3 4895.0 4917.7 4948.3 4338.8 4435.5 4453.9 4455.4 4487.1 5810.4 5816.0 5829.7 5831.2 5841.0 6133.2 6181.9 6188.9 6199.8 6199.9 5804.0 5808.5 5813.9 5820.9 5823.9 5321.1 5367.0 5395.4 5441.7 5449.3 6263.6 6304.9 6321.6 6330.7 6368.9 6236.0 6237.8 6245.3 6247.3 6256.8 6189.1 6238.4 6250.7 6264.5 6304.7 6152.7 6158.1 6182.7 6183.9 6184.6 6181.2 6191.6 6217.9 6218.9 6222.0 5066.9 6016.4 6064.0 6103.9 6104.6 4753.4 6105.1 6131.6 6144.1 6156.6 6006.0 6017.1 6017.7 6025.5 6037.1 5977.3 6041.5 6057.1 6079.6 6126.8 5944.7 5951.7 5953.1 5955.8 5967.2 14:11:04 INFO - PROCESS | 1649 | 14:11:04 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:65669.49 Median:38758.40 stddev:141082.90 (364.0%) stddev-sans-first:141393.83 14:11:04 INFO - PROCESS | 1649 | Values: 501.8 510.3 511.3 539.8 552.3 30890.9 40798.8 41772.0 41891.4 43440.8 42699.4 43091.6 43101.0 43149.8 43258.1 9485.7 9664.0 9666.7 9669.9 9679.5 9504.0 9614.2 9618.7 9620.6 9627.6 9485.4 9531.9 9541.5 9543.0 9543.6 9580.3 9617.5 9628.9 9632.6 9647.0 9439.4 9502.2 9516.0 9518.8 9532.3 40574.4 40670.2 40829.8 40882.4 40904.0 3340.3 3352.3 3387.5 3447.4 3818.8 30578.5 30963.4 30999.4 31133.2 31710.8 3078.6 3201.7 3209.0 3211.1 3243.6 445889.0 446441.0 447435.8 449425.0 451834.0 58131.4 58134.7 59638.7 59653.4 60022.0 34833.7 35098.5 35575.8 62749.1 63288.7 16063.4 16255.3 16405.8 16433.2 16463.7 12515.7 12582.6 12585.8 12617.9 12625.9 19443.2 19468.2 19469.9 19474.6 19475.1 7314.8 7687.5 7922.0 8079.8 8102.9 1014.1 1024.7 1067.2 1079.2 1121.4 23038.9 23752.6 28076.2 28739.4 30114.5 995.2 1024.2 1062.3 1113.0 1136.8 12688.9 14541.2 15281.0 15351.7 15779.8 621617.0 627049.0 627532.0 627637.0 628515.0 55475.9 56094.0 56166.4 56200.0 56255.9 1249.7 1292.7 1350.2 1375.7 1399.8 26116.9 29341.7 30873.7 30931.9 31404.2 48104.3 48680.0 48698.8 48825.0 48870.5 493662.0 494623.0 495334.0 495616.0 495748.0 22737.8 25585.7 25701.4 26306.5 26338.6 45711.9 45903.3 46057.5 46398.8 46777.6 35859.1 37507.4 38254.4 39008.8 39189.6 36739.0 36956.8 38453.0 38497.8 38508.0 36581.8 37070.6 37313.6 37548.8 37660.4 32281.4 34805.0 34950.8 35683.8 35729.1 20791.4 25027.6 25407.9 25603.5 25721.2 14:11:04 INFO - PROCESS | 1649 | 14:11:04 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:33979.60 Median:365.34 stddev:97307.71 (26634.6%) stddev-sans-first:97547.89 14:11:04 INFO - PROCESS | 1649 | Values: 331.9 360.0 360.9 369.8 386.4 19371.0 20082.8 20924.5 21013.6 21128.6 14623.8 19799.1 19994.0 20054.2 20454.9 5385.2 5574.5 5581.0 5581.2 5616.3 5918.4 6091.9 6093.7 6093.8 6101.3 5341.6 5420.8 6037.5 6058.1 6062.6 5402.2 5456.9 5459.2 5465.5 5468.5 5038.8 5270.2 5295.8 5297.5 5352.4 13295.1 13339.7 13425.2 13441.8 25343.7 2092.1 2121.2 2121.9 2182.9 2216.3 9845.2 9865.7 9936.9 10249.8 10301.6 1772.3 1821.1 1834.1 1860.6 1892.4 81274.6 106345.0 126286.0 126783.0 127167.0 11206.2 11369.2 11529.0 11535.5 11723.7 14745.0 14751.6 14770.5 14771.4 14784.9 8209.6 8481.9 8482.8 8584.1 8626.2 8334.7 8342.5 8368.7 8540.4 8568.0 4438.2 4514.1 4519.0 4577.3 4622.0 1798.2 1877.6 1942.8 1948.2 1948.5 701.1 748.3 792.3 918.0 961.8 19500.0 20679.3 21416.6 21453.0 21605.7 857.6 889.5 924.7 964.7 1006.6 10717.0 11475.6 11572.9 11594.4 11618.4 570028.0 575544.0 589165.0 590036.0 590296.0 68592.0 120140.0 122743.4 132396.3 138907.0 896.1 905.2 922.7 924.5 928.0 19406.9 19714.8 19777.8 19838.9 19875.0 54836.4 55193.4 56079.7 56305.7 56570.0 75226.6 80694.6 81489.6 81652.0 82632.6 6485.4 7914.8 7944.3 7965.0 8328.4 10510.6 12965.5 13125.4 13394.5 13406.3 8132.6 11120.8 11266.0 11422.5 11554.9 11133.1 11188.1 11224.9 11364.4 11627.0 11405.3 11528.4 11727.7 11807.6 11955.3 7375.6 7497.7 7545.1 7648.6 7701.5 9268.5 9709.0 10198.6 10996.0 11326.2 14:11:04 INFO - PROCESS | 1649 | 14:11:04 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5556.78 Median:5008.91 stddev:3102.30 (61.9%) stddev-sans-first:3096.31 14:11:04 INFO - PROCESS | 1649 | Values: 1538.1 1564.8 1794.9 1873.0 1949.4 8275.9 8690.2 8695.1 8722.7 8723.3 7683.7 7921.2 7939.2 8284.5 8511.2 2289.6 2319.5 2326.3 2327.4 2329.6 2332.5 2355.2 2368.3 2370.0 2375.9 2306.0 2319.7 2341.2 2342.4 2346.9 2304.2 2306.5 2330.8 2331.4 2333.8 2012.2 2327.3 2343.8 2348.6 2349.9 8549.9 8567.8 8649.0 8670.6 8681.6 5111.4 5251.4 5253.8 5263.5 5274.7 3282.4 3303.9 3531.3 3589.4 3591.5 1014.4 1044.1 1045.2 1049.6 1058.5 11197.0 11201.2 11374.9 11379.4 11391.5 4448.0 4599.4 4701.2 4709.0 4716.5 9667.4 9671.5 9679.1 9703.8 9711.5 5252.4 5258.2 5262.9 5275.2 5284.0 4258.0 4601.4 4823.5 4826.3 4836.0 3710.3 3744.1 3803.4 3849.4 3860.1 1222.2 1230.2 1230.2 1243.1 1245.4 789.5 866.2 901.0 931.9 959.6 5063.3 5663.4 5675.9 5861.0 6000.5 421.6 426.8 430.5 431.0 433.0 3811.7 3838.3 3941.7 3957.8 3992.5 10133.5 10384.6 10533.2 10553.6 10555.1 10495.7 10508.9 10510.3 10524.8 10544.5 7739.3 7953.5 8088.7 8137.0 8140.6 5665.5 5682.9 5696.9 5715.0 5793.4 9516.8 9742.8 9756.2 9762.5 9771.6 8127.5 9252.5 9350.0 9658.9 9682.0 6827.6 7044.3 7050.6 7218.8 7382.5 7971.2 8047.4 8186.1 8290.1 8303.6 7032.6 7072.5 7319.3 7374.3 7383.3 7087.7 7136.7 7225.1 7294.3 7382.4 6790.3 6934.1 6936.8 6947.4 6952.2 4821.3 4862.4 4914.2 4996.4 5021.4 4830.5 4903.4 5039.2 5135.6 5171.6 14:11:04 INFO - PROCESS | 1649 | 14:11:04 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4327.90 Median:4579.21 stddev:1350.24 (29.5%) stddev-sans-first:1319.58 14:11:04 INFO - PROCESS | 1649 | Values: 289.7 299.0 308.0 309.0 313.5 4807.6 5350.0 5366.0 5414.6 5422.5 4794.2 4860.0 5024.2 5083.3 5274.6 3870.6 4009.7 4024.7 4028.8 4046.0 4035.6 4044.2 4060.8 4063.9 4070.9 3825.5 3968.6 3969.4 3997.1 4003.8 3367.5 3967.8 3969.2 3972.8 4007.3 3423.8 3425.8 3430.9 3445.6 3448.5 4479.7 4870.3 5122.3 5139.3 5159.2 4463.9 4503.8 4510.1 4541.5 4555.3 5484.7 5511.3 5513.7 5520.2 5549.7 3328.0 3756.8 3761.5 3771.6 3814.2 4799.6 5231.8 5281.8 5430.7 5719.9 3209.9 3281.8 3659.4 3814.7 3819.9 3861.4 3862.2 3865.9 3876.1 3881.7 4321.4 4403.9 4422.4 4533.6 4537.4 5455.6 5563.3 5611.9 5621.9 5646.7 1239.6 1296.6 1325.0 1329.5 1329.8 742.0 797.6 804.5 811.5 816.7 3901.4 3989.3 4014.6 4301.8 4305.7 4037.6 4163.3 4164.3 4733.9 4867.7 3824.1 3898.7 4250.2 4271.5 4308.8 2272.2 2510.5 2511.9 2524.4 2553.3 4048.8 4461.2 4625.3 4917.3 4918.0 4453.7 4536.2 4803.1 4843.8 4875.7 3959.7 4054.9 4713.7 5663.3 6028.4 5347.6 5794.7 5804.7 5850.4 5854.0 5134.7 5502.0 5749.8 5755.8 5773.5 5354.8 5405.0 5536.0 5898.6 5980.2 3444.8 3725.9 3737.2 3744.3 3827.3 5741.3 5749.7 5774.6 5811.5 5833.7 4870.7 5299.9 5576.6 5610.5 5618.5 4452.2 4900.5 5464.8 5635.8 5661.6 5500.6 5531.4 5534.7 5560.3 5567.4 5156.9 5243.4 5538.6 5582.4 5587.4 4603.1 4973.1 5171.8 5488.8 5491.3 14:11:04 INFO - PROCESS | 1649 | 14:11:04 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:19886.76 Median:4385.70 stddev:28735.72 (655.2%) stddev-sans-first:28779.86 14:11:04 INFO - PROCESS | 1649 | Values: 605.8 732.0 758.3 788.5 799.3 14344.0 14389.6 14974.0 15010.5 15033.9 13497.0 13627.8 13654.2 13658.9 13659.3 699.0 717.3 718.2 870.6 876.6 880.6 886.5 891.6 894.3 897.5 944.1 945.6 945.7 946.2 947.8 942.0 942.3 942.6 943.9 944.9 544.5 561.4 678.2 694.8 700.5 12074.4 14385.4 14420.5 14441.8 14442.6 12840.4 12967.8 13123.7 13142.8 13146.3 10235.7 11916.5 11953.0 12006.0 12531.0 4337.8 4375.3 4421.7 4491.5 4571.5 84267.0 84514.6 84550.2 85773.7 85777.6 10347.8 10362.8 10387.8 10478.4 10498.7 19799.3 20023.2 20055.1 20093.2 20262.9 7513.7 7583.6 7658.1 7692.7 7898.8 3971.2 4169.4 4222.7 4269.6 4396.1 3009.3 3381.9 3485.5 3490.1 3498.8 636.8 641.7 649.6 654.3 662.1 598.8 624.9 629.3 630.6 631.6 8230.3 8677.7 8688.2 8713.7 8751.4 577.6 596.8 611.7 621.2 634.2 4061.2 4215.8 4219.2 4288.7 4332.5 91298.0 116479.5 117691.8 118333.0 122685.0 67865.0 67886.0 78335.4 92805.6 101346.5 80213.0 81112.0 81250.2 82206.6 82483.0 9538.2 9697.4 9701.9 9719.3 9724.6 51619.0 52479.0 53017.5 54191.2 55045.3 54981.7 64571.7 67867.0 72343.9 72479.0 12496.4 12581.3 12699.5 12749.6 12771.8 13972.0 14317.5 14351.5 14377.4 14393.7 12077.5 12991.9 13185.5 13189.8 13503.5 12544.1 12944.3 12996.6 13146.5 13674.7 12729.5 12928.2 13028.4 13067.7 13355.4 10795.2 11004.5 11027.4 11131.3 11502.5 10677.7 11074.5 11320.5 11352.5 11359.7 14:11:04 INFO - PROCESS | 1649 | -------- Summary: end -------- 14:11:04 INFO - PROCESS | 1649 | 14:11:05 INFO - TEST-INFO | 1649: exit 0 14:11:05 INFO - TEST-OK | dromaeo_css | took 1120027ms 14:11:05 INFO - TEST-START | kraken 14:11:05 INFO - operating with platform_type : mac_ 14:11:05 INFO - Initialising browser for kraken test... 14:11:05 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpUH7Vi_/profile http://localhost:49266/getInfo.html) 14:11:07 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 14:11:07 INFO - PROCESS | 1656 | colorDepth:24 14:11:07 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 14:11:07 INFO - PROCESS | 1656 | __metrics 14:11:07 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:11:07 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:11:08 INFO - TEST-INFO | 1656: exit 0 14:11:08 INFO - Browser initialized. 14:11:08 INFO - Running cycle 1/1 for kraken test... 14:11:08 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpUH7Vi_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:11:20 INFO - PROCESS | 1657 | RSS: Main: 230678528 14:11:20 INFO - PROCESS | 1657 | 14:11:23 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 14:11:24 INFO - PROCESS | 1657 | RSS: Main: 291160064 14:11:24 INFO - PROCESS | 1657 | 14:11:26 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 14:11:27 INFO - PROCESS | 1657 | RSS: Main: 292130816 14:11:27 INFO - PROCESS | 1657 | 14:11:31 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 14:11:31 INFO - PROCESS | 1657 | RSS: Main: 379531264 14:11:31 INFO - PROCESS | 1657 | 14:11:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 14:11:34 INFO - PROCESS | 1657 | RSS: Main: 285581312 14:11:34 INFO - PROCESS | 1657 | 14:11:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:11:37 INFO - PROCESS | 1657 | RSS: Main: 287965184 14:11:37 INFO - PROCESS | 1657 | 14:11:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 14:11:40 INFO - PROCESS | 1657 | RSS: Main: 326512640 14:11:40 INFO - PROCESS | 1657 | 14:11:43 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 14:11:44 INFO - PROCESS | 1657 | RSS: Main: 328196096 14:11:44 INFO - PROCESS | 1657 | 14:11:46 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 14:11:47 INFO - PROCESS | 1657 | RSS: Main: 324096000 14:11:47 INFO - PROCESS | 1657 | 14:11:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:11:49 INFO - PROCESS | 1657 | RSS: Main: 301658112 14:11:49 INFO - PROCESS | 1657 | 14:11:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:11:52 INFO - PROCESS | 1657 | RSS: Main: 301920256 14:11:52 INFO - PROCESS | 1657 | 14:11:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:11:54 INFO - PROCESS | 1657 | RSS: Main: 313815040 14:11:54 INFO - PROCESS | 1657 | 14:11:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:11:58 INFO - PROCESS | 1657 | RSS: Main: 324222976 14:11:58 INFO - PROCESS | 1657 | 14:12:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:12:02 INFO - PROCESS | 1657 | RSS: Main: 318087168 14:12:02 INFO - PROCESS | 1657 | 14:12:03 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 14:12:04 INFO - PROCESS | 1657 | RSS: Main: 362049536 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | __start_tp_report 14:12:04 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 14:12:04 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 14:12:04 INFO - PROCESS | 1657 | |i|pagename|runs| 14:12:04 INFO - PROCESS | 1657 | |0;ai-astar;118;167;146;114;113;114;113;113;115;114 14:12:04 INFO - PROCESS | 1657 | |1;audio-beat-detection;151;119;106;169;139;138;130;112;142;114 14:12:04 INFO - PROCESS | 1657 | |2;audio-dft;183;222;257;211;184;183;192;204;260;181 14:12:04 INFO - PROCESS | 1657 | |3;audio-fft;83;59;60;87;60;59;59;58;85;57 14:12:04 INFO - PROCESS | 1657 | |4;audio-oscillator;85;71;96;75;66;67;92;67;68;89 14:12:04 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;89;86;88;86;88;86;89;84;124;129 14:12:04 INFO - PROCESS | 1657 | |6;imaging-darkroom;100;94;96;95;93;95;96;94;93;94 14:12:04 INFO - PROCESS | 1657 | |7;imaging-desaturate;80;81;81;82;81;83;83;83;83;83 14:12:04 INFO - PROCESS | 1657 | |8;json-parse-financial;57;57;54;57;55;56;55;53;54;55 14:12:04 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;70;65;67;77;73;67;69;69;70;71 14:12:04 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;90;86;90;89;87;86;89;92;87;86 14:12:04 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;147;147;167;152;141;145;146;137;175;131 14:12:04 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;171;172;173;175;173;174;173;172;172;169 14:12:04 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;69;70;68;68;68;69;67;67;67;68 14:12:04 INFO - PROCESS | 1657 | __end_tp_report 14:12:04 INFO - PROCESS | 1657 | __start_cc_report 14:12:04 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2138 14:12:04 INFO - PROCESS | 1657 | __end_cc_report 14:12:04 INFO - PROCESS | 1657 | __startTimestamp1477516324068__endTimestamp 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | ------- Summary: start ------- 14:12:04 INFO - PROCESS | 1657 | Number of tests: 14 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:122.70 Median:114.50 stddev:18.55 (16.2%) stddev-sans-first:19.59 14:12:04 INFO - PROCESS | 1657 | Values: 118.0 167.0 146.0 114.0 113.0 114.0 113.0 113.0 115.0 114.0 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:132.00 Median:138.50 stddev:19.69 (14.2%) stddev-sans-first:19.64 14:12:04 INFO - PROCESS | 1657 | Values: 151.0 119.0 106.0 169.0 139.0 138.0 130.0 112.0 142.0 114.0 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:207.70 Median:207.50 stddev:30.07 (14.5%) stddev-sans-first:30.53 14:12:04 INFO - PROCESS | 1657 | Values: 183.0 222.0 257.0 211.0 184.0 183.0 192.0 204.0 260.0 181.0 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:66.70 Median:60.00 stddev:12.69 (21.2%) stddev-sans-first:12.02 14:12:04 INFO - PROCESS | 1657 | Values: 83.0 59.0 60.0 87.0 60.0 59.0 59.0 58.0 85.0 57.0 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:80.00 stddev:11.70 (14.6%) stddev-sans-first:12.10 14:12:04 INFO - PROCESS | 1657 | Values: 85.0 71.0 96.0 75.0 66.0 67.0 92.0 67.0 68.0 89.0 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:94.90 Median:87.00 stddev:16.77 (19.3%) stddev-sans-first:17.65 14:12:04 INFO - PROCESS | 1657 | Values: 89.0 86.0 88.0 86.0 88.0 86.0 89.0 84.0 124.0 129.0 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:94.50 stddev:2.05 (2.2%) stddev-sans-first:1.13 14:12:04 INFO - PROCESS | 1657 | Values: 100.0 94.0 96.0 95.0 93.0 95.0 96.0 94.0 93.0 94.0 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:82.00 Median:83.00 stddev:1.15 (1.4%) stddev-sans-first:0.97 14:12:04 INFO - PROCESS | 1657 | Values: 80.0 81.0 81.0 82.0 81.0 83.0 83.0 83.0 83.0 83.0 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.50 stddev:1.42 (2.6%) stddev-sans-first:1.36 14:12:04 INFO - PROCESS | 1657 | Values: 57.0 57.0 54.0 57.0 55.0 56.0 55.0 53.0 54.0 55.0 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:3.39 (4.8%) stddev-sans-first:3.60 14:12:04 INFO - PROCESS | 1657 | Values: 70.0 65.0 67.0 77.0 73.0 67.0 69.0 69.0 70.0 71.0 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:89.00 stddev:2.10 (2.4%) stddev-sans-first:2.12 14:12:04 INFO - PROCESS | 1657 | Values: 90.0 86.0 90.0 89.0 87.0 86.0 89.0 92.0 87.0 86.0 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:148.80 Median:147.00 stddev:13.22 (9.0%) stddev-sans-first:14.01 14:12:04 INFO - PROCESS | 1657 | Values: 147.0 147.0 167.0 152.0 141.0 145.0 146.0 137.0 175.0 131.0 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.40 Median:173.00 stddev:1.65 (1.0%) stddev-sans-first:1.67 14:12:04 INFO - PROCESS | 1657 | Values: 171.0 172.0 173.0 175.0 173.0 174.0 173.0 172.0 172.0 169.0 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:1.00 14:12:04 INFO - PROCESS | 1657 | Values: 69.0 70.0 68.0 68.0 68.0 69.0 67.0 67.0 67.0 68.0 14:12:04 INFO - PROCESS | 1657 | -------- Summary: end -------- 14:12:04 INFO - PROCESS | 1657 | 14:12:04 INFO - TEST-INFO | 1657: exit 0 14:12:04 INFO - TEST-OK | kraken | took 59441ms 14:12:04 INFO - SUITE-END | took 1179s 14:12:05 INFO - Completed test suite (00:19:39) 14:12:05 INFO - javascript benchmark 14:12:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3332.5667663203785, 3350.262942152727, 3360.1935535471493, 3386.0, 3403.4384326586114, 5621.269234999455, 5706.029974904266, 5769.076947688365, 5785.363610002902, 5817.592768506201, 5652.0, 5713.257276554042, 5721.141828725683, 5728.398518155585, 5738.0, 5094.235864620318, 5138.563222126124, 5139.794408223666, 5222.817201397952, 5242.056429842627, 4567.931481027787, 5295.417504074549, 5326.520613144816, 5331.120365139747, 5389.865253368677, 5153.6392452528335, 5298.09932311508, 5307.310049693554, 5309.123994540934, 5400.621956463049, 5182.974085129588, 5230.947690523084, 5236.3978142513715, 5243.475652434726, 5246.213068039787, 5154.149565321717, 5184.248283998799, 5209.374875014984, 5210.557102646279, 5219.973900130513, 5716.371199168088, 5720.427957204288, 5721.742521586539, 5727.398623144593, 5732.197492351074, 6106.642687876637, 6146.508279337642, 6147.569670123094, 6154.26148862135, 6167.845803854894, 6100.479656792151, 6145.770845830852, 6168.87662246757, 6181.629102253879, 6197.0, 4849.587785038311, 5734.512566431817, 5793.304803423532, 5807.09989951554, 5815.41845815415, 6168.228971378578, 6232.8130156095385, 6261.7182226799905, 6280.654563998933, 6296.653684047421, 6120.357362476965, 6154.3845615438995, 6156.384361563808, 6166.229221347332, 6170.10533472644, 6125.540584456184, 6133.785317513865, 6143.419763699257, 6149.569530132848, 6168.136460895478, 6146.354632763496, 6193.84515387119, 6200.937990620127, 6205.410486003822, 6210.130581718563, 6201.162843016225, 6201.6899155042065, 6206.627602343874, 6210.441060304594, 6213.875722485525, 4840.878978025577, 4876.341693871353, 4894.975525122351, 4917.655764096479, 4948.282499037619, 4338.82644694215, 4435.467743870691, 4453.933191002137, 4455.443069616298, 4487.102579484116, 5810.389909059572, 5816.040353341736, 5829.679367634821, 5831.241938547962, 5840.970795145997, 6133.1510485532735, 6181.907271390933, 6188.8762224755255, 6199.783007594712, 6199.938000619936, 5803.970980145073, 5808.535317174616, 5813.912791308134, 5820.912686309709, 5823.88352232953, 5321.122014867581, 5367.007103685831, 5395.406505284415, 5441.673499589958, 5449.291592093082, 6263.561550691408, 6304.905426418607, 6321.557490975588, 6330.683465826598, 6368.87262254743, 6236.002239641635, 6237.750489980532, 6245.281792593728, 6247.344028877084, 6256.843578910563, 6189.071639253968, 6238.407351301619, 6250.718717657625, 6264.498840092872, 6304.6847657617855, 6152.723127459365, 6158.076288556573, 6182.721777519932, 6183.938160618516, 6184.567080304336, 6181.227346581677, 6191.597546159485, 6217.875642487215, 6218.9378106218355, 6222.0355844844125, 5066.873328166826, 6016.368281330398, 6064.02975523914, 6103.908441373472, 6104.610739188311, 4753.405824271966, 6105.114758359924, 6131.570790044654, 6144.109104179958, 6156.5998210116195, 6006.039033754579, 6017.097435384728, 6017.669028203491, 6025.457708806229, 6037.0642550404755, 5977.342492325927, 6041.546883983807, 6057.121717351048, 6079.574429790049, 6126.816195514141, 5944.673042982505, 5951.732172052355, 5953.077273022688, 5955.791547295824, 5967.194428752063], "value": 5685.757626327689, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.75915560530933, 510.3263691926657, 511.3327108123897, 539.8353502181833, 552.2930648769576, 30890.91881784138, 40798.79600601996, 41772.0, 41891.37162942554, 43440.78279608605, 42699.359509607384, 43091.56908430915, 43101.0, 43149.7842510787, 43258.13483730324, 9485.668001619944, 9663.95168024159, 9666.709998700015, 9669.854952175723, 9679.516024198761, 9504.002079781627, 9614.182794462478, 9618.711438656852, 9620.56707448163, 9627.614895404175, 9485.430874147572, 9531.904680953172, 9541.522923853814, 9542.952285238563, 9543.618255269781, 9580.329376943617, 9617.471039092847, 9628.855567166498, 9632.56653450593, 9647.0, 9439.433633981984, 9502.239820814319, 9516.048395160498, 9518.809623807485, 9532.332736708433, 40574.39138412926, 40670.186596268206, 40829.79585102056, 40882.38676419856, 40903.97740056492, 3340.3486320167576, 3352.329534093167, 3387.5257463955068, 3447.4311738563356, 3818.8281527331337, 30578.54132188019, 30963.380732385227, 30999.38001239985, 31133.221669458217, 31710.841445792856, 3078.553609726577, 3201.743860491155, 3208.951865722016, 3211.084840820355, 3243.5728279557156, 445889.0, 446441.0, 447435.7628211871, 449425.0, 451834.0, 58131.41868581345, 58134.70932645394, 59638.70180649156, 59653.40346596565, 60022.0, 34833.65166348304, 35098.47352289667, 35575.82212088923, 62749.05876411858, 63288.68355658192, 16063.437779677655, 16255.349786008665, 16405.83594164043, 16433.178341082898, 16463.670726585402, 12515.74968500643, 12582.622521324374, 12585.811212831815, 12617.93691031539, 12625.87374126247, 19443.22227110928, 19468.22127114928, 19469.902650486656, 19474.610507790047, 19475.104979003972, 7314.793059145181, 7687.500312479671, 7921.989643935268, 8079.798005049921, 8102.918970810217, 1014.1430491234895, 1024.7489365105596, 1067.1836045425257, 1079.1852151625471, 1121.4112590889686, 23038.884805575864, 23752.64371034436, 28076.157715268575, 28739.425211495654, 30114.54828177579, 995.1938929467154, 1024.1806554756165, 1062.2935747727674, 1113.0483436661539, 1136.7669627726295, 12688.936555317165, 14541.200233987232, 15281.006734562217, 15351.692966140838, 15779.763303550455, 621617.0, 627049.0, 627532.0, 627637.0, 628515.0, 55475.89048219094, 56094.0, 56166.43833561612, 56200.0, 56255.87488250212, 1249.6688377579949, 1292.7091404434116, 1350.2371160294401, 1375.6698392385756, 1399.839018512884, 26116.8694156528, 29341.706582933897, 30873.691263087083, 30931.906809318887, 31404.214894627818, 48104.27843582279, 48680.0, 48698.75650621724, 48825.0, 48870.51129488659, 493662.0, 494623.0, 495334.0, 495616.0, 495748.0, 22737.772622273566, 25585.744142558335, 25701.3574660635, 26306.4738705221, 26338.60492092582, 45711.85720357018, 45903.3114503276, 46057.539424605326, 46398.84002899955, 46777.59667209989, 35859.10352241215, 37507.43738843974, 38254.42618360782, 39008.804955975036, 39189.60810391974, 36739.0, 36956.81521592483, 38453.0, 38497.80751096339, 38508.0, 36581.817090914374, 37070.59041162316, 37313.62686373101, 37548.812255938545, 37660.43509347417, 32281.3856473033, 34805.0, 34950.82524587361, 35683.82158089193, 35729.1067723309, 20791.388418220115, 25027.624585631595, 25407.87296063508, 25603.487930241663, 25721.241570309554], "value": 19562.212783837276, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.86559443425415, 360.0198906016905, 360.8610684886318, 369.8243334416153, 386.3991493228033, 19371.031448427577, 20082.799172008275, 20924.476888077807, 21013.579728405442, 21128.577428451423, 14623.78064329036, 19799.109040093193, 19994.0, 20054.1978320867, 20454.897725511353, 5385.192221166827, 5574.526165275936, 5581.0, 5581.246531718227, 5616.297962754656, 5918.437748413915, 6091.908621370683, 6093.664848433335, 6093.78671746489, 6101.267847858263, 5341.599380046493, 5420.783168673248, 6037.456628903398, 6058.091286307067, 6062.575619706622, 5402.216678581623, 5456.945430545723, 5459.235707001023, 5465.453454654523, 5468.48049435303, 5038.7984480620735, 5270.209468579706, 5295.841124766263, 5297.523222909918, 5352.437994010651, 13295.108939522284, 13339.666508337272, 13425.194488330731, 13441.798373024412, 25343.746562534325, 2092.0794850265834, 2121.16214095433, 2121.8514703970727, 2182.9126834926583, 2216.2797090945505, 9845.212383009342, 9865.654702085392, 9936.90063099367, 10249.795004099951, 10301.587936482532, 1772.273367919147, 1821.134960893565, 1834.092124398418, 1860.5906700525863, 1892.4198294424173, 81274.59362703208, 106345.0, 126286.0, 126783.0, 127167.0, 11206.159538034517, 11369.204155709187, 11529.0, 11535.480903359368, 11723.706907327385, 14745.0, 14751.557453276204, 14770.483033094006, 14771.40914363435, 14784.852151478563, 8209.58952052395, 8481.915180848237, 8482.787930301793, 8584.141585841366, 8626.18051285127, 8334.708285209988, 8342.499450033018, 8368.665253389918, 8540.44487108336, 8568.0, 4438.245498265303, 4514.074614703978, 4518.954810451919, 4577.3362862384765, 4621.976890115528, 1798.1998010885031, 1877.6150889067708, 1942.8348590369976, 1948.1720268885667, 1948.4509814697349, 701.1200942816732, 748.296601194875, 792.338397438134, 917.977050573741, 961.8076384723083, 19500.024998750006, 20679.27622533234, 21416.571668566852, 21453.0, 21605.67591486129, 857.6226460357423, 889.4885440871525, 924.6671198368582, 964.6527250189926, 1006.642641862143, 10716.981886720749, 11475.598354057567, 11572.884271157349, 11594.420278986017, 11618.419079046183, 570028.0, 575544.0, 589165.0, 590036.0, 590296.0, 68592.01163659264, 120140.0, 122743.38628306802, 132396.3380183093, 138907.0, 896.050186801992, 905.1943770044666, 922.6540047482194, 924.4761800285124, 927.958241879117, 19406.932618706105, 19714.802851971872, 19777.802221977596, 19838.90080549588, 19875.0, 54836.35490935278, 55193.44806551883, 56079.71960140336, 56305.71847140738, 56570.0, 75226.62386688251, 80694.59652701698, 81489.59255203686, 81652.0, 82632.58683706544, 6485.3514648534765, 7914.841703165789, 7944.285014348505, 7965.044194696674, 8328.417010809186, 10510.579576817148, 12965.481380745043, 13125.409356578786, 13394.531191408254, 13406.32968351598, 8132.593370331341, 11120.777584448428, 11265.986061254527, 11422.45796817419, 11554.884451155382, 11133.109351251718, 11188.10495160369, 11224.887751122384, 11364.374959377314, 11627.0, 11405.258658187191, 11528.423578821194, 11727.706807329885, 11807.586734464596, 11955.282683039046, 7375.631218438949, 7497.737579184703, 7545.132309784445, 7648.617569121633, 7701.4608977371045, 9268.490233037248, 9709.0, 10198.643047493302, 10996.0, 11326.150538709466], "value": 9261.594597642861, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1538.0771537077756, 1564.8043994500688, 1794.9192286347113, 1872.9719054214181, 1949.4054313434397, 8275.917240827597, 8690.174433428814, 8695.087015863339, 8722.738317850475, 8723.345749068822, 7683.731069412558, 7921.247481489264, 7939.1663875293125, 8284.502929824215, 8511.233988940994, 2289.5878741826423, 2319.524497478021, 2326.302109367183, 2327.359976006603, 2329.557384097016, 2332.4518738096535, 2355.1756885090167, 2368.3250273672006, 2370.0401337458356, 2375.869327187004, 2305.965410518844, 2319.663648770927, 2341.203990643181, 2342.4378149244144, 2346.9178578749747, 2304.2050492580033, 2306.5156317173414, 2330.8368414211013, 2331.3821837213154, 2333.8482998605036, 2012.175008246612, 2327.290176496168, 2343.824213184001, 2348.635961425982, 2349.917752878658, 8549.914500854975, 8567.82864342716, 8648.956755216246, 8670.609822558, 8681.609327580274, 5111.361079865017, 5251.396089449724, 5253.789848406097, 5263.526282634543, 5274.657147285453, 3282.376348493802, 3303.9174020649434, 3531.346700860349, 3589.4436362363876, 3591.515145455356, 1014.3964341216964, 1044.1281529922448, 1045.2474218562622, 1049.5749221565202, 1058.4601853054921, 11197.048250898684, 11201.215914885961, 11374.886251137548, 11379.431028448627, 11391.487383067783, 4447.955520444754, 4599.402077729874, 4701.2242979908615, 4708.952910470852, 4716.481187069419, 9667.419954802734, 9671.468069256258, 9679.08048735369, 9703.805923881624, 9711.46586937711, 5252.44849290819, 5258.158694608845, 5262.894742105136, 5275.235090911873, 5284.04887120322, 4257.9561810331215, 4601.355810186509, 4823.541763532458, 4826.251930950708, 4836.0, 3710.348894741814, 3744.1388480649057, 3803.3914573668635, 3849.3840985442193, 3860.0542866997203, 1222.1689251309017, 1230.1757822259108, 1230.2434003088035, 1243.1422318600137, 1245.3586403002462, 789.5065584009993, 866.2463656618656, 900.963961441535, 931.8960806715983, 959.64972784933, 5063.316452278968, 5663.377028526858, 5675.858103547362, 5860.974329492322, 6000.519958403317, 421.62475396896787, 426.78020819278095, 430.53502217605535, 431.019430794944, 433.0343334364385, 3811.7331786774657, 3838.328292548849, 3941.7043721720984, 3957.821898014539, 3992.500937382827, 10133.543990520444, 10384.636537721144, 10533.157347412189, 10553.577856885639, 10555.050045495942, 10495.737606559896, 10508.894911050791, 10510.264281500222, 10524.789504210026, 10544.525496352682, 7739.303462688273, 7953.483023603448, 8088.676452941934, 8137.023557173177, 8140.59297035134, 5665.546756259407, 5682.943170568242, 5696.886062278814, 5715.0, 5793.449622285876, 9516.809663806824, 9742.805143896941, 9756.170725488413, 9762.463064531235, 9771.560279787285, 8127.512349259064, 9252.49111298885, 9350.0, 9658.90341096608, 9682.0, 6827.590344579341, 7044.330788575077, 7050.612216327946, 7218.783436496913, 7382.483226174117, 7971.242731940688, 8047.396445266511, 8186.140455252113, 8290.088090310182, 8303.584820758817, 7032.613206273704, 7072.469564782765, 7319.34125928669, 7374.33630973215, 7383.2985866342615, 7087.7164913402885, 7136.714531418893, 7225.1329840419485, 7294.307040831112, 7382.446316526175, 6790.320967903368, 6934.133233345832, 6936.7572134975035, 6947.44420446353, 6952.235254121942, 4821.300911367902, 4862.367892173995, 4914.238293064581, 4996.400431948189, 5021.422536408214, 4830.541098595628, 4903.436104847846, 5039.168537191469, 5135.563477104488, 5171.612129090408], "value": 4405.605303587901, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.7146310883779, 298.9641243050834, 308.0112837790692, 308.96601373848875, 313.5077927653582, 4807.6153907687385, 5349.960779611393, 5365.973170134149, 5414.593905457092, 5422.508810077231, 4794.2329227323635, 4859.979404325096, 5024.246363045545, 5083.339165908426, 5274.5780337573, 3870.612938706128, 4009.739366941154, 4024.678025757932, 4028.811500607313, 4045.9595404045876, 4035.57626449223, 4044.1911617676583, 4060.776657283848, 4063.878083657495, 4070.9185816283657, 3825.5218097737784, 3968.5634580196156, 3969.384745364473, 3997.1206334606486, 4003.837927519136, 3367.4948757686298, 3967.789824103633, 3969.233691007503, 3972.82122304497, 4007.258657148422, 3423.828808559587, 3425.794452332868, 3430.9313813723834, 3445.552078229814, 3448.4654878493875, 4479.70088674284, 4870.269459581055, 5122.257272695476, 5139.280500729901, 5159.2003239497935, 4463.933041004388, 4503.819847206108, 4510.052888893337, 4541.477730061019, 4555.339475775994, 5484.698341591211, 5511.255980442629, 5513.719336994482, 5520.2059330717675, 5549.722513874331, 3328.0, 3756.8067142928567, 3761.492198553215, 3771.585125636178, 3814.237152569497, 4799.592034677022, 5231.8168864089575, 5281.788728450857, 5430.728463576845, 5719.914201286984, 3209.935801283961, 3281.7702760806515, 3659.432787917877, 3814.6948244140403, 3819.904502387407, 3861.4207868819904, 3862.227554489057, 3865.903352416156, 3876.127871228951, 3881.6608270146753, 4321.395004699345, 4403.899025243689, 4422.425084738963, 4533.602405666498, 4537.4328208973875, 5455.645383050089, 5563.276774019351, 5611.859703507445, 5621.8594535136945, 5646.741303478641, 1239.5599562155485, 1296.5656505070717, 1324.973500529984, 1329.4682127149217, 1329.8005299205004, 741.9649588823669, 797.5613412623034, 804.4730701390598, 811.5130921447085, 816.6529225079317, 3901.3809269152985, 3989.3018721723583, 4014.558398576154, 4301.827926882952, 4305.720128191658, 4037.5962403759977, 4163.292240319153, 4164.250434921682, 4733.881652958704, 4867.712175277614, 3824.1395685970433, 3898.707596930185, 4250.193667691219, 4271.487421509439, 4308.763018033976, 2272.204958083129, 2510.5230006298743, 2511.9073203156686, 2524.3941454050896, 2553.327570441364, 4048.8380464781667, 4461.166889558692, 4625.306204069417, 4917.311576379309, 4917.975410122926, 4453.724424552466, 4536.228841097021, 4803.1114243864295, 4843.782029808667, 4875.7074575525585, 3959.742616729903, 4054.8986275343354, 4713.690291757014, 5663.320401551922, 6028.4272994065495, 5347.598930080182, 5794.739236734273, 5804.709764511673, 5850.414958504286, 5853.970730146322, 5134.717590532557, 5502.0, 5749.770009199585, 5755.762511060142, 5773.53811695055, 5354.839354819362, 5404.972975135256, 5535.972320138535, 5898.587098903035, 5980.19267398913, 3444.844981975867, 3725.8882233533036, 3737.215184811111, 3744.344739670601, 3827.3302172120325, 5741.311042674905, 5749.655020698771, 5774.624649397776, 5811.476967072815, 5833.666533386509, 4870.659053866196, 5299.91579573044, 5576.609637325349, 5610.5231055360755, 5618.494335509824, 4452.154090722753, 4900.534449227318, 5464.80873169453, 5635.774569017193, 5661.556303142837, 5500.642458240201, 5531.391546929754, 5534.667919924817, 5560.332760068818, 5567.359753628384, 5156.871078223075, 5243.449437808975, 5538.6399884007415, 5582.441755824385, 5587.435518054951, 4603.079384123122, 4973.129702302155, 5171.793128274978, 5488.807891723771, 5491.313585801775], "value": 3899.3341787028344, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.7637521366668, 731.9853602927939, 758.2947858491603, 788.5268838696779, 799.2992444979744, 14344.0, 14389.568312950601, 14974.0, 15010.549683509485, 15033.924830375847, 13497.0, 13627.795583066238, 13654.180749155057, 13658.863411365883, 13659.317034148278, 698.9999999999975, 717.347214035228, 718.2063819479479, 870.5777697816554, 876.5748611923212, 880.5773228850156, 886.5079880666227, 891.5765011619461, 894.2532984957568, 897.4884315939917, 944.1031020530489, 945.5603144537788, 945.7210123013728, 946.1720994130136, 947.7535840681403, 942.048530983708, 942.3121121581285, 942.5522876633613, 943.8678584998106, 944.8771659684265, 544.4936209325326, 561.357245953381, 678.2132726037772, 694.8262934266434, 700.4886432904009, 12074.396280185956, 14385.352659130363, 14420.49528266516, 14441.78337324941, 14442.566722998326, 12840.357982100857, 12967.805482917764, 13123.737525249537, 13142.802857957138, 13146.342682865818, 10235.692929212135, 11916.46375913086, 11953.0, 12006.0, 12530.997520198362, 4337.804798784062, 4375.299952007664, 4421.6904816662845, 4491.461024677027, 4571.542845715435, 84267.0, 84514.57742711308, 84550.15449845546, 85773.71339429913, 85777.57111214467, 10347.793044139074, 10362.844557331646, 10387.84418233727, 10478.37129772216, 10498.71912031281, 19799.30702425437, 20023.195592837314, 20055.09752061161, 20093.196272149242, 20262.89868550648, 7513.71760878324, 7583.62081895903, 7658.081030276291, 7692.73075442368, 7898.842023159505, 3971.1660551284554, 4169.437125987953, 4222.683298752606, 4269.637080848101, 4396.098799746044, 3009.277773334384, 3381.917786308407, 3485.5120283160377, 3490.127468132967, 3498.755087143926, 636.7707625254905, 641.7433026789322, 649.5810202419508, 654.2933631677777, 662.1491383572087, 598.8173607049891, 624.9312575616678, 629.3266205160526, 630.6121735132951, 631.5673763472037, 8230.259276665129, 8677.6528938843, 8688.218060374596, 8713.695020674246, 8751.387402881737, 577.5956830218863, 596.7762089216544, 611.6788685939828, 621.1676353686082, 634.2357459261578, 4061.2283666103344, 4215.831366745296, 4219.177260434247, 4288.699791014661, 4332.545082766327, 91298.0, 116479.5168434845, 117691.8230817698, 118333.0, 122685.0, 67865.0, 67885.98171027438, 78335.38310785808, 92805.60791588132, 101346.4932675332, 80213.0, 81112.0, 81250.18749812545, 82206.58896705478, 82483.0, 9538.23694104456, 9697.369670971364, 9701.854472183064, 9719.271054670786, 9724.562394692115, 51619.0, 52479.0, 53017.4698253028, 54191.18713219384, 55045.348639540876, 54981.72509137429, 64571.67714161399, 67867.0, 72343.91484127638, 72479.0, 12496.375181241083, 12581.308028058545, 12699.492020319083, 12749.617511474671, 12771.763576933785, 13972.02195846281, 14317.498887538886, 14351.497697580535, 14377.424903004181, 14393.712125757636, 12077.51689932393, 12991.870081299066, 13185.472581096648, 13189.802152967904, 13503.527376541773, 12544.121911466109, 12944.288064156563, 12996.610101696964, 13146.539871104465, 13674.658133546742, 12729.490820367082, 12928.224306541637, 13028.41372138275, 13067.708411289452, 13355.3990070449, 10795.244332896622, 11004.504797284302, 11027.393493357942, 11131.276467029616, 11502.506905769147, 10677.679669609925, 11074.501647426046, 11320.490577923849, 11352.545898164311, 11359.659210223706], "value": 7303.69147743867, "unit": "score"}], "name": "dromaeo_css", "value": 7110.7131947649505, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 167.0, 146.0, 114.0, 113.0, 114.0, 113.0, 113.0, 115.0, 114.0], "value": 122.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 119.0, 106.0, 169.0, 139.0, 138.0, 130.0, 112.0, 142.0, 114.0], "value": 132.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 222.0, 257.0, 211.0, 184.0, 183.0, 192.0, 204.0, 260.0, 181.0], "value": 207.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 60.0, 87.0, 60.0, 59.0, 59.0, 58.0, 85.0, 57.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 96.0, 75.0, 66.0, 67.0, 92.0, 67.0, 68.0, 89.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 86.0, 88.0, 86.0, 89.0, 84.0, 124.0, 129.0], "value": 94.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 94.0, 96.0, 95.0, 93.0, 95.0, 96.0, 94.0, 93.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 81.0, 82.0, 81.0, 83.0, 83.0, 83.0, 83.0, 83.0], "value": 82.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 54.0, 57.0, 55.0, 56.0, 55.0, 53.0, 54.0, 55.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 65.0, 67.0, 77.0, 73.0, 67.0, 69.0, 69.0, 70.0, 71.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 89.0, 87.0, 86.0, 89.0, 92.0, 87.0, 86.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 167.0, 152.0, 141.0, 145.0, 146.0, 137.0, 175.0, 131.0], "value": 148.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 173.0, 175.0, 173.0, 174.0, 173.0, 172.0, 172.0, 169.0], "value": 172.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 68.0, 68.0, 68.0, 69.0, 67.0, 67.0, 67.0, 68.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1481.1999999999998, "alertThreshold": 2.0}]} 14:12:05 INFO - Return code: 0 14:12:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:12:05 INFO - # TBPL SUCCESS # 14:12:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:12:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:12:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:12:05 INFO - 2016-10-26 14:12:05.391 screenresolution[1661:10338] starting screenresolution argv=screenresolution get 14:12:05 INFO - 2016-10-26 14:12:05.394 screenresolution[1661:10338] Display 0: 1600x1200x32@60 14:12:05 INFO - 2016-10-26 14:12:05.399 screenresolution[1662:10341] starting screenresolution argv=screenresolution list 14:12:05 INFO - Available Modes on Display 0 14:12:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:12:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:12:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:12:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:12:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:12:05 INFO - Intel Iris: 14:12:05 INFO - Chipset Model: Intel Iris 14:12:05 INFO - Type: GPU 14:12:05 INFO - Bus: Built-In 14:12:05 INFO - VRAM (Dynamic, Max): 1536 MB 14:12:05 INFO - Vendor: Intel (0x8086) 14:12:05 INFO - Device ID: 0x0a2e 14:12:05 INFO - Revision ID: 0x0009 14:12:05 INFO - Displays: 14:12:05 INFO - Display: 14:12:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:12:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:12:05 INFO - Display Serial Number: 1600x1200 60 14:12:05 INFO - Main Display: Yes 14:12:05 INFO - Mirror: Off 14:12:05 INFO - Online: Yes 14:12:05 INFO - Rotation: Supported 14:12:05 INFO - Return code: 0 14:12:05 INFO - Running post-action listener: _resource_record_post_action 14:12:05 INFO - [mozharness: 2016-10-26 21:12:05.639171Z] Finished run-tests step (success) 14:12:05 INFO - Running post-run listener: _resource_record_post_run 14:12:05 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 33430528; Write bytes: 708189184; Read time: 315; Write time: 2022 14:12:05 INFO - TinderboxPrint: CPU usage
53.6% 14:12:05 INFO - TinderboxPrint: I/O read bytes / time
33,430,528 / 315 14:12:05 INFO - TinderboxPrint: I/O write bytes / time
708,189,184 / 2,022 14:12:05 INFO - TinderboxPrint: CPU idle
2,255.9 (46.4%) 14:12:05 INFO - TinderboxPrint: CPU system
973.8 (20.0%) 14:12:05 INFO - TinderboxPrint: CPU user
1,632.0 (33.6%) 14:12:05 INFO - TinderboxPrint: Swap in / out
435,630,080 / 0 14:12:05 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 391182848; Write bytes: 385033728; Read time: 29311; Write time: 724 14:12:05 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 32484352; Write bytes: 299312640; Read time: 300; Write time: 1206 14:12:06 INFO - Running post-run listener: _upload_blobber_files 14:12:06 INFO - Blob upload gear active. 14:12:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:12:06 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 14:12:06 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:12:06 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:12:06 INFO - (blobuploader) - INFO - Open directory for files ... 14:12:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:12:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:12:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:12:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:12:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:12:07 INFO - (blobuploader) - INFO - Done attempting. 14:12:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:12:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:12:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:12:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:12:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:12:09 INFO - (blobuploader) - INFO - Done attempting. 14:12:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:12:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:12:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:12:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:12:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:12:11 INFO - (blobuploader) - INFO - Done attempting. 14:12:11 INFO - (blobuploader) - INFO - Iteration through files over. 14:12:12 INFO - Return code: 0 14:12:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:12:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:12:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f05f27a422f10619436ce6c3c5d2e72a5ecf4c4fdbbc1abbb0fb56ca3d9fc044519db29446ebeba9e672e22677e392c948b1c2623af10f816037e9cfd6e2eec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18e4b9fb1dee28d4f3704ab184b0fb43dd567db6c9cdd17c00b3f12eb51d70cb04ad89f24c331b6036c15409d3457081c640fb14f48ddfa4da9ba863fcc53dcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20a45c7e62ee1f14c5b2e7a7b4035e7cdc39063ba38e4ca13126a7e92190b14d4838adf6b96756bae0571dea1c4a58ac88bd61d86fd9458c0021765676d87468"} 14:12:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:12:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:12:12 INFO - Contents: 14:12:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f05f27a422f10619436ce6c3c5d2e72a5ecf4c4fdbbc1abbb0fb56ca3d9fc044519db29446ebeba9e672e22677e392c948b1c2623af10f816037e9cfd6e2eec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18e4b9fb1dee28d4f3704ab184b0fb43dd567db6c9cdd17c00b3f12eb51d70cb04ad89f24c331b6036c15409d3457081c640fb14f48ddfa4da9ba863fcc53dcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20a45c7e62ee1f14c5b2e7a7b4035e7cdc39063ba38e4ca13126a7e92190b14d4838adf6b96756bae0571dea1c4a58ac88bd61d86fd9458c0021765676d87468"} 14:12:12 INFO - Running post-run listener: copy_logs_to_upload_dir 14:12:12 INFO - Copying logs to upload dir... 14:12:12 INFO - mkdir: /builds/slave/test/build/upload/logs 14:12:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.309224 ========= master_lag: 3.69 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-10-26 14:12:15.721690) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-10-26 14:12:15.729436) ========= 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.YpGAeUzLNB/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.9vPMwS83Cb/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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/Firefox/sha512/7f05f27a422f10619436ce6c3c5d2e72a5ecf4c4fdbbc1abbb0fb56ca3d9fc044519db29446ebeba9e672e22677e392c948b1c2623af10f816037e9cfd6e2eec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18e4b9fb1dee28d4f3704ab184b0fb43dd567db6c9cdd17c00b3f12eb51d70cb04ad89f24c331b6036c15409d3457081c640fb14f48ddfa4da9ba863fcc53dcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20a45c7e62ee1f14c5b2e7a7b4035e7cdc39063ba38e4ca13126a7e92190b14d4838adf6b96756bae0571dea1c4a58ac88bd61d86fd9458c0021765676d87468"} build_url:https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011038 build_url: 'https://queue.taskcluster.net/v1/task/YxxHO84PSQat-ijixqtTCw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f05f27a422f10619436ce6c3c5d2e72a5ecf4c4fdbbc1abbb0fb56ca3d9fc044519db29446ebeba9e672e22677e392c948b1c2623af10f816037e9cfd6e2eec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18e4b9fb1dee28d4f3704ab184b0fb43dd567db6c9cdd17c00b3f12eb51d70cb04ad89f24c331b6036c15409d3457081c640fb14f48ddfa4da9ba863fcc53dcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20a45c7e62ee1f14c5b2e7a7b4035e7cdc39063ba38e4ca13126a7e92190b14d4838adf6b96756bae0571dea1c4a58ac88bd61d86fd9458c0021765676d87468"}' ========= master_lag: 4.82 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-10-26 14:12:20.561027) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 14:12:20.561347) ========= 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.YpGAeUzLNB/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.9vPMwS83Cb/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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.005154 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 14:12:20.601291) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2016-10-26 14:12:20.601592) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2016-10-26 14:12:24.570307) ========= ========= Total master_lag: 8.62 =========