builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0171 starttime: 1479152647.34 results: success (0) buildid: 20161114081542 builduid: c0b4dd1356ae41cf97e49288305ed2d3 revision: f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:07.340708) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:07.341191) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:07.341472) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:07.372408) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:07.372784) ========= 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.bKxFfr6nyI/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.WNFZppOZUi/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-14 11:44:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.5M=0.001s 2016-11-14 11:44:07 (15.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131105 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:07.527603) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:07.527920) ========= 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.bKxFfr6nyI/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.WNFZppOZUi/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.025252 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:07.577059) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 11:44:07.577422) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d --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 f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bKxFfr6nyI/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.WNFZppOZUi/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-14 11:44:07,662 truncating revision to first 12 chars 2016-11-14 11:44:07,662 Setting DEBUG logging. 2016-11-14 11:44:07,662 attempt 1/10 2016-11-14 11:44:07,662 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f11ca24b2382?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 11:44:09,065 unpacking tar archive at: mozilla-aurora-f11ca24b2382/testing/mozharness/ program finished with exit code 0 elapsedTime=1.659991 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 11:44:09.265516) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:09.265864) ========= script_repo_revision: f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:09.266293) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:09.266566) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:09.285093) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-14 11:44:09.285462) ========= /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.bKxFfr6nyI/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.WNFZppOZUi/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 11:44:09 INFO - MultiFileLogger online at 20161114 11:44:09 in /builds/slave/test 11:44:09 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 11:44:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:44:09 INFO - {'append_to_log': False, 11:44:09 INFO - 'base_work_dir': '/builds/slave/test', 11:44:09 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:44:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:44:09 INFO - 'branch': 'Mozilla-Aurora', 11:44:09 INFO - 'buildbot_json_path': 'buildprops.json', 11:44:09 INFO - 'config_files': ('talos/mac_config.py',), 11:44:09 INFO - 'default_actions': ('clobber', 11:44:09 INFO - 'read-buildbot-config', 11:44:09 INFO - 'download-and-extract', 11:44:09 INFO - 'populate-webroot', 11:44:09 INFO - 'create-virtualenv', 11:44:09 INFO - 'install', 11:44:09 INFO - 'run-tests'), 11:44:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:44:09 INFO - 'download_minidump_stackwalk': True, 11:44:09 INFO - 'download_symbols': 'ondemand', 11:44:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:44:09 INFO - 'tooltool.py': '/tools/tooltool.py', 11:44:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:44:09 INFO - '/tools/misc-python/virtualenv.py')}, 11:44:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:44:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:44:09 INFO - 'installer_path': 'installer.exe', 11:44:09 INFO - 'log_level': 'info', 11:44:09 INFO - 'log_name': 'talos', 11:44:09 INFO - 'log_to_console': True, 11:44:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:44:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:44:09 INFO - 'opt_config_files': (), 11:44:09 INFO - 'pip_index': False, 11:44:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:44:09 INFO - 'cmd': ('bash', 11:44:09 INFO - '-c', 11:44:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:44:09 INFO - 'enabled': True, 11:44:09 INFO - 'halt_on_failure': False, 11:44:09 INFO - 'name': 'check_screen_resolution'},), 11:44:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:44:09 INFO - 'cmd': ('bash', 11:44:09 INFO - '-c', 11:44:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:44:09 INFO - 'enabled': True, 11:44:09 INFO - 'halt_on_failure': False, 11:44:09 INFO - 'name': 'check_screen_resolution'},), 11:44:09 INFO - 'run_cmd_checks_enabled': True, 11:44:09 INFO - 'sps_profile': False, 11:44:09 INFO - 'sps_profile_interval': 0, 11:44:09 INFO - 'suite': 'dromaeojs', 11:44:09 INFO - 'system_bits': '32', 11:44:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:44:09 INFO - 'title': 't-yosemite-r7-0171', 11:44:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:44:09 INFO - 'use_talos_json': True, 11:44:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:44:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:44:09 INFO - 'work_dir': 'build'} 11:44:09 INFO - [mozharness: 2016-11-14 19:44:09.493936Z] Running clobber step. 11:44:09 INFO - Running pre-action listener: _resource_record_pre_action 11:44:09 INFO - Running main action method: clobber 11:44:09 INFO - rmtree: /builds/slave/test/build 11:44:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:44:11 INFO - Running post-action listener: _resource_record_post_action 11:44:11 INFO - [mozharness: 2016-11-14 19:44:11.861637Z] Finished clobber step (success) 11:44:11 INFO - [mozharness: 2016-11-14 19:44:11.861798Z] Running read-buildbot-config step. 11:44:11 INFO - Running pre-action listener: _resource_record_pre_action 11:44:11 INFO - Running main action method: read_buildbot_config 11:44:11 INFO - Using buildbot properties: 11:44:11 INFO - { 11:44:11 INFO - "project": "", 11:44:11 INFO - "product": "firefox", 11:44:11 INFO - "script_repo_revision": "production", 11:44:11 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 11:44:11 INFO - "repository": "", 11:44:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 11:44:11 INFO - "buildid": "20161114081542", 11:44:11 INFO - "pgo_build": "False", 11:44:11 INFO - "basedir": "/builds/slave/test", 11:44:11 INFO - "buildnumber": 129, 11:44:11 INFO - "slavename": "t-yosemite-r7-0171", 11:44:11 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 11:44:11 INFO - "platform": "yosemite_r7", 11:44:11 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:44:11 INFO - "branch": "mozilla-aurora", 11:44:11 INFO - "repo_path": "releases/mozilla-aurora", 11:44:11 INFO - "revision": "f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d", 11:44:11 INFO - "stage_platform": "macosx64", 11:44:11 INFO - "builduid": "c0b4dd1356ae41cf97e49288305ed2d3", 11:44:11 INFO - "slavebuilddir": "test" 11:44:11 INFO - } 11:44:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 11:44:11 INFO - Running post-action listener: _resource_record_post_action 11:44:11 INFO - [mozharness: 2016-11-14 19:44:11.865167Z] Finished read-buildbot-config step (success) 11:44:11 INFO - [mozharness: 2016-11-14 19:44:11.865269Z] Running download-and-extract step. 11:44:11 INFO - Running pre-action listener: _resource_record_pre_action 11:44:11 INFO - Running main action method: download_and_extract 11:44:11 INFO - mkdir: /builds/slave/test/build/tests 11:44: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')]} 11:44:11 INFO - trying https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 11:44:11 INFO - Downloading https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/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 11:44:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/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 11:44:12 INFO - Downloaded 1199 bytes. 11:44:12 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 11:44:12 INFO - Using the following test package requirements: 11:44:12 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:44:12 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:12 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 11:44:12 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:12 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 11:44:12 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:12 INFO - u'jsshell-mac.zip'], 11:44:12 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:12 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 11:44:12 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:44:12 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:12 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 11:44:12 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:12 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 11:44:12 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:12 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 11:44:12 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:12 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 11:44:12 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:44:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:44:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:44:12 INFO - Fetch https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:44:13 INFO - Http code: 200 11:44:13 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 11:44:13 INFO - x-amz-cf-id: nz_XhbZKGl-Okk6177uEC2Sn4NvYdP7sJxSN4LVxacvvkplP19V97A== 11:44:13 INFO - x-amz-version-id: beIhMZs6B_1lhCUU5tXTJZFEtB99JGu9 11:44:13 INFO - Expected file size: 16327504 11:44:13 INFO - Obtained file size: 16327504 11:44:20 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 11:44:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:44:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:44:20 INFO - Fetch https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:44:20 INFO - Http code: 200 11:44:20 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 11:44:20 INFO - x-amz-cf-id: akGxM2lYnc_wFZ9MKA2GXR0VdH9BUSIeFPFqgQWPfk80rPOV24tLsQ== 11:44:20 INFO - x-amz-version-id: beIhMZs6B_1lhCUU5tXTJZFEtB99JGu9 11:44:21 INFO - Expected file size: 16327504 11:44:21 INFO - Obtained file size: 16327504 11:44:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 11:44:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:44:27 INFO - Fetch https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 11:44:28 INFO - Http code: 200 11:44:28 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 11:44:28 INFO - x-amz-cf-id: l3pFwmUT7WUpJg0xJNS25g4cOZS7j50eCUrHjBSBfyveojDnL0hgYg== 11:44:28 INFO - x-amz-version-id: fL.n5SYgNX8ZLi3RUYlMNYmEt036hZfV 11:44:28 INFO - Expected file size: 14409038 11:44:28 INFO - Obtained file size: 14409038 11:44:28 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')]} 11:44:28 INFO - trying https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:44:28 INFO - Downloading https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 11:44:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/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 11:44:31 INFO - Downloaded 114359577 bytes. 11:44:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:44:31 INFO - mkdir: /builds/slave/test/properties 11:44:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:44:31 INFO - Writing to file /builds/slave/test/properties/build_url 11:44:31 INFO - Contents: 11:44:31 INFO - build_url:https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:44:32 INFO - Running post-action listener: _resource_record_post_action 11:44:32 INFO - Running post-action listener: set_extra_try_arguments 11:44:32 INFO - [mozharness: 2016-11-14 19:44:32.237863Z] Finished download-and-extract step (success) 11:44:32 INFO - [mozharness: 2016-11-14 19:44:32.238015Z] Running populate-webroot step. 11:44:32 INFO - Running pre-action listener: _resource_record_pre_action 11:44:32 INFO - Running main action method: populate_webroot 11:44:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:44:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:44:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:44:32 INFO - '--tppagecycles', 11:44:32 INFO - '7'], 11:44:32 INFO - 'tests': ['tsvgm']}}, 11:44:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:44:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:44:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:44:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:44:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:44:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:44:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:32 INFO - 'talos_options': ['--disable-e10s'], 11:44:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:44:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:44:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:32 INFO - 'talos_options': ['--disable-e10s'], 11:44:32 INFO - 'tests': ['damp', 'tps']}, 11:44:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:32 INFO - 'tests': ['damp', 'tps']}, 11:44:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:44:32 INFO - 'tests': ['dromaeo_dom']}, 11:44:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:44:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:44:32 INFO - 'tests': ['basic_compositor_video']}, 11:44:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:44:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:44:32 INFO - 'tests': ['a11yr', 11:44:32 INFO - 'ts_paint', 11:44:32 INFO - 'tpaint', 11:44:32 INFO - 'sessionrestore', 11:44:32 INFO - 'sessionrestore_no_auto_restore', 11:44:32 INFO - 'tabpaint']}, 11:44:32 INFO - 'other-e10s': {'tests': ['a11yr', 11:44:32 INFO - 'ts_paint', 11:44:32 INFO - 'tpaint', 11:44:32 INFO - 'sessionrestore', 11:44:32 INFO - 'sessionrestore_no_auto_restore', 11:44:32 INFO - 'tabpaint']}, 11:44:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:44:32 INFO - 'ts_paint', 11:44:32 INFO - 'tpaint', 11:44:32 INFO - 'sessionrestore', 11:44:32 INFO - 'sessionrestore_no_auto_restore', 11:44:32 INFO - 'tabpaint']}, 11:44:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:44:32 INFO - 'ts_paint', 11:44:32 INFO - 'tpaint', 11:44:32 INFO - 'sessionrestore', 11:44:32 INFO - 'sessionrestore_no_auto_restore', 11:44:32 INFO - 'tabpaint']}, 11:44:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:44:32 INFO - 'tests': ['a11yr', 11:44:32 INFO - 'ts_paint', 11:44:32 INFO - 'tpaint', 11:44:32 INFO - 'sessionrestore', 11:44:32 INFO - 'sessionrestore_no_auto_restore', 11:44:32 INFO - 'tabpaint']}, 11:44:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:44:32 INFO - 'tests': ['a11yr', 11:44:32 INFO - 'ts_paint', 11:44:32 INFO - 'tpaint', 11:44:32 INFO - 'sessionrestore', 11:44:32 INFO - 'sessionrestore_no_auto_restore', 11:44:32 INFO - 'tabpaint']}, 11:44:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:44:32 INFO - 'tests': ['tsvgx', 11:44:32 INFO - 'tsvgr_opacity', 11:44:32 INFO - 'tart', 11:44:32 INFO - 'tscrollx', 11:44:32 INFO - 'cart']}, 11:44:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:44:32 INFO - 'tsvgr_opacity', 11:44:32 INFO - 'tart', 11:44:32 INFO - 'tscrollx', 11:44:32 INFO - 'cart']}, 11:44:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:32 INFO - 'talos_options': ['--disable-e10s'], 11:44:32 INFO - 'tests': ['tp5o']}, 11:44:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:32 INFO - 'tests': ['tp5o']}, 11:44:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:32 INFO - 'talos_options': ['--disable-e10s', 11:44:32 INFO - '--xperf_path', 11:44:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:44:32 INFO - 'tests': ['tp5n']}, 11:44:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:32 INFO - 'talos_options': ['--xperf_path', 11:44:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:44:32 INFO - 'tests': ['tp5n']}}, 11:44:32 INFO - 'talos.zip': {'path': '', 11:44:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:44:32 INFO - Running post-action listener: _resource_record_post_action 11:44:32 INFO - [mozharness: 2016-11-14 19:44:32.256536Z] Finished populate-webroot step (success) 11:44:32 INFO - [mozharness: 2016-11-14 19:44:32.256649Z] Running create-virtualenv step. 11:44:32 INFO - Running pre-action listener: _resource_record_pre_action 11:44:32 INFO - Running main action method: create_virtualenv 11:44:32 INFO - Creating virtualenv /builds/slave/test/build/venv 11:44:32 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 11:44:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:44:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:44:32 INFO - Using real prefix '/tools/python27' 11:44:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:44:33 INFO - Installing distribute.............................................................................................................................................................................................done. 11:44:35 INFO - Installing pip.................done. 11:44:35 INFO - Return code: 0 11:44:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:44:36 INFO - Reading from file tmpfile_stdout 11:44:36 INFO - Output received: 11:44:36 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) 11:44:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:36 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 0x1009aa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100920570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda3588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007bed30>, '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 0x100624db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007b6b20>, '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.bKxFfr6nyI/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.WNFZppOZUi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 11:44:36 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 11:44:36 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 11:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKxFfr6nyI/Render', 11:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:36 INFO - 'HOME': '/Users/cltbld', 11:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:36 INFO - 'LOGNAME': 'cltbld', 11:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:36 INFO - 'MOZ_NO_REMOTE': '1', 11:44:36 INFO - 'NO_EM_RESTART': '1', 11:44:36 INFO - 'PAGER': '/bin/cat', 11:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:36 INFO - 'PWD': '/builds/slave/test', 11:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:36 INFO - 'SHELL': '/bin/bash', 11:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNFZppOZUi/Listeners', 11:44:36 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 11:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:36 INFO - 'USER': 'cltbld', 11:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:36 INFO - 'XPC_FLAGS': '0x0', 11:44:36 INFO - 'XPC_SERVICE_NAME': '0', 11:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:36 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 11:44:36 INFO - Cleaning up... 11:44:36 INFO - Return code: 0 11:44:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:36 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 0x1009aa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100920570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda3588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007bed30>, '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 0x100624db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007b6b20>, '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.bKxFfr6nyI/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.WNFZppOZUi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 11:44:36 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 11:44:36 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 11:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKxFfr6nyI/Render', 11:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:36 INFO - 'HOME': '/Users/cltbld', 11:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:36 INFO - 'LOGNAME': 'cltbld', 11:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:36 INFO - 'MOZ_NO_REMOTE': '1', 11:44:36 INFO - 'NO_EM_RESTART': '1', 11:44:36 INFO - 'PAGER': '/bin/cat', 11:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:36 INFO - 'PWD': '/builds/slave/test', 11:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:36 INFO - 'SHELL': '/bin/bash', 11:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNFZppOZUi/Listeners', 11:44:36 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 11:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:36 INFO - 'USER': 'cltbld', 11:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:36 INFO - 'XPC_FLAGS': '0x0', 11:44:36 INFO - 'XPC_SERVICE_NAME': '0', 11:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:36 INFO - Downloading/unpacking psutil>=3.1.1 11:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:44:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:44:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:44:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:44:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:44:40 INFO - Installing collected packages: psutil 11:44:40 INFO - Running setup.py install for psutil 11:44:40 INFO - building 'psutil._psutil_osx' extension 11:44:40 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 11:44:40 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 11:44:40 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 11:44:40 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 11:44:41 INFO - building 'psutil._psutil_posix' extension 11:44:41 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 11:44:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:44:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:44:41 INFO - ^ 11:44:41 INFO - 1 warning generated. 11:44:41 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 11:44:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:44:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:44:41 INFO - Successfully installed psutil 11:44:41 INFO - Cleaning up... 11:44:41 INFO - Return code: 0 11:44:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:44:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1009aa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100920570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda3588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007bed30>, '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 0x100624db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007b6b20>, '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.bKxFfr6nyI/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.WNFZppOZUi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 11:44:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:44:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKxFfr6nyI/Render', 11:44:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:41 INFO - 'HOME': '/Users/cltbld', 11:44:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:41 INFO - 'LOGNAME': 'cltbld', 11:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:41 INFO - 'MOZ_NO_REMOTE': '1', 11:44:41 INFO - 'NO_EM_RESTART': '1', 11:44:41 INFO - 'PAGER': '/bin/cat', 11:44:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:41 INFO - 'PWD': '/builds/slave/test', 11:44:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:41 INFO - 'SHELL': '/bin/bash', 11:44:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNFZppOZUi/Listeners', 11:44:41 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 11:44:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:41 INFO - 'USER': 'cltbld', 11:44:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:41 INFO - 'XPC_FLAGS': '0x0', 11:44:41 INFO - 'XPC_SERVICE_NAME': '0', 11:44:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:44:41 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 11:44:41 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 11:44:41 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 11:44:41 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 11:44:41 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 11:44:41 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 11:44:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:44:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:44:44 INFO - Installing collected packages: mozsystemmonitor 11:44:44 INFO - Running setup.py install for mozsystemmonitor 11:44:44 INFO - Successfully installed mozsystemmonitor 11:44:44 INFO - Cleaning up... 11:44:44 INFO - Return code: 0 11:44:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:44 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 0x1009aa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100920570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda3588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007bed30>, '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 0x100624db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007b6b20>, '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.bKxFfr6nyI/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.WNFZppOZUi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 11:44:44 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 11:44:44 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 11:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKxFfr6nyI/Render', 11:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:44 INFO - 'HOME': '/Users/cltbld', 11:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:44 INFO - 'LOGNAME': 'cltbld', 11:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:44 INFO - 'MOZ_NO_REMOTE': '1', 11:44:44 INFO - 'NO_EM_RESTART': '1', 11:44:44 INFO - 'PAGER': '/bin/cat', 11:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:44 INFO - 'PWD': '/builds/slave/test', 11:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:44 INFO - 'SHELL': '/bin/bash', 11:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNFZppOZUi/Listeners', 11:44:44 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 11:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:44 INFO - 'USER': 'cltbld', 11:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:44 INFO - 'XPC_FLAGS': '0x0', 11:44:44 INFO - 'XPC_SERVICE_NAME': '0', 11:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:44 INFO - Downloading/unpacking jsonschema==2.5.1 11:44:44 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 11:44:44 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 11:44:44 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 11:44:44 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 11:44:44 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 11:44:44 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 11:44:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:44:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:44:47 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 11:44:47 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 11:44:47 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 11:44:47 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 11:44:47 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 11:44:47 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 11:44:47 INFO - Downloading functools32-3.2.3-2.tar.gz 11:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:44:47 INFO - warning: no files found matching '*.txt' 11:44:47 INFO - no previously-included directories found matching 'build' 11:44:47 INFO - no previously-included directories found matching 'dist' 11:44:47 INFO - no previously-included directories found matching '.git*' 11:44:47 INFO - Installing collected packages: jsonschema, functools32 11:44:47 INFO - Running setup.py install for functools32 11:44:47 INFO - warning: no files found matching '*.txt' 11:44:47 INFO - no previously-included directories found matching 'build' 11:44:47 INFO - no previously-included directories found matching 'dist' 11:44:47 INFO - no previously-included directories found matching '.git*' 11:44:47 INFO - Successfully installed jsonschema functools32 11:44:47 INFO - Cleaning up... 11:44:48 INFO - Return code: 0 11:44:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:44: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')]} 11:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44: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')]} 11:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:48 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 0x1009aa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100920570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda3588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007bed30>, '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 0x100624db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007b6b20>, '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.bKxFfr6nyI/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.WNFZppOZUi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 11:44:48 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 11:44:48 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 11:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKxFfr6nyI/Render', 11:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:48 INFO - 'HOME': '/Users/cltbld', 11:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:48 INFO - 'LOGNAME': 'cltbld', 11:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:48 INFO - 'MOZ_NO_REMOTE': '1', 11:44:48 INFO - 'NO_EM_RESTART': '1', 11:44:48 INFO - 'PAGER': '/bin/cat', 11:44:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:48 INFO - 'PWD': '/builds/slave/test', 11:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:48 INFO - 'SHELL': '/bin/bash', 11:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNFZppOZUi/Listeners', 11:44:48 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 11:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:48 INFO - 'USER': 'cltbld', 11:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:48 INFO - 'XPC_FLAGS': '0x0', 11:44:48 INFO - 'XPC_SERVICE_NAME': '0', 11:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:44:48 INFO - Cleaning up... 11:44:48 INFO - Return code: 0 11:44:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:44: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')]} 11:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44: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')]} 11:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:48 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 0x1009aa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100920570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda3588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007bed30>, '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 0x100624db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007b6b20>, '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.bKxFfr6nyI/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.WNFZppOZUi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 11:44:48 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 11:44:48 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 11:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKxFfr6nyI/Render', 11:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:48 INFO - 'HOME': '/Users/cltbld', 11:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:48 INFO - 'LOGNAME': 'cltbld', 11:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:48 INFO - 'MOZ_NO_REMOTE': '1', 11:44:48 INFO - 'NO_EM_RESTART': '1', 11:44:48 INFO - 'PAGER': '/bin/cat', 11:44:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:48 INFO - 'PWD': '/builds/slave/test', 11:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:48 INFO - 'SHELL': '/bin/bash', 11:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNFZppOZUi/Listeners', 11:44:48 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 11:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:48 INFO - 'USER': 'cltbld', 11:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:48 INFO - 'XPC_FLAGS': '0x0', 11:44:48 INFO - 'XPC_SERVICE_NAME': '0', 11:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:48 INFO - Downloading/unpacking blobuploader==1.2.4 11:44:48 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 11:44:48 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 11:44:48 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 11:44:48 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 11:44:48 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 11:44:48 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 11:44:51 INFO - Downloading blobuploader-1.2.4.tar.gz 11:44:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:44:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:44:51 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 11:44:51 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 11:44:51 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 11:44:51 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 11:44:51 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 11:44:51 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 11:44:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:44:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:44:51 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 11:44:51 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 11:44:51 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 11:44:51 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 11:44:51 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 11:44:51 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 11:44:52 INFO - Downloading docopt-0.6.1.tar.gz 11:44:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:44:52 INFO - Installing collected packages: blobuploader, requests, docopt 11:44:52 INFO - Running setup.py install for blobuploader 11:44:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:44:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:44:52 INFO - Running setup.py install for requests 11:44:53 INFO - Running setup.py install for docopt 11:44:53 INFO - Successfully installed blobuploader requests docopt 11:44:53 INFO - Cleaning up... 11:44:53 INFO - Return code: 0 11:44:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:44:53 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')]} 11:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:53 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')]} 11:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:53 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 0x1009aa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100920570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda3588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007bed30>, '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 0x100624db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007b6b20>, '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.bKxFfr6nyI/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.WNFZppOZUi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 11:44:53 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 11:44:53 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 11:44:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKxFfr6nyI/Render', 11:44:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:53 INFO - 'HOME': '/Users/cltbld', 11:44:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:53 INFO - 'LOGNAME': 'cltbld', 11:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:53 INFO - 'MOZ_NO_REMOTE': '1', 11:44:53 INFO - 'NO_EM_RESTART': '1', 11:44:53 INFO - 'PAGER': '/bin/cat', 11:44:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:53 INFO - 'PWD': '/builds/slave/test', 11:44:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:53 INFO - 'SHELL': '/bin/bash', 11:44:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNFZppOZUi/Listeners', 11:44:53 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 11:44:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:53 INFO - 'USER': 'cltbld', 11:44:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:53 INFO - 'XPC_FLAGS': '0x0', 11:44:53 INFO - 'XPC_SERVICE_NAME': '0', 11:44:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:44:53 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Abajv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:44:53 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-yafFtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:44:53 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-mtP9_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:44:53 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-5XAZJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:44:53 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-wSsMP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:44:54 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-ZnvGeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:44:54 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-H0lWgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:44:54 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-ysQzC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:44:54 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-k0I7x8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:44:54 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-ZhiL1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:44:54 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-e0fcLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:44:54 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-0pNxok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:44:54 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-9KB3PZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:44:54 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-EvRGoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:44:54 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-ySXYbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:44:55 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-iOdDBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:44:55 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-K3mDPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:44:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:44:55 INFO - Running setup.py install for manifestparser 11:44:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:44:55 INFO - Running setup.py install for mozcrash 11:44:55 INFO - Running setup.py install for mozdebug 11:44:55 INFO - Running setup.py install for mozdevice 11:44:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:44:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:44:55 INFO - Running setup.py install for mozfile 11:44:56 INFO - Running setup.py install for mozhttpd 11:44:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:44:56 INFO - Running setup.py install for mozinfo 11:44:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:44:56 INFO - Running setup.py install for mozInstall 11:44:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:44:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:44:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:44:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:44:56 INFO - Running setup.py install for mozleak 11:44:56 INFO - Running setup.py install for mozlog 11:44:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:44:56 INFO - Running setup.py install for moznetwork 11:44:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:44:56 INFO - Running setup.py install for mozprocess 11:44:57 INFO - Running setup.py install for mozprofile 11:44:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:44:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:44:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:44:57 INFO - Running setup.py install for mozrunner 11:44:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:44:57 INFO - Running setup.py install for mozscreenshot 11:44:57 INFO - Running setup.py install for moztest 11:44:57 INFO - Running setup.py install for mozversion 11:44:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:44:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:44:57 INFO - Cleaning up... 11:44:57 INFO - Return code: 0 11:44:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:57 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 0x1009aa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100920570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda3588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007bed30>, '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 0x100624db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007b6b20>, '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.bKxFfr6nyI/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.WNFZppOZUi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 11:44:57 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 11:44:57 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 11:44:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKxFfr6nyI/Render', 11:44:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:57 INFO - 'HOME': '/Users/cltbld', 11:44:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:57 INFO - 'LOGNAME': 'cltbld', 11:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:57 INFO - 'MOZ_NO_REMOTE': '1', 11:44:57 INFO - 'NO_EM_RESTART': '1', 11:44:57 INFO - 'PAGER': '/bin/cat', 11:44:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:57 INFO - 'PWD': '/builds/slave/test', 11:44:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:57 INFO - 'SHELL': '/bin/bash', 11:44:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNFZppOZUi/Listeners', 11:44:57 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 11:44:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:57 INFO - 'USER': 'cltbld', 11:44:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:57 INFO - 'XPC_FLAGS': '0x0', 11:44:57 INFO - 'XPC_SERVICE_NAME': '0', 11:44:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:44:58 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-vtN8ou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:44:58 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)) 11:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:44:58 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-sNLRZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:44:58 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)) 11:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:44:58 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-dza9b3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:44:58 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)) 11:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:44:58 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-EHYgaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:44:58 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)) 11:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:44:58 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-TJiAYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:44:58 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)) 11:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:44:58 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-0rT5e2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:44:58 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)) 11:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:44:58 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-KkGUiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:44:58 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)) 11:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:44:58 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-30fbIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:44:59 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)) 11:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:44:59 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-s_NH0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:44:59 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)) 11:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:44:59 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-E3XYYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:44:59 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)) 11:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:44:59 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-uDsQAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:44:59 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)) 11:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:44:59 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-VH74Lg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:44:59 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)) 11:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:44:59 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-KhZbt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:44:59 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)) 11:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:44:59 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-9vqSer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:44:59 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)) 11:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:44:59 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-ELwd2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:44:59 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)) 11:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:44:59 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-4O8Y3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:44:59 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-nf9MA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:45:00 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)) 11:45:00 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)) 11:45:00 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)) 11:45:00 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)) 11:45:00 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)) 11:45:00 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)) 11:45:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:45:00 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 11:45:00 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 11:45:00 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 11:45:00 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 11:45:00 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 11:45:00 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 11:45:02 INFO - Downloading blessings-1.6.tar.gz 11:45:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:45:02 INFO - Installing collected packages: blessings 11:45:02 INFO - Running setup.py install for blessings 11:45:02 INFO - Successfully installed blessings 11:45:02 INFO - Cleaning up... 11:45:02 INFO - Return code: 0 11:45:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:45:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:45:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:45:03 INFO - Reading from file tmpfile_stdout 11:45:03 INFO - Current package versions: 11:45:03 INFO - blessings == 1.6 11:45:03 INFO - blobuploader == 1.2.4 11:45:03 INFO - docopt == 0.6.1 11:45:03 INFO - functools32 == 3.2.3-2 11:45:03 INFO - jsonschema == 2.5.1 11:45:03 INFO - manifestparser == 1.1 11:45:03 INFO - mozInstall == 1.12 11:45:03 INFO - mozcrash == 1.0 11:45:03 INFO - mozdebug == 0.1 11:45:03 INFO - mozdevice == 0.48 11:45:03 INFO - mozfile == 1.2 11:45:03 INFO - mozhttpd == 0.7 11:45:03 INFO - mozinfo == 0.9 11:45:03 INFO - mozleak == 0.1 11:45:03 INFO - mozlog == 3.4 11:45:03 INFO - moznetwork == 0.27 11:45:03 INFO - mozprocess == 0.23 11:45:03 INFO - mozprofile == 0.28 11:45:03 INFO - mozrunner == 6.13 11:45:03 INFO - mozscreenshot == 0.1 11:45:03 INFO - mozsystemmonitor == 0.3 11:45:03 INFO - moztest == 0.7 11:45:03 INFO - mozversion == 1.4 11:45:03 INFO - psutil == 3.1.1 11:45:03 INFO - requests == 1.2.3 11:45:03 INFO - wsgiref == 0.1.2 11:45:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:45:03 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')]} 11:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:03 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')]} 11:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:03 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 0x1009aa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100920570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda3588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007bed30>, '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 0x100624db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007b6b20>, '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.bKxFfr6nyI/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.WNFZppOZUi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 11:45:03 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 11:45:03 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 11:45:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKxFfr6nyI/Render', 11:45:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:03 INFO - 'HOME': '/Users/cltbld', 11:45:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:03 INFO - 'LOGNAME': 'cltbld', 11:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:03 INFO - 'MOZ_NO_REMOTE': '1', 11:45:03 INFO - 'NO_EM_RESTART': '1', 11:45:03 INFO - 'PAGER': '/bin/cat', 11:45:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:03 INFO - 'PWD': '/builds/slave/test', 11:45:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:03 INFO - 'SHELL': '/bin/bash', 11:45:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNFZppOZUi/Listeners', 11:45:03 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 11:45:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:03 INFO - 'USER': 'cltbld', 11:45:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:03 INFO - 'XPC_FLAGS': '0x0', 11:45:03 INFO - 'XPC_SERVICE_NAME': '0', 11:45:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:04 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)) 11:45:04 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)) 11:45:04 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)) 11:45:04 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)) 11:45:04 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)) 11:45:04 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)) 11:45:04 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)) 11:45:04 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)) 11:45:04 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)) 11:45:04 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)) 11:45:04 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)) 11:45:04 INFO - Cleaning up... 11:45:04 INFO - Return code: 0 11:45:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:45: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')]} 11:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45: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')]} 11:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1009aa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100920570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda3588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1007bed30>, '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 0x100624db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007b6b20>, '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.bKxFfr6nyI/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.WNFZppOZUi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 11:45: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', 'jsonschema'] in /builds/slave/test/build 11:45: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 jsonschema 11:45:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKxFfr6nyI/Render', 11:45:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:04 INFO - 'HOME': '/Users/cltbld', 11:45:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:04 INFO - 'LOGNAME': 'cltbld', 11:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:04 INFO - 'MOZ_NO_REMOTE': '1', 11:45:04 INFO - 'NO_EM_RESTART': '1', 11:45:04 INFO - 'PAGER': '/bin/cat', 11:45:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:04 INFO - 'PWD': '/builds/slave/test', 11:45:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:04 INFO - 'SHELL': '/bin/bash', 11:45:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNFZppOZUi/Listeners', 11:45:04 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 11:45:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:04 INFO - 'USER': 'cltbld', 11:45:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:04 INFO - 'XPC_FLAGS': '0x0', 11:45:04 INFO - 'XPC_SERVICE_NAME': '0', 11:45:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:45:04 INFO - Cleaning up... 11:45:04 INFO - Return code: 0 11:45:04 INFO - Running post-action listener: _resource_record_post_action 11:45:04 INFO - Running post-action listener: _start_resource_monitoring 11:45:04 INFO - Starting resource monitoring. 11:45:04 INFO - [mozharness: 2016-11-14 19:45:04.968169Z] Finished create-virtualenv step (success) 11:45:04 INFO - [mozharness: 2016-11-14 19:45:04.969151Z] Running install step. 11:45:04 INFO - Running pre-action listener: _resource_record_pre_action 11:45:04 INFO - Running main action method: install 11:45:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:45:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:45:05 INFO - Reading from file tmpfile_stdout 11:45:05 INFO - Detecting whether we're running mozinstall >=1.0... 11:45:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:45:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:45:05 INFO - Reading from file tmpfile_stdout 11:45:05 INFO - Output received: 11:45:05 INFO - Usage: mozinstall [options] installer 11:45:05 INFO - Options: 11:45:05 INFO - -h, --help show this help message and exit 11:45:05 INFO - -d DEST, --destination=DEST 11:45:05 INFO - Directory to install application into. [default: 11:45:05 INFO - "/builds/slave/test"] 11:45:05 INFO - --app=APP Application being installed. [default: firefox] 11:45:05 INFO - mkdir: /builds/slave/test/build/application 11:45:05 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'] 11:45:05 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 11:45:36 INFO - Reading from file tmpfile_stdout 11:45:36 INFO - Output received: 11:45:36 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:45:36 INFO - Running post-action listener: _resource_record_post_action 11:45:36 INFO - [mozharness: 2016-11-14 19:45:36.738195Z] Finished install step (success) 11:45:36 INFO - [mozharness: 2016-11-14 19:45:36.738305Z] Running run-tests step. 11:45:36 INFO - Running pre-action listener: _resource_record_pre_action 11:45:36 INFO - Running main action method: run_tests 11:45:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:45:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:45:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:45:36 INFO - 2016-11-14 11:45:36.752 screenresolution[1639:7561] starting screenresolution argv=screenresolution get 11:45:36 INFO - 2016-11-14 11:45:36.755 screenresolution[1639:7561] Display 0: 1600x1200x32@60 11:45:36 INFO - 2016-11-14 11:45:36.761 screenresolution[1640:7564] starting screenresolution argv=screenresolution list 11:45:36 INFO - Available Modes on Display 0 11:45:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:45:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:45:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:45:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:45:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:45:36 INFO - Intel Iris: 11:45:36 INFO - Chipset Model: Intel Iris 11:45:36 INFO - Type: GPU 11:45:36 INFO - Bus: Built-In 11:45:36 INFO - VRAM (Dynamic, Max): 1536 MB 11:45:36 INFO - Vendor: Intel (0x8086) 11:45:36 INFO - Device ID: 0x0a2e 11:45:36 INFO - Revision ID: 0x0009 11:45:36 INFO - Displays: 11:45:36 INFO - Display: 11:45:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:45:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:45:36 INFO - Display Serial Number: 1600x1200 60 11:45:36 INFO - Main Display: Yes 11:45:36 INFO - Mirror: Off 11:45:36 INFO - Online: Yes 11:45:36 INFO - Rotation: Supported 11:45:36 INFO - Return code: 0 11:45:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:45:36 INFO - Python 2.7.3 11:45:36 INFO - Return code: 0 11:45:36 INFO - grabbing minidump binary from tooltool 11:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:45:37 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 0x1007bed30>, '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 0x100624db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1007b6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:45:37 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 11:45:37 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 11:45:37 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 11:45:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:45:37 INFO - Return code: 0 11:45:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:45:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:45:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:45:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:45:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:45:37 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/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--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 11:45:37 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/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0171 --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 11:45:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKxFfr6nyI/Render', 11:45:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:37 INFO - 'HOME': '/Users/cltbld', 11:45:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:37 INFO - 'LOGNAME': 'cltbld', 11:45:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:45:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:37 INFO - 'MOZ_NO_REMOTE': '1', 11:45:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:45:37 INFO - 'NO_EM_RESTART': '1', 11:45:37 INFO - 'PAGER': '/bin/cat', 11:45:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:37 INFO - 'PWD': '/builds/slave/test', 11:45:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:45:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:37 INFO - 'SHELL': '/bin/bash', 11:45:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNFZppOZUi/Listeners', 11:45:37 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 11:45:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:37 INFO - 'USER': 'cltbld', 11:45:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:37 INFO - 'XPC_FLAGS': '0x0', 11:45:37 INFO - 'XPC_SERVICE_NAME': '0', 11:45:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:37 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/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--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 11:45:37 INFO - mozversion application_buildid: 20161114081542 11:45:37 INFO - mozversion application_changeset: f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d 11:45:37 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:45:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:45:37 INFO - mozversion application_name: Firefox 11:45:37 INFO - mozversion application_remotingname: firefox-dev 11:45:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:45:37 INFO - mozversion application_vendor: Mozilla 11:45:37 INFO - mozversion application_version: 52.0a2 11:45:37 INFO - mozversion platform_buildid: 20161114081542 11:45:37 INFO - mozversion platform_changeset: f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d 11:45:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:45:37 INFO - mozversion platform_version: 52.0a2 11:45:37 INFO - using testdate: 1479152737 11:45:37 INFO - actual date: 1479152737 11:45:37 INFO - starting webserver on 'localhost:49279' 11:45:37 INFO - SUITE-START | Running 2 tests 11:45:37 INFO - TEST-START | dromaeo_css 11:45:37 INFO - operating with platform_type : mac_ 11:45:37 INFO - Initialising browser for dromaeo_css test... 11:45:37 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpUNYTtA/profile http://localhost:49279/getInfo.html) 11:45:39 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 11:45:39 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 11:45:39 INFO - PROCESS | 1650 | colorDepth:24 11:45:39 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 11:45:39 INFO - PROCESS | 1650 | __metrics 11:45:40 INFO - PROCESS | 1650 | 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" 11:45:40 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:45:40 INFO - TEST-INFO | 1650: exit 0 11:45:40 INFO - Browser initialized. 11:45:40 INFO - Running cycle 1/1 for dromaeo_css test... 11:45:40 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpUNYTtA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:45:52 INFO - PROCESS | 1652 | RSS: Main: 236277760 11:45:52 INFO - PROCESS | 1652 | 11:46:44 INFO - PROCESS | 1652 | 1479152804458 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:48:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 11:48:59 INFO - PROCESS | 1652 | RSS: Main: 395890688 11:48:59 INFO - PROCESS | 1652 | 11:52:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 11:52:02 INFO - PROCESS | 1652 | RSS: Main: 369029120 11:52:02 INFO - PROCESS | 1652 | 11:55:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 11:55:05 INFO - PROCESS | 1652 | RSS: Main: 340946944 11:55:05 INFO - PROCESS | 1652 | 11:58:08 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 11:58:08 INFO - PROCESS | 1652 | RSS: Main: 336076800 11:58:08 INFO - PROCESS | 1652 | 12:01:12 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 12:01:12 INFO - PROCESS | 1652 | RSS: Main: 327335936 12:01:12 INFO - PROCESS | 1652 | 12:04:15 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 12:04:16 INFO - PROCESS | 1652 | RSS: Main: 475799552 12:04:16 INFO - PROCESS | 1652 | 12:04:16 INFO - PROCESS | 1652 | __start_tp_report 12:04:16 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 12:04:16 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 12:04:16 INFO - PROCESS | 1652 | |i|pagename|runs| 12:04:16 INFO - PROCESS | 1652 | |0;dojo.html;3283.6059672839256;3286.474164133738;3329.450640644295;3341.933161336775;3359.664033596641;5725.942740572593;5768.653880767157;5774.5091667208235;5808.157817116516;5821.56338274629;4614.3309220163055;5802.216700745398;5809.796657116991;5834.854128646786;5861.8827623447505;5232.555232805197;5233.136532472137;5254.527092561669;5290.312259406272;5308.469153084699;5188.0920838853235;5205.141151709963;5219;5352.5182733553975;5398.028354896117;5208.166693329068;5284.2866213061325;5285.577153827704;5293.9735301323435;5309.283246761695;5295.602829787743;5322.988632159916;5353.9732301338245;5355.9020400817735;5373.408925018245;5132.871678208075;5211.42674305826;5281.498257665516;5304.283921670563;5305;5669.0646043402785;5676.091825307931;5692.0608099663505;5727.541796656257;5756;6156;6273.1531243282025;6278.3093859675855;6280.623162610258;6317.652529110897;6283.560150789446;6335.429811317003;6354;6357.300696923335;6362.936370636281;5725.599208055438;5762.538996880239;5773.682447465346;5796.913046304308;5873.618214816023;6282.057691346335;6337.714802833883;6339.4611458025665;6341.524385671001;6345.682715864188;6214.37856214384;6236.781712640127;6238.781642642486;6246.718897649616;6264.59280146789;6132.325444201134;6249.125122482765;6251.374862513712;6354.26925903527;6391.2330520337855;6107.947487155049;6184.752609895644;6225.968870155621;6277.435030847246;6315.210598675166;6285.277193122758;6306.306306306303;6313.08460273267;6315.084312774622;6340.556161068681;4859.829905953275;4902.730349830794;4959.776810043557;4963.776630051656;4977.278294647256;4403.987082970934;4422.756748378806;4460.576245256761;4469.910601788011;4476.843310484118;5836.299644042742;5844.1818145459665;5846.590738648339;5857.7656893724625;5909.763609455574;6344;6344.936550634527;6351.364863513704;6352.936470635327;6367.395097465734;5626.099824028136;5723.084306510938;5734.369219385864;5784.248047753765;5845.561582881301;5448.128299472065;5463.644863083887;5476.890462190734;5478.780848766005;5488.984537860429;6303.7793677222;6316.715747791269;6321.936780632227;6330.556861019869;6337.90493142594;6131;6236.126942228001;6353.650549219838;6364.268109167505;6384.521160913043;6250.312465628868;6258.843528911813;6279.560430769802;6320.747170113328;6337.4613157882095;6177.042558403455;6219.533534984804;6232.626042437469;6241.31345551998;6245.531585131224;6153.600015998945;6256.249250090018;6346.7143978522;6348.968255158695;6358.237011558643;6041.395860413923;6118.174046503665;6134.969325153525;6138.9095594588325;6201.472874805511;6195.907061393992;6197.907031394442;6208.720607572761;6210.944139496206;6220.564560480723;6030.216071910623;6043.697815109315;6059.575829691879;6061.848453788691;6074.3318234994995;6012.759489620366;6066.545009124245;6096.75612975476;6102.176206212104;6120.020796672511;5187.714675692873;6029.065494848305;6038.818835434944;6048.0625503047095;6079.452849243589 12:04:16 INFO - PROCESS | 1652 | |1;ext.html;489.09029205677433;508.4711899624391;512.4849526226143;517.5988608828158;540.3407842432234;29177.270568235806;30890;30952.690473095263;38717;39335.606643933555;40056.79971600145;40494.7975260124;40532.39201411974;40591.39112913309;40614.59385406145;9101.49941753205;9254.981951985275;9261.95369023154;9262.397944133643;9268.25853931684;9178.082191780835;9276.211522020636;9300.976892541816;9303.906960930406;9322.347435679505;9308.162265396113;9335.719928402159;9356.532173391337;9369.67206147785;9376.390534615228;9148.817023659556;9244.537773111351;9298.070192980715;9298.302627302912;9310.115539023782;8465.153484651548;8736.951565812144;9198.770030749218;9256.305777066747;9266.027067157986;37829.432558511646;38262.23475530474;38550.42174367387;38699.806500967316;38733.03167420809;3155.179653290138;3273.3944220319318;3298.0765385692043;3318.6515415881468;3319.1536158279728;28937.710622893712;28972.855135724185;29144;29495.70504294951;29758.553621695693;3111.48660471022;3197.712205901457;3200.8559614817154;3229.677032296775;3238.740900727936;427441.8627906872;482822;485933;488815;492512;30658.38683226323;45745;56053.7197314019;56087;56120;32407.67592324094;33220.66779332176;33384.833075834955;33462.996110116736;33556.66443335584;13984.790228146587;14106;14182.361793719307;14195.432182712575;14198.716025679429;11833.290002599872;11895.88104118965;11897.988670962892;11936.880631193751;11937.164398492023;6645.568038077509;7667.118281397599;7686.884696729554;7771.300582947561;7789.883151752728;3128.906132816019;3169.112648458435;3188.2985743136474;3191.69678880506;3211;813.5932033983008;899.8920129584492;905.7690288976269;948.6537413844035;958.91849192818;28013.57979630307;28692;28821.42357152887;29488.557671634942;29565.556516651835;933.7618907178762;1028.598846449885;1047.261680515235;1066.2962444786558;1075.2150929821273;14533.491327803476;14970.47603333878;15108.449529257565;15237.00959437633;15281.694366112837;683212;683458;684509;685832;686963;55054;55532;55540.72229638826;55591.16613250804;55803.72098139564;1197.2995797458411;1233.1614502138452;1236.9814452783035;1326.4959315460064;1354.4040622126233;28439.146825595264;29528.96648617288;29883.850580746956;30106.397872042;30809.845950771;46698.53301466942;47234.81912952204;47239.055218896116;47411;47412.52587474081;543808;548178;549369;551739.9652007017;708262;36709.26581468409;37460;38247.42628860625;38465.80767096147;38516;43092.13815723731;43107;43560;43972.340414893166;44364.77817610891;36194.09514762152;36217.63782362248;36387.27225455529;36790.63209367979;37332.06669833276;36417;37233.813830930674;37374.8131259342;37404.62595374011;37532.624673754006;35957.64042359543;36388.636113638524;36703;37181.62818371782;37421.25157496889;31237.84381078171;33035.00894973155;33469.66530334763;33508;33607.66392336045;24409;24578.055413820206;24588;24609.50780984406;24660.070188637925 12:04:16 INFO - PROCESS | 1652 | |2;jquery.html;356.4777600814806;373.0896612266071;377.9754315969462;391.4421948723069;408.6424378668665;14440.350184241708;14842.77735833962;15342.84657153428;19526.41420757376;20140.798592014075;19032.61934761304;19107.61784764304;19588.902055489703;19878.90060549695;20160.49598760028;5743.4543718346795;6028;6030.396960303979;6050.606710563821;6050.848728781772;4140.68944829139;4346.369776382423;5894.381089985554;5973.611715238518;5981.312149102843;4355.324924636687;4397.12057588482;6278.68606569672;6574.572652777554;6616.139901812781;6362.363763623647;6541.084248205255;6606.306337834554;6663.3336666333225;6695.397414232718;5704;5721.485066344049;5729;5735.225744524478;5739.598228124033;24327;25447.61828572573;25496.74503254962;25698.100566480083;25761.871190643928;1858.6468570971472;2464.137551856854;2468.2101727447225;2658.3275927862414;2763.69599344072;12552.560660376934;14473.42106315746;14731.926340368336;15599.532014039596;15693.8229632778;1588.4043483693615;1833.9908300458546;1853.166075266122;1881.3321270948752;1935.2452543508043;119932;121871;130043;131288.3435582816;133688;11537.250078744855;11539.307641541367;11562.710932226762;11572.24780389272;11929.463174157183;14799;14825.110493370217;14849.40602375893;14856.885733570025;14864.331105100297;7306.452016098814;8224.794380140544;8403.915960840435;8505.10696376884;8655.350848686248;8279.834403312021;8291.170882911661;8292.17078292166;8404.159584041668;8461.450005749606;3753.512043434336;4152.979235103805;4189.91062323798;4431.844885429059;4517.864464066083;1552.309222396023;1754.5086676325277;1922.087008670898;1923.519120219945;1924.220690620301;720.204174387305;744.5235049568281;802.9558374289353;804.63389157933;1041.354360296621;18948.92598091417;19543.120559574854;19620.607587848164;20636;21083.156673733185;925.9305552083486;927.1470247372433;930.5719369090159;944.5938246554048;976.7558110472382;10852.728681783019;11073.5570577176;11116.999470047735;11118.61084862026;11185.272957257752;592780;599498;624148;635636;636576;127009.36495317465;127846.36076819742;131170;134949.3252533731;138419;954.4893481987103;956.8086382723344;967.1923943507251;981.6073570571829;986.8470387090013;16724.832751672813;18385.356512521998;18651.347202847835;18699.439016829518;18795.624087518445;56302.718486407306;56916;57529.71235143798;57990.13014804697;58665;80588.59705701434;82176;82443;83896;83909;5481.205225242397;5619.157126431068;6341.365863413622;7816.140224575179;8329.083800782031;11311.208215424842;12636.747265054832;12833.358332083546;13074;13085.476580936656;10814.89185108139;10999.845016273177;10999.890001100206;11143;11593.072554195813;10850.40322782223;10986.780264394827;11000.614978475714;11243.325400475998;11332.660020199408;10812.783744325227;11044.447777610927;11265.042471389695;11361.261518001304;11646.475908584303;8037.115917248981;8329.083800781787;8329.50022998622;8357.749267521984;8461.407701461085;10163.695089147337;10900.836487452852;10961.890381096087;11041.889581104086;11312.547498099984 12:04:16 INFO - PROCESS | 1652 | |3;mootools.html;1470.779383092536;1498.3631956418521;1648.9175541222944;1799.0914588133007;1824.3888297420356;6585.703643336049;8451.408401411903;8451.746447606567;8463.322934165268;8465.44974576653;7355.852882942338;7631.1605723370385;7634.083909930802;7680.347170490515;7698.307152356287;2085.7914208579173;2132.2110818996994;2226.421130506072;2303.078768492608;2304.7695230476984;2047.7440319960006;2104.8002638495987;2348.0255693887016;2349.3891588187025;2350.0129945422964;2263.5020295534955;2281.817454603636;2315.6294992801154;2318.6985691860036;2319.8376113672048;2279.247848210087;2282.6233671444193;2303.98624605174;2327.104064934995;2332.976670233293;2327.6392159215347;2331.2306938710353;2352.294311706481;2358.705161854768;2361.9173328933407;8352.749417517483;8377.748667539983;8454.788630284293;8471.618777155043;8482.703105391343;5072.036313100499;5259.2899958505495;5260.421353651095;5261.394939581959;5266.183741520071;3455.2398472336045;3486.18074752433;3500.7199424046016;3520.6127325994375;3583.3191693578137;997.875265591801;1005.1506477026901;1064.297563608015;1098.8461615373853;1105.3202280597454;10847.511861966152;10971.725706857313;10979.890201097967;10983.945080274547;11000.394978276192;4466.955330446719;4626.629869610423;4756;4758.976205119022;4762.761861906891;9320.673776417792;9472.384295020802;9517.571709272961;9530.999245079305;9531.189848862925;5213.713245771443;5222.686638801684;5223.190405487156;5223.529882310534;5231.136862417735;4060.9796951015055;4592.119658888234;4736.786844592001;4749.572538471554;4793.113274044313;3558.323918455485;3667.0832291927018;3672.2839046385698;3801.6388443097862;3895.7078219133114;1233.962981110568;1270.0538099116218;1289.5937779599396;1298.9155704879154;1301.5054279373778;785.8978332816697;880.41892351048;900.090908182727;918.2653876898454;925.8333499969935;5643.379228284885;5670.5747068969995;5752.568557358215;5787.218725472091;5883.264591926009;797.9920200797918;901.6528636474873;922.9861552076724;938.2822141062093;989.5398639632606;3757.4176002719623;3796.354619714655;3801.8099095045136;3853.788041657736;3878.7478813877005;9830.557624906896;10334.018268264503;10356.326838755456;10361.171106311476;10414.5834166634;10066.798664026679;10328.018838210359;10328.070473657406;10340.948295258475;10356.482175891175;7582.696692132363;7726.575038372944;7841.215878412113;7842.764717058383;7861.4103942204565;5117.925235700396;5139.4603566626465;5529.4470552944385;5712.400197979152;5787.826365209051;8706.345794299063;8850.336224783296;9097.13577210164;9538.189253913495;9595.088466595662;9446.669366572138;9457.763555910882;9584.424934503952;9616.423014619146;9723.708288751348;7436.107667079985;7674.155842857393;7746.728864489716;7851.293383595504;7864.095629002738;6358.682065896779;7126.501144920013;7912.604369781603;7972.1230664628;7980.680772769024;6096.725647345968;7148.28517148281;7149.0706208192605;7151.60666163366;7171.211166771546;6950.478714096362;7051.753188638373;7067.469939754436;7071.611061391673;7107.82230444243;6569.770058047945;6713.194416669836;6714.932850671431;6716.328367163244;6718.966405167943;3950.091478959913;4068.5524592295415;4760.071786001697;4830.468648448597;4898.730569818694;4772.236442169236;4792.113459010095;4830.106430310263;5010.173321401928;5049.747512624427 12:04:16 INFO - PROCESS | 1652 | |4;prototype.html;257.2360090531123;309.81256339914364;312.62485017978423;316.68331668331666;319.29595242490313;4597.218472859614;5225.178403662023;5265.657257399363;5289.80473103662;5319.441458646845;4726.700157456701;4798.544138306864;4870.391201099863;5070.898582028357;5261.76322065507;3553.3781588222087;3846.8076596170217;3929.744566603176;3958.7228893977435;3967.3453880109746;3104.58088158098;3384.323135372923;3733.365327894251;3949.34835752102;3956.3076461619244;3032.272254658877;3255.544223808669;3876.3604005339084;3883.825227864753;3900.941485877714;3202.2794871153806;3285.8357082145917;3435.7974708851953;3931.036895960494;3953.2686453006286;2857.3428111534254;3070.6775788542104;3409.5226668266464;3418.000469725189;3428.468587368962;5064.708499332646;5070.518300761421;5097.643164978453;5103.897922041576;5121.00140472608;4209.621134097946;4453.51011388747;4487.439070116236;4519.457665080178;4529.343245229456;5490.627343164209;5506.669599824023;5530.557555395559;5537.944620553823;5542.362628297757;3752.43713442983;3765.8681946131755;3778.4521244419407;3778.7543809652284;3840.020794697334;5347.598930080259;5527.585431092684;5755.654660720328;5764.394738552475;5826.854328641775;3642.0712718257205;3801.9429708554394;3818;3826.253880493277;3832.6550610445192;3461.7576769625884;3772.4718539404507;3879.51710905021;3922.587608620735;3928.980355098206;3489.023073539413;4272.347143192588;4466.0398014427055;4525.954740452553;4542.909141817145;5015.3730783652045;5041.294218809369;5183.9113786105345;5538.476918847349;5616.5787565932715;1121.8837256929244;1245.40843099527;1246.5512415530402;1247.8689737577606;1250.562303193893;696.5924933913717;819.5041999590281;819.7499762572353;822.7038266224155;828.225609055535;4233.491980962304;4388.232059389594;4772.618190544748;4814.9518504814505;4817.470078291385;4737.242041273448;4936.358273424432;4984.77568509418;5292.735363231823;5310.955282216297;4288.656907447396;4343.045629466753;4343.548846198064;4751.59611433032;4852.393450818648;2406.819488538367;2738.0964281786964;2779.998401151168;2798.902038428645;2804.5512717965435;5260.342457192851;5371.368029665514;5439.945600544023;5581.944180558143;5607.243022191952;4857.562819346205;4887.898203772412;4900.623197749089;5498.367687715884;5498.525663451383;4511.233090374644;4684.391029166187;5410.026195284808;5434.592405569519;5445.727713614224;4774.856754297377;4787.712737235777;5267.446918073547;5274.657147285414;5283.735813209248;4852.611791056636;4963.4540200577185;5010.918285254469;5161.7677204526635;5297.2783845251215;5037.874053148701;5097.439281678938;5170.146925757208;5457.863553411196;5479.424660410759;3033.620797400325;3061.6172978377704;3571.5535558055244;3588.946165807567;3589.497470354205;4940.6788558743565;4984.102861484823;4989.825356112518;5232.581393488435;5332.78709819254;4192.538820729656;5076.263941728503;5081.593472522115;5122.871928201825;5158.561522270499;4756.381670382828;4948.1835497142565;4986.227134794112;5123.538881500683;5154.922676159786;4363.89090272733;4467.441569803775;5002.799888004439;5011.348524691767;5116;5044.722540260321;5336.973315133399;5338.876757097141;5360.919586206288;5498.76595021823;4561.954380456153;5351.759170837244;5370.946290537201;5410.567154627542;5473.124300111963 12:04:16 INFO - PROCESS | 1652 | |5;yui.html;695.2803848017303;738.7491768294192;743.9293267139622;767.1906139023332;775.1279810213509;13920.234387108705;14088.64778380541;14312.785308220386;14328.140311581286;14340.211288379134;14066.64833379166;14702.779458308107;14759.77860332096;14961.551153465387;14996.250187490608;661.7319985405918;683.3883674111667;833.5040650812757;857.1557016338933;857.4212406625534;685.1880521581917;816.7753867686374;840.6637345061994;849.4605925237457;856.9742907712779;869.7390782765177;884.95575221239;887.3433659092258;888.1251966812684;889.6752685269862;878.2008372381134;878.3412440669498;878.925291350236;879.2965627498008;882.7793051737066;517.627308337996;534.1427009649534;637.2798737426679;657.4149007383432;662.4071456046811;13757.38091785872;14177.007609467342;14195.077319974273;14207.14757114566;14228.075175113689;12801.359932003363;13021.674458138528;13044.804327934993;13114.213147211198;13183.538576149884;11715.414229288586;12188.390580470941;12299.508019679202;12327.876721232762;12539.435725392379;4295.8496452624;4389.253826849444;4406.317020861771;4406.515283318832;4475.843345482893;76201.85697214546;92342;95263;95890;97981;10468.895311046945;10563.63027294041;10733.141348692086;10815.78368432627;10973.890261097447;20192.293269735488;20283.492912677008;20405.2858149967;20536.07587658559;20582.691259631116;7413.518121322097;7589.848203035908;7608.885866712004;7625;7829.843403132019;4131.318332475108;4174.102567947906;4283.892902677396;4295.935560966588;4406.096750166273;3401.285729996679;3516.0506663200795;3517.1031386996665;3524.9823750881083;3541.38025845476;626.8934281172212;627.4666533446534;628.522323034497;634.1755717567145;644.2880616918331;621.9004959206505;627.4541149200224;635.8029011006603;649.8375406148463;688.3632639808158;8811.603477843513;8848.46909185451;8861.113888611088;9068.183863452321;9456.810863782686;517.5962749055742;556.6862205195735;617.5677026081761;664.8404382948154;678.4267294136447;4393.826609101273;4465.26323156682;4466.151431228056;4473.753943533137;4621.445426548836;87550.56224718835;88653.55673221593;117408;119262;120238;95448;96509.51745241228;100073;100533.49733251287;100885;89204;90081.54959225161;92092;93298;93447.53276233576;8562.357823163164;8644.740657780276;8844.73465796027;8876.778080548038;8904.821903561762;58885;58894;59067.113993290994;59992;60007;64257.678711606146;79862.6006869962;82174.58912705399;83568.58215708882;86105.56947215224;10436.686899393031;11695;11828.881711182778;12109.515619375126;12218;13133.868661313265;14293.785593216318;14299.571012869214;14323.140611562923;14852.405903763729;11319.490622921823;13235.602931912059;13291.667708306983;13461;13742.793858091936;12854.742905142031;12921.418536166084;12982.15615984958;13394.129381590166;13544.255065971467;12290.508379664714;12786.36068196605;12851.871481285067;13401.597952061455;14067.648308792363;10347.379157250589;10880.347179169275;11329.376884271443;11522.711932201762;11774.175807693378;11433.256838305484;11435.828462573234;11481.598144064918;11566.339741859587;11600.419979000848 12:04:16 INFO - PROCESS | 1652 | __end_tp_report 12:04:16 INFO - PROCESS | 1652 | __start_cc_report 12:04:16 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1871 12:04:16 INFO - PROCESS | 1652 | __end_cc_report 12:04:16 INFO - PROCESS | 1652 | __startTimestamp1479153856178__endTimestamp 12:04:16 INFO - PROCESS | 1652 | 12:04:16 INFO - PROCESS | 1652 | ------- Summary: start ------- 12:04:16 INFO - PROCESS | 1652 | Number of tests: 6 12:04:16 INFO - PROCESS | 1652 | 12:04:16 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5791.65 Median:6048.06 stddev:658.67 (10.9%) stddev-sans-first:630.62 12:04:16 INFO - PROCESS | 1652 | Values: 3283.6 3286.5 3329.5 3341.9 3359.7 5725.9 5768.7 5774.5 5808.2 5821.6 4614.3 5802.2 5809.8 5834.9 5861.9 5232.6 5233.1 5254.5 5290.3 5308.5 5188.1 5205.1 5219.0 5352.5 5398.0 5208.2 5284.3 5285.6 5294.0 5309.3 5295.6 5323.0 5354.0 5355.9 5373.4 5132.9 5211.4 5281.5 5304.3 5305.0 5669.1 5676.1 5692.1 5727.5 5756.0 6156.0 6273.2 6278.3 6280.6 6317.7 6283.6 6335.4 6354.0 6357.3 6362.9 5725.6 5762.5 5773.7 5796.9 5873.6 6282.1 6337.7 6339.5 6341.5 6345.7 6214.4 6236.8 6238.8 6246.7 6264.6 6132.3 6249.1 6251.4 6354.3 6391.2 6107.9 6184.8 6226.0 6277.4 6315.2 6285.3 6306.3 6313.1 6315.1 6340.6 4859.8 4902.7 4959.8 4963.8 4977.3 4404.0 4422.8 4460.6 4469.9 4476.8 5836.3 5844.2 5846.6 5857.8 5909.8 6344.0 6344.9 6351.4 6352.9 6367.4 5626.1 5723.1 5734.4 5784.2 5845.6 5448.1 5463.6 5476.9 5478.8 5489.0 6303.8 6316.7 6321.9 6330.6 6337.9 6131.0 6236.1 6353.7 6364.3 6384.5 6250.3 6258.8 6279.6 6320.7 6337.5 6177.0 6219.5 6232.6 6241.3 6245.5 6153.6 6256.2 6346.7 6349.0 6358.2 6041.4 6118.2 6135.0 6138.9 6201.5 6195.9 6197.9 6208.7 6210.9 6220.6 6030.2 6043.7 6059.6 6061.8 6074.3 6012.8 6066.5 6096.8 6102.2 6120.0 5187.7 6029.1 6038.8 6048.1 6079.5 12:04:16 INFO - PROCESS | 1652 | 12:04:16 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:68989.37 Median:38038.43 stddev:157765.14 (414.8%) stddev-sans-first:158123.88 12:04:16 INFO - PROCESS | 1652 | Values: 489.1 508.5 512.5 517.6 540.3 29177.3 30890.0 30952.7 38717.0 39335.6 40056.8 40494.8 40532.4 40591.4 40614.6 9101.5 9255.0 9262.0 9262.4 9268.3 9178.1 9276.2 9301.0 9303.9 9322.3 9308.2 9335.7 9356.5 9369.7 9376.4 9148.8 9244.5 9298.1 9298.3 9310.1 8465.2 8737.0 9198.8 9256.3 9266.0 37829.4 38262.2 38550.4 38699.8 38733.0 3155.2 3273.4 3298.1 3318.7 3319.2 28937.7 28972.9 29144.0 29495.7 29758.6 3111.5 3197.7 3200.9 3229.7 3238.7 427441.9 482822.0 485933.0 488815.0 492512.0 30658.4 45745.0 56053.7 56087.0 56120.0 32407.7 33220.7 33384.8 33463.0 33556.7 13984.8 14106.0 14182.4 14195.4 14198.7 11833.3 11895.9 11898.0 11936.9 11937.2 6645.6 7667.1 7686.9 7771.3 7789.9 3128.9 3169.1 3188.3 3191.7 3211.0 813.6 899.9 905.8 948.7 958.9 28013.6 28692.0 28821.4 29488.6 29565.6 933.8 1028.6 1047.3 1066.3 1075.2 14533.5 14970.5 15108.4 15237.0 15281.7 683212.0 683458.0 684509.0 685832.0 686963.0 55054.0 55532.0 55540.7 55591.2 55803.7 1197.3 1233.2 1237.0 1326.5 1354.4 28439.1 29529.0 29883.9 30106.4 30809.8 46698.5 47234.8 47239.1 47411.0 47412.5 543808.0 548178.0 549369.0 551740.0 708262.0 36709.3 37460.0 38247.4 38465.8 38516.0 43092.1 43107.0 43560.0 43972.3 44364.8 36194.1 36217.6 36387.3 36790.6 37332.1 36417.0 37233.8 37374.8 37404.6 37532.6 35957.6 36388.6 36703.0 37181.6 37421.3 31237.8 33035.0 33469.7 33508.0 33607.7 24409.0 24578.1 24588.0 24609.5 24660.1 12:04:16 INFO - PROCESS | 1652 | 12:04:16 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:36117.72 Median:25472.18 stddev:103378.89 (405.9%) stddev-sans-first:103634.02 12:04:16 INFO - PROCESS | 1652 | Values: 356.5 373.1 378.0 391.4 408.6 14440.4 14842.8 15342.8 19526.4 20140.8 19032.6 19107.6 19588.9 19878.9 20160.5 5743.5 6028.0 6030.4 6050.6 6050.8 4140.7 4346.4 5894.4 5973.6 5981.3 4355.3 4397.1 6278.7 6574.6 6616.1 6362.4 6541.1 6606.3 6663.3 6695.4 5704.0 5721.5 5729.0 5735.2 5739.6 24327.0 25447.6 25496.7 25698.1 25761.9 1858.6 2464.1 2468.2 2658.3 2763.7 12552.6 14473.4 14731.9 15599.5 15693.8 1588.4 1834.0 1853.2 1881.3 1935.2 119932.0 121871.0 130043.0 131288.3 133688.0 11537.3 11539.3 11562.7 11572.2 11929.5 14799.0 14825.1 14849.4 14856.9 14864.3 7306.5 8224.8 8403.9 8505.1 8655.4 8279.8 8291.2 8292.2 8404.2 8461.5 3753.5 4153.0 4189.9 4431.8 4517.9 1552.3 1754.5 1922.1 1923.5 1924.2 720.2 744.5 803.0 804.6 1041.4 18948.9 19543.1 19620.6 20636.0 21083.2 925.9 927.1 930.6 944.6 976.8 10852.7 11073.6 11117.0 11118.6 11185.3 592780.0 599498.0 624148.0 635636.0 636576.0 127009.4 127846.4 131170.0 134949.3 138419.0 954.5 956.8 967.2 981.6 986.8 16724.8 18385.4 18651.3 18699.4 18795.6 56302.7 56916.0 57529.7 57990.1 58665.0 80588.6 82176.0 82443.0 83896.0 83909.0 5481.2 5619.2 6341.4 7816.1 8329.1 11311.2 12636.7 12833.4 13074.0 13085.5 10814.9 10999.8 10999.9 11143.0 11593.1 10850.4 10986.8 11000.6 11243.3 11332.7 10812.8 11044.4 11265.0 11361.3 11646.5 8037.1 8329.1 8329.5 8357.7 8461.4 10163.7 10900.8 10961.9 11041.9 11312.5 12:04:16 INFO - PROCESS | 1652 | 12:04:16 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5443.63 Median:5176.59 stddev:2998.92 (57.9%) stddev-sans-first:2992.47 12:04:16 INFO - PROCESS | 1652 | Values: 1470.8 1498.4 1648.9 1799.1 1824.4 6585.7 8451.4 8451.7 8463.3 8465.4 7355.9 7631.2 7634.1 7680.3 7698.3 2085.8 2132.2 2226.4 2303.1 2304.8 2047.7 2104.8 2348.0 2349.4 2350.0 2263.5 2281.8 2315.6 2318.7 2319.8 2279.2 2282.6 2304.0 2327.1 2333.0 2327.6 2331.2 2352.3 2358.7 2361.9 8352.7 8377.7 8454.8 8471.6 8482.7 5072.0 5259.3 5260.4 5261.4 5266.2 3455.2 3486.2 3500.7 3520.6 3583.3 997.9 1005.2 1064.3 1098.8 1105.3 10847.5 10971.7 10979.9 10983.9 11000.4 4467.0 4626.6 4756.0 4759.0 4762.8 9320.7 9472.4 9517.6 9531.0 9531.2 5213.7 5222.7 5223.2 5223.5 5231.1 4061.0 4592.1 4736.8 4749.6 4793.1 3558.3 3667.1 3672.3 3801.6 3895.7 1234.0 1270.1 1289.6 1298.9 1301.5 785.9 880.4 900.1 918.3 925.8 5643.4 5670.6 5752.6 5787.2 5883.3 798.0 901.7 923.0 938.3 989.5 3757.4 3796.4 3801.8 3853.8 3878.7 9830.6 10334.0 10356.3 10361.2 10414.6 10066.8 10328.0 10328.1 10340.9 10356.5 7582.7 7726.6 7841.2 7842.8 7861.4 5117.9 5139.5 5529.4 5712.4 5787.8 8706.3 8850.3 9097.1 9538.2 9595.1 9446.7 9457.8 9584.4 9616.4 9723.7 7436.1 7674.2 7746.7 7851.3 7864.1 6358.7 7126.5 7912.6 7972.1 7980.7 6096.7 7148.3 7149.1 7151.6 7171.2 6950.5 7051.8 7067.5 7071.6 7107.8 6569.8 6713.2 6714.9 6716.3 6719.0 3950.1 4068.6 4760.1 4830.5 4898.7 4772.2 4792.1 4830.1 5010.2 5049.7 12:04:16 INFO - PROCESS | 1652 | 12:04:16 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4264.92 Median:4816.21 stddev:1313.21 (27.3%) stddev-sans-first:1281.98 12:04:16 INFO - PROCESS | 1652 | Values: 257.2 309.8 312.6 316.7 319.3 4597.2 5225.2 5265.7 5289.8 5319.4 4726.7 4798.5 4870.4 5070.9 5261.8 3553.4 3846.8 3929.7 3958.7 3967.3 3104.6 3384.3 3733.4 3949.3 3956.3 3032.3 3255.5 3876.4 3883.8 3900.9 3202.3 3285.8 3435.8 3931.0 3953.3 2857.3 3070.7 3409.5 3418.0 3428.5 5064.7 5070.5 5097.6 5103.9 5121.0 4209.6 4453.5 4487.4 4519.5 4529.3 5490.6 5506.7 5530.6 5537.9 5542.4 3752.4 3765.9 3778.5 3778.8 3840.0 5347.6 5527.6 5755.7 5764.4 5826.9 3642.1 3801.9 3818.0 3826.3 3832.7 3461.8 3772.5 3879.5 3922.6 3929.0 3489.0 4272.3 4466.0 4526.0 4542.9 5015.4 5041.3 5183.9 5538.5 5616.6 1121.9 1245.4 1246.6 1247.9 1250.6 696.6 819.5 819.7 822.7 828.2 4233.5 4388.2 4772.6 4815.0 4817.5 4737.2 4936.4 4984.8 5292.7 5311.0 4288.7 4343.0 4343.5 4751.6 4852.4 2406.8 2738.1 2780.0 2798.9 2804.6 5260.3 5371.4 5439.9 5581.9 5607.2 4857.6 4887.9 4900.6 5498.4 5498.5 4511.2 4684.4 5410.0 5434.6 5445.7 4774.9 4787.7 5267.4 5274.7 5283.7 4852.6 4963.5 5010.9 5161.8 5297.3 5037.9 5097.4 5170.1 5457.9 5479.4 3033.6 3061.6 3571.6 3588.9 3589.5 4940.7 4984.1 4989.8 5232.6 5332.8 4192.5 5076.3 5081.6 5122.9 5158.6 4756.4 4948.2 4986.2 5123.5 5154.9 4363.9 4467.4 5002.8 5011.3 5116.0 5044.7 5337.0 5338.9 5360.9 5498.8 4562.0 5351.8 5370.9 5410.6 5473.1 12:04:16 INFO - PROCESS | 1652 | 12:04:16 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:21187.55 Median:4435.89 stddev:31153.01 (702.3%) stddev-sans-first:31202.40 12:04:16 INFO - PROCESS | 1652 | Values: 695.3 738.7 743.9 767.2 775.1 13920.2 14088.6 14312.8 14328.1 14340.2 14066.6 14702.8 14759.8 14961.6 14996.3 661.7 683.4 833.5 857.2 857.4 685.2 816.8 840.7 849.5 857.0 869.7 885.0 887.3 888.1 889.7 878.2 878.3 878.9 879.3 882.8 517.6 534.1 637.3 657.4 662.4 13757.4 14177.0 14195.1 14207.1 14228.1 12801.4 13021.7 13044.8 13114.2 13183.5 11715.4 12188.4 12299.5 12327.9 12539.4 4295.8 4389.3 4406.3 4406.5 4475.8 76201.9 92342.0 95263.0 95890.0 97981.0 10468.9 10563.6 10733.1 10815.8 10973.9 20192.3 20283.5 20405.3 20536.1 20582.7 7413.5 7589.8 7608.9 7625.0 7829.8 4131.3 4174.1 4283.9 4295.9 4406.1 3401.3 3516.1 3517.1 3525.0 3541.4 626.9 627.5 628.5 634.2 644.3 621.9 627.5 635.8 649.8 688.4 8811.6 8848.5 8861.1 9068.2 9456.8 517.6 556.7 617.6 664.8 678.4 4393.8 4465.3 4466.2 4473.8 4621.4 87550.6 88653.6 117408.0 119262.0 120238.0 95448.0 96509.5 100073.0 100533.5 100885.0 89204.0 90081.5 92092.0 93298.0 93447.5 8562.4 8644.7 8844.7 8876.8 8904.8 58885.0 58894.0 59067.1 59992.0 60007.0 64257.7 79862.6 82174.6 83568.6 86105.6 10436.7 11695.0 11828.9 12109.5 12218.0 13133.9 14293.8 14299.6 14323.1 14852.4 11319.5 13235.6 13291.7 13461.0 13742.8 12854.7 12921.4 12982.2 13394.1 13544.3 12290.5 12786.4 12851.9 13401.6 14067.6 10347.4 10880.3 11329.4 11522.7 11774.2 11433.3 11435.8 11481.6 11566.3 11600.4 12:04:16 INFO - PROCESS | 1652 | -------- Summary: end -------- 12:04:16 INFO - PROCESS | 1652 | 12:04:16 INFO - TEST-INFO | 1652: exit 0 12:04:16 INFO - TEST-OK | dromaeo_css | took 1119466ms 12:04:16 INFO - TEST-START | kraken 12:04:16 INFO - operating with platform_type : mac_ 12:04:16 INFO - Initialising browser for kraken test... 12:04:16 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpq9_HTc/profile http://localhost:49279/getInfo.html) 12:04:18 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 12:04:19 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 12:04:19 INFO - PROCESS | 1662 | colorDepth:24 12:04:19 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 12:04:19 INFO - PROCESS | 1662 | __metrics 12:04:19 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:04:19 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:04:20 INFO - TEST-INFO | 1662: exit 0 12:04:20 INFO - Browser initialized. 12:04:20 INFO - Running cycle 1/1 for kraken test... 12:04:20 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpq9_HTc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:04:32 INFO - PROCESS | 1663 | RSS: Main: 236920832 12:04:32 INFO - PROCESS | 1663 | 12:04:35 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 12:04:35 INFO - PROCESS | 1663 | RSS: Main: 283815936 12:04:35 INFO - PROCESS | 1663 | 12:04:38 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 12:04:39 INFO - PROCESS | 1663 | RSS: Main: 294703104 12:04:39 INFO - PROCESS | 1663 | 12:04:42 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 12:04:43 INFO - PROCESS | 1663 | RSS: Main: 388907008 12:04:43 INFO - PROCESS | 1663 | 12:04:45 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 12:04:45 INFO - PROCESS | 1663 | RSS: Main: 291733504 12:04:45 INFO - PROCESS | 1663 | 12:04:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:04:48 INFO - PROCESS | 1663 | RSS: Main: 292663296 12:04:48 INFO - PROCESS | 1663 | 12:04:51 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 12:04:51 INFO - PROCESS | 1663 | RSS: Main: 333451264 12:04:51 INFO - PROCESS | 1663 | 12:04:54 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 12:04:55 INFO - PROCESS | 1663 | RSS: Main: 333332480 12:04:55 INFO - PROCESS | 1663 | 12:04:58 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 12:04:58 INFO - PROCESS | 1663 | RSS: Main: 330805248 12:04:58 INFO - PROCESS | 1663 | 12:05:00 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:05:00 INFO - PROCESS | 1663 | RSS: Main: 307433472 12:05:00 INFO - PROCESS | 1663 | 12:05:02 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:05:03 INFO - PROCESS | 1663 | RSS: Main: 307126272 12:05:03 INFO - PROCESS | 1663 | 12:05:05 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:05:06 INFO - PROCESS | 1663 | RSS: Main: 309919744 12:05:06 INFO - PROCESS | 1663 | 12:05:09 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:05:10 INFO - PROCESS | 1663 | RSS: Main: 321642496 12:05:10 INFO - PROCESS | 1663 | 12:05:13 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:05:13 INFO - PROCESS | 1663 | RSS: Main: 321875968 12:05:13 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 12:05:15 INFO - PROCESS | 1663 | RSS: Main: 365522944 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | __start_tp_report 12:05:15 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 12:05:15 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 12:05:15 INFO - PROCESS | 1663 | |i|pagename|runs| 12:05:15 INFO - PROCESS | 1663 | |0;ai-astar;117;114;115;114;114;114;118;161;166;115 12:05:15 INFO - PROCESS | 1663 | |1;audio-beat-detection;138;112;112;143;109;113;116;110;141;109 12:05:15 INFO - PROCESS | 1663 | |2;audio-dft;185;185;183;185;185;257;223;185;183;230 12:05:15 INFO - PROCESS | 1663 | |3;audio-fft;80;58;58;86;61;59;59;59;82;58 12:05:15 INFO - PROCESS | 1663 | |4;audio-oscillator;81;70;94;69;70;91;126;98;68;92 12:05:15 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;87;84;90;87;86;84;88;84;87;84 12:05:15 INFO - PROCESS | 1663 | |6;imaging-darkroom;95;95;95;95;95;96;96;95;94;95 12:05:15 INFO - PROCESS | 1663 | |7;imaging-desaturate;83;111;79;79;82;84;86;82;82;82 12:05:15 INFO - PROCESS | 1663 | |8;json-parse-financial;56;56;53;51;58;59;58;56;55;56 12:05:15 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;64;72;67;76;70;68;72;103;105;70 12:05:15 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;84;87;83;85;96;82;80;97;85;88 12:05:15 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;144;152;151;154;164;150;149;149;196;143 12:05:15 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;156;168;168;169;169;168;168;166;166;217 12:05:15 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;65;66;65;65;66;65;65;66;65;67 12:05:15 INFO - PROCESS | 1663 | __end_tp_report 12:05:15 INFO - PROCESS | 1663 | __start_cc_report 12:05:15 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2006 12:05:15 INFO - PROCESS | 1663 | __end_cc_report 12:05:15 INFO - PROCESS | 1663 | __startTimestamp1479153915609__endTimestamp 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | ------- Summary: start ------- 12:05:15 INFO - PROCESS | 1663 | Number of tests: 14 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:124.80 Median:116.00 stddev:20.48 (17.7%) stddev-sans-first:21.52 12:05:15 INFO - PROCESS | 1663 | Values: 117.0 114.0 115.0 114.0 114.0 114.0 118.0 161.0 166.0 115.0 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:120.30 Median:114.50 stddev:14.25 (12.4%) stddev-sans-first:13.60 12:05:15 INFO - PROCESS | 1663 | Values: 138.0 112.0 112.0 143.0 109.0 113.0 116.0 110.0 141.0 109.0 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:200.10 Median:185.00 stddev:26.63 (14.4%) stddev-sans-first:27.68 12:05:15 INFO - PROCESS | 1663 | Values: 185.0 185.0 183.0 185.0 185.0 257.0 223.0 185.0 183.0 230.0 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:66.00 Median:60.00 stddev:11.62 (19.4%) stddev-sans-first:11.17 12:05:15 INFO - PROCESS | 1663 | Values: 80.0 58.0 58.0 86.0 61.0 59.0 59.0 59.0 82.0 58.0 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:85.90 Median:86.00 stddev:18.31 (21.3%) stddev-sans-first:19.34 12:05:15 INFO - PROCESS | 1663 | Values: 81.0 70.0 94.0 69.0 70.0 91.0 126.0 98.0 68.0 92.0 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.00 stddev:2.08 (2.4%) stddev-sans-first:2.18 12:05:15 INFO - PROCESS | 1663 | Values: 87.0 84.0 90.0 87.0 86.0 84.0 88.0 84.0 87.0 84.0 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 12:05:15 INFO - PROCESS | 1663 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 94.0 95.0 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:82.00 stddev:9.37 (11.4%) stddev-sans-first:9.91 12:05:15 INFO - PROCESS | 1663 | Values: 83.0 111.0 79.0 79.0 82.0 84.0 86.0 82.0 82.0 82.0 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:2.39 (4.3%) stddev-sans-first:2.54 12:05:15 INFO - PROCESS | 1663 | Values: 56.0 56.0 53.0 51.0 58.0 59.0 58.0 56.0 55.0 56.0 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:76.70 Median:70.00 stddev:14.75 (21.1%) stddev-sans-first:14.91 12:05:15 INFO - PROCESS | 1663 | Values: 64.0 72.0 67.0 76.0 70.0 68.0 72.0 103.0 105.0 70.0 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:86.00 stddev:5.66 (6.6%) stddev-sans-first:5.92 12:05:15 INFO - PROCESS | 1663 | Values: 84.0 87.0 83.0 85.0 96.0 82.0 80.0 97.0 85.0 88.0 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:155.20 Median:151.50 stddev:15.45 (10.2%) stddev-sans-first:15.85 12:05:15 INFO - PROCESS | 1663 | Values: 144.0 152.0 151.0 154.0 164.0 150.0 149.0 149.0 196.0 143.0 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.50 Median:168.00 stddev:16.44 (9.8%) stddev-sans-first:16.45 12:05:15 INFO - PROCESS | 1663 | Values: 156.0 168.0 168.0 169.0 169.0 168.0 168.0 166.0 166.0 217.0 12:05:15 INFO - PROCESS | 1663 | 12:05:15 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:65.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 12:05:15 INFO - PROCESS | 1663 | Values: 65.0 66.0 65.0 65.0 66.0 65.0 65.0 66.0 65.0 67.0 12:05:15 INFO - PROCESS | 1663 | -------- Summary: end -------- 12:05:15 INFO - PROCESS | 1663 | 12:05:16 INFO - TEST-INFO | 1663: exit 0 12:05:16 INFO - TEST-OK | kraken | took 59675ms 12:05:16 INFO - SUITE-END | took 1179s 12:05:16 INFO - Completed test suite (00:19:39) 12:05:16 INFO - javascript benchmark 12:05:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3283.6059672839256, 3286.474164133738, 3329.450640644295, 3341.933161336775, 3359.664033596641, 5725.942740572593, 5768.653880767157, 5774.5091667208235, 5808.157817116516, 5821.56338274629, 4614.3309220163055, 5802.216700745398, 5809.796657116991, 5834.854128646786, 5861.8827623447505, 5232.555232805197, 5233.136532472137, 5254.527092561669, 5290.312259406272, 5308.469153084699, 5188.0920838853235, 5205.141151709963, 5219.0, 5352.5182733553975, 5398.028354896117, 5208.166693329068, 5284.2866213061325, 5285.577153827704, 5293.9735301323435, 5309.283246761695, 5295.602829787743, 5322.988632159916, 5353.9732301338245, 5355.9020400817735, 5373.408925018245, 5132.871678208075, 5211.42674305826, 5281.498257665516, 5304.283921670563, 5305.0, 5669.0646043402785, 5676.091825307931, 5692.0608099663505, 5727.541796656257, 5756.0, 6156.0, 6273.1531243282025, 6278.3093859675855, 6280.623162610258, 6317.652529110897, 6283.560150789446, 6335.429811317003, 6354.0, 6357.300696923335, 6362.936370636281, 5725.599208055438, 5762.538996880239, 5773.682447465346, 5796.913046304308, 5873.618214816023, 6282.057691346335, 6337.714802833883, 6339.4611458025665, 6341.524385671001, 6345.682715864188, 6214.37856214384, 6236.781712640127, 6238.781642642486, 6246.718897649616, 6264.59280146789, 6132.325444201134, 6249.125122482765, 6251.374862513712, 6354.26925903527, 6391.2330520337855, 6107.947487155049, 6184.752609895644, 6225.968870155621, 6277.435030847246, 6315.210598675166, 6285.277193122758, 6306.306306306303, 6313.08460273267, 6315.084312774622, 6340.556161068681, 4859.829905953275, 4902.730349830794, 4959.776810043557, 4963.776630051656, 4977.278294647256, 4403.987082970934, 4422.756748378806, 4460.576245256761, 4469.910601788011, 4476.843310484118, 5836.299644042742, 5844.1818145459665, 5846.590738648339, 5857.7656893724625, 5909.763609455574, 6344.0, 6344.936550634527, 6351.364863513704, 6352.936470635327, 6367.395097465734, 5626.099824028136, 5723.084306510938, 5734.369219385864, 5784.248047753765, 5845.561582881301, 5448.128299472065, 5463.644863083887, 5476.890462190734, 5478.780848766005, 5488.984537860429, 6303.7793677222, 6316.715747791269, 6321.936780632227, 6330.556861019869, 6337.90493142594, 6131.0, 6236.126942228001, 6353.650549219838, 6364.268109167505, 6384.521160913043, 6250.312465628868, 6258.843528911813, 6279.560430769802, 6320.747170113328, 6337.4613157882095, 6177.042558403455, 6219.533534984804, 6232.626042437469, 6241.31345551998, 6245.531585131224, 6153.600015998945, 6256.249250090018, 6346.7143978522, 6348.968255158695, 6358.237011558643, 6041.395860413923, 6118.174046503665, 6134.969325153525, 6138.9095594588325, 6201.472874805511, 6195.907061393992, 6197.907031394442, 6208.720607572761, 6210.944139496206, 6220.564560480723, 6030.216071910623, 6043.697815109315, 6059.575829691879, 6061.848453788691, 6074.3318234994995, 6012.759489620366, 6066.545009124245, 6096.75612975476, 6102.176206212104, 6120.020796672511, 5187.714675692873, 6029.065494848305, 6038.818835434944, 6048.0625503047095, 6079.452849243589], "value": 5748.098653600228, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.09029205677433, 508.4711899624391, 512.4849526226143, 517.5988608828158, 540.3407842432234, 29177.270568235806, 30890.0, 30952.690473095263, 38717.0, 39335.606643933555, 40056.79971600145, 40494.7975260124, 40532.39201411974, 40591.39112913309, 40614.59385406145, 9101.49941753205, 9254.981951985275, 9261.95369023154, 9262.397944133643, 9268.25853931684, 9178.082191780835, 9276.211522020636, 9300.976892541816, 9303.906960930406, 9322.347435679505, 9308.162265396113, 9335.719928402159, 9356.532173391337, 9369.67206147785, 9376.390534615228, 9148.817023659556, 9244.537773111351, 9298.070192980715, 9298.302627302912, 9310.115539023782, 8465.153484651548, 8736.951565812144, 9198.770030749218, 9256.305777066747, 9266.027067157986, 37829.432558511646, 38262.23475530474, 38550.42174367387, 38699.806500967316, 38733.03167420809, 3155.179653290138, 3273.3944220319318, 3298.0765385692043, 3318.6515415881468, 3319.1536158279728, 28937.710622893712, 28972.855135724185, 29144.0, 29495.70504294951, 29758.553621695693, 3111.48660471022, 3197.712205901457, 3200.8559614817154, 3229.677032296775, 3238.740900727936, 427441.8627906872, 482822.0, 485933.0, 488815.0, 492512.0, 30658.38683226323, 45745.0, 56053.7197314019, 56087.0, 56120.0, 32407.67592324094, 33220.66779332176, 33384.833075834955, 33462.996110116736, 33556.66443335584, 13984.790228146587, 14106.0, 14182.361793719307, 14195.432182712575, 14198.716025679429, 11833.290002599872, 11895.88104118965, 11897.988670962892, 11936.880631193751, 11937.164398492023, 6645.568038077509, 7667.118281397599, 7686.884696729554, 7771.300582947561, 7789.883151752728, 3128.906132816019, 3169.112648458435, 3188.2985743136474, 3191.69678880506, 3211.0, 813.5932033983008, 899.8920129584492, 905.7690288976269, 948.6537413844035, 958.91849192818, 28013.57979630307, 28692.0, 28821.42357152887, 29488.557671634942, 29565.556516651835, 933.7618907178762, 1028.598846449885, 1047.261680515235, 1066.2962444786558, 1075.2150929821273, 14533.491327803476, 14970.47603333878, 15108.449529257565, 15237.00959437633, 15281.694366112837, 683212.0, 683458.0, 684509.0, 685832.0, 686963.0, 55054.0, 55532.0, 55540.72229638826, 55591.16613250804, 55803.72098139564, 1197.2995797458411, 1233.1614502138452, 1236.9814452783035, 1326.4959315460064, 1354.4040622126233, 28439.146825595264, 29528.96648617288, 29883.850580746956, 30106.397872042, 30809.845950771, 46698.53301466942, 47234.81912952204, 47239.055218896116, 47411.0, 47412.52587474081, 543808.0, 548178.0, 549369.0, 551739.9652007017, 708262.0, 36709.26581468409, 37460.0, 38247.42628860625, 38465.80767096147, 38516.0, 43092.13815723731, 43107.0, 43560.0, 43972.340414893166, 44364.77817610891, 36194.09514762152, 36217.63782362248, 36387.27225455529, 36790.63209367979, 37332.06669833276, 36417.0, 37233.813830930674, 37374.8131259342, 37404.62595374011, 37532.624673754006, 35957.64042359543, 36388.636113638524, 36703.0, 37181.62818371782, 37421.25157496889, 31237.84381078171, 33035.00894973155, 33469.66530334763, 33508.0, 33607.66392336045, 24409.0, 24578.055413820206, 24588.0, 24609.50780984406, 24660.070188637925], "value": 18083.54250267566, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.4777600814806, 373.0896612266071, 377.9754315969462, 391.4421948723069, 408.6424378668665, 14440.350184241708, 14842.77735833962, 15342.84657153428, 19526.41420757376, 20140.798592014075, 19032.61934761304, 19107.61784764304, 19588.902055489703, 19878.90060549695, 20160.49598760028, 5743.4543718346795, 6028.0, 6030.396960303979, 6050.606710563821, 6050.848728781772, 4140.68944829139, 4346.369776382423, 5894.381089985554, 5973.611715238518, 5981.312149102843, 4355.324924636687, 4397.12057588482, 6278.68606569672, 6574.572652777554, 6616.139901812781, 6362.363763623647, 6541.084248205255, 6606.306337834554, 6663.3336666333225, 6695.397414232718, 5704.0, 5721.485066344049, 5729.0, 5735.225744524478, 5739.598228124033, 24327.0, 25447.61828572573, 25496.74503254962, 25698.100566480083, 25761.871190643928, 1858.6468570971472, 2464.137551856854, 2468.2101727447225, 2658.3275927862414, 2763.69599344072, 12552.560660376934, 14473.42106315746, 14731.926340368336, 15599.532014039596, 15693.8229632778, 1588.4043483693615, 1833.9908300458546, 1853.166075266122, 1881.3321270948752, 1935.2452543508043, 119932.0, 121871.0, 130043.0, 131288.3435582816, 133688.0, 11537.250078744855, 11539.307641541367, 11562.710932226762, 11572.24780389272, 11929.463174157183, 14799.0, 14825.110493370217, 14849.40602375893, 14856.885733570025, 14864.331105100297, 7306.452016098814, 8224.794380140544, 8403.915960840435, 8505.10696376884, 8655.350848686248, 8279.834403312021, 8291.170882911661, 8292.17078292166, 8404.159584041668, 8461.450005749606, 3753.512043434336, 4152.979235103805, 4189.91062323798, 4431.844885429059, 4517.864464066083, 1552.309222396023, 1754.5086676325277, 1922.087008670898, 1923.519120219945, 1924.220690620301, 720.204174387305, 744.5235049568281, 802.9558374289353, 804.63389157933, 1041.354360296621, 18948.92598091417, 19543.120559574854, 19620.607587848164, 20636.0, 21083.156673733185, 925.9305552083486, 927.1470247372433, 930.5719369090159, 944.5938246554048, 976.7558110472382, 10852.728681783019, 11073.5570577176, 11116.999470047735, 11118.61084862026, 11185.272957257752, 592780.0, 599498.0, 624148.0, 635636.0, 636576.0, 127009.36495317465, 127846.36076819742, 131170.0, 134949.3252533731, 138419.0, 954.4893481987103, 956.8086382723344, 967.1923943507251, 981.6073570571829, 986.8470387090013, 16724.832751672813, 18385.356512521998, 18651.347202847835, 18699.439016829518, 18795.624087518445, 56302.718486407306, 56916.0, 57529.71235143798, 57990.13014804697, 58665.0, 80588.59705701434, 82176.0, 82443.0, 83896.0, 83909.0, 5481.205225242397, 5619.157126431068, 6341.365863413622, 7816.140224575179, 8329.083800782031, 11311.208215424842, 12636.747265054832, 12833.358332083546, 13074.0, 13085.476580936656, 10814.89185108139, 10999.845016273177, 10999.890001100206, 11143.0, 11593.072554195813, 10850.40322782223, 10986.780264394827, 11000.614978475714, 11243.325400475998, 11332.660020199408, 10812.783744325227, 11044.447777610927, 11265.042471389695, 11361.261518001304, 11646.475908584303, 8037.115917248981, 8329.083800781787, 8329.50022998622, 8357.749267521984, 8461.407701461085, 10163.695089147337, 10900.836487452852, 10961.890381096087, 11041.889581104086, 11312.547498099984], "value": 9560.607626538822, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1470.779383092536, 1498.3631956418521, 1648.9175541222944, 1799.0914588133007, 1824.3888297420356, 6585.703643336049, 8451.408401411903, 8451.746447606567, 8463.322934165268, 8465.44974576653, 7355.852882942338, 7631.1605723370385, 7634.083909930802, 7680.347170490515, 7698.307152356287, 2085.7914208579173, 2132.2110818996994, 2226.421130506072, 2303.078768492608, 2304.7695230476984, 2047.7440319960006, 2104.8002638495987, 2348.0255693887016, 2349.3891588187025, 2350.0129945422964, 2263.5020295534955, 2281.817454603636, 2315.6294992801154, 2318.6985691860036, 2319.8376113672048, 2279.247848210087, 2282.6233671444193, 2303.98624605174, 2327.104064934995, 2332.976670233293, 2327.6392159215347, 2331.2306938710353, 2352.294311706481, 2358.705161854768, 2361.9173328933407, 8352.749417517483, 8377.748667539983, 8454.788630284293, 8471.618777155043, 8482.703105391343, 5072.036313100499, 5259.2899958505495, 5260.421353651095, 5261.394939581959, 5266.183741520071, 3455.2398472336045, 3486.18074752433, 3500.7199424046016, 3520.6127325994375, 3583.3191693578137, 997.875265591801, 1005.1506477026901, 1064.297563608015, 1098.8461615373853, 1105.3202280597454, 10847.511861966152, 10971.725706857313, 10979.890201097967, 10983.945080274547, 11000.394978276192, 4466.955330446719, 4626.629869610423, 4756.0, 4758.976205119022, 4762.761861906891, 9320.673776417792, 9472.384295020802, 9517.571709272961, 9530.999245079305, 9531.189848862925, 5213.713245771443, 5222.686638801684, 5223.190405487156, 5223.529882310534, 5231.136862417735, 4060.9796951015055, 4592.119658888234, 4736.786844592001, 4749.572538471554, 4793.113274044313, 3558.323918455485, 3667.0832291927018, 3672.2839046385698, 3801.6388443097862, 3895.7078219133114, 1233.962981110568, 1270.0538099116218, 1289.5937779599396, 1298.9155704879154, 1301.5054279373778, 785.8978332816697, 880.41892351048, 900.090908182727, 918.2653876898454, 925.8333499969935, 5643.379228284885, 5670.5747068969995, 5752.568557358215, 5787.218725472091, 5883.264591926009, 797.9920200797918, 901.6528636474873, 922.9861552076724, 938.2822141062093, 989.5398639632606, 3757.4176002719623, 3796.354619714655, 3801.8099095045136, 3853.788041657736, 3878.7478813877005, 9830.557624906896, 10334.018268264503, 10356.326838755456, 10361.171106311476, 10414.5834166634, 10066.798664026679, 10328.018838210359, 10328.070473657406, 10340.948295258475, 10356.482175891175, 7582.696692132363, 7726.575038372944, 7841.215878412113, 7842.764717058383, 7861.4103942204565, 5117.925235700396, 5139.4603566626465, 5529.4470552944385, 5712.400197979152, 5787.826365209051, 8706.345794299063, 8850.336224783296, 9097.13577210164, 9538.189253913495, 9595.088466595662, 9446.669366572138, 9457.763555910882, 9584.424934503952, 9616.423014619146, 9723.708288751348, 7436.107667079985, 7674.155842857393, 7746.728864489716, 7851.293383595504, 7864.095629002738, 6358.682065896779, 7126.501144920013, 7912.604369781603, 7972.1230664628, 7980.680772769024, 6096.725647345968, 7148.28517148281, 7149.0706208192605, 7151.60666163366, 7171.211166771546, 6950.478714096362, 7051.753188638373, 7067.469939754436, 7071.611061391673, 7107.82230444243, 6569.770058047945, 6713.194416669836, 6714.932850671431, 6716.328367163244, 6718.966405167943, 3950.091478959913, 4068.5524592295415, 4760.071786001697, 4830.468648448597, 4898.730569818694, 4772.236442169236, 4792.113459010095, 4830.106430310263, 5010.173321401928, 5049.747512624427], "value": 4412.351107967036, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [257.2360090531123, 309.81256339914364, 312.62485017978423, 316.68331668331666, 319.29595242490313, 4597.218472859614, 5225.178403662023, 5265.657257399363, 5289.80473103662, 5319.441458646845, 4726.700157456701, 4798.544138306864, 4870.391201099863, 5070.898582028357, 5261.76322065507, 3553.3781588222087, 3846.8076596170217, 3929.744566603176, 3958.7228893977435, 3967.3453880109746, 3104.58088158098, 3384.323135372923, 3733.365327894251, 3949.34835752102, 3956.3076461619244, 3032.272254658877, 3255.544223808669, 3876.3604005339084, 3883.825227864753, 3900.941485877714, 3202.2794871153806, 3285.8357082145917, 3435.7974708851953, 3931.036895960494, 3953.2686453006286, 2857.3428111534254, 3070.6775788542104, 3409.5226668266464, 3418.000469725189, 3428.468587368962, 5064.708499332646, 5070.518300761421, 5097.643164978453, 5103.897922041576, 5121.00140472608, 4209.621134097946, 4453.51011388747, 4487.439070116236, 4519.457665080178, 4529.343245229456, 5490.627343164209, 5506.669599824023, 5530.557555395559, 5537.944620553823, 5542.362628297757, 3752.43713442983, 3765.8681946131755, 3778.4521244419407, 3778.7543809652284, 3840.020794697334, 5347.598930080259, 5527.585431092684, 5755.654660720328, 5764.394738552475, 5826.854328641775, 3642.0712718257205, 3801.9429708554394, 3818.0, 3826.253880493277, 3832.6550610445192, 3461.7576769625884, 3772.4718539404507, 3879.51710905021, 3922.587608620735, 3928.980355098206, 3489.023073539413, 4272.347143192588, 4466.0398014427055, 4525.954740452553, 4542.909141817145, 5015.3730783652045, 5041.294218809369, 5183.9113786105345, 5538.476918847349, 5616.5787565932715, 1121.8837256929244, 1245.40843099527, 1246.5512415530402, 1247.8689737577606, 1250.562303193893, 696.5924933913717, 819.5041999590281, 819.7499762572353, 822.7038266224155, 828.225609055535, 4233.491980962304, 4388.232059389594, 4772.618190544748, 4814.9518504814505, 4817.470078291385, 4737.242041273448, 4936.358273424432, 4984.77568509418, 5292.735363231823, 5310.955282216297, 4288.656907447396, 4343.045629466753, 4343.548846198064, 4751.59611433032, 4852.393450818648, 2406.819488538367, 2738.0964281786964, 2779.998401151168, 2798.902038428645, 2804.5512717965435, 5260.342457192851, 5371.368029665514, 5439.945600544023, 5581.944180558143, 5607.243022191952, 4857.562819346205, 4887.898203772412, 4900.623197749089, 5498.367687715884, 5498.525663451383, 4511.233090374644, 4684.391029166187, 5410.026195284808, 5434.592405569519, 5445.727713614224, 4774.856754297377, 4787.712737235777, 5267.446918073547, 5274.657147285414, 5283.735813209248, 4852.611791056636, 4963.4540200577185, 5010.918285254469, 5161.7677204526635, 5297.2783845251215, 5037.874053148701, 5097.439281678938, 5170.146925757208, 5457.863553411196, 5479.424660410759, 3033.620797400325, 3061.6172978377704, 3571.5535558055244, 3588.946165807567, 3589.497470354205, 4940.6788558743565, 4984.102861484823, 4989.825356112518, 5232.581393488435, 5332.78709819254, 4192.538820729656, 5076.263941728503, 5081.593472522115, 5122.871928201825, 5158.561522270499, 4756.381670382828, 4948.1835497142565, 4986.227134794112, 5123.538881500683, 5154.922676159786, 4363.89090272733, 4467.441569803775, 5002.799888004439, 5011.348524691767, 5116.0, 5044.722540260321, 5336.973315133399, 5338.876757097141, 5360.919586206288, 5498.76595021823, 4561.954380456153, 5351.759170837244, 5370.946290537201, 5410.567154627542, 5473.124300111963], "value": 3846.2887061379674, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.2803848017303, 738.7491768294192, 743.9293267139622, 767.1906139023332, 775.1279810213509, 13920.234387108705, 14088.64778380541, 14312.785308220386, 14328.140311581286, 14340.211288379134, 14066.64833379166, 14702.779458308107, 14759.77860332096, 14961.551153465387, 14996.250187490608, 661.7319985405918, 683.3883674111667, 833.5040650812757, 857.1557016338933, 857.4212406625534, 685.1880521581917, 816.7753867686374, 840.6637345061994, 849.4605925237457, 856.9742907712779, 869.7390782765177, 884.95575221239, 887.3433659092258, 888.1251966812684, 889.6752685269862, 878.2008372381134, 878.3412440669498, 878.925291350236, 879.2965627498008, 882.7793051737066, 517.627308337996, 534.1427009649534, 637.2798737426679, 657.4149007383432, 662.4071456046811, 13757.38091785872, 14177.007609467342, 14195.077319974273, 14207.14757114566, 14228.075175113689, 12801.359932003363, 13021.674458138528, 13044.804327934993, 13114.213147211198, 13183.538576149884, 11715.414229288586, 12188.390580470941, 12299.508019679202, 12327.876721232762, 12539.435725392379, 4295.8496452624, 4389.253826849444, 4406.317020861771, 4406.515283318832, 4475.843345482893, 76201.85697214546, 92342.0, 95263.0, 95890.0, 97981.0, 10468.895311046945, 10563.63027294041, 10733.141348692086, 10815.78368432627, 10973.890261097447, 20192.293269735488, 20283.492912677008, 20405.2858149967, 20536.07587658559, 20582.691259631116, 7413.518121322097, 7589.848203035908, 7608.885866712004, 7625.0, 7829.843403132019, 4131.318332475108, 4174.102567947906, 4283.892902677396, 4295.935560966588, 4406.096750166273, 3401.285729996679, 3516.0506663200795, 3517.1031386996665, 3524.9823750881083, 3541.38025845476, 626.8934281172212, 627.4666533446534, 628.522323034497, 634.1755717567145, 644.2880616918331, 621.9004959206505, 627.4541149200224, 635.8029011006603, 649.8375406148463, 688.3632639808158, 8811.603477843513, 8848.46909185451, 8861.113888611088, 9068.183863452321, 9456.810863782686, 517.5962749055742, 556.6862205195735, 617.5677026081761, 664.8404382948154, 678.4267294136447, 4393.826609101273, 4465.26323156682, 4466.151431228056, 4473.753943533137, 4621.445426548836, 87550.56224718835, 88653.55673221593, 117408.0, 119262.0, 120238.0, 95448.0, 96509.51745241228, 100073.0, 100533.49733251287, 100885.0, 89204.0, 90081.54959225161, 92092.0, 93298.0, 93447.53276233576, 8562.357823163164, 8644.740657780276, 8844.73465796027, 8876.778080548038, 8904.821903561762, 58885.0, 58894.0, 59067.113993290994, 59992.0, 60007.0, 64257.678711606146, 79862.6006869962, 82174.58912705399, 83568.58215708882, 86105.56947215224, 10436.686899393031, 11695.0, 11828.881711182778, 12109.515619375126, 12218.0, 13133.868661313265, 14293.785593216318, 14299.571012869214, 14323.140611562923, 14852.405903763729, 11319.490622921823, 13235.602931912059, 13291.667708306983, 13461.0, 13742.793858091936, 12854.742905142031, 12921.418536166084, 12982.15615984958, 13394.129381590166, 13544.255065971467, 12290.508379664714, 12786.36068196605, 12851.871481285067, 13401.597952061455, 14067.648308792363, 10347.379157250589, 10880.347179169275, 11329.376884271443, 11522.711932201762, 11774.175807693378, 11433.256838305484, 11435.828462573234, 11481.598144064918, 11566.339741859587, 11600.419979000848], "value": 7399.568812785467, "unit": "score"}], "name": "dromaeo_css", "value": 7069.298679758888, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 115.0, 114.0, 114.0, 114.0, 118.0, 161.0, 166.0, 115.0], "value": 124.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 112.0, 112.0, 143.0, 109.0, 113.0, 116.0, 110.0, 141.0, 109.0], "value": 120.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 185.0, 185.0, 257.0, 223.0, 185.0, 183.0, 230.0], "value": 200.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 58.0, 86.0, 61.0, 59.0, 59.0, 59.0, 82.0, 58.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 70.0, 94.0, 69.0, 70.0, 91.0, 126.0, 98.0, 68.0, 92.0], "value": 85.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 90.0, 87.0, 86.0, 84.0, 88.0, 84.0, 87.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 94.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 111.0, 79.0, 79.0, 82.0, 84.0, 86.0, 82.0, 82.0, 82.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 53.0, 51.0, 58.0, 59.0, 58.0, 56.0, 55.0, 56.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 67.0, 76.0, 70.0, 68.0, 72.0, 103.0, 105.0, 70.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 83.0, 85.0, 96.0, 82.0, 80.0, 97.0, 85.0, 88.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 152.0, 151.0, 154.0, 164.0, 150.0, 149.0, 149.0, 196.0, 143.0], "value": 155.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 168.0, 168.0, 169.0, 169.0, 168.0, 168.0, 166.0, 166.0, 217.0], "value": 171.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 66.0, 65.0, 65.0, 66.0, 65.0, 67.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1474.7, "alertThreshold": 2.0}]} 12:05:16 INFO - Return code: 0 12:05:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:05:16 ERROR - # TBPL SUCCESS # 12:05:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:05:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:05:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:05:16 INFO - 2016-11-14 12:05:16.902 screenresolution[1667:9934] starting screenresolution argv=screenresolution get 12:05:16 INFO - 2016-11-14 12:05:16.904 screenresolution[1667:9934] Display 0: 1600x1200x32@60 12:05:16 INFO - 2016-11-14 12:05:16.911 screenresolution[1668:9937] starting screenresolution argv=screenresolution list 12:05:16 INFO - Available Modes on Display 0 12:05:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:05:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:05:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:05:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:05:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:05:17 INFO - Intel Iris: 12:05:17 INFO - Chipset Model: Intel Iris 12:05:17 INFO - Type: GPU 12:05:17 INFO - Bus: Built-In 12:05:17 INFO - VRAM (Dynamic, Max): 1536 MB 12:05:17 INFO - Vendor: Intel (0x8086) 12:05:17 INFO - Device ID: 0x0a2e 12:05:17 INFO - Revision ID: 0x0009 12:05:17 INFO - Displays: 12:05:17 INFO - Display: 12:05:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:05:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:05:17 INFO - Display Serial Number: 1600x1200 60 12:05:17 INFO - Main Display: Yes 12:05:17 INFO - Mirror: Off 12:05:17 INFO - Online: Yes 12:05:17 INFO - Rotation: Supported 12:05:17 INFO - Return code: 0 12:05:17 INFO - Running post-action listener: _resource_record_post_action 12:05:17 INFO - [mozharness: 2016-11-14 20:05:17.151271Z] Finished run-tests step (success) 12:05:17 INFO - Running post-run listener: _resource_record_post_run 12:05:17 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38319104; Write bytes: 652568576; Read time: 384; Write time: 2009 12:05:17 INFO - TinderboxPrint: CPU usage
53.6% 12:05:17 INFO - TinderboxPrint: I/O read bytes / time
38,319,104 / 384 12:05:17 INFO - TinderboxPrint: I/O write bytes / time
652,568,576 / 2,009 12:05:17 INFO - TinderboxPrint: CPU idle
2,246.9 (46.4%) 12:05:17 INFO - TinderboxPrint: CPU system
973.8 (20.1%) 12:05:17 INFO - TinderboxPrint: CPU user
1,625.1 (33.5%) 12:05:17 INFO - TinderboxPrint: Swap in / out
391,286,784 / 0 12:05:17 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345362944; Write bytes: 345069056; Read time: 26494; Write time: 660 12:05:17 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 37344256; Write bytes: 297452032; Read time: 368; Write time: 1291 12:05:17 INFO - Running post-run listener: _upload_blobber_files 12:05:17 INFO - Blob upload gear active. 12:05:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:05:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:05:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:05:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:05:18 INFO - (blobuploader) - INFO - Open directory for files ... 12:05:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:05:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:05:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:05:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:05:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:05:18 INFO - (blobuploader) - INFO - Done attempting. 12:05:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:05:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:05:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:05:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:05:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:05:19 INFO - (blobuploader) - INFO - Done attempting. 12:05:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:05:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:05:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:05:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:05:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:05:22 INFO - (blobuploader) - INFO - Done attempting. 12:05:22 INFO - (blobuploader) - INFO - Iteration through files over. 12:05:22 INFO - Return code: 0 12:05:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:05:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:05:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/50d516566eb8d05aaeb8504d38fd48f36d5e6a470a1b936e351bce639bfb4b05124c863423d1318b982ee6338b57126902c6e16c3e4b2e8403702960df04830a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85a4f6ac88674bbb166d0e65144cff21b4ac1534ad581ac2a566d56ad51f0a1d492462686d84ddb4546293decf83d0b21eb659a7b1269a40ce08ef0e8ca00e4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7107860a7093585f1dfe5f7a2788ae57264363e73905d7f292dbcc9ff3502359576a4396ab86720b63e3a47cfd4a6bf790f72979a30d8304d9d86eadbee3b3e"} 12:05:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:05:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:05:22 INFO - Contents: 12:05:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/50d516566eb8d05aaeb8504d38fd48f36d5e6a470a1b936e351bce639bfb4b05124c863423d1318b982ee6338b57126902c6e16c3e4b2e8403702960df04830a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85a4f6ac88674bbb166d0e65144cff21b4ac1534ad581ac2a566d56ad51f0a1d492462686d84ddb4546293decf83d0b21eb659a7b1269a40ce08ef0e8ca00e4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7107860a7093585f1dfe5f7a2788ae57264363e73905d7f292dbcc9ff3502359576a4396ab86720b63e3a47cfd4a6bf790f72979a30d8304d9d86eadbee3b3e"} 12:05:22 INFO - Running post-run listener: copy_logs_to_upload_dir 12:05:22 INFO - Copying logs to upload dir... 12:05:22 INFO - mkdir: /builds/slave/test/build/upload/logs 12:05:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.158230 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-14 12:05:22.495589) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 12:05:22.503619) ========= 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.bKxFfr6nyI/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.WNFZppOZUi/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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/50d516566eb8d05aaeb8504d38fd48f36d5e6a470a1b936e351bce639bfb4b05124c863423d1318b982ee6338b57126902c6e16c3e4b2e8403702960df04830a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85a4f6ac88674bbb166d0e65144cff21b4ac1534ad581ac2a566d56ad51f0a1d492462686d84ddb4546293decf83d0b21eb659a7b1269a40ce08ef0e8ca00e4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7107860a7093585f1dfe5f7a2788ae57264363e73905d7f292dbcc9ff3502359576a4396ab86720b63e3a47cfd4a6bf790f72979a30d8304d9d86eadbee3b3e"} build_url:https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010825 build_url: 'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/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/50d516566eb8d05aaeb8504d38fd48f36d5e6a470a1b936e351bce639bfb4b05124c863423d1318b982ee6338b57126902c6e16c3e4b2e8403702960df04830a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85a4f6ac88674bbb166d0e65144cff21b4ac1534ad581ac2a566d56ad51f0a1d492462686d84ddb4546293decf83d0b21eb659a7b1269a40ce08ef0e8ca00e4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7107860a7093585f1dfe5f7a2788ae57264363e73905d7f292dbcc9ff3502359576a4396ab86720b63e3a47cfd4a6bf790f72979a30d8304d9d86eadbee3b3e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 12:05:22.539462) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 12:05:22.539786) ========= 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.bKxFfr6nyI/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.WNFZppOZUi/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.004903 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 12:05:22.587815) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 12:05:22.588120) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 12:05:22.591479) ========= ========= Total master_lag: 0.20 =========