builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0224 starttime: 1484365825.5 results: success (0) buildid: 20170113173724 builduid: 0fbb9f0f519d417ba66667ca455cab17 revision: be9be2c13b4dc9038b331a91f923a4658014bae4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 19:50:25.500684) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 19:50:25.501069) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 19:50:25.501341) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 19:50:25.527433) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 19:50:25.527719) ========= 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.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-13 19:50:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2017-01-13 19:50:26 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.699975 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 19:50:26.251927) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 19:50:26.252281) ========= 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.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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.026093 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 19:50:26.302670) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 19:50:26.303012) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev be9be2c13b4dc9038b331a91f923a4658014bae4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev be9be2c13b4dc9038b331a91f923a4658014bae4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-13 19:50:26,405 truncating revision to first 12 chars 2017-01-13 19:50:26,405 Setting DEBUG logging. 2017-01-13 19:50:26,405 attempt 1/10 2017-01-13 19:50:26,405 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/be9be2c13b4d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-13 19:50:27,635 unpacking tar archive at: mozilla-aurora-be9be2c13b4d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.501929 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 19:50:27.829591) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 19:50:27.829955) ========= script_repo_revision: be9be2c13b4dc9038b331a91f923a4658014bae4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 19:50:27.830346) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 19:50:27.830631) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 19:50:27.850358) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-01-13 19:50:27.850692) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 19:50:28 INFO - MultiFileLogger online at 20170113 19:50:28 in /builds/slave/test 19:50:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 19:50:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:50:28 INFO - {'append_to_log': False, 19:50:28 INFO - 'base_work_dir': '/builds/slave/test', 19:50:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:50:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:50:28 INFO - 'branch': 'Mozilla-Aurora', 19:50:28 INFO - 'buildbot_json_path': 'buildprops.json', 19:50:28 INFO - 'config_files': ('talos/mac_config.py',), 19:50:28 INFO - 'default_actions': ('clobber', 19:50:28 INFO - 'read-buildbot-config', 19:50:28 INFO - 'download-and-extract', 19:50:28 INFO - 'populate-webroot', 19:50:28 INFO - 'create-virtualenv', 19:50:28 INFO - 'install', 19:50:28 INFO - 'run-tests'), 19:50:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:50:28 INFO - 'download_minidump_stackwalk': True, 19:50:28 INFO - 'download_symbols': 'ondemand', 19:50:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:50:28 INFO - 'tooltool.py': '/tools/tooltool.py', 19:50:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:50:28 INFO - '/tools/misc-python/virtualenv.py')}, 19:50:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:50:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:50:28 INFO - 'installer_path': 'installer.exe', 19:50:28 INFO - 'log_level': 'info', 19:50:28 INFO - 'log_name': 'talos', 19:50:28 INFO - 'log_to_console': True, 19:50:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:50:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:50:28 INFO - 'opt_config_files': (), 19:50:28 INFO - 'pip_index': False, 19:50:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:50:28 INFO - 'cmd': ('bash', 19:50:28 INFO - '-c', 19:50:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:50:28 INFO - 'enabled': True, 19:50:28 INFO - 'halt_on_failure': False, 19:50:28 INFO - 'name': 'check_screen_resolution'},), 19:50:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:50:28 INFO - 'cmd': ('bash', 19:50:28 INFO - '-c', 19:50:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:50:28 INFO - 'enabled': True, 19:50:28 INFO - 'halt_on_failure': False, 19:50:28 INFO - 'name': 'check_screen_resolution'},), 19:50:28 INFO - 'run_cmd_checks_enabled': True, 19:50:28 INFO - 'sps_profile': False, 19:50:28 INFO - 'sps_profile_interval': 0, 19:50:28 INFO - 'suite': 'dromaeojs', 19:50:28 INFO - 'system_bits': '32', 19:50:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:50:28 INFO - 'title': 't-yosemite-r7-0224', 19:50:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:50:28 INFO - 'use_talos_json': True, 19:50:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:50:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:50:28 INFO - 'work_dir': 'build'} 19:50:28 INFO - [mozharness: 2017-01-14 03:50:28.060292Z] Running clobber step. 19:50:28 INFO - Running pre-action listener: _resource_record_pre_action 19:50:28 INFO - Running main action method: clobber 19:50:28 INFO - rmtree: /builds/slave/test/build 19:50:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:50:30 INFO - Running post-action listener: _resource_record_post_action 19:50:30 INFO - [mozharness: 2017-01-14 03:50:30.063551Z] Finished clobber step (success) 19:50:30 INFO - [mozharness: 2017-01-14 03:50:30.063679Z] Running read-buildbot-config step. 19:50:30 INFO - Running pre-action listener: _resource_record_pre_action 19:50:30 INFO - Running main action method: read_buildbot_config 19:50:30 INFO - Using buildbot properties: 19:50:30 INFO - { 19:50:30 INFO - "project": "", 19:50:30 INFO - "product": "firefox", 19:50:30 INFO - "script_repo_revision": "production", 19:50:30 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 19:50:30 INFO - "repository": "", 19:50:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 19:50:30 INFO - "buildid": "20170113173724", 19:50:30 INFO - "pgo_build": "False", 19:50:30 INFO - "basedir": "/builds/slave/test", 19:50:30 INFO - "buildnumber": 87, 19:50:30 INFO - "slavename": "t-yosemite-r7-0224", 19:50:30 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 19:50:30 INFO - "platform": "yosemite_r7", 19:50:30 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 19:50:30 INFO - "branch": "mozilla-aurora", 19:50:30 INFO - "repo_path": "releases/mozilla-aurora", 19:50:30 INFO - "revision": "be9be2c13b4dc9038b331a91f923a4658014bae4", 19:50:30 INFO - "stage_platform": "macosx64", 19:50:30 INFO - "builduid": "0fbb9f0f519d417ba66667ca455cab17", 19:50:30 INFO - "slavebuilddir": "test" 19:50:30 INFO - } 19:50:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 19:50:30 INFO - Running post-action listener: _resource_record_post_action 19:50:30 INFO - [mozharness: 2017-01-14 03:50:30.066985Z] Finished read-buildbot-config step (success) 19:50:30 INFO - [mozharness: 2017-01-14 03:50:30.067101Z] Running download-and-extract step. 19:50:30 INFO - Running pre-action listener: _resource_record_pre_action 19:50:30 INFO - Running main action method: download_and_extract 19:50:30 INFO - mkdir: /builds/slave/test/build/tests 19:50: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')]} 19:50:30 INFO - trying https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 19:50:30 INFO - Downloading https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 19:50:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 19:50:31 INFO - Downloaded 1199 bytes. 19:50:31 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 19:50:31 INFO - Using the following test package requirements: 19:50:31 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 19:50:31 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:50:31 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 19:50:31 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:50:31 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 19:50:31 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:50:31 INFO - u'jsshell-mac.zip'], 19:50:31 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:50:31 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 19:50:31 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 19:50:31 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:50:31 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 19:50:31 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:50:31 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 19:50:31 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:50:31 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 19:50:31 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:50:31 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 19:50:31 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 19:50:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 19:50:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:50:31 INFO - Fetch https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 19:50:32 INFO - Http code: 200 19:50:32 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 19:50:32 INFO - x-amz-cf-id: jH6CN0nJg2uX-e_Nx7X6n8X2qmXN4rYMkpmbglE5HikRgwwmH0D-aA== 19:50:32 INFO - x-amz-version-id: 3Pty3y7tmp_IXsSkhM9EkrZJr4gyGzeJ 19:50:32 INFO - Expected file size: 16275780 19:50:32 INFO - Obtained file size: 16275780 19:50:39 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 19:50:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 19:50:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:50:39 INFO - Fetch https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 19:50:39 INFO - Http code: 200 19:50:39 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 19:50:39 INFO - x-amz-cf-id: 2Q631W-dZS_Ipbj56lW5BwW6x_5cFswauXsadzUHvjbT8j8GbvWCRA== 19:50:39 INFO - x-amz-version-id: 3Pty3y7tmp_IXsSkhM9EkrZJr4gyGzeJ 19:50:40 INFO - Expected file size: 16275780 19:50:40 INFO - Obtained file size: 16275780 19:50:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 19:50:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 19:50:46 INFO - Fetch https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 19:50:47 INFO - Http code: 200 19:50:47 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 19:50:47 INFO - x-amz-cf-id: eCUNDTkvByFfZo8mzg4QOD2s3VJvkDAOmsFNqyReWvFM8yh1_tN6LQ== 19:50:47 INFO - x-amz-version-id: B9ywSXi5kDLG34Gl7bZ21uCFWKsTv4JG 19:50:47 INFO - Expected file size: 14409038 19:50:47 INFO - Obtained file size: 14409038 19:50:48 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')]} 19:50:48 INFO - trying https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:50:48 INFO - Downloading https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 19:50:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 19:50:54 INFO - Downloaded 109997377 bytes. 19:50:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:50:54 INFO - mkdir: /builds/slave/test/properties 19:50:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:50:54 INFO - Writing to file /builds/slave/test/properties/build_url 19:50:54 INFO - Contents: 19:50:54 INFO - build_url:https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:50:55 INFO - Running post-action listener: _resource_record_post_action 19:50:55 INFO - Running post-action listener: set_extra_try_arguments 19:50:55 INFO - [mozharness: 2017-01-14 03:50:55.163676Z] Finished download-and-extract step (success) 19:50:55 INFO - [mozharness: 2017-01-14 03:50:55.163831Z] Running populate-webroot step. 19:50:55 INFO - Running pre-action listener: _resource_record_pre_action 19:50:55 INFO - Running main action method: populate_webroot 19:50:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:50:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:50:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:50:55 INFO - '--tppagecycles', 19:50:55 INFO - '7'], 19:50:55 INFO - 'tests': ['tsvgm']}}, 19:50:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:50:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:50:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:50:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:50:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:50:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:50:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:50:55 INFO - 'talos_options': ['--disable-e10s'], 19:50:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:50:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:50:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:50:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:50:55 INFO - 'talos_options': ['--disable-e10s'], 19:50:55 INFO - 'tests': ['damp', 'tps']}, 19:50:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:50:55 INFO - 'tests': ['damp', 'tps']}, 19:50:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:50:55 INFO - 'tests': ['dromaeo_dom']}, 19:50:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:50:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:50:55 INFO - 'tests': ['basic_compositor_video']}, 19:50:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:50:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:50:55 INFO - 'tests': ['a11yr', 19:50:55 INFO - 'ts_paint', 19:50:55 INFO - 'tpaint', 19:50:55 INFO - 'sessionrestore', 19:50:55 INFO - 'sessionrestore_no_auto_restore', 19:50:55 INFO - 'tabpaint']}, 19:50:55 INFO - 'other-e10s': {'tests': ['a11yr', 19:50:55 INFO - 'ts_paint', 19:50:55 INFO - 'tpaint', 19:50:55 INFO - 'sessionrestore', 19:50:55 INFO - 'sessionrestore_no_auto_restore', 19:50:55 INFO - 'tabpaint']}, 19:50:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:50:55 INFO - 'ts_paint', 19:50:55 INFO - 'tpaint', 19:50:55 INFO - 'sessionrestore', 19:50:55 INFO - 'sessionrestore_no_auto_restore', 19:50:55 INFO - 'tabpaint']}, 19:50:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:50:55 INFO - 'ts_paint', 19:50:55 INFO - 'tpaint', 19:50:55 INFO - 'sessionrestore', 19:50:55 INFO - 'sessionrestore_no_auto_restore', 19:50:55 INFO - 'tabpaint']}, 19:50:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:50:55 INFO - 'tests': ['a11yr', 19:50:55 INFO - 'ts_paint', 19:50:55 INFO - 'tpaint', 19:50:55 INFO - 'sessionrestore', 19:50:55 INFO - 'sessionrestore_no_auto_restore', 19:50:55 INFO - 'tabpaint']}, 19:50:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:50:55 INFO - 'tests': ['a11yr', 19:50:55 INFO - 'ts_paint', 19:50:55 INFO - 'tpaint', 19:50:55 INFO - 'sessionrestore', 19:50:55 INFO - 'sessionrestore_no_auto_restore', 19:50:55 INFO - 'tabpaint']}, 19:50:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:50:55 INFO - 'tests': ['tsvgx', 19:50:55 INFO - 'tsvgr_opacity', 19:50:55 INFO - 'tart', 19:50:55 INFO - 'tscrollx', 19:50:55 INFO - 'cart']}, 19:50:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:50:55 INFO - 'tsvgr_opacity', 19:50:55 INFO - 'tart', 19:50:55 INFO - 'tscrollx', 19:50:55 INFO - 'cart']}, 19:50:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:50:55 INFO - 'talos_options': ['--disable-e10s'], 19:50:55 INFO - 'tests': ['tp5o']}, 19:50:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:50:55 INFO - 'tests': ['tp5o']}, 19:50:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:50:55 INFO - 'talos_options': ['--disable-e10s', 19:50:55 INFO - '--xperf_path', 19:50:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:50:55 INFO - 'tests': ['tp5n']}, 19:50:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:50:55 INFO - 'talos_options': ['--xperf_path', 19:50:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:50:55 INFO - 'tests': ['tp5n']}}, 19:50:55 INFO - 'talos.zip': {'path': '', 19:50:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:50:55 INFO - Running post-action listener: _resource_record_post_action 19:50:55 INFO - [mozharness: 2017-01-14 03:50:55.182353Z] Finished populate-webroot step (success) 19:50:55 INFO - [mozharness: 2017-01-14 03:50:55.182461Z] Running create-virtualenv step. 19:50:55 INFO - Running pre-action listener: _resource_record_pre_action 19:50:55 INFO - Running main action method: create_virtualenv 19:50:55 INFO - Creating virtualenv /builds/slave/test/build/venv 19:50:55 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 19:50:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:50:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:50:55 INFO - Using real prefix '/tools/python27' 19:50:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:50:56 INFO - Installing distribute.............................................................................................................................................................................................done. 19:50:58 INFO - Installing pip.................done. 19:50:58 INFO - Return code: 0 19:50:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:50:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:50:59 INFO - Reading from file tmpfile_stdout 19:50:59 INFO - Output received: 19:50:59 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) 19:50:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:50:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:50:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:50:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10348f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103405570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe03acd6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a3d30>, '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 0x103109db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10329bb20>, '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.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 19:50:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:50:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:50:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8gJtQy7GeB/Render', 19:50:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:50:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:50:59 INFO - 'HOME': '/Users/cltbld', 19:50:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:50:59 INFO - 'LOGNAME': 'cltbld', 19:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:50:59 INFO - 'MOZ_NO_REMOTE': '1', 19:50:59 INFO - 'NO_EM_RESTART': '1', 19:50:59 INFO - 'PAGER': '/bin/cat', 19:50:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:50:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:50:59 INFO - 'PWD': '/builds/slave/test', 19:50:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:50:59 INFO - 'SHELL': '/bin/bash', 19:50:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmLjJlGPOQ/Listeners', 19:50:59 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 19:50:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:50:59 INFO - 'USER': 'cltbld', 19:50:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:50:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:50:59 INFO - 'XPC_FLAGS': '0x0', 19:50:59 INFO - 'XPC_SERVICE_NAME': '0', 19:50:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:50:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:50:59 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 19:50:59 INFO - Cleaning up... 19:50:59 INFO - Return code: 0 19:50:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:50:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:50:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:50:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10348f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103405570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe03acd6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a3d30>, '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 0x103109db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10329bb20>, '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.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 19:50:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:50:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:50:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8gJtQy7GeB/Render', 19:50:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:50:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:50:59 INFO - 'HOME': '/Users/cltbld', 19:50:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:50:59 INFO - 'LOGNAME': 'cltbld', 19:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:50:59 INFO - 'MOZ_NO_REMOTE': '1', 19:50:59 INFO - 'NO_EM_RESTART': '1', 19:50:59 INFO - 'PAGER': '/bin/cat', 19:50:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:50:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:50:59 INFO - 'PWD': '/builds/slave/test', 19:50:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:50:59 INFO - 'SHELL': '/bin/bash', 19:50:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmLjJlGPOQ/Listeners', 19:50:59 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 19:50:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:50:59 INFO - 'USER': 'cltbld', 19:50:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:50:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:50:59 INFO - 'XPC_FLAGS': '0x0', 19:50:59 INFO - 'XPC_SERVICE_NAME': '0', 19:50:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:50:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:50:59 INFO - Downloading/unpacking psutil>=3.1.1 19:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:50:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:50:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:50:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:50:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:51:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:51:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:51:03 INFO - Installing collected packages: psutil 19:51:03 INFO - Running setup.py install for psutil 19:51:03 INFO - building 'psutil._psutil_osx' extension 19:51:03 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 19:51:04 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 19:51:04 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 19:51:04 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 19:51:04 INFO - building 'psutil._psutil_posix' extension 19:51:04 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 19:51:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:51:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:51:04 INFO - ^ 19:51:04 INFO - 1 warning generated. 19:51:04 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 19:51:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:51:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:51:04 INFO - Successfully installed psutil 19:51:04 INFO - Cleaning up... 19:51:04 INFO - Return code: 0 19:51:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:04 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 0x10348f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103405570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe03acd6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a3d30>, '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 0x103109db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10329bb20>, '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.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 19:51:04 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 19:51:04 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 19:51:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8gJtQy7GeB/Render', 19:51:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:04 INFO - 'HOME': '/Users/cltbld', 19:51:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:04 INFO - 'LOGNAME': 'cltbld', 19:51:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:04 INFO - 'MOZ_NO_REMOTE': '1', 19:51:04 INFO - 'NO_EM_RESTART': '1', 19:51:04 INFO - 'PAGER': '/bin/cat', 19:51:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:04 INFO - 'PWD': '/builds/slave/test', 19:51:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:04 INFO - 'SHELL': '/bin/bash', 19:51:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmLjJlGPOQ/Listeners', 19:51:04 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 19:51:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:04 INFO - 'USER': 'cltbld', 19:51:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:04 INFO - 'XPC_FLAGS': '0x0', 19:51:04 INFO - 'XPC_SERVICE_NAME': '0', 19:51:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:51:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:51:07 INFO - Installing collected packages: mozsystemmonitor 19:51:07 INFO - Running setup.py install for mozsystemmonitor 19:51:07 INFO - Successfully installed mozsystemmonitor 19:51:07 INFO - Cleaning up... 19:51:08 INFO - Return code: 0 19:51:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:08 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 0x10348f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103405570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe03acd6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a3d30>, '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 0x103109db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10329bb20>, '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.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 19:51:08 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 19:51:08 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 19:51:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8gJtQy7GeB/Render', 19:51:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:08 INFO - 'HOME': '/Users/cltbld', 19:51:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:08 INFO - 'LOGNAME': 'cltbld', 19:51:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:08 INFO - 'MOZ_NO_REMOTE': '1', 19:51:08 INFO - 'NO_EM_RESTART': '1', 19:51:08 INFO - 'PAGER': '/bin/cat', 19:51:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:08 INFO - 'PWD': '/builds/slave/test', 19:51:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:08 INFO - 'SHELL': '/bin/bash', 19:51:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmLjJlGPOQ/Listeners', 19:51:08 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 19:51:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:08 INFO - 'USER': 'cltbld', 19:51:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:08 INFO - 'XPC_FLAGS': '0x0', 19:51:08 INFO - 'XPC_SERVICE_NAME': '0', 19:51:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:08 INFO - Downloading/unpacking jsonschema==2.5.1 19:51:08 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 19:51:08 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 19:51:08 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 19:51:08 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 19:51:08 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 19:51:08 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 19:51:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:51:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:51:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:11 INFO - Downloading functools32-3.2.3-2.tar.gz 19:51:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:51:11 INFO - warning: no files found matching '*.txt' 19:51:11 INFO - no previously-included directories found matching 'build' 19:51:11 INFO - no previously-included directories found matching 'dist' 19:51:11 INFO - no previously-included directories found matching '.git*' 19:51:11 INFO - Installing collected packages: jsonschema, functools32 19:51:11 INFO - Running setup.py install for functools32 19:51:11 INFO - warning: no files found matching '*.txt' 19:51:11 INFO - no previously-included directories found matching 'build' 19:51:11 INFO - no previously-included directories found matching 'dist' 19:51:11 INFO - no previously-included directories found matching '.git*' 19:51:11 INFO - Successfully installed jsonschema functools32 19:51:11 INFO - Cleaning up... 19:51:11 INFO - Return code: 0 19:51:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:51:11 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')]} 19:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:11 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')]} 19:51:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:11 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 0x10348f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103405570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe03acd6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a3d30>, '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 0x103109db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10329bb20>, '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.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 19:51:11 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 19:51:11 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 19:51:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8gJtQy7GeB/Render', 19:51:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:11 INFO - 'HOME': '/Users/cltbld', 19:51:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:11 INFO - 'LOGNAME': 'cltbld', 19:51:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:11 INFO - 'MOZ_NO_REMOTE': '1', 19:51:11 INFO - 'NO_EM_RESTART': '1', 19:51:11 INFO - 'PAGER': '/bin/cat', 19:51:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:11 INFO - 'PWD': '/builds/slave/test', 19:51:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:11 INFO - 'SHELL': '/bin/bash', 19:51:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmLjJlGPOQ/Listeners', 19:51:11 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 19:51:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:11 INFO - 'USER': 'cltbld', 19:51:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:11 INFO - 'XPC_FLAGS': '0x0', 19:51:11 INFO - 'XPC_SERVICE_NAME': '0', 19:51:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:51:11 INFO - Cleaning up... 19:51:11 INFO - Return code: 0 19:51:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:51:11 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')]} 19:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:11 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')]} 19:51:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:11 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 0x10348f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103405570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe03acd6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a3d30>, '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 0x103109db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10329bb20>, '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.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 19:51:11 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 19:51:11 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 19:51:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8gJtQy7GeB/Render', 19:51:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:11 INFO - 'HOME': '/Users/cltbld', 19:51:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:11 INFO - 'LOGNAME': 'cltbld', 19:51:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:11 INFO - 'MOZ_NO_REMOTE': '1', 19:51:11 INFO - 'NO_EM_RESTART': '1', 19:51:11 INFO - 'PAGER': '/bin/cat', 19:51:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:11 INFO - 'PWD': '/builds/slave/test', 19:51:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:11 INFO - 'SHELL': '/bin/bash', 19:51:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmLjJlGPOQ/Listeners', 19:51:11 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 19:51:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:11 INFO - 'USER': 'cltbld', 19:51:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:11 INFO - 'XPC_FLAGS': '0x0', 19:51:11 INFO - 'XPC_SERVICE_NAME': '0', 19:51:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:12 INFO - Downloading/unpacking blobuploader==1.2.4 19:51:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:14 INFO - Downloading blobuploader-1.2.4.tar.gz 19:51:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:51:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:51:14 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 19:51:14 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 19:51:14 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 19:51:14 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 19:51:14 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 19:51:14 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 19:51:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:51:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:51:15 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 19:51:15 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 19:51:15 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 19:51:15 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 19:51:15 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 19:51:15 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 19:51:16 INFO - Downloading docopt-0.6.1.tar.gz 19:51:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:51:16 INFO - Installing collected packages: blobuploader, requests, docopt 19:51:16 INFO - Running setup.py install for blobuploader 19:51:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:51:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:51:16 INFO - Running setup.py install for requests 19:51:16 INFO - Running setup.py install for docopt 19:51:16 INFO - Successfully installed blobuploader requests docopt 19:51:16 INFO - Cleaning up... 19:51:17 INFO - Return code: 0 19:51:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:17 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 0x10348f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103405570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe03acd6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a3d30>, '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 0x103109db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10329bb20>, '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.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 19:51:17 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 19:51:17 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 19:51:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8gJtQy7GeB/Render', 19:51:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:17 INFO - 'HOME': '/Users/cltbld', 19:51:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:17 INFO - 'LOGNAME': 'cltbld', 19:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:17 INFO - 'MOZ_NO_REMOTE': '1', 19:51:17 INFO - 'NO_EM_RESTART': '1', 19:51:17 INFO - 'PAGER': '/bin/cat', 19:51:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:17 INFO - 'PWD': '/builds/slave/test', 19:51:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:17 INFO - 'SHELL': '/bin/bash', 19:51:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmLjJlGPOQ/Listeners', 19:51:17 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 19:51:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:17 INFO - 'USER': 'cltbld', 19:51:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:17 INFO - 'XPC_FLAGS': '0x0', 19:51:17 INFO - 'XPC_SERVICE_NAME': '0', 19:51:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:51:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-jIICCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:51:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-WiQdRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:51:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-7QGhAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:51:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-jpC4NR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:51:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-JGU7SD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:51:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-6YDEXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:51:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:51:17 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-ZDwsKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:51:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-e5iog_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:51:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-c60G74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:51:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-U0Ci1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:51:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-bufKzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:51:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-DdUA_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:51:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-gKagYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:51:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-f_EMPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:51:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-g6p2cK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:51:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-HkyE1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:51:18 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-7JjmWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:51:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:51:19 INFO - Running setup.py install for manifestparser 19:51:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:51:19 INFO - Running setup.py install for mozcrash 19:51:19 INFO - Running setup.py install for mozdebug 19:51:19 INFO - Running setup.py install for mozdevice 19:51:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:51:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:51:19 INFO - Running setup.py install for mozfile 19:51:19 INFO - Running setup.py install for mozhttpd 19:51:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:51:19 INFO - Running setup.py install for mozinfo 19:51:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:51:20 INFO - Running setup.py install for mozInstall 19:51:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:51:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:51:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:51:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:51:20 INFO - Running setup.py install for mozleak 19:51:20 INFO - Running setup.py install for mozlog 19:51:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:51:20 INFO - Running setup.py install for moznetwork 19:51:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:51:20 INFO - Running setup.py install for mozprocess 19:51:20 INFO - Running setup.py install for mozprofile 19:51:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:51:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:51:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:51:20 INFO - Running setup.py install for mozrunner 19:51:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:51:21 INFO - Running setup.py install for mozscreenshot 19:51:21 INFO - Running setup.py install for moztest 19:51:21 INFO - Running setup.py install for mozversion 19:51:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:51:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:51:21 INFO - Cleaning up... 19:51:21 INFO - Return code: 0 19:51:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:21 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 0x10348f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103405570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe03acd6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a3d30>, '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 0x103109db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10329bb20>, '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.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 19:51:21 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 19:51:21 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 19:51:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8gJtQy7GeB/Render', 19:51:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:21 INFO - 'HOME': '/Users/cltbld', 19:51:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:21 INFO - 'LOGNAME': 'cltbld', 19:51:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:21 INFO - 'MOZ_NO_REMOTE': '1', 19:51:21 INFO - 'NO_EM_RESTART': '1', 19:51:21 INFO - 'PAGER': '/bin/cat', 19:51:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:21 INFO - 'PWD': '/builds/slave/test', 19:51:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:21 INFO - 'SHELL': '/bin/bash', 19:51:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmLjJlGPOQ/Listeners', 19:51:21 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 19:51:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:21 INFO - 'USER': 'cltbld', 19:51:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:21 INFO - 'XPC_FLAGS': '0x0', 19:51:21 INFO - 'XPC_SERVICE_NAME': '0', 19:51:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:51:21 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-v1LPHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:51:22 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)) 19:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:51:22 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-ApQ918-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:51:22 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-LVOeMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:51:22 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)) 19:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:51:22 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-6QfIg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:51:22 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)) 19:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:51:22 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-leVqb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:51:22 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)) 19:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:51:22 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-uFsyyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:51:22 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)) 19:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:51:22 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-F1aHLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:51:22 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)) 19:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:51:22 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-IZNcvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:51:22 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)) 19:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:51:22 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-pMTkgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:51:22 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)) 19:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:51:22 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-k3DY17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:51:22 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)) 19:51:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:51:22 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-ZqGChB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:51:23 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)) 19:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:51:23 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-jhBxJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:51:23 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)) 19:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:51:23 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-4RI_uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:51:23 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)) 19:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:51:23 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-9hg1ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:51:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:51:23 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-7ZnVXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:51:23 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)) 19:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:51:23 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-rXERcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:51:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:51:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:51:23 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-CKrKZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:51:23 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)) 19:51:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:51:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:51:23 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)) 19:51:23 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)) 19:51:23 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)) 19:51:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:51:23 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 19:51:23 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 19:51:23 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 19:51:23 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 19:51:23 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 19:51:23 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 19:51:26 INFO - Downloading blessings-1.6.tar.gz 19:51:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:51:26 INFO - Installing collected packages: blessings 19:51:26 INFO - Running setup.py install for blessings 19:51:26 INFO - Successfully installed blessings 19:51:26 INFO - Cleaning up... 19:51:26 INFO - Return code: 0 19:51:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:51:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:51:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:51:26 INFO - Reading from file tmpfile_stdout 19:51:26 INFO - Current package versions: 19:51:26 INFO - blessings == 1.6 19:51:26 INFO - blobuploader == 1.2.4 19:51:26 INFO - docopt == 0.6.1 19:51:26 INFO - functools32 == 3.2.3-2 19:51:26 INFO - jsonschema == 2.5.1 19:51:26 INFO - manifestparser == 1.1 19:51:26 INFO - mozInstall == 1.12 19:51:26 INFO - mozcrash == 1.0 19:51:26 INFO - mozdebug == 0.1 19:51:26 INFO - mozdevice == 0.48 19:51:26 INFO - mozfile == 1.2 19:51:26 INFO - mozhttpd == 0.7 19:51:26 INFO - mozinfo == 0.9 19:51:26 INFO - mozleak == 0.1 19:51:26 INFO - mozlog == 3.4 19:51:26 INFO - moznetwork == 0.27 19:51:26 INFO - mozprocess == 0.23 19:51:26 INFO - mozprofile == 0.28 19:51:26 INFO - mozrunner == 6.13 19:51:26 INFO - mozscreenshot == 0.1 19:51:26 INFO - mozsystemmonitor == 0.3 19:51:26 INFO - moztest == 0.8 19:51:26 INFO - mozversion == 1.4 19:51:26 INFO - psutil == 3.1.1 19:51:26 INFO - requests == 1.2.3 19:51:26 INFO - wsgiref == 0.1.2 19:51:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:51:26 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')]} 19:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:26 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')]} 19:51:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:26 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 0x10348f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103405570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe03acd6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a3d30>, '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 0x103109db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10329bb20>, '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.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 19:51:26 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 19:51:26 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 19:51:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8gJtQy7GeB/Render', 19:51:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:26 INFO - 'HOME': '/Users/cltbld', 19:51:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:26 INFO - 'LOGNAME': 'cltbld', 19:51:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:26 INFO - 'MOZ_NO_REMOTE': '1', 19:51:26 INFO - 'NO_EM_RESTART': '1', 19:51:26 INFO - 'PAGER': '/bin/cat', 19:51:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:26 INFO - 'PWD': '/builds/slave/test', 19:51:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:26 INFO - 'SHELL': '/bin/bash', 19:51:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmLjJlGPOQ/Listeners', 19:51:26 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 19:51:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:26 INFO - 'USER': 'cltbld', 19:51:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:26 INFO - 'XPC_FLAGS': '0x0', 19:51:26 INFO - 'XPC_SERVICE_NAME': '0', 19:51:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:27 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)) 19:51:27 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)) 19:51:27 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)) 19:51:27 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)) 19:51:27 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)) 19:51:27 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)) 19:51:27 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)) 19:51:27 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)) 19:51:27 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)) 19:51:27 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)) 19:51:27 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)) 19:51:27 INFO - Cleaning up... 19:51:27 INFO - Return code: 0 19:51:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19: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')]} 19:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19: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')]} 19:51:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10348f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103405570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe03acd6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a3d30>, '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 0x103109db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10329bb20>, '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.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 19: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', 'jsonschema'] in /builds/slave/test/build 19: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 jsonschema 19:51:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8gJtQy7GeB/Render', 19:51:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:27 INFO - 'HOME': '/Users/cltbld', 19:51:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:27 INFO - 'LOGNAME': 'cltbld', 19:51:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:27 INFO - 'MOZ_NO_REMOTE': '1', 19:51:27 INFO - 'NO_EM_RESTART': '1', 19:51:27 INFO - 'PAGER': '/bin/cat', 19:51:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:27 INFO - 'PWD': '/builds/slave/test', 19:51:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:27 INFO - 'SHELL': '/bin/bash', 19:51:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmLjJlGPOQ/Listeners', 19:51:27 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 19:51:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:27 INFO - 'USER': 'cltbld', 19:51:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:27 INFO - 'XPC_FLAGS': '0x0', 19:51:27 INFO - 'XPC_SERVICE_NAME': '0', 19:51:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:51:27 INFO - Cleaning up... 19:51:27 INFO - Return code: 0 19:51:27 INFO - Running post-action listener: _resource_record_post_action 19:51:27 INFO - Running post-action listener: _start_resource_monitoring 19:51:27 INFO - Starting resource monitoring. 19:51:27 INFO - [mozharness: 2017-01-14 03:51:27.498026Z] Finished create-virtualenv step (success) 19:51:27 INFO - [mozharness: 2017-01-14 03:51:27.499000Z] Running install step. 19:51:27 INFO - Running pre-action listener: _resource_record_pre_action 19:51:27 INFO - Running main action method: install 19:51:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:51:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:51:27 INFO - Reading from file tmpfile_stdout 19:51:27 INFO - Detecting whether we're running mozinstall >=1.0... 19:51:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:51:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:51:27 INFO - Reading from file tmpfile_stdout 19:51:27 INFO - Output received: 19:51:27 INFO - Usage: mozinstall [options] installer 19:51:27 INFO - Options: 19:51:27 INFO - -h, --help show this help message and exit 19:51:27 INFO - -d DEST, --destination=DEST 19:51:27 INFO - Directory to install application into. [default: 19:51:27 INFO - "/builds/slave/test"] 19:51:27 INFO - --app=APP Application being installed. [default: firefox] 19:51:27 INFO - mkdir: /builds/slave/test/build/application 19:51:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:51:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 19:51:58 INFO - Reading from file tmpfile_stdout 19:51:58 INFO - Output received: 19:51:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 19:51:58 INFO - Running post-action listener: _resource_record_post_action 19:51:58 INFO - [mozharness: 2017-01-14 03:51:58.714669Z] Finished install step (success) 19:51:58 INFO - [mozharness: 2017-01-14 03:51:58.714774Z] Running run-tests step. 19:51:58 INFO - Running pre-action listener: _resource_record_pre_action 19:51:58 INFO - Running main action method: run_tests 19:51:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:51:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:51:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:51:58 INFO - 2017-01-13 19:51:58.729 screenresolution[1637:8031] starting screenresolution argv=screenresolution get 19:51:58 INFO - 2017-01-13 19:51:58.731 screenresolution[1637:8031] Display 0: 1600x1200x32@60 19:51:58 INFO - 2017-01-13 19:51:58.737 screenresolution[1638:8033] starting screenresolution argv=screenresolution list 19:51:58 INFO - Available Modes on Display 0 19:51:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:51:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:51:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:51:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:51:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:51:58 INFO - Intel Iris: 19:51:58 INFO - Chipset Model: Intel Iris 19:51:58 INFO - Type: GPU 19:51:58 INFO - Bus: Built-In 19:51:58 INFO - VRAM (Dynamic, Max): 1536 MB 19:51:58 INFO - Vendor: Intel (0x8086) 19:51:58 INFO - Device ID: 0x0a2e 19:51:58 INFO - Revision ID: 0x0009 19:51:58 INFO - Displays: 19:51:58 INFO - Display: 19:51:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:51:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:51:58 INFO - Display Serial Number: 1600x1200 60 19:51:58 INFO - Main Display: Yes 19:51:58 INFO - Mirror: Off 19:51:58 INFO - Online: Yes 19:51:58 INFO - Rotation: Supported 19:51:58 INFO - Return code: 0 19:51:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:51:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:51:58 INFO - Python 2.7.3 19:51:58 INFO - Return code: 0 19:51:58 INFO - grabbing minidump binary from tooltool 19:51:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:58 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 0x1032a3d30>, '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 0x103109db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10329bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:51:58 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 19:51:58 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 19:51:58 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 19:51:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:51:59 INFO - Return code: 0 19:51:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:51:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:51:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:51:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:51:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:51:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0224', '--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 19:51:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0224 --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 19:51:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8gJtQy7GeB/Render', 19:51:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:59 INFO - 'HOME': '/Users/cltbld', 19:51:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:59 INFO - 'LOGNAME': 'cltbld', 19:51:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:51:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:51:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:59 INFO - 'MOZ_NO_REMOTE': '1', 19:51:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:51:59 INFO - 'NO_EM_RESTART': '1', 19:51:59 INFO - 'PAGER': '/bin/cat', 19:51:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:59 INFO - 'PWD': '/builds/slave/test', 19:51:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:51:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:59 INFO - 'SHELL': '/bin/bash', 19:51:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmLjJlGPOQ/Listeners', 19:51:59 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 19:51:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:59 INFO - 'USER': 'cltbld', 19:51:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:59 INFO - 'XPC_FLAGS': '0x0', 19:51:59 INFO - 'XPC_SERVICE_NAME': '0', 19:51:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0224', '--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 19:51:59 INFO - mozversion application_buildid: 20170113173724 19:51:59 INFO - mozversion application_changeset: be9be2c13b4dc9038b331a91f923a4658014bae4 19:51:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:51:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:51:59 INFO - mozversion application_name: Firefox 19:51:59 INFO - mozversion application_remotingname: firefox-dev 19:51:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:51:59 INFO - mozversion application_vendor: Mozilla 19:51:59 INFO - mozversion application_version: 52.0a2 19:51:59 INFO - mozversion platform_buildid: 20170113173724 19:51:59 INFO - mozversion platform_changeset: be9be2c13b4dc9038b331a91f923a4658014bae4 19:51:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:51:59 INFO - mozversion platform_version: 52.0a2 19:51:59 INFO - using testdate: 1484365919 19:51:59 INFO - actual date: 1484365919 19:51:59 INFO - starting webserver on 'localhost:49249' 19:51:59 INFO - SUITE-START | Running 2 tests 19:51:59 INFO - TEST-START | dromaeo_css 19:51:59 INFO - operating with platform_type : mac_ 19:51:59 INFO - Initialising browser for dromaeo_css test... 19:51:59 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpEqJVW7/profile http://localhost:49249/getInfo.html) 19:52:02 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 19:52:02 INFO - PROCESS | 1648 | colorDepth:24 19:52:02 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 19:52:02 INFO - PROCESS | 1648 | __metrics 19:52:02 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:52:02 INFO - PROCESS | 1648 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:52:03 INFO - TEST-INFO | 1648: exit 0 19:52:03 INFO - Browser initialized. 19:52:03 INFO - Running cycle 1/1 for dromaeo_css test... 19:52:03 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpEqJVW7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:52:15 INFO - PROCESS | 1651 | RSS: Main: 222334976 19:52:15 INFO - PROCESS | 1651 | 19:55:18 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 19:55:20 INFO - PROCESS | 1651 | RSS: Main: 367702016 19:55:20 INFO - PROCESS | 1651 | 19:58:23 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 19:58:23 INFO - PROCESS | 1651 | RSS: Main: 350257152 19:58:23 INFO - PROCESS | 1651 | 20:01:26 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 20:01:26 INFO - PROCESS | 1651 | RSS: Main: 311816192 20:01:26 INFO - PROCESS | 1651 | 20:04:29 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 20:04:29 INFO - PROCESS | 1651 | RSS: Main: 308482048 20:04:29 INFO - PROCESS | 1651 | 20:07:33 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 20:07:33 INFO - PROCESS | 1651 | RSS: Main: 306393088 20:07:33 INFO - PROCESS | 1651 | 20:10:36 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 20:10:36 INFO - PROCESS | 1651 | RSS: Main: 447696896 20:10:36 INFO - PROCESS | 1651 | 20:10:36 INFO - PROCESS | 1651 | __start_tp_report 20:10:36 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 20:10:36 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 20:10:36 INFO - PROCESS | 1651 | |i|pagename|runs| 20:10:36 INFO - PROCESS | 1651 | |0;dojo.html;2953.438846619142;3337.833108344583;3380.307037057405;3398.1674489750017;3429.039868836727;5702.572307076965;5750.424957504248;5761.740721667525;5764.538836893049;5787.739551720172;5693.174489698991;5795.449432303924;5811.476967072963;5813.796517121901;5828.3297420796625;5234.293370394986;5267.104592219332;5268.47315268472;5300.522952934235;5328.600354973392;5161.406438259611;5195.792168313263;5197.324347834776;5200.609954253446;5349.170878513836;5216.139337009389;5280.2079688046715;5301.469853014706;5312.654677445973;5345.358556973169;5263.34208223972;5272.446393128724;5275.57795376369;5292.311999440049;5343.786248550054;5112.079825631385;5161.561267292284;5232.790688372461;5260.184671375943;5268.183431568074;5631.155326700987;5670.518005969498;5676.687782171979;5725.685087320196;5749.798757043525;6177.845553861144;6204.162438070848;6260.373962603749;6261.311255761862;6274.811755647338;6326.525510586678;6330.68346582669;6341.017142342898;6345.07996340528;6360.7137678804565;5729.398413166641;5767.452092051249;5774.2782152230975;5783.392743761887;5808.36108028125;6172.22847144107;6278.15244941927;6297.4962003039645;6314.305426403092;6320.683965801692;6221.9377806221355;6221.968890155611;6224.906626400608;6227.9377206227355;6235;6149.677819268879;6221.09794079865;6296.811095667045;6355.269144048401;6356.841078973062;6169.784057557964;6232.376762323822;6240.063990401385;6289.433950944345;6294.748210071546;6177.598456100339;6272.6550039747335;6288.119663247149;6302.464290535264;6312.084747711556;4940;4947.084789313988;4949.158643030693;4951.826686066042;4969.130402179604;3499.842507087187;4373.016071383977;4388.780560972003;4425.09285596452;4433.246348120827;5740;5758.452946970115;5765.625234359753;5773.220615216976;5810.970945145247;6273.9372606273355;6275.6862156892885;6279.183706118267;6281.214848143982;6281.277653069956;5458.2904222450825;5591.944080559224;5605.439456054443;5738.626989245686;5738.655680659173;5395.433479484676;5432.429594892558;5446.210299506629;5450.31871016123;5471.534919531805;6287.46556542699;6324.462420694293;6338.619682819046;6354.110424540568;6355.205599300088;5078.7206703631655;6299.559030867978;6303.401176888188;6326.2092238470195;6338.746450141943;6148.462884278931;6233.625982441068;6242.719077641608;6259.123722678738;6290.559660823698;6229.563930525001;6253.906191407224;6254.8123556293385;6269.96865015672;6274.341194174728;6287.2140982377205;6289.245290565162;6316.368363163647;6324.177856878577;6326.557141000086;5967.731452084753;6053.667048312385;6108.311716707834;6135.845176233341;6185.907211391743;6180.845478863064;6186.566940314135;6194.256689197326;6199.628022318675;6209.658468784261;5828.76684932598;6028.728707208098;6031.60794548353;6068.939310607014;6084.178635884099;5951.196588460675;6037.366076562073;6040.244969378828;6146.754129834757;6179.81460556184;5892.6759028252145;5937.881242375042;6008.789692360747;6058.485028772604;6064.272287325372 20:10:36 INFO - PROCESS | 1651 | |1;ext.html;516.6719133350322;517.2525700362976;527.1512864288495;529.7404271906765;560.475954982092;29769.55345669814;30569.541456878163;31594.210144746394;39364;40512;41391.17217655652;41899.16201675965;41910;41961.580384196146;41964.58035419645;9343.112404321579;9466.24270058397;9470.147686708195;9476.099770521823;9482.288828337867;8499.320054395634;8707.521086340248;9276.165145136936;9408.905910940906;11128.554857805679;11127.054200392935;11355.1483638727;11357.488912998935;11360.60237891674;11383.544658213661;11353.20527563071;11431.542738290458;11434.714132146679;11441.828372574419;11450.026747726453;11286.887131128666;11299.661010169708;11324.660260192208;11350.09199264057;11359.602413915556;39292.80353598242;39453;39586.40620390696;39603;39690.60309396898;2944.661363943131;3054.7250747432836;3186.2990142168687;3256.690614391629;3681.834317455721;29133.708662913523;29715.851420742758;29960.250993725112;30315.848420757975;30362.937297194712;3185.5221716742444;3200.9039728808175;3218.034589623104;3220.951685724716;3323.9501407478906;511032;511680;512682;512741.9999999963;519305;32181.51727724086;32828.67171328304;32987;57812.71093644505;58286.125708114414;34912.476312855324;34947.65052349444;34948.47577286343;34963.951081467596;34967;13976.930115349358;14063.367148478343;14099.436022559188;14101.224432656303;14107.78838317426;12088.335141567299;12167.817482737766;12197.39013049344;12201.938990305169;12210.206336588093;7187.101612298463;7328.267173282736;7338.816529586803;7374.299441553044;7374.705011799468;3137.1686503076703;3229.289556297611;3244.270039241152;3244.951325730116;3246.269589342426;892.3084609427616;901.7925877048316;907.1608761895352;947.1854205383365;1004.2417974429405;28984.42031159365;30254.54618180729;30566.54150187749;31097.53353699696;31670.683293166774;1017.5980487707315;1046.4035499765062;1076.8869268726828;1092.3937214845678;1093.7211011192096;14377.712445751027;14721.484748033767;14738.263086845614;15132.924335378253;15138.924305378623;586053;693344;693515;693761;694571;46825.5317446828;47782.5221747785;54270.185947210826;54697;55041.1743823843;1154.543955137716;1214.4717048084112;1331.1281110872394;1364.8635136486273;1381.7029338692028;24881;29066.952414969903;29946.321313831333;30086;30215;48358.758206208746;48919.75540122419;48931.75534122306;48964;49138.26292605685;450126;455098;456202;529544;613503;41391.79304103581;41869;42132.57867421286;42180.15639687128;42522.57477425186;45925.31112033255;45981.080378392915;46036.76981615071;46108.61674149781;46357;37588.62411375851;38158;38195;38444.80777596206;38595.80702096584;37279.81360093182;37999.86070067564;38167.2366552673;38771.41842872416;39667.801660991514;36814.26371472609;38138;38495.422568660935;38532.42201366926;38577.6142238574;32063.198420038752;33798.49302260419;34219.4867076999;34413.65586344105;34546;21857.67213491767;24890.502189955736;24954;25409;25415.47507149577 20:10:37 INFO - PROCESS | 1651 | |2;jquery.html;322.4663182433071;348.6461241839532;370.3907072865137;383.804259827488;401.8151650240889;14730.042547234436;14892.478763243289;15937;20344.491387715316;20818.583628327426;18246.726299105525;19875.503112422197;20460.28389006385;20677.379678609628;20909.9545022749;5648.548116150719;5937.37657545958;5942.494887934531;5951.196588460567;5958.225430694004;4704.694194877339;5864.354920958713;5920.615160014607;5926.703664816763;5927.081302398114;5586.608937374385;5878.941210587904;5879.735411906453;5880.117982302646;5886.293644762634;5762.711864406763;5870.647761134345;5871.031279838843;5877.412258774131;5883.294004719461;5080.288759573663;5138.845834624967;5147.871303217412;5155.922661160085;5359.866003349908;10950.288231265022;12892;12984;13125.540606078837;13133.212007279499;2222.022310183514;2475.2450502596703;2487.589547724623;2523.6340730594143;2635.288472112519;9685.079917407906;9703;9770.804583908282;10185.388876667424;10263.89736102637;1675.7067513185266;1726.6201435684254;1733.6532693461359;1777.7511148439228;1793.4709260768104;90256;127907.72092279144;129966;137410;140502;10530.631427899987;11235.494402751732;11423.828642570368;11560.826587601192;11815.88184118165;14570.344334504973;14631.341589628491;14633.634159145893;14633.853661463248;14650.120992740469;8291.792705182297;8531.701390451304;8544.31645468361;8655.956720216485;8700.91299087001;6593.406593406617;8478.23695867375;8494.193051660082;8663.913360866436;8668.349873759493;4421.292593185075;4458.6878918475395;4495.617872480876;4516.616087632522;4545.272756358967;1733.3673209278522;1793.0591884559599;1854.110027186931;1914.3108480946848;1920.6446807340687;767.5087943716027;904.3533873280504;936.8266870628955;984.5569493727851;998.7852611688522;20815;21218;21670.565981382595;21736.565268694536;21939.341819745434;841.6296829395046;911.6444586611227;918.6830543462495;965.8985806490358;1003.9508713394521;10748.87136850635;11391.678054383769;11490.597829076109;11584.42077896102;11589.710257243467;620753;621648;624462;626804.7319526747;633696.8315158486;111851.88148118577;115428;116111.6777664442;131885;132729.67270327365;879.3756432932689;921.7695576105973;945.6122989574379;993.1905497019906;1003.3377970539409;14782.556523304318;18942.052897355352;19559.511012224808;20136.093875775332;20199.091040902902;37493;55179;55663.72168139133;57397.42602573921;57826.13260801007;76144.0963975905;79867.20132798598;80163;81398;83316.16683833084;7833.960830195812;8033.6786528538205;8230.917690823255;8421.115782842713;8515.74452766418;9967.40195588267;12893.35533223354;13051.347432628521;13181.80227296572;13286.667833304246;11234.662960111209;11554.537818487166;11631.76736465283;11742.765144697229;11791.469383877575;9778.16885564735;10735.336022916461;11056.778864422506;11188.944055279671;11699.23954942926;11493.827592586285;11593.130515211562;11741.354225517678;11935.104867134825;11935.164538482222;8122.822190782422;8132.146124656826;8212;8363.456375335583;8365.749027529184;4427.424434823452;4736.597389221817;6543.247526534509;7036.859262814817;8070.798230044061 20:10:37 INFO - PROCESS | 1651 | |3;mootools.html;1457.9198144102077;1497.169071165503;1639.9590010249742;1776.5203395083336;1832.2029916986112;8098.433109682317;8517.744467665972;8518.574071296425;8522.062573116953;8526.914730852697;7432.777016689508;7705.768826935187;8116.797080073001;8320.33437325014;8349.165083491647;2337.777911098447;2370.928872133839;2373.596488596943;2375.251795684354;2375.762423757628;2098.2026829804654;2357.610994185957;2382.7140743110763;2400.531896280226;2411.3007227903886;2087.311187308185;2118.8410869184795;2287.8970446329954;2363.9763602364014;2365.467769751809;2334.5914464968732;2349.4243910242017;2353.0470159585225;2354.540864531417;2359.339384972202;2334.0897050150456;2386.1529157149134;2389.7968672662846;2392.9162479313313;2394.976050239493;8329.541875196861;8382.119877412906;8391.874121888177;8403.831923361498;8418.200270974248;3745.6441638044344;5204.635675502716;5205.375354957391;5207;5207.427183009904;3175.1903264667594;3262.934741305184;3490.127468132967;3493.1441796759955;3496.125968507873;905.714699869539;956.6651671914774;1013.0679774607305;1060.549266561716;1060.8726952765717;10754.139668826476;10770.946145269223;10890.945545272301;10911;10922.726931826688;4541.341505481687;4619.584237418649;4738.07607516531;4757.619390448755;4764.071006153767;9359.532023398802;9371.812563748823;9397.812043759086;9399.718008459757;9434.009429009855;5211.947880521222;5216.58267338612;5222.895542089137;5223.817166399158;5236.947630523646;4287.7427354358215;4617.607503362185;4685.320628508915;4798.73606951621;4832.540908613676;3796.7342286040266;3827.7129215308964;3853.903652408656;3890.1636148228267;3918.8824335269987;1121.5962253588702;1246.401727170963;1252.6242127361754;1254.0469243374919;1259.508791571288;740.9407247420302;786.3434032582858;832.3466079127855;877.5349064995562;899.2581120575551;5134.820281290137;5149.815542425263;5427.3215848019;5610.719464026783;5657.264555607745;891.7636826241052;903.9412438191497;932.6269492203037;946.7491114854569;946.9857952130724;3823.3691440912494;3915.1190982028816;3940.1922605866303;4022.135240923157;4060.4721386220194;10074.697759067094;10139.898601014043;10279.588816447407;10297;10392.168626509861;9854.704358869245;10108.696739097839;10288.485575721184;10305.227107966932;10313.535890884928;7549.8297763846695;7770.494917830323;7811.80470488231;8003.999500062492;8025.317847982987;5566.359868614999;5696.088625819849;5785.855353616025;5828.125781132864;5963.880722385441;9456.810863782823;9459.479728614991;9514.38156519824;9568.138867501959;9657.130858222794;9141.908580914373;9553.474558899326;9565.282603804882;9635.710928672152;9775.315727899264;7192.928070719226;7369.115706115301;7372.815679607838;7381.188069312479;7385.335319821242;7452.776416707507;7591.164971853203;7680.423968202519;7695.769126926201;7700.268474494914;6809;6862.107925969592;6913.10129683138;6998.405135563534;7010.68451919675;6800.353966373186;6815.488838337045;6842.521023528305;6914.792556223321;6916.585004899722;6362.427381535684;6364.427201551883;6379.883520384007;6419.197600299963;6428.0576976865805;4590.049577717538;4618.399608050931;4659.4874563797275;4659.650526210616;4746.43042834862;4560.156371071363;4636.953630463652;4708.246680531099;4709.835155769531;4732.739699316433 20:10:37 INFO - PROCESS | 1651 | |4;prototype.html;299.8006325793348;306.4392162342913;306.6534815658306;312.54368621812165;316.2788841441514;5166.715830629314;5221.503957124077;5257.237700533425;5271.130263506517;5295.4704529547025;4281.914361712764;4567.246404343279;5170.500554839092;5188.792448302072;5215.895682086356;3083.753299736016;3484.0767196692896;3916.4908561886914;3931.783751893645;3974.582668819776;3228.8547015384365;3465.861365545375;3794.829232684536;3815.6756675682464;4034.3343348347635;3350.7486938479587;3966.841326346943;3985.342418500944;3987.1626958338784;3991.4696819110372;3565.554305711786;3772.020518353136;3976.880693579197;3980.6218409251073;4019.5578486366476;3435.9656403435897;3454.8598962342367;3465.7227421806197;3470.8372695147154;3482.8432720527385;3966.682665386791;5014.147594908874;5018.598512119058;5028.647994640377;5043.293938848564;4323.292299541664;4341.804618792162;4418.5702304010865;4543.31850222466;4587.839425620121;5446.972765136188;5472.644278121909;5495.945040549584;5508.476694713996;5549.278593782823;3080.4917188663844;3794.886153415402;3814.1608846053823;3821.5796262411113;3832.7125465590193;5295.4969277918535;5628.493435590775;5658.076254073635;5701.372848986608;5781.653100813965;3755.605661405568;3757.530308711411;3769.076576238839;3774.641409066134;3777.981110094432;3823.7896915669903;3828.8659896903473;3832.9808350958624;3839.9040023999064;3854.5181852268465;3793.3551296279697;4498.955010449919;4529.207388707029;4559.882828706922;4562.338460923148;4561.042181141931;4849.612031037508;5224.440024388098;5466.835994920159;5543.861403464865;1241.6026871401245;1244.7552447552448;1247.457356050121;1250.1374051904156;1260.7518556628872;694.8783962806489;844.7001314533375;858.7337925243195;860.1226748716399;863.6718047142172;4550.567696068868;4770.759602503304;4916.926246106311;4926.236433352836;5016.673916195436;5416.729163541807;5496.862578435571;5616.522595579339;5656.236408084938;5676.290463692038;4410.5368936261875;4637.052437976058;4719.7404142772475;4851.175300198975;5167.250748641426;2427.478092210183;2499.675042244533;2818.689944106147;2827.08119861046;2837.276494493891;4714.033623107255;5112.821051263188;5172.7068232941765;5602.327720673464;5629.521490673339;4708.835190768306;5136.691798492103;5354.063038968165;5377.973110134502;5569.994801871323;5905.822825315247;5987.880242395215;5997.430244126801;6000.21997140369;6009;4771.475137734777;5042.621803364836;5720;5731;5791.8552036199435;5396.622236443568;5652.869426114711;5665.915011274917;5711.685857277889;5726.914096288642;4734.810607575659;5005.399352077774;5164.912561127362;5865.648061116347;5899.321578018411;3284.21178917063;3788.6590206881515;3861.6524512793985;3874.205787813548;3879.80600969956;4631.606313463259;5138.845834624967;5387.222216668569;5654.349749778664;5730.770769169187;4849.636277279289;5473.686315284223;5494.862628434161;5498.642588231752;5508.724563771875;4577.359169716309;5461.508464238238;5523.95613622946;5564.582656300713;5594.440555944373;5123.948760512347;5366.517013468807;5377.61210437422;5407.78368865241;5462.317210348707;4646.628269738345;5353.357597088374;5517.36550296721;5522.502974732132;5593.958205255134;4897.7061376316105;4903.877403064952;4918.532739389752;5305.761240744098;5379.946200537945 20:10:37 INFO - PROCESS | 1651 | |5;yui.html;679.7926632377123;737.0970561062699;756.6821934787389;761.101899758285;805.9758207253783;13782.517611883573;13821.447142114315;14233;14419.206943618097;14459.78310325346;12864.16382935111;12954.93522532386;12963.80554291684;12965.611031669041;12972.610821675342;649.9837504062401;666.4235436347573;815.946963447377;831.0816547714779;838.8196537744384;834.2366734437982;834.9123342049058;835.4861760017576;836.93304535637;840.0045945554507;870.8824308718337;871.5729292646621;872.6291326186378;876.8597024476085;879.5118709116438;875.382855087162;876.9429987050853;877.868319752036;877.903430622631;878.6002368922125;646.7283740828863;647.8088963755656;648.8637386148914;649.4804156674688;649.6849028221309;12380.62858114258;12484.188527745668;12686.80969785454;12708.110432269745;12890.355482225852;13311.866881331256;13466.057375983684;13510.256935868523;13575.253361065137;13756.312184390841;9000.909990900072;10169.745756356077;10542;10657.467126643714;11006.834897476545;4134.813933373005;4305.332673435622;4340.739555626641;4452.042810795662;4522.253828118356;77652;77813.22186778173;78108.21891781066;79425;80320;10062.295639305179;10088.092071713434;10208.489575521195;10209.336393134423;10213.234007449471;18415.90792046031;19039.143238554298;19099;19112.426627200926;19166.137523811463;7126.821829454305;7327.487075904742;7403.481756277009;7491.213422590658;7546.396288296923;4024.8993775155855;4101.036256479725;4108.322126849036;4169.437125988014;4217.831286748496;3277.2789986202997;3385.306012267479;3390.3388839176123;3392.3215356928713;3397.3375191837354;622.3496446213718;626.6396821827376;628.673089993201;631.6525910749161;633.5311869216838;498.66589385112064;501.078016449727;514;605.1527860994555;618.4434009391583;8107.675692972215;8635.740927772176;8717.30261579072;8796.296296296408;8865.64537418509;576.7375843990974;597.2593983460478;604.6130476495047;610.548194336197;621.8165641135876;4048.9392659110135;4278.871633850989;4287.464066991626;4328.156009578102;4392.121575684812;74860;75625.70394809054;115886;116615.66768664579;126947.3652631731;59090.40909590935;70476.29523704697;93911.53044234871;98520;113929;77426.6128669353;91223.54388228149;91674;92060.53969730108;93908;9134.588943497425;9613.711588652352;9638.325317227727;9638.566264517973;9670.661526846527;37152.51389944372;42752.57247427486;52609.47390526046;53329.6667583298;53597.19604206018;25042.37394065163;25913.740862591887;27414;82087;82526.17473825425;10343.43111128865;12308.09224570193;12477.563285284972;12510;12832.871671283168;12821.931464174455;13542.864571354161;13893.861061389256;14190.716185676436;15048.021878577858;12593.433295501907;13051.28217947984;13364.198148111145;13592.524261650795;13649.181049137083;12571;13404.664883377603;13428.758359262503;13538.323083845966;13907.652308691959;12902.354882255662;13154;13784.793228101787;13843.653908652364;13865.792013120014;9915.008499150028;10225.642102526375;10424.061834434937;10873.184511161853;11048.81676674121;10419.479026048819;10778.461076946278;11233;11385.373804440835;11546.842044904899 20:10:37 INFO - PROCESS | 1651 | __end_tp_report 20:10:37 INFO - PROCESS | 1651 | __start_cc_report 20:10:37 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1657 20:10:37 INFO - PROCESS | 1651 | __end_cc_report 20:10:37 INFO - PROCESS | 1651 | __startTimestamp1484367036992__endTimestamp 20:10:37 INFO - PROCESS | 1651 | 20:10:37 INFO - PROCESS | 1651 | ------- Summary: start ------- 20:10:37 INFO - PROCESS | 1651 | Number of tests: 6 20:10:37 INFO - PROCESS | 1651 | 20:10:37 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5767.01 Median:6028.73 stddev:670.05 (11.1%) stddev-sans-first:634.71 20:10:37 INFO - PROCESS | 1651 | Values: 2953.4 3337.8 3380.3 3398.2 3429.0 5702.6 5750.4 5761.7 5764.5 5787.7 5693.2 5795.4 5811.5 5813.8 5828.3 5234.3 5267.1 5268.5 5300.5 5328.6 5161.4 5195.8 5197.3 5200.6 5349.2 5216.1 5280.2 5301.5 5312.7 5345.4 5263.3 5272.4 5275.6 5292.3 5343.8 5112.1 5161.6 5232.8 5260.2 5268.2 5631.2 5670.5 5676.7 5725.7 5749.8 6177.8 6204.2 6260.4 6261.3 6274.8 6326.5 6330.7 6341.0 6345.1 6360.7 5729.4 5767.5 5774.3 5783.4 5808.4 6172.2 6278.2 6297.5 6314.3 6320.7 6221.9 6222.0 6224.9 6227.9 6235.0 6149.7 6221.1 6296.8 6355.3 6356.8 6169.8 6232.4 6240.1 6289.4 6294.7 6177.6 6272.7 6288.1 6302.5 6312.1 4940.0 4947.1 4949.2 4951.8 4969.1 3499.8 4373.0 4388.8 4425.1 4433.2 5740.0 5758.5 5765.6 5773.2 5811.0 6273.9 6275.7 6279.2 6281.2 6281.3 5458.3 5591.9 5605.4 5738.6 5738.7 5395.4 5432.4 5446.2 5450.3 5471.5 6287.5 6324.5 6338.6 6354.1 6355.2 5078.7 6299.6 6303.4 6326.2 6338.7 6148.5 6233.6 6242.7 6259.1 6290.6 6229.6 6253.9 6254.8 6270.0 6274.3 6287.2 6289.2 6316.4 6324.2 6326.6 5967.7 6053.7 6108.3 6135.8 6185.9 6180.8 6186.6 6194.3 6199.6 6209.7 5828.8 6028.7 6031.6 6068.9 6084.2 5951.2 6037.4 6040.2 6146.8 6179.8 5892.7 5937.9 6008.8 6058.5 6064.3 20:10:37 INFO - PROCESS | 1651 | 20:10:37 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:67939.97 Median:34965.48 stddev:152531.43 (436.2%) stddev-sans-first:152875.32 20:10:37 INFO - PROCESS | 1651 | Values: 516.7 517.3 527.2 529.7 560.5 29769.6 30569.5 31594.2 39364.0 40512.0 41391.2 41899.2 41910.0 41961.6 41964.6 9343.1 9466.2 9470.1 9476.1 9482.3 8499.3 8707.5 9276.2 9408.9 11128.6 11127.1 11355.1 11357.5 11360.6 11383.5 11353.2 11431.5 11434.7 11441.8 11450.0 11286.9 11299.7 11324.7 11350.1 11359.6 39292.8 39453.0 39586.4 39603.0 39690.6 2944.7 3054.7 3186.3 3256.7 3681.8 29133.7 29715.9 29960.3 30315.8 30362.9 3185.5 3200.9 3218.0 3221.0 3324.0 511032.0 511680.0 512682.0 512742.0 519305.0 32181.5 32828.7 32987.0 57812.7 58286.1 34912.5 34947.7 34948.5 34964.0 34967.0 13976.9 14063.4 14099.4 14101.2 14107.8 12088.3 12167.8 12197.4 12201.9 12210.2 7187.1 7328.3 7338.8 7374.3 7374.7 3137.2 3229.3 3244.3 3245.0 3246.3 892.3 901.8 907.2 947.2 1004.2 28984.4 30254.5 30566.5 31097.5 31670.7 1017.6 1046.4 1076.9 1092.4 1093.7 14377.7 14721.5 14738.3 15132.9 15138.9 586053.0 693344.0 693515.0 693761.0 694571.0 46825.5 47782.5 54270.2 54697.0 55041.2 1154.5 1214.5 1331.1 1364.9 1381.7 24881.0 29067.0 29946.3 30086.0 30215.0 48358.8 48919.8 48931.8 48964.0 49138.3 450126.0 455098.0 456202.0 529544.0 613503.0 41391.8 41869.0 42132.6 42180.2 42522.6 45925.3 45981.1 46036.8 46108.6 46357.0 37588.6 38158.0 38195.0 38444.8 38595.8 37279.8 37999.9 38167.2 38771.4 39667.8 36814.3 38138.0 38495.4 38532.4 38577.6 32063.2 33798.5 34219.5 34413.7 34546.0 21857.7 24890.5 24954.0 25409.0 25415.5 20:10:37 INFO - PROCESS | 1651 | 20:10:37 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:35298.04 Median:392.81 stddev:104352.51 (26565.7%) stddev-sans-first:104612.20 20:10:37 INFO - PROCESS | 1651 | Values: 322.5 348.6 370.4 383.8 401.8 14730.0 14892.5 15937.0 20344.5 20818.6 18246.7 19875.5 20460.3 20677.4 20910.0 5648.5 5937.4 5942.5 5951.2 5958.2 4704.7 5864.4 5920.6 5926.7 5927.1 5586.6 5878.9 5879.7 5880.1 5886.3 5762.7 5870.6 5871.0 5877.4 5883.3 5080.3 5138.8 5147.9 5155.9 5359.9 10950.3 12892.0 12984.0 13125.5 13133.2 2222.0 2475.2 2487.6 2523.6 2635.3 9685.1 9703.0 9770.8 10185.4 10263.9 1675.7 1726.6 1733.7 1777.8 1793.5 90256.0 127907.7 129966.0 137410.0 140502.0 10530.6 11235.5 11423.8 11560.8 11815.9 14570.3 14631.3 14633.6 14633.9 14650.1 8291.8 8531.7 8544.3 8656.0 8700.9 6593.4 8478.2 8494.2 8663.9 8668.3 4421.3 4458.7 4495.6 4516.6 4545.3 1733.4 1793.1 1854.1 1914.3 1920.6 767.5 904.4 936.8 984.6 998.8 20815.0 21218.0 21670.6 21736.6 21939.3 841.6 911.6 918.7 965.9 1004.0 10748.9 11391.7 11490.6 11584.4 11589.7 620753.0 621648.0 624462.0 626804.7 633696.8 111851.9 115428.0 116111.7 131885.0 132729.7 879.4 921.8 945.6 993.2 1003.3 14782.6 18942.1 19559.5 20136.1 20199.1 37493.0 55179.0 55663.7 57397.4 57826.1 76144.1 79867.2 80163.0 81398.0 83316.2 7834.0 8033.7 8230.9 8421.1 8515.7 9967.4 12893.4 13051.3 13181.8 13286.7 11234.7 11554.5 11631.8 11742.8 11791.5 9778.2 10735.3 11056.8 11188.9 11699.2 11493.8 11593.1 11741.4 11935.1 11935.2 8122.8 8132.1 8212.0 8363.5 8365.7 4427.4 4736.6 6543.2 7036.9 8070.8 20:10:37 INFO - PROCESS | 1651 | 20:10:37 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5442.40 Median:5205.01 stddev:2997.28 (57.6%) stddev-sans-first:2990.73 20:10:37 INFO - PROCESS | 1651 | Values: 1457.9 1497.2 1640.0 1776.5 1832.2 8098.4 8517.7 8518.6 8522.1 8526.9 7432.8 7705.8 8116.8 8320.3 8349.2 2337.8 2370.9 2373.6 2375.3 2375.8 2098.2 2357.6 2382.7 2400.5 2411.3 2087.3 2118.8 2287.9 2364.0 2365.5 2334.6 2349.4 2353.0 2354.5 2359.3 2334.1 2386.2 2389.8 2392.9 2395.0 8329.5 8382.1 8391.9 8403.8 8418.2 3745.6 5204.6 5205.4 5207.0 5207.4 3175.2 3262.9 3490.1 3493.1 3496.1 905.7 956.7 1013.1 1060.5 1060.9 10754.1 10770.9 10890.9 10911.0 10922.7 4541.3 4619.6 4738.1 4757.6 4764.1 9359.5 9371.8 9397.8 9399.7 9434.0 5211.9 5216.6 5222.9 5223.8 5236.9 4287.7 4617.6 4685.3 4798.7 4832.5 3796.7 3827.7 3853.9 3890.2 3918.9 1121.6 1246.4 1252.6 1254.0 1259.5 740.9 786.3 832.3 877.5 899.3 5134.8 5149.8 5427.3 5610.7 5657.3 891.8 903.9 932.6 946.7 947.0 3823.4 3915.1 3940.2 4022.1 4060.5 10074.7 10139.9 10279.6 10297.0 10392.2 9854.7 10108.7 10288.5 10305.2 10313.5 7549.8 7770.5 7811.8 8004.0 8025.3 5566.4 5696.1 5785.9 5828.1 5963.9 9456.8 9459.5 9514.4 9568.1 9657.1 9141.9 9553.5 9565.3 9635.7 9775.3 7192.9 7369.1 7372.8 7381.2 7385.3 7452.8 7591.2 7680.4 7695.8 7700.3 6809.0 6862.1 6913.1 6998.4 7010.7 6800.4 6815.5 6842.5 6914.8 6916.6 6362.4 6364.4 6379.9 6419.2 6428.1 4590.0 4618.4 4659.5 4659.7 4746.4 4560.2 4637.0 4708.2 4709.8 4732.7 20:10:37 INFO - PROCESS | 1651 | 20:10:37 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4399.51 Median:4910.40 stddev:1369.61 (27.9%) stddev-sans-first:1338.44 20:10:37 INFO - PROCESS | 1651 | Values: 299.8 306.4 306.7 312.5 316.3 5166.7 5221.5 5257.2 5271.1 5295.5 4281.9 4567.2 5170.5 5188.8 5215.9 3083.8 3484.1 3916.5 3931.8 3974.6 3228.9 3465.9 3794.8 3815.7 4034.3 3350.7 3966.8 3985.3 3987.2 3991.5 3565.6 3772.0 3976.9 3980.6 4019.6 3436.0 3454.9 3465.7 3470.8 3482.8 3966.7 5014.1 5018.6 5028.6 5043.3 4323.3 4341.8 4418.6 4543.3 4587.8 5447.0 5472.6 5495.9 5508.5 5549.3 3080.5 3794.9 3814.2 3821.6 3832.7 5295.5 5628.5 5658.1 5701.4 5781.7 3755.6 3757.5 3769.1 3774.6 3778.0 3823.8 3828.9 3833.0 3839.9 3854.5 3793.4 4499.0 4529.2 4559.9 4562.3 4561.0 4849.6 5224.4 5466.8 5543.9 1241.6 1244.8 1247.5 1250.1 1260.8 694.9 844.7 858.7 860.1 863.7 4550.6 4770.8 4916.9 4926.2 5016.7 5416.7 5496.9 5616.5 5656.2 5676.3 4410.5 4637.1 4719.7 4851.2 5167.3 2427.5 2499.7 2818.7 2827.1 2837.3 4714.0 5112.8 5172.7 5602.3 5629.5 4708.8 5136.7 5354.1 5378.0 5570.0 5905.8 5987.9 5997.4 6000.2 6009.0 4771.5 5042.6 5720.0 5731.0 5791.9 5396.6 5652.9 5665.9 5711.7 5726.9 4734.8 5005.4 5164.9 5865.6 5899.3 3284.2 3788.7 3861.7 3874.2 3879.8 4631.6 5138.8 5387.2 5654.3 5730.8 4849.6 5473.7 5494.9 5498.6 5508.7 4577.4 5461.5 5524.0 5564.6 5594.4 5123.9 5366.5 5377.6 5407.8 5462.3 4646.6 5353.4 5517.4 5522.5 5594.0 4897.7 4903.9 4918.5 5305.8 5379.9 20:10:37 INFO - PROCESS | 1651 | 20:10:37 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:18946.95 Median:4296.40 stddev:28112.85 (654.3%) stddev-sans-first:28158.25 20:10:37 INFO - PROCESS | 1651 | Values: 679.8 737.1 756.7 761.1 806.0 13782.5 13821.4 14233.0 14419.2 14459.8 12864.2 12954.9 12963.8 12965.6 12972.6 650.0 666.4 815.9 831.1 838.8 834.2 834.9 835.5 836.9 840.0 870.9 871.6 872.6 876.9 879.5 875.4 876.9 877.9 877.9 878.6 646.7 647.8 648.9 649.5 649.7 12380.6 12484.2 12686.8 12708.1 12890.4 13311.9 13466.1 13510.3 13575.3 13756.3 9000.9 10169.7 10542.0 10657.5 11006.8 4134.8 4305.3 4340.7 4452.0 4522.3 77652.0 77813.2 78108.2 79425.0 80320.0 10062.3 10088.1 10208.5 10209.3 10213.2 18415.9 19039.1 19099.0 19112.4 19166.1 7126.8 7327.5 7403.5 7491.2 7546.4 4024.9 4101.0 4108.3 4169.4 4217.8 3277.3 3385.3 3390.3 3392.3 3397.3 622.3 626.6 628.7 631.7 633.5 498.7 501.1 514.0 605.2 618.4 8107.7 8635.7 8717.3 8796.3 8865.6 576.7 597.3 604.6 610.5 621.8 4048.9 4278.9 4287.5 4328.2 4392.1 74860.0 75625.7 115886.0 116615.7 126947.4 59090.4 70476.3 93911.5 98520.0 113929.0 77426.6 91223.5 91674.0 92060.5 93908.0 9134.6 9613.7 9638.3 9638.6 9670.7 37152.5 42752.6 52609.5 53329.7 53597.2 25042.4 25913.7 27414.0 82087.0 82526.2 10343.4 12308.1 12477.6 12510.0 12832.9 12821.9 13542.9 13893.9 14190.7 15048.0 12593.4 13051.3 13364.2 13592.5 13649.2 12571.0 13404.7 13428.8 13538.3 13907.7 12902.4 13154.0 13784.8 13843.7 13865.8 9915.0 10225.6 10424.1 10873.2 11048.8 10419.5 10778.5 11233.0 11385.4 11546.8 20:10:37 INFO - PROCESS | 1651 | -------- Summary: end -------- 20:10:37 INFO - PROCESS | 1651 | 20:10:37 INFO - TEST-INFO | 1651: exit 0 20:10:37 INFO - TEST-OK | dromaeo_css | took 1118287ms 20:10:37 INFO - TEST-START | kraken 20:10:37 INFO - operating with platform_type : mac_ 20:10:37 INFO - Initialising browser for kraken test... 20:10:37 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpCwK3c5/profile http://localhost:49249/getInfo.html) 20:10:40 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 20:10:40 INFO - PROCESS | 1656 | colorDepth:24 20:10:40 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 20:10:40 INFO - PROCESS | 1656 | __metrics 20:10:40 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:10:40 INFO - PROCESS | 1656 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:10:41 INFO - TEST-INFO | 1656: exit 0 20:10:41 INFO - Browser initialized. 20:10:41 INFO - Running cycle 1/1 for kraken test... 20:10:41 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmpCwK3c5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:10:53 INFO - PROCESS | 1657 | RSS: Main: 222265344 20:10:53 INFO - PROCESS | 1657 | 20:10:56 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 20:10:56 INFO - PROCESS | 1657 | RSS: Main: 272674816 20:10:56 INFO - PROCESS | 1657 | 20:10:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 20:11:00 INFO - PROCESS | 1657 | RSS: Main: 283758592 20:11:00 INFO - PROCESS | 1657 | 20:11:03 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 20:11:03 INFO - PROCESS | 1657 | RSS: Main: 380235776 20:11:03 INFO - PROCESS | 1657 | 20:11:05 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 20:11:06 INFO - PROCESS | 1657 | RSS: Main: 282988544 20:11:06 INFO - PROCESS | 1657 | 20:11:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:11:08 INFO - PROCESS | 1657 | RSS: Main: 284164096 20:11:08 INFO - PROCESS | 1657 | 20:11:11 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 20:11:12 INFO - PROCESS | 1657 | RSS: Main: 324943872 20:11:12 INFO - PROCESS | 1657 | 20:11:15 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 20:11:15 INFO - PROCESS | 1657 | RSS: Main: 327065600 20:11:15 INFO - PROCESS | 1657 | 20:11:18 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 20:11:19 INFO - PROCESS | 1657 | RSS: Main: 325382144 20:11:19 INFO - PROCESS | 1657 | 20:11:20 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:11:21 INFO - PROCESS | 1657 | RSS: Main: 299757568 20:11:21 INFO - PROCESS | 1657 | 20:11:23 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:11:23 INFO - PROCESS | 1657 | RSS: Main: 301273088 20:11:23 INFO - PROCESS | 1657 | 20:11:26 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:11:26 INFO - PROCESS | 1657 | RSS: Main: 303828992 20:11:26 INFO - PROCESS | 1657 | 20:11:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:11:30 INFO - PROCESS | 1657 | RSS: Main: 322502656 20:11:30 INFO - PROCESS | 1657 | 20:11:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:11:33 INFO - PROCESS | 1657 | RSS: Main: 315580416 20:11:33 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 20:11:35 INFO - PROCESS | 1657 | RSS: Main: 360361984 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | __start_tp_report 20:11:35 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 20:11:35 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 20:11:35 INFO - PROCESS | 1657 | |i|pagename|runs| 20:11:35 INFO - PROCESS | 1657 | |0;ai-astar;117;113;115;114;116;116;115;115;116;114 20:11:35 INFO - PROCESS | 1657 | |1;audio-beat-detection;144;112;111;142;110;106;111;108;138;109 20:11:35 INFO - PROCESS | 1657 | |2;audio-dft;185;183;182;182;183;181;181;183;183;183 20:11:35 INFO - PROCESS | 1657 | |3;audio-fft;82;58;58;89;64;59;61;58;81;58 20:11:35 INFO - PROCESS | 1657 | |4;audio-oscillator;89;71;92;70;68;68;90;67;68;90 20:11:35 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;86;85;87;85;86;84;92;85;86;85 20:11:35 INFO - PROCESS | 1657 | |6;imaging-darkroom;118;163;153;95;96;96;98;96;95;96 20:11:35 INFO - PROCESS | 1657 | |7;imaging-desaturate;84;83;81;79;83;83;84;84;83;82 20:11:35 INFO - PROCESS | 1657 | |8;json-parse-financial;53;55;53;53;56;58;59;59;57;57 20:11:35 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;65;66;81;77;72;70;69;71;70;72 20:11:35 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;83;81;84;82;84;84;81;83;82;94 20:11:35 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;155;150;147;137;141;130;142;142;181;137 20:11:35 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;165;163;165;161;164;175;219;203;163;219 20:11:35 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;67;66;66;67;64;66;67;66;66 20:11:35 INFO - PROCESS | 1657 | __end_tp_report 20:11:35 INFO - PROCESS | 1657 | __start_cc_report 20:11:35 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1928 20:11:35 INFO - PROCESS | 1657 | __end_cc_report 20:11:35 INFO - PROCESS | 1657 | __startTimestamp1484367095861__endTimestamp 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | ------- Summary: start ------- 20:11:35 INFO - PROCESS | 1657 | Number of tests: 14 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:115.10 Median:115.50 stddev:1.20 (1.0%) stddev-sans-first:1.05 20:11:35 INFO - PROCESS | 1657 | Values: 117.0 113.0 115.0 114.0 116.0 116.0 115.0 115.0 116.0 114.0 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:119.10 Median:111.50 stddev:15.50 (13.9%) stddev-sans-first:13.57 20:11:35 INFO - PROCESS | 1657 | Values: 144.0 112.0 111.0 142.0 110.0 106.0 111.0 108.0 138.0 109.0 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.00 stddev:1.17 (0.6%) stddev-sans-first:0.87 20:11:35 INFO - PROCESS | 1657 | Values: 185.0 183.0 182.0 182.0 183.0 181.0 181.0 183.0 183.0 183.0 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:66.80 Median:62.50 stddev:12.19 (19.5%) stddev-sans-first:11.62 20:11:35 INFO - PROCESS | 1657 | Values: 82.0 58.0 58.0 89.0 64.0 59.0 61.0 58.0 81.0 58.0 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:80.00 stddev:11.23 (14.0%) stddev-sans-first:11.08 20:11:35 INFO - PROCESS | 1657 | Values: 89.0 71.0 92.0 70.0 68.0 68.0 90.0 67.0 68.0 90.0 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:86.00 stddev:2.23 (2.6%) stddev-sans-first:2.37 20:11:35 INFO - PROCESS | 1657 | Values: 86.0 85.0 87.0 85.0 86.0 84.0 92.0 85.0 86.0 85.0 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:110.60 Median:96.00 stddev:26.03 (27.1%) stddev-sans-first:27.47 20:11:35 INFO - PROCESS | 1657 | Values: 118.0 163.0 153.0 95.0 96.0 96.0 98.0 96.0 95.0 96.0 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:82.60 Median:83.00 stddev:1.58 (1.9%) stddev-sans-first:1.59 20:11:35 INFO - PROCESS | 1657 | Values: 84.0 83.0 81.0 79.0 83.0 83.0 84.0 84.0 83.0 82.0 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:57.00 stddev:2.40 (4.2%) stddev-sans-first:2.29 20:11:35 INFO - PROCESS | 1657 | Values: 53.0 55.0 53.0 53.0 56.0 58.0 59.0 59.0 57.0 57.0 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.50 stddev:4.76 (6.7%) stddev-sans-first:4.47 20:11:35 INFO - PROCESS | 1657 | Values: 65.0 66.0 81.0 77.0 72.0 70.0 69.0 71.0 70.0 72.0 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:83.50 stddev:3.77 (4.5%) stddev-sans-first:3.98 20:11:35 INFO - PROCESS | 1657 | Values: 83.0 81.0 84.0 82.0 84.0 84.0 81.0 83.0 82.0 94.0 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:144.50 stddev:14.13 (9.8%) stddev-sans-first:14.63 20:11:35 INFO - PROCESS | 1657 | Values: 155.0 150.0 147.0 137.0 141.0 130.0 142.0 142.0 181.0 137.0 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.70 Median:170.00 stddev:24.13 (14.2%) stddev-sans-first:25.00 20:11:35 INFO - PROCESS | 1657 | Values: 165.0 163.0 165.0 161.0 164.0 175.0 219.0 203.0 163.0 219.0 20:11:35 INFO - PROCESS | 1657 | 20:11:35 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:0.92 (1.4%) stddev-sans-first:0.93 20:11:35 INFO - PROCESS | 1657 | Values: 67.0 67.0 66.0 66.0 67.0 64.0 66.0 67.0 66.0 66.0 20:11:35 INFO - PROCESS | 1657 | -------- Summary: end -------- 20:11:35 INFO - PROCESS | 1657 | 20:11:36 INFO - TEST-INFO | 1657: exit 0 20:11:36 INFO - TEST-OK | kraken | took 59321ms 20:11:36 INFO - SUITE-END | took 1177s 20:11:37 INFO - Completed test suite (00:19:37) 20:11:37 INFO - javascript benchmark 20:11:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2953.438846619142, 3337.833108344583, 3380.307037057405, 3398.1674489750017, 3429.039868836727, 5702.572307076965, 5750.424957504248, 5761.740721667525, 5764.538836893049, 5787.739551720172, 5693.174489698991, 5795.449432303924, 5811.476967072963, 5813.796517121901, 5828.3297420796625, 5234.293370394986, 5267.104592219332, 5268.47315268472, 5300.522952934235, 5328.600354973392, 5161.406438259611, 5195.792168313263, 5197.324347834776, 5200.609954253446, 5349.170878513836, 5216.139337009389, 5280.2079688046715, 5301.469853014706, 5312.654677445973, 5345.358556973169, 5263.34208223972, 5272.446393128724, 5275.57795376369, 5292.311999440049, 5343.786248550054, 5112.079825631385, 5161.561267292284, 5232.790688372461, 5260.184671375943, 5268.183431568074, 5631.155326700987, 5670.518005969498, 5676.687782171979, 5725.685087320196, 5749.798757043525, 6177.845553861144, 6204.162438070848, 6260.373962603749, 6261.311255761862, 6274.811755647338, 6326.525510586678, 6330.68346582669, 6341.017142342898, 6345.07996340528, 6360.7137678804565, 5729.398413166641, 5767.452092051249, 5774.2782152230975, 5783.392743761887, 5808.36108028125, 6172.22847144107, 6278.15244941927, 6297.4962003039645, 6314.305426403092, 6320.683965801692, 6221.9377806221355, 6221.968890155611, 6224.906626400608, 6227.9377206227355, 6235.0, 6149.677819268879, 6221.09794079865, 6296.811095667045, 6355.269144048401, 6356.841078973062, 6169.784057557964, 6232.376762323822, 6240.063990401385, 6289.433950944345, 6294.748210071546, 6177.598456100339, 6272.6550039747335, 6288.119663247149, 6302.464290535264, 6312.084747711556, 4940.0, 4947.084789313988, 4949.158643030693, 4951.826686066042, 4969.130402179604, 3499.842507087187, 4373.016071383977, 4388.780560972003, 4425.09285596452, 4433.246348120827, 5740.0, 5758.452946970115, 5765.625234359753, 5773.220615216976, 5810.970945145247, 6273.9372606273355, 6275.6862156892885, 6279.183706118267, 6281.214848143982, 6281.277653069956, 5458.2904222450825, 5591.944080559224, 5605.439456054443, 5738.626989245686, 5738.655680659173, 5395.433479484676, 5432.429594892558, 5446.210299506629, 5450.31871016123, 5471.534919531805, 6287.46556542699, 6324.462420694293, 6338.619682819046, 6354.110424540568, 6355.205599300088, 5078.7206703631655, 6299.559030867978, 6303.401176888188, 6326.2092238470195, 6338.746450141943, 6148.462884278931, 6233.625982441068, 6242.719077641608, 6259.123722678738, 6290.559660823698, 6229.563930525001, 6253.906191407224, 6254.8123556293385, 6269.96865015672, 6274.341194174728, 6287.2140982377205, 6289.245290565162, 6316.368363163647, 6324.177856878577, 6326.557141000086, 5967.731452084753, 6053.667048312385, 6108.311716707834, 6135.845176233341, 6185.907211391743, 6180.845478863064, 6186.566940314135, 6194.256689197326, 6199.628022318675, 6209.658468784261, 5828.76684932598, 6028.728707208098, 6031.60794548353, 6068.939310607014, 6084.178635884099, 5951.196588460675, 6037.366076562073, 6040.244969378828, 6146.754129834757, 6179.81460556184, 5892.6759028252145, 5937.881242375042, 6008.789692360747, 6058.485028772604, 6064.272287325372], "value": 5721.659490273917, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.6719133350322, 517.2525700362976, 527.1512864288495, 529.7404271906765, 560.475954982092, 29769.55345669814, 30569.541456878163, 31594.210144746394, 39364.0, 40512.0, 41391.17217655652, 41899.16201675965, 41910.0, 41961.580384196146, 41964.58035419645, 9343.112404321579, 9466.24270058397, 9470.147686708195, 9476.099770521823, 9482.288828337867, 8499.320054395634, 8707.521086340248, 9276.165145136936, 9408.905910940906, 11128.554857805679, 11127.054200392935, 11355.1483638727, 11357.488912998935, 11360.60237891674, 11383.544658213661, 11353.20527563071, 11431.542738290458, 11434.714132146679, 11441.828372574419, 11450.026747726453, 11286.887131128666, 11299.661010169708, 11324.660260192208, 11350.09199264057, 11359.602413915556, 39292.80353598242, 39453.0, 39586.40620390696, 39603.0, 39690.60309396898, 2944.661363943131, 3054.7250747432836, 3186.2990142168687, 3256.690614391629, 3681.834317455721, 29133.708662913523, 29715.851420742758, 29960.250993725112, 30315.848420757975, 30362.937297194712, 3185.5221716742444, 3200.9039728808175, 3218.034589623104, 3220.951685724716, 3323.9501407478906, 511032.0, 511680.0, 512682.0, 512741.9999999963, 519305.0, 32181.51727724086, 32828.67171328304, 32987.0, 57812.71093644505, 58286.125708114414, 34912.476312855324, 34947.65052349444, 34948.47577286343, 34963.951081467596, 34967.0, 13976.930115349358, 14063.367148478343, 14099.436022559188, 14101.224432656303, 14107.78838317426, 12088.335141567299, 12167.817482737766, 12197.39013049344, 12201.938990305169, 12210.206336588093, 7187.101612298463, 7328.267173282736, 7338.816529586803, 7374.299441553044, 7374.705011799468, 3137.1686503076703, 3229.289556297611, 3244.270039241152, 3244.951325730116, 3246.269589342426, 892.3084609427616, 901.7925877048316, 907.1608761895352, 947.1854205383365, 1004.2417974429405, 28984.42031159365, 30254.54618180729, 30566.54150187749, 31097.53353699696, 31670.683293166774, 1017.5980487707315, 1046.4035499765062, 1076.8869268726828, 1092.3937214845678, 1093.7211011192096, 14377.712445751027, 14721.484748033767, 14738.263086845614, 15132.924335378253, 15138.924305378623, 586053.0, 693344.0, 693515.0, 693761.0, 694571.0, 46825.5317446828, 47782.5221747785, 54270.185947210826, 54697.0, 55041.1743823843, 1154.543955137716, 1214.4717048084112, 1331.1281110872394, 1364.8635136486273, 1381.7029338692028, 24881.0, 29066.952414969903, 29946.321313831333, 30086.0, 30215.0, 48358.758206208746, 48919.75540122419, 48931.75534122306, 48964.0, 49138.26292605685, 450126.0, 455098.0, 456202.0, 529544.0, 613503.0, 41391.79304103581, 41869.0, 42132.57867421286, 42180.15639687128, 42522.57477425186, 45925.31112033255, 45981.080378392915, 46036.76981615071, 46108.61674149781, 46357.0, 37588.62411375851, 38158.0, 38195.0, 38444.80777596206, 38595.80702096584, 37279.81360093182, 37999.86070067564, 38167.2366552673, 38771.41842872416, 39667.801660991514, 36814.26371472609, 38138.0, 38495.422568660935, 38532.42201366926, 38577.6142238574, 32063.198420038752, 33798.49302260419, 34219.4867076999, 34413.65586344105, 34546.0, 21857.67213491767, 24890.502189955736, 24954.0, 25409.0, 25415.47507149577], "value": 18578.73513887158, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.4663182433071, 348.6461241839532, 370.3907072865137, 383.804259827488, 401.8151650240889, 14730.042547234436, 14892.478763243289, 15937.0, 20344.491387715316, 20818.583628327426, 18246.726299105525, 19875.503112422197, 20460.28389006385, 20677.379678609628, 20909.9545022749, 5648.548116150719, 5937.37657545958, 5942.494887934531, 5951.196588460567, 5958.225430694004, 4704.694194877339, 5864.354920958713, 5920.615160014607, 5926.703664816763, 5927.081302398114, 5586.608937374385, 5878.941210587904, 5879.735411906453, 5880.117982302646, 5886.293644762634, 5762.711864406763, 5870.647761134345, 5871.031279838843, 5877.412258774131, 5883.294004719461, 5080.288759573663, 5138.845834624967, 5147.871303217412, 5155.922661160085, 5359.866003349908, 10950.288231265022, 12892.0, 12984.0, 13125.540606078837, 13133.212007279499, 2222.022310183514, 2475.2450502596703, 2487.589547724623, 2523.6340730594143, 2635.288472112519, 9685.079917407906, 9703.0, 9770.804583908282, 10185.388876667424, 10263.89736102637, 1675.7067513185266, 1726.6201435684254, 1733.6532693461359, 1777.7511148439228, 1793.4709260768104, 90256.0, 127907.72092279144, 129966.0, 137410.0, 140502.0, 10530.631427899987, 11235.494402751732, 11423.828642570368, 11560.826587601192, 11815.88184118165, 14570.344334504973, 14631.341589628491, 14633.634159145893, 14633.853661463248, 14650.120992740469, 8291.792705182297, 8531.701390451304, 8544.31645468361, 8655.956720216485, 8700.91299087001, 6593.406593406617, 8478.23695867375, 8494.193051660082, 8663.913360866436, 8668.349873759493, 4421.292593185075, 4458.6878918475395, 4495.617872480876, 4516.616087632522, 4545.272756358967, 1733.3673209278522, 1793.0591884559599, 1854.110027186931, 1914.3108480946848, 1920.6446807340687, 767.5087943716027, 904.3533873280504, 936.8266870628955, 984.5569493727851, 998.7852611688522, 20815.0, 21218.0, 21670.565981382595, 21736.565268694536, 21939.341819745434, 841.6296829395046, 911.6444586611227, 918.6830543462495, 965.8985806490358, 1003.9508713394521, 10748.87136850635, 11391.678054383769, 11490.597829076109, 11584.42077896102, 11589.710257243467, 620753.0, 621648.0, 624462.0, 626804.7319526747, 633696.8315158486, 111851.88148118577, 115428.0, 116111.6777664442, 131885.0, 132729.67270327365, 879.3756432932689, 921.7695576105973, 945.6122989574379, 993.1905497019906, 1003.3377970539409, 14782.556523304318, 18942.052897355352, 19559.511012224808, 20136.093875775332, 20199.091040902902, 37493.0, 55179.0, 55663.72168139133, 57397.42602573921, 57826.13260801007, 76144.0963975905, 79867.20132798598, 80163.0, 81398.0, 83316.16683833084, 7833.960830195812, 8033.6786528538205, 8230.917690823255, 8421.115782842713, 8515.74452766418, 9967.40195588267, 12893.35533223354, 13051.347432628521, 13181.80227296572, 13286.667833304246, 11234.662960111209, 11554.537818487166, 11631.76736465283, 11742.765144697229, 11791.469383877575, 9778.16885564735, 10735.336022916461, 11056.778864422506, 11188.944055279671, 11699.23954942926, 11493.827592586285, 11593.130515211562, 11741.354225517678, 11935.104867134825, 11935.164538482222, 8122.822190782422, 8132.146124656826, 8212.0, 8363.456375335583, 8365.749027529184, 4427.424434823452, 4736.597389221817, 6543.247526534509, 7036.859262814817, 8070.798230044061], "value": 9171.101106297887, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1457.9198144102077, 1497.169071165503, 1639.9590010249742, 1776.5203395083336, 1832.2029916986112, 8098.433109682317, 8517.744467665972, 8518.574071296425, 8522.062573116953, 8526.914730852697, 7432.777016689508, 7705.768826935187, 8116.797080073001, 8320.33437325014, 8349.165083491647, 2337.777911098447, 2370.928872133839, 2373.596488596943, 2375.251795684354, 2375.762423757628, 2098.2026829804654, 2357.610994185957, 2382.7140743110763, 2400.531896280226, 2411.3007227903886, 2087.311187308185, 2118.8410869184795, 2287.8970446329954, 2363.9763602364014, 2365.467769751809, 2334.5914464968732, 2349.4243910242017, 2353.0470159585225, 2354.540864531417, 2359.339384972202, 2334.0897050150456, 2386.1529157149134, 2389.7968672662846, 2392.9162479313313, 2394.976050239493, 8329.541875196861, 8382.119877412906, 8391.874121888177, 8403.831923361498, 8418.200270974248, 3745.6441638044344, 5204.635675502716, 5205.375354957391, 5207.0, 5207.427183009904, 3175.1903264667594, 3262.934741305184, 3490.127468132967, 3493.1441796759955, 3496.125968507873, 905.714699869539, 956.6651671914774, 1013.0679774607305, 1060.549266561716, 1060.8726952765717, 10754.139668826476, 10770.946145269223, 10890.945545272301, 10911.0, 10922.726931826688, 4541.341505481687, 4619.584237418649, 4738.07607516531, 4757.619390448755, 4764.071006153767, 9359.532023398802, 9371.812563748823, 9397.812043759086, 9399.718008459757, 9434.009429009855, 5211.947880521222, 5216.58267338612, 5222.895542089137, 5223.817166399158, 5236.947630523646, 4287.7427354358215, 4617.607503362185, 4685.320628508915, 4798.73606951621, 4832.540908613676, 3796.7342286040266, 3827.7129215308964, 3853.903652408656, 3890.1636148228267, 3918.8824335269987, 1121.5962253588702, 1246.401727170963, 1252.6242127361754, 1254.0469243374919, 1259.508791571288, 740.9407247420302, 786.3434032582858, 832.3466079127855, 877.5349064995562, 899.2581120575551, 5134.820281290137, 5149.815542425263, 5427.3215848019, 5610.719464026783, 5657.264555607745, 891.7636826241052, 903.9412438191497, 932.6269492203037, 946.7491114854569, 946.9857952130724, 3823.3691440912494, 3915.1190982028816, 3940.1922605866303, 4022.135240923157, 4060.4721386220194, 10074.697759067094, 10139.898601014043, 10279.588816447407, 10297.0, 10392.168626509861, 9854.704358869245, 10108.696739097839, 10288.485575721184, 10305.227107966932, 10313.535890884928, 7549.8297763846695, 7770.494917830323, 7811.80470488231, 8003.999500062492, 8025.317847982987, 5566.359868614999, 5696.088625819849, 5785.855353616025, 5828.125781132864, 5963.880722385441, 9456.810863782823, 9459.479728614991, 9514.38156519824, 9568.138867501959, 9657.130858222794, 9141.908580914373, 9553.474558899326, 9565.282603804882, 9635.710928672152, 9775.315727899264, 7192.928070719226, 7369.115706115301, 7372.815679607838, 7381.188069312479, 7385.335319821242, 7452.776416707507, 7591.164971853203, 7680.423968202519, 7695.769126926201, 7700.268474494914, 6809.0, 6862.107925969592, 6913.10129683138, 6998.405135563534, 7010.68451919675, 6800.353966373186, 6815.488838337045, 6842.521023528305, 6914.792556223321, 6916.585004899722, 6362.427381535684, 6364.427201551883, 6379.883520384007, 6419.197600299963, 6428.0576976865805, 4590.049577717538, 4618.399608050931, 4659.4874563797275, 4659.650526210616, 4746.43042834862, 4560.156371071363, 4636.953630463652, 4708.246680531099, 4709.835155769531, 4732.739699316433], "value": 4403.8861797983855, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.8006325793348, 306.4392162342913, 306.6534815658306, 312.54368621812165, 316.2788841441514, 5166.715830629314, 5221.503957124077, 5257.237700533425, 5271.130263506517, 5295.4704529547025, 4281.914361712764, 4567.246404343279, 5170.500554839092, 5188.792448302072, 5215.895682086356, 3083.753299736016, 3484.0767196692896, 3916.4908561886914, 3931.783751893645, 3974.582668819776, 3228.8547015384365, 3465.861365545375, 3794.829232684536, 3815.6756675682464, 4034.3343348347635, 3350.7486938479587, 3966.841326346943, 3985.342418500944, 3987.1626958338784, 3991.4696819110372, 3565.554305711786, 3772.020518353136, 3976.880693579197, 3980.6218409251073, 4019.5578486366476, 3435.9656403435897, 3454.8598962342367, 3465.7227421806197, 3470.8372695147154, 3482.8432720527385, 3966.682665386791, 5014.147594908874, 5018.598512119058, 5028.647994640377, 5043.293938848564, 4323.292299541664, 4341.804618792162, 4418.5702304010865, 4543.31850222466, 4587.839425620121, 5446.972765136188, 5472.644278121909, 5495.945040549584, 5508.476694713996, 5549.278593782823, 3080.4917188663844, 3794.886153415402, 3814.1608846053823, 3821.5796262411113, 3832.7125465590193, 5295.4969277918535, 5628.493435590775, 5658.076254073635, 5701.372848986608, 5781.653100813965, 3755.605661405568, 3757.530308711411, 3769.076576238839, 3774.641409066134, 3777.981110094432, 3823.7896915669903, 3828.8659896903473, 3832.9808350958624, 3839.9040023999064, 3854.5181852268465, 3793.3551296279697, 4498.955010449919, 4529.207388707029, 4559.882828706922, 4562.338460923148, 4561.042181141931, 4849.612031037508, 5224.440024388098, 5466.835994920159, 5543.861403464865, 1241.6026871401245, 1244.7552447552448, 1247.457356050121, 1250.1374051904156, 1260.7518556628872, 694.8783962806489, 844.7001314533375, 858.7337925243195, 860.1226748716399, 863.6718047142172, 4550.567696068868, 4770.759602503304, 4916.926246106311, 4926.236433352836, 5016.673916195436, 5416.729163541807, 5496.862578435571, 5616.522595579339, 5656.236408084938, 5676.290463692038, 4410.5368936261875, 4637.052437976058, 4719.7404142772475, 4851.175300198975, 5167.250748641426, 2427.478092210183, 2499.675042244533, 2818.689944106147, 2827.08119861046, 2837.276494493891, 4714.033623107255, 5112.821051263188, 5172.7068232941765, 5602.327720673464, 5629.521490673339, 4708.835190768306, 5136.691798492103, 5354.063038968165, 5377.973110134502, 5569.994801871323, 5905.822825315247, 5987.880242395215, 5997.430244126801, 6000.21997140369, 6009.0, 4771.475137734777, 5042.621803364836, 5720.0, 5731.0, 5791.8552036199435, 5396.622236443568, 5652.869426114711, 5665.915011274917, 5711.685857277889, 5726.914096288642, 4734.810607575659, 5005.399352077774, 5164.912561127362, 5865.648061116347, 5899.321578018411, 3284.21178917063, 3788.6590206881515, 3861.6524512793985, 3874.205787813548, 3879.80600969956, 4631.606313463259, 5138.845834624967, 5387.222216668569, 5654.349749778664, 5730.770769169187, 4849.636277279289, 5473.686315284223, 5494.862628434161, 5498.642588231752, 5508.724563771875, 4577.359169716309, 5461.508464238238, 5523.95613622946, 5564.582656300713, 5594.440555944373, 5123.948760512347, 5366.517013468807, 5377.61210437422, 5407.78368865241, 5462.317210348707, 4646.628269738345, 5353.357597088374, 5517.36550296721, 5522.502974732132, 5593.958205255134, 4897.7061376316105, 4903.877403064952, 4918.532739389752, 5305.761240744098, 5379.946200537945], "value": 3962.816554254089, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.7926632377123, 737.0970561062699, 756.6821934787389, 761.101899758285, 805.9758207253783, 13782.517611883573, 13821.447142114315, 14233.0, 14419.206943618097, 14459.78310325346, 12864.16382935111, 12954.93522532386, 12963.80554291684, 12965.611031669041, 12972.610821675342, 649.9837504062401, 666.4235436347573, 815.946963447377, 831.0816547714779, 838.8196537744384, 834.2366734437982, 834.9123342049058, 835.4861760017576, 836.93304535637, 840.0045945554507, 870.8824308718337, 871.5729292646621, 872.6291326186378, 876.8597024476085, 879.5118709116438, 875.382855087162, 876.9429987050853, 877.868319752036, 877.903430622631, 878.6002368922125, 646.7283740828863, 647.8088963755656, 648.8637386148914, 649.4804156674688, 649.6849028221309, 12380.62858114258, 12484.188527745668, 12686.80969785454, 12708.110432269745, 12890.355482225852, 13311.866881331256, 13466.057375983684, 13510.256935868523, 13575.253361065137, 13756.312184390841, 9000.909990900072, 10169.745756356077, 10542.0, 10657.467126643714, 11006.834897476545, 4134.813933373005, 4305.332673435622, 4340.739555626641, 4452.042810795662, 4522.253828118356, 77652.0, 77813.22186778173, 78108.21891781066, 79425.0, 80320.0, 10062.295639305179, 10088.092071713434, 10208.489575521195, 10209.336393134423, 10213.234007449471, 18415.90792046031, 19039.143238554298, 19099.0, 19112.426627200926, 19166.137523811463, 7126.821829454305, 7327.487075904742, 7403.481756277009, 7491.213422590658, 7546.396288296923, 4024.8993775155855, 4101.036256479725, 4108.322126849036, 4169.437125988014, 4217.831286748496, 3277.2789986202997, 3385.306012267479, 3390.3388839176123, 3392.3215356928713, 3397.3375191837354, 622.3496446213718, 626.6396821827376, 628.673089993201, 631.6525910749161, 633.5311869216838, 498.66589385112064, 501.078016449727, 514.0, 605.1527860994555, 618.4434009391583, 8107.675692972215, 8635.740927772176, 8717.30261579072, 8796.296296296408, 8865.64537418509, 576.7375843990974, 597.2593983460478, 604.6130476495047, 610.548194336197, 621.8165641135876, 4048.9392659110135, 4278.871633850989, 4287.464066991626, 4328.156009578102, 4392.121575684812, 74860.0, 75625.70394809054, 115886.0, 116615.66768664579, 126947.3652631731, 59090.40909590935, 70476.29523704697, 93911.53044234871, 98520.0, 113929.0, 77426.6128669353, 91223.54388228149, 91674.0, 92060.53969730108, 93908.0, 9134.588943497425, 9613.711588652352, 9638.325317227727, 9638.566264517973, 9670.661526846527, 37152.51389944372, 42752.57247427486, 52609.47390526046, 53329.6667583298, 53597.19604206018, 25042.37394065163, 25913.740862591887, 27414.0, 82087.0, 82526.17473825425, 10343.43111128865, 12308.09224570193, 12477.563285284972, 12510.0, 12832.871671283168, 12821.931464174455, 13542.864571354161, 13893.861061389256, 14190.716185676436, 15048.021878577858, 12593.433295501907, 13051.28217947984, 13364.198148111145, 13592.524261650795, 13649.181049137083, 12571.0, 13404.664883377603, 13428.758359262503, 13538.323083845966, 13907.652308691959, 12902.354882255662, 13154.0, 13784.793228101787, 13843.653908652364, 13865.792013120014, 9915.008499150028, 10225.642102526375, 10424.061834434937, 10873.184511161853, 11048.81676674121, 10419.479026048819, 10778.461076946278, 11233.0, 11385.373804440835, 11546.842044904899], "value": 7040.454779615729, "unit": "score"}], "name": "dromaeo_css", "value": 7021.13996673548, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 113.0, 115.0, 114.0, 116.0, 116.0, 115.0, 115.0, 116.0, 114.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 112.0, 111.0, 142.0, 110.0, 106.0, 111.0, 108.0, 138.0, 109.0], "value": 119.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 182.0, 182.0, 183.0, 181.0, 181.0, 183.0, 183.0, 183.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 58.0, 89.0, 64.0, 59.0, 61.0, 58.0, 81.0, 58.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 92.0, 70.0, 68.0, 68.0, 90.0, 67.0, 68.0, 90.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 85.0, 86.0, 84.0, 92.0, 85.0, 86.0, 85.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 163.0, 153.0, 95.0, 96.0, 96.0, 98.0, 96.0, 95.0, 96.0], "value": 110.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 81.0, 79.0, 83.0, 83.0, 84.0, 84.0, 83.0, 82.0], "value": 82.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 53.0, 53.0, 56.0, 58.0, 59.0, 59.0, 57.0, 57.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 81.0, 77.0, 72.0, 70.0, 69.0, 71.0, 70.0, 72.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 84.0, 82.0, 84.0, 84.0, 81.0, 83.0, 82.0, 94.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 150.0, 147.0, 137.0, 141.0, 130.0, 142.0, 142.0, 181.0, 137.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 165.0, 161.0, 164.0, 175.0, 219.0, 203.0, 163.0, 219.0], "value": 179.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 66.0, 67.0, 64.0, 66.0, 67.0, 66.0, 66.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1443.4, "alertThreshold": 2.0}]} 20:11:37 INFO - Return code: 0 20:11:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:11:37 INFO - # TBPL SUCCESS # 20:11:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:11:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:11:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:11:37 INFO - 2017-01-13 20:11:37.180 screenresolution[1661:10380] starting screenresolution argv=screenresolution get 20:11:37 INFO - 2017-01-13 20:11:37.183 screenresolution[1661:10380] Display 0: 1600x1200x32@60 20:11:37 INFO - 2017-01-13 20:11:37.189 screenresolution[1662:10383] starting screenresolution argv=screenresolution list 20:11:37 INFO - Available Modes on Display 0 20:11:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:11:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:11:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:11:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:11:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:11:37 INFO - Intel Iris: 20:11:37 INFO - Chipset Model: Intel Iris 20:11:37 INFO - Type: GPU 20:11:37 INFO - Bus: Built-In 20:11:37 INFO - VRAM (Dynamic, Max): 1536 MB 20:11:37 INFO - Vendor: Intel (0x8086) 20:11:37 INFO - Device ID: 0x0a2e 20:11:37 INFO - Revision ID: 0x0009 20:11:37 INFO - Displays: 20:11:37 INFO - Display: 20:11:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:11:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:11:37 INFO - Display Serial Number: 1600x1200 60 20:11:37 INFO - Main Display: Yes 20:11:37 INFO - Mirror: Off 20:11:37 INFO - Online: Yes 20:11:37 INFO - Rotation: Supported 20:11:37 INFO - Return code: 0 20:11:37 INFO - Running post-action listener: _resource_record_post_action 20:11:37 INFO - [mozharness: 2017-01-14 04:11:37.434781Z] Finished run-tests step (success) 20:11:37 INFO - Running post-run listener: _resource_record_post_run 20:11:37 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 32431104; Write bytes: 661488640; Read time: 200; Write time: 1888 20:11:37 INFO - TinderboxPrint: CPU usage
53.3% 20:11:37 INFO - TinderboxPrint: I/O read bytes / time
32,431,104 / 200 20:11:37 INFO - TinderboxPrint: I/O write bytes / time
661,488,640 / 1,888 20:11:37 INFO - TinderboxPrint: CPU idle
2,259.2 (46.7%) 20:11:37 INFO - TinderboxPrint: CPU system
963.7 (19.9%) 20:11:37 INFO - TinderboxPrint: CPU user
1,614.6 (33.4%) 20:11:37 INFO - TinderboxPrint: Swap in / out
377,475,072 / 0 20:11:37 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 348541440; Write bytes: 346564608; Read time: 26864; Write time: 718 20:11:37 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 29043712; Write bytes: 307608576; Read time: 176; Write time: 1140 20:11:37 INFO - Running post-run listener: _upload_blobber_files 20:11:37 INFO - Blob upload gear active. 20:11:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:11:37 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 20:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:11:38 INFO - (blobuploader) - INFO - Open directory for files ... 20:11:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:11:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:11:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:11:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:11:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:11:39 INFO - (blobuploader) - INFO - Done attempting. 20:11:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:11:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:11:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:11:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:11:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:11:40 INFO - (blobuploader) - INFO - Done attempting. 20:11:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:11:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:11:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:11:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:11:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:11:42 INFO - (blobuploader) - INFO - Done attempting. 20:11:42 INFO - (blobuploader) - INFO - Iteration through files over. 20:11:42 INFO - Return code: 0 20:11:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:11:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:11:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da4a914d75149247e1c00f0491534ec17754f3275249c1aae4a04d0dae63cf7ee9d5db62e112f59ecd352412ddd818e49a436d0ded0d437e797cc7e76bd36c94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9d349eb13ba5dfeefc7c88140119e6f0384822f4e4c4d4ea9866ea67d40830a2db0d059003bdcaa1cacc79058a77940be6636ee439d026672236c4cd6067dbb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff9905048dbc5c0dc22b78b273802341fefef061844d5889738e4c90fed208c4bcd74b90ed8391a7ef8bcd6a891958ce06a3fdcd507d40639cd0ee4c741c4337"} 20:11:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:11:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:11:42 INFO - Contents: 20:11:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da4a914d75149247e1c00f0491534ec17754f3275249c1aae4a04d0dae63cf7ee9d5db62e112f59ecd352412ddd818e49a436d0ded0d437e797cc7e76bd36c94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9d349eb13ba5dfeefc7c88140119e6f0384822f4e4c4d4ea9866ea67d40830a2db0d059003bdcaa1cacc79058a77940be6636ee439d026672236c4cd6067dbb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff9905048dbc5c0dc22b78b273802341fefef061844d5889738e4c90fed208c4bcd74b90ed8391a7ef8bcd6a891958ce06a3fdcd507d40639cd0ee4c741c4337"} 20:11:42 INFO - Running post-run listener: copy_logs_to_upload_dir 20:11:42 INFO - Copying logs to upload dir... 20:11:42 INFO - mkdir: /builds/slave/test/build/upload/logs 20:11:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.127972 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-01-13 20:11:43.017118) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 20:11:43.020924) ========= 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.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da4a914d75149247e1c00f0491534ec17754f3275249c1aae4a04d0dae63cf7ee9d5db62e112f59ecd352412ddd818e49a436d0ded0d437e797cc7e76bd36c94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9d349eb13ba5dfeefc7c88140119e6f0384822f4e4c4d4ea9866ea67d40830a2db0d059003bdcaa1cacc79058a77940be6636ee439d026672236c4cd6067dbb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff9905048dbc5c0dc22b78b273802341fefef061844d5889738e4c90fed208c4bcd74b90ed8391a7ef8bcd6a891958ce06a3fdcd507d40639cd0ee4c741c4337"} build_url:https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011084 build_url: 'https://queue.taskcluster.net/v1/task/OkvBlPpXQISBOigoec6zyw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da4a914d75149247e1c00f0491534ec17754f3275249c1aae4a04d0dae63cf7ee9d5db62e112f59ecd352412ddd818e49a436d0ded0d437e797cc7e76bd36c94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9d349eb13ba5dfeefc7c88140119e6f0384822f4e4c4d4ea9866ea67d40830a2db0d059003bdcaa1cacc79058a77940be6636ee439d026672236c4cd6067dbb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff9905048dbc5c0dc22b78b273802341fefef061844d5889738e4c90fed208c4bcd74b90ed8391a7ef8bcd6a891958ce06a3fdcd507d40639cd0ee4c741c4337"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 20:11:43.054670) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 20:11:43.055091) ========= 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.8gJtQy7GeB/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.SmLjJlGPOQ/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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.005300 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 20:11:43.103183) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 20:11:43.103472) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 20:11:43.103785) ========= ========= Total master_lag: 0.18 =========