builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0306 starttime: 1477866283.86 results: success (0) buildid: 20161030133222 builduid: b681267884774c8fadef1c0b35fa4d65 revision: d7d142a7614746f609a0997bb70bc7cfae04915d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-30 15:24:43.858342) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-30 15:24:43.858708) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-30 15:24:43.858984) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-30 15:24:44.098905) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 15:24:44.099190) ========= 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.DIg49BniEo/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.NqVFRKgBKJ/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-30 15:24:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.35M=0.001s 2016-10-30 15:24:44 (8.35 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.166400 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 15:24:44.288928) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 15:24:44.289320) ========= 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.DIg49BniEo/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.NqVFRKgBKJ/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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.019429 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 15:24:44.339266) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-30 15:24:44.339674) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev d7d142a7614746f609a0997bb70bc7cfae04915d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev d7d142a7614746f609a0997bb70bc7cfae04915d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DIg49BniEo/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.NqVFRKgBKJ/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-30 15:24:44,419 truncating revision to first 12 chars 2016-10-30 15:24:44,419 Setting DEBUG logging. 2016-10-30 15:24:44,419 attempt 1/10 2016-10-30 15:24:44,419 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/d7d142a76147?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-30 15:24:46,333 unpacking tar archive at: larch-d7d142a76147/testing/mozharness/ program finished with exit code 0 elapsedTime=2.191991 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-30 15:24:46.554229) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-30 15:24:46.554586) ========= script_repo_revision: d7d142a7614746f609a0997bb70bc7cfae04915d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-30 15:24:46.555006) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-30 15:24:46.555333) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-30 15:24:46.574085) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-30 15:24:46.574525) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DIg49BniEo/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.NqVFRKgBKJ/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 15:24:46 INFO - MultiFileLogger online at 20161030 15:24:46 in /builds/slave/test 15:24:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 15:24:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:24:46 INFO - {'append_to_log': False, 15:24:46 INFO - 'base_work_dir': '/builds/slave/test', 15:24:46 INFO - 'blob_upload_branch': 'Larch', 15:24:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:24:46 INFO - 'branch': 'Larch', 15:24:46 INFO - 'buildbot_json_path': 'buildprops.json', 15:24:46 INFO - 'config_files': ('talos/mac_config.py',), 15:24:46 INFO - 'default_actions': ('clobber', 15:24:46 INFO - 'read-buildbot-config', 15:24:46 INFO - 'download-and-extract', 15:24:46 INFO - 'populate-webroot', 15:24:46 INFO - 'create-virtualenv', 15:24:46 INFO - 'install', 15:24:46 INFO - 'run-tests'), 15:24:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:24:46 INFO - 'download_minidump_stackwalk': True, 15:24:46 INFO - 'download_symbols': 'ondemand', 15:24:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:24:46 INFO - 'tooltool.py': '/tools/tooltool.py', 15:24:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:24:46 INFO - '/tools/misc-python/virtualenv.py')}, 15:24:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:24:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:24:46 INFO - 'installer_path': 'installer.exe', 15:24:46 INFO - 'log_level': 'info', 15:24:46 INFO - 'log_name': 'talos', 15:24:46 INFO - 'log_to_console': True, 15:24:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:24:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:24:46 INFO - 'opt_config_files': (), 15:24:46 INFO - 'pip_index': False, 15:24:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:24:46 INFO - 'cmd': ('bash', 15:24:46 INFO - '-c', 15:24:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:24:46 INFO - 'enabled': True, 15:24:46 INFO - 'halt_on_failure': False, 15:24:46 INFO - 'name': 'check_screen_resolution'},), 15:24:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:24:46 INFO - 'cmd': ('bash', 15:24:46 INFO - '-c', 15:24:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:24:46 INFO - 'enabled': True, 15:24:46 INFO - 'halt_on_failure': False, 15:24:46 INFO - 'name': 'check_screen_resolution'},), 15:24:46 INFO - 'run_cmd_checks_enabled': True, 15:24:46 INFO - 'sps_profile': False, 15:24:46 INFO - 'sps_profile_interval': 0, 15:24:46 INFO - 'suite': 'dromaeojs', 15:24:46 INFO - 'system_bits': '32', 15:24:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:24:46 INFO - 'title': 't-yosemite-r7-0306', 15:24:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:24:46 INFO - 'use_talos_json': True, 15:24:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:24:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:24:46 INFO - 'work_dir': 'build'} 15:24:46 INFO - [mozharness: 2016-10-30 22:24:46.777500Z] Running clobber step. 15:24:46 INFO - Running pre-action listener: _resource_record_pre_action 15:24:46 INFO - Running main action method: clobber 15:24:46 INFO - rmtree: /builds/slave/test/build 15:24:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:24:48 INFO - Running post-action listener: _resource_record_post_action 15:24:48 INFO - [mozharness: 2016-10-30 22:24:48.332057Z] Finished clobber step (success) 15:24:48 INFO - [mozharness: 2016-10-30 22:24:48.332190Z] Running read-buildbot-config step. 15:24:48 INFO - Running pre-action listener: _resource_record_pre_action 15:24:48 INFO - Running main action method: read_buildbot_config 15:24:48 INFO - Using buildbot properties: 15:24:48 INFO - { 15:24:48 INFO - "project": "", 15:24:48 INFO - "product": "firefox", 15:24:48 INFO - "script_repo_revision": "production", 15:24:48 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 15:24:48 INFO - "repository": "", 15:24:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 15:24:48 INFO - "buildid": "20161030133222", 15:24:48 INFO - "pgo_build": "False", 15:24:48 INFO - "basedir": "/builds/slave/test", 15:24:48 INFO - "buildnumber": 5, 15:24:48 INFO - "slavename": "t-yosemite-r7-0306", 15:24:48 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:24:48 INFO - "platform": "yosemite_r7", 15:24:48 INFO - "scheduler": "tests-larch-macosx64-talos", 15:24:48 INFO - "branch": "larch", 15:24:48 INFO - "repo_path": "projects/larch", 15:24:48 INFO - "revision": "d7d142a7614746f609a0997bb70bc7cfae04915d", 15:24:48 INFO - "stage_platform": "macosx64", 15:24:48 INFO - "builduid": "b681267884774c8fadef1c0b35fa4d65", 15:24:48 INFO - "slavebuilddir": "test" 15:24:48 INFO - } 15:24:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 15:24:48 INFO - Running post-action listener: _resource_record_post_action 15:24:48 INFO - [mozharness: 2016-10-30 22:24:48.335575Z] Finished read-buildbot-config step (success) 15:24:48 INFO - [mozharness: 2016-10-30 22:24:48.335705Z] Running download-and-extract step. 15:24:48 INFO - Running pre-action listener: _resource_record_pre_action 15:24:48 INFO - Running main action method: download_and_extract 15:24:48 INFO - mkdir: /builds/slave/test/build/tests 15:24: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')]} 15:24:48 INFO - trying https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 15:24:48 INFO - Downloading https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:24:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 15:24:49 INFO - Downloaded 1199 bytes. 15:24:49 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:24:49 INFO - Using the following test package requirements: 15:24:49 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:24:49 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:24:49 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 15:24:49 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:24:49 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 15:24:49 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:24:49 INFO - u'jsshell-mac.zip'], 15:24:49 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:24:49 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 15:24:49 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:24:49 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:24:49 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 15:24:49 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:24:49 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 15:24:49 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:24:49 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 15:24:49 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:24:49 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 15:24:49 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:24:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:24:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:24:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:24:50 INFO - Http code: 200 15:24:50 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 15:24:50 INFO - x-amz-cf-id: 5cf0_-PgoqljtL1mWRga4jQ8w9JBVMQQq22iLt6-M3xEQhM5YnVn7A== 15:24:50 INFO - x-amz-version-id: WOVJCHxG48GQFeNxmxj8Nj3hxwEkRJr3 15:24:50 INFO - Expected file size: 16391873 15:24:50 INFO - Obtained file size: 16391873 15:24:57 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:24:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:24:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:24:57 INFO - Fetch https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:24:57 INFO - Http code: 200 15:24:57 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 15:24:57 INFO - x-amz-cf-id: BymltiLWhmiEk_tbiSWMq5zBWRO-jXGiXRi-JzvcHg0pZCjs-jS5-w== 15:24:57 INFO - x-amz-version-id: WOVJCHxG48GQFeNxmxj8Nj3hxwEkRJr3 15:24:58 INFO - Expected file size: 16391873 15:24:58 INFO - Obtained file size: 16391873 15:25:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 15:25:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:25:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 15:25:04 INFO - Http code: 200 15:25:04 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 15:25:04 INFO - x-amz-cf-id: oY0C_VxYwLYqaJzJcQZQPcC82KpZF1gN4MP2WDDr7i-FNqYp-16CTA== 15:25:04 INFO - x-amz-version-id: 2HTGhzo8wia6K_SL6wZm_8B9wOG9TJek 15:25:05 INFO - Expected file size: 14409038 15:25:05 INFO - Obtained file size: 14409038 15:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:05 INFO - trying https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:25:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 15:25:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 15:25:08 INFO - Downloaded 112161130 bytes. 15:25:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:25:08 INFO - mkdir: /builds/slave/test/properties 15:25:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:25:08 INFO - Writing to file /builds/slave/test/properties/build_url 15:25:08 INFO - Contents: 15:25:08 INFO - build_url:https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:25:09 INFO - Running post-action listener: _resource_record_post_action 15:25:09 INFO - Running post-action listener: set_extra_try_arguments 15:25:09 INFO - [mozharness: 2016-10-30 22:25:09.047883Z] Finished download-and-extract step (success) 15:25:09 INFO - [mozharness: 2016-10-30 22:25:09.048017Z] Running populate-webroot step. 15:25:09 INFO - Running pre-action listener: _resource_record_pre_action 15:25:09 INFO - Running main action method: populate_webroot 15:25:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:25:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:25:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:25:09 INFO - '--tppagecycles', 15:25:09 INFO - '7'], 15:25:09 INFO - 'tests': ['tsvgm']}}, 15:25:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:25:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:25:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:25:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:25:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:25:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:25:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:09 INFO - 'talos_options': ['--disable-e10s'], 15:25:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:25:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:25:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:09 INFO - 'talos_options': ['--disable-e10s'], 15:25:09 INFO - 'tests': ['damp', 'tps']}, 15:25:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:09 INFO - 'tests': ['damp', 'tps']}, 15:25:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:25:09 INFO - 'tests': ['dromaeo_dom']}, 15:25:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:25:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:25:09 INFO - 'tests': ['basic_compositor_video']}, 15:25:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:25:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:25:09 INFO - 'tests': ['a11yr', 15:25:09 INFO - 'ts_paint', 15:25:09 INFO - 'tpaint', 15:25:09 INFO - 'sessionrestore', 15:25:09 INFO - 'sessionrestore_no_auto_restore', 15:25:09 INFO - 'tabpaint']}, 15:25:09 INFO - 'other-e10s': {'tests': ['a11yr', 15:25:09 INFO - 'ts_paint', 15:25:09 INFO - 'tpaint', 15:25:09 INFO - 'sessionrestore', 15:25:09 INFO - 'sessionrestore_no_auto_restore', 15:25:09 INFO - 'tabpaint']}, 15:25:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:25:09 INFO - 'ts_paint', 15:25:09 INFO - 'tpaint', 15:25:09 INFO - 'sessionrestore', 15:25:09 INFO - 'sessionrestore_no_auto_restore', 15:25:09 INFO - 'tabpaint']}, 15:25:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:25:09 INFO - 'ts_paint', 15:25:09 INFO - 'tpaint', 15:25:09 INFO - 'sessionrestore', 15:25:09 INFO - 'sessionrestore_no_auto_restore', 15:25:09 INFO - 'tabpaint']}, 15:25:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:25:09 INFO - 'tests': ['a11yr', 15:25:09 INFO - 'ts_paint', 15:25:09 INFO - 'tpaint', 15:25:09 INFO - 'sessionrestore', 15:25:09 INFO - 'sessionrestore_no_auto_restore', 15:25:09 INFO - 'tabpaint']}, 15:25:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:25:09 INFO - 'tests': ['a11yr', 15:25:09 INFO - 'ts_paint', 15:25:09 INFO - 'tpaint', 15:25:09 INFO - 'sessionrestore', 15:25:09 INFO - 'sessionrestore_no_auto_restore', 15:25:09 INFO - 'tabpaint']}, 15:25:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:25:09 INFO - 'tests': ['tsvgx', 15:25:09 INFO - 'tsvgr_opacity', 15:25:09 INFO - 'tart', 15:25:09 INFO - 'tscrollx', 15:25:09 INFO - 'cart']}, 15:25:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:25:09 INFO - 'tsvgr_opacity', 15:25:09 INFO - 'tart', 15:25:09 INFO - 'tscrollx', 15:25:09 INFO - 'cart']}, 15:25:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:09 INFO - 'talos_options': ['--disable-e10s'], 15:25:09 INFO - 'tests': ['tp5o']}, 15:25:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:09 INFO - 'tests': ['tp5o']}, 15:25:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:09 INFO - 'talos_options': ['--disable-e10s', 15:25:09 INFO - '--xperf_path', 15:25:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:25:09 INFO - 'tests': ['tp5n']}, 15:25:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:09 INFO - 'talos_options': ['--xperf_path', 15:25:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:25:09 INFO - 'tests': ['tp5n']}}, 15:25:09 INFO - 'talos.zip': {'path': '', 15:25:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:25:09 INFO - Running post-action listener: _resource_record_post_action 15:25:09 INFO - [mozharness: 2016-10-30 22:25:09.066930Z] Finished populate-webroot step (success) 15:25:09 INFO - [mozharness: 2016-10-30 22:25:09.067056Z] Running create-virtualenv step. 15:25:09 INFO - Running pre-action listener: _resource_record_pre_action 15:25:09 INFO - Running main action method: create_virtualenv 15:25:09 INFO - Creating virtualenv /builds/slave/test/build/venv 15:25:09 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 15:25:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:25:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:25:09 INFO - Using real prefix '/tools/python27' 15:25:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:25:09 INFO - Installing distribute.............................................................................................................................................................................................done. 15:25:12 INFO - Installing pip.................done. 15:25:12 INFO - Return code: 0 15:25:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:25:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:25:12 INFO - Reading from file tmpfile_stdout 15:25:12 INFO - Output received: 15:25:12 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:25:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:25:13 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')]} 15:25:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:13 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')]} 15:25:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:13 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 0x105bdf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b05744b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059f3d30>, '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 0x105819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059ebb20>, '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.DIg49BniEo/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.NqVFRKgBKJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 15:25:13 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 15:25:13 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 15:25:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DIg49BniEo/Render', 15:25:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:13 INFO - 'HOME': '/Users/cltbld', 15:25:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:13 INFO - 'LOGNAME': 'cltbld', 15:25:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:13 INFO - 'MOZ_NO_REMOTE': '1', 15:25:13 INFO - 'NO_EM_RESTART': '1', 15:25:13 INFO - 'PAGER': '/bin/cat', 15:25:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:13 INFO - 'PWD': '/builds/slave/test', 15:25:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:13 INFO - 'SHELL': '/bin/bash', 15:25:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NqVFRKgBKJ/Listeners', 15:25:13 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 15:25:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:13 INFO - 'USER': 'cltbld', 15:25:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:13 INFO - 'XPC_FLAGS': '0x0', 15:25:13 INFO - 'XPC_SERVICE_NAME': '0', 15:25:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:13 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 15:25:13 INFO - Cleaning up... 15:25:13 INFO - Return code: 0 15:25:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:25:13 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')]} 15:25:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:13 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')]} 15:25:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:13 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 0x105bdf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b05744b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059f3d30>, '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 0x105819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059ebb20>, '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.DIg49BniEo/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.NqVFRKgBKJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 15:25:13 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 15:25:13 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 15:25:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DIg49BniEo/Render', 15:25:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:13 INFO - 'HOME': '/Users/cltbld', 15:25:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:13 INFO - 'LOGNAME': 'cltbld', 15:25:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:13 INFO - 'MOZ_NO_REMOTE': '1', 15:25:13 INFO - 'NO_EM_RESTART': '1', 15:25:13 INFO - 'PAGER': '/bin/cat', 15:25:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:13 INFO - 'PWD': '/builds/slave/test', 15:25:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:13 INFO - 'SHELL': '/bin/bash', 15:25:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NqVFRKgBKJ/Listeners', 15:25:13 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 15:25:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:13 INFO - 'USER': 'cltbld', 15:25:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:13 INFO - 'XPC_FLAGS': '0x0', 15:25:13 INFO - 'XPC_SERVICE_NAME': '0', 15:25:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:13 INFO - Downloading/unpacking psutil>=3.1.1 15:25:13 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 15:25:13 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 15:25:13 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 15:25:13 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 15:25:13 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 15:25:13 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 15:25:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:25:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:25:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:25:17 INFO - Installing collected packages: psutil 15:25:17 INFO - Running setup.py install for psutil 15:25:17 INFO - building 'psutil._psutil_osx' extension 15:25:17 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 15:25:17 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 15:25:17 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 15:25:17 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 15:25:17 INFO - building 'psutil._psutil_posix' extension 15:25:17 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 15:25:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:25:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:25:17 INFO - ^ 15:25:17 INFO - 1 warning generated. 15:25:17 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 15:25:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:25:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:25:18 INFO - Successfully installed psutil 15:25:18 INFO - Cleaning up... 15:25:18 INFO - Return code: 0 15:25:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:25:18 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')]} 15:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:18 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')]} 15:25:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:18 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 0x105bdf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b05744b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059f3d30>, '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 0x105819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059ebb20>, '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.DIg49BniEo/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.NqVFRKgBKJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 15:25:18 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 15:25:18 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 15:25:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DIg49BniEo/Render', 15:25:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:18 INFO - 'HOME': '/Users/cltbld', 15:25:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:18 INFO - 'LOGNAME': 'cltbld', 15:25:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:18 INFO - 'MOZ_NO_REMOTE': '1', 15:25:18 INFO - 'NO_EM_RESTART': '1', 15:25:18 INFO - 'PAGER': '/bin/cat', 15:25:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:18 INFO - 'PWD': '/builds/slave/test', 15:25:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:18 INFO - 'SHELL': '/bin/bash', 15:25:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NqVFRKgBKJ/Listeners', 15:25:18 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 15:25:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:18 INFO - 'USER': 'cltbld', 15:25:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:18 INFO - 'XPC_FLAGS': '0x0', 15:25:18 INFO - 'XPC_SERVICE_NAME': '0', 15:25:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:25:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:25:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:25:20 INFO - Installing collected packages: mozsystemmonitor 15:25:20 INFO - Running setup.py install for mozsystemmonitor 15:25:21 INFO - Successfully installed mozsystemmonitor 15:25:21 INFO - Cleaning up... 15:25:21 INFO - Return code: 0 15:25:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105bdf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b05744b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059f3d30>, '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 0x105819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059ebb20>, '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.DIg49BniEo/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.NqVFRKgBKJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 15:25:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DIg49BniEo/Render', 15:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:21 INFO - 'HOME': '/Users/cltbld', 15:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:21 INFO - 'LOGNAME': 'cltbld', 15:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:21 INFO - 'MOZ_NO_REMOTE': '1', 15:25:21 INFO - 'NO_EM_RESTART': '1', 15:25:21 INFO - 'PAGER': '/bin/cat', 15:25:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:21 INFO - 'PWD': '/builds/slave/test', 15:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:21 INFO - 'SHELL': '/bin/bash', 15:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NqVFRKgBKJ/Listeners', 15:25:21 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 15:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:21 INFO - 'USER': 'cltbld', 15:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:21 INFO - 'XPC_FLAGS': '0x0', 15:25:21 INFO - 'XPC_SERVICE_NAME': '0', 15:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:21 INFO - Downloading/unpacking jsonschema==2.5.1 15:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:25:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:24 INFO - Downloading functools32-3.2.3-2.tar.gz 15:25:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:25:24 INFO - warning: no files found matching '*.txt' 15:25:24 INFO - no previously-included directories found matching 'build' 15:25:24 INFO - no previously-included directories found matching 'dist' 15:25:24 INFO - no previously-included directories found matching '.git*' 15:25:24 INFO - Installing collected packages: jsonschema, functools32 15:25:24 INFO - Running setup.py install for functools32 15:25:24 INFO - warning: no files found matching '*.txt' 15:25:24 INFO - no previously-included directories found matching 'build' 15:25:24 INFO - no previously-included directories found matching 'dist' 15:25:24 INFO - no previously-included directories found matching '.git*' 15:25:24 INFO - Successfully installed jsonschema functools32 15:25:24 INFO - Cleaning up... 15:25:24 INFO - Return code: 0 15:25:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105bdf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b05744b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059f3d30>, '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 0x105819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059ebb20>, '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.DIg49BniEo/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.NqVFRKgBKJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 15:25:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:25:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:25:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DIg49BniEo/Render', 15:25:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:24 INFO - 'HOME': '/Users/cltbld', 15:25:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:24 INFO - 'LOGNAME': 'cltbld', 15:25:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:24 INFO - 'MOZ_NO_REMOTE': '1', 15:25:24 INFO - 'NO_EM_RESTART': '1', 15:25:24 INFO - 'PAGER': '/bin/cat', 15:25:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:24 INFO - 'PWD': '/builds/slave/test', 15:25:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:24 INFO - 'SHELL': '/bin/bash', 15:25:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NqVFRKgBKJ/Listeners', 15:25:24 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 15:25:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:24 INFO - 'USER': 'cltbld', 15:25:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:24 INFO - 'XPC_FLAGS': '0x0', 15:25:24 INFO - 'XPC_SERVICE_NAME': '0', 15:25:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:25:25 INFO - Cleaning up... 15:25:25 INFO - Return code: 0 15:25:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105bdf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b05744b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059f3d30>, '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 0x105819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059ebb20>, '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.DIg49BniEo/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.NqVFRKgBKJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 15:25:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:25:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DIg49BniEo/Render', 15:25:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:25 INFO - 'HOME': '/Users/cltbld', 15:25:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:25 INFO - 'LOGNAME': 'cltbld', 15:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:25 INFO - 'MOZ_NO_REMOTE': '1', 15:25:25 INFO - 'NO_EM_RESTART': '1', 15:25:25 INFO - 'PAGER': '/bin/cat', 15:25:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:25 INFO - 'PWD': '/builds/slave/test', 15:25:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:25 INFO - 'SHELL': '/bin/bash', 15:25:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NqVFRKgBKJ/Listeners', 15:25:25 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 15:25:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:25 INFO - 'USER': 'cltbld', 15:25:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:25 INFO - 'XPC_FLAGS': '0x0', 15:25:25 INFO - 'XPC_SERVICE_NAME': '0', 15:25:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:25 INFO - Downloading/unpacking blobuploader==1.2.4 15:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:27 INFO - Downloading blobuploader-1.2.4.tar.gz 15:25:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:25:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:25:27 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 15:25:27 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 15:25:27 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 15:25:27 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 15:25:27 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 15:25:27 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 15:25:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:25:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:25:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:29 INFO - Downloading docopt-0.6.1.tar.gz 15:25:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:25:29 INFO - Installing collected packages: blobuploader, requests, docopt 15:25:29 INFO - Running setup.py install for blobuploader 15:25:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:25:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:25:29 INFO - Running setup.py install for requests 15:25:29 INFO - Running setup.py install for docopt 15:25:29 INFO - Successfully installed blobuploader requests docopt 15:25:29 INFO - Cleaning up... 15:25:30 INFO - Return code: 0 15:25:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105bdf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b05744b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059f3d30>, '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 0x105819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059ebb20>, '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.DIg49BniEo/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.NqVFRKgBKJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 15:25:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:25:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:25:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DIg49BniEo/Render', 15:25:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:30 INFO - 'HOME': '/Users/cltbld', 15:25:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:30 INFO - 'LOGNAME': 'cltbld', 15:25:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:30 INFO - 'MOZ_NO_REMOTE': '1', 15:25:30 INFO - 'NO_EM_RESTART': '1', 15:25:30 INFO - 'PAGER': '/bin/cat', 15:25:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:30 INFO - 'PWD': '/builds/slave/test', 15:25:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:30 INFO - 'SHELL': '/bin/bash', 15:25:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NqVFRKgBKJ/Listeners', 15:25:30 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 15:25:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:30 INFO - 'USER': 'cltbld', 15:25:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:30 INFO - 'XPC_FLAGS': '0x0', 15:25:30 INFO - 'XPC_SERVICE_NAME': '0', 15:25:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:25:30 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-zXuB0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:25:30 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-JJoZtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:25:30 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-2ixpAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:25:30 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-QgA817-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:25:30 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-xlS8xx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:25:30 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-Eq1uyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:25:30 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-zVGC1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:25:31 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-QfPdsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:25:31 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ySR3Bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:25:31 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ogLHdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:25:31 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-SfVRnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:25:31 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-U4zdap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:25:31 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-rf3svt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:25:31 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-5kPXhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:25:31 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-LJvIvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:25:31 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-1OsSKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:25:31 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ZOn0Ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:25:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:25:32 INFO - Running setup.py install for manifestparser 15:25:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:25:32 INFO - Running setup.py install for mozcrash 15:25:32 INFO - Running setup.py install for mozdebug 15:25:32 INFO - Running setup.py install for mozdevice 15:25:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:25:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:25:32 INFO - Running setup.py install for mozfile 15:25:32 INFO - Running setup.py install for mozhttpd 15:25:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:25:32 INFO - Running setup.py install for mozinfo 15:25:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:25:33 INFO - Running setup.py install for mozInstall 15:25:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:25:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:25:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:25:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:25:33 INFO - Running setup.py install for mozleak 15:25:33 INFO - Running setup.py install for mozlog 15:25:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:25:33 INFO - Running setup.py install for moznetwork 15:25:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:25:33 INFO - Running setup.py install for mozprocess 15:25:33 INFO - Running setup.py install for mozprofile 15:25:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:25:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:25:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:25:34 INFO - Running setup.py install for mozrunner 15:25:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:25:34 INFO - Running setup.py install for mozscreenshot 15:25:34 INFO - Running setup.py install for moztest 15:25:34 INFO - Running setup.py install for mozversion 15:25:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:25:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:25:34 INFO - Cleaning up... 15:25:34 INFO - Return code: 0 15:25:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:25:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105bdf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b05744b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059f3d30>, '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 0x105819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059ebb20>, '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.DIg49BniEo/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.NqVFRKgBKJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 15:25:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:25:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:25:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DIg49BniEo/Render', 15:25:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:34 INFO - 'HOME': '/Users/cltbld', 15:25:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:34 INFO - 'LOGNAME': 'cltbld', 15:25:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:34 INFO - 'MOZ_NO_REMOTE': '1', 15:25:34 INFO - 'NO_EM_RESTART': '1', 15:25:34 INFO - 'PAGER': '/bin/cat', 15:25:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:34 INFO - 'PWD': '/builds/slave/test', 15:25:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:34 INFO - 'SHELL': '/bin/bash', 15:25:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NqVFRKgBKJ/Listeners', 15:25:34 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 15:25:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:34 INFO - 'USER': 'cltbld', 15:25:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:34 INFO - 'XPC_FLAGS': '0x0', 15:25:34 INFO - 'XPC_SERVICE_NAME': '0', 15:25:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:25:34 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-wZwHzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:25:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:25:35 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-X60559-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:25:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:25:35 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-7WcKRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:25:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:25:35 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-kS_GZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:25:35 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)) 15:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:25:35 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-u7l0Ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:25:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:25:35 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-vnZUil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:25:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:25:35 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-usyYBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:25:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:25:35 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-KO3NMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:25:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:25:35 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-7v1Ro6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:25:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:25:35 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-hAwJfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:25:36 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-c_nAYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:25:36 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-miaNXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:25:36 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)) 15:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:25:36 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ckYWiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:25:36 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-jSpyWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:25:36 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-O8L_7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:25:36 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-A3HFvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:25:36 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)) 15:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:25:36 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-fISkgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:25:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:25: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 15:25: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 15:25: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 15:25: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 15:25: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 15:25: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 15:25:39 INFO - Downloading blessings-1.6.tar.gz 15:25:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:25:39 INFO - Installing collected packages: blessings 15:25:39 INFO - Running setup.py install for blessings 15:25:39 INFO - Successfully installed blessings 15:25:39 INFO - Cleaning up... 15:25:39 INFO - Return code: 0 15:25:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:25:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:25:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:25:39 INFO - Reading from file tmpfile_stdout 15:25:39 INFO - Current package versions: 15:25:39 INFO - blessings == 1.6 15:25:39 INFO - blobuploader == 1.2.4 15:25:39 INFO - docopt == 0.6.1 15:25:39 INFO - functools32 == 3.2.3-2 15:25:39 INFO - jsonschema == 2.5.1 15:25:39 INFO - manifestparser == 1.1 15:25:39 INFO - mozInstall == 1.12 15:25:39 INFO - mozcrash == 0.17 15:25:39 INFO - mozdebug == 0.1 15:25:39 INFO - mozdevice == 0.48 15:25:39 INFO - mozfile == 1.2 15:25:39 INFO - mozhttpd == 0.7 15:25:39 INFO - mozinfo == 0.9 15:25:39 INFO - mozleak == 0.1 15:25:39 INFO - mozlog == 3.4 15:25:39 INFO - moznetwork == 0.27 15:25:39 INFO - mozprocess == 0.23 15:25:39 INFO - mozprofile == 0.28 15:25:39 INFO - mozrunner == 6.12 15:25:39 INFO - mozscreenshot == 0.1 15:25:39 INFO - mozsystemmonitor == 0.3 15:25:39 INFO - moztest == 0.7 15:25:39 INFO - mozversion == 1.4 15:25:39 INFO - psutil == 3.1.1 15:25:39 INFO - requests == 1.2.3 15:25:39 INFO - wsgiref == 0.1.2 15:25:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:39 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 0x105bdf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b05744b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059f3d30>, '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 0x105819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059ebb20>, '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.DIg49BniEo/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.NqVFRKgBKJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 15:25:39 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 15:25:39 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 15:25:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DIg49BniEo/Render', 15:25:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:39 INFO - 'HOME': '/Users/cltbld', 15:25:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:39 INFO - 'LOGNAME': 'cltbld', 15:25:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:39 INFO - 'MOZ_NO_REMOTE': '1', 15:25:39 INFO - 'NO_EM_RESTART': '1', 15:25:39 INFO - 'PAGER': '/bin/cat', 15:25:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:39 INFO - 'PWD': '/builds/slave/test', 15:25:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:39 INFO - 'SHELL': '/bin/bash', 15:25:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NqVFRKgBKJ/Listeners', 15:25:39 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 15:25:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:39 INFO - 'USER': 'cltbld', 15:25:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:39 INFO - 'XPC_FLAGS': '0x0', 15:25:39 INFO - 'XPC_SERVICE_NAME': '0', 15:25:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:40 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)) 15:25:40 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)) 15:25:40 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)) 15:25:40 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)) 15:25:40 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)) 15:25:40 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)) 15:25:40 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)) 15:25:40 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)) 15:25:40 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)) 15:25:40 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)) 15:25:40 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)) 15:25:40 INFO - Cleaning up... 15:25:40 INFO - Return code: 0 15:25:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:40 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 0x105bdf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b05744b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059f3d30>, '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 0x105819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059ebb20>, '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.DIg49BniEo/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.NqVFRKgBKJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 15:25:40 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 15:25:40 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 15:25:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DIg49BniEo/Render', 15:25:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:40 INFO - 'HOME': '/Users/cltbld', 15:25:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:40 INFO - 'LOGNAME': 'cltbld', 15:25:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:40 INFO - 'MOZ_NO_REMOTE': '1', 15:25:40 INFO - 'NO_EM_RESTART': '1', 15:25:40 INFO - 'PAGER': '/bin/cat', 15:25:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:40 INFO - 'PWD': '/builds/slave/test', 15:25:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:40 INFO - 'SHELL': '/bin/bash', 15:25:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NqVFRKgBKJ/Listeners', 15:25:40 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 15:25:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:40 INFO - 'USER': 'cltbld', 15:25:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:40 INFO - 'XPC_FLAGS': '0x0', 15:25:40 INFO - 'XPC_SERVICE_NAME': '0', 15:25:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:25:40 INFO - Cleaning up... 15:25:40 INFO - Return code: 0 15:25:40 INFO - Running post-action listener: _resource_record_post_action 15:25:40 INFO - Running post-action listener: _start_resource_monitoring 15:25:40 INFO - Starting resource monitoring. 15:25:40 INFO - [mozharness: 2016-10-30 22:25:40.484712Z] Finished create-virtualenv step (success) 15:25:40 INFO - [mozharness: 2016-10-30 22:25:40.485727Z] Running install step. 15:25:40 INFO - Running pre-action listener: _resource_record_pre_action 15:25:40 INFO - Running main action method: install 15:25:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:25:40 INFO - Reading from file tmpfile_stdout 15:25:40 INFO - Detecting whether we're running mozinstall >=1.0... 15:25:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:25:40 INFO - Reading from file tmpfile_stdout 15:25:40 INFO - Output received: 15:25:40 INFO - Usage: mozinstall [options] installer 15:25:40 INFO - Options: 15:25:40 INFO - -h, --help show this help message and exit 15:25:40 INFO - -d DEST, --destination=DEST 15:25:40 INFO - Directory to install application into. [default: 15:25:40 INFO - "/builds/slave/test"] 15:25:40 INFO - --app=APP Application being installed. [default: firefox] 15:25:40 INFO - mkdir: /builds/slave/test/build/application 15:25:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:26:15 INFO - Reading from file tmpfile_stdout 15:26:15 INFO - Output received: 15:26:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:26:15 INFO - Running post-action listener: _resource_record_post_action 15:26:15 INFO - [mozharness: 2016-10-30 22:26:15.588683Z] Finished install step (success) 15:26:15 INFO - [mozharness: 2016-10-30 22:26:15.588797Z] Running run-tests step. 15:26:15 INFO - Running pre-action listener: _resource_record_pre_action 15:26:15 INFO - Running main action method: run_tests 15:26:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:26:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:26:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:26:15 INFO - 2016-10-30 15:26:15.601 screenresolution[1956:29395] starting screenresolution argv=screenresolution get 15:26:15 INFO - 2016-10-30 15:26:15.604 screenresolution[1956:29395] Display 0: 1600x1200x32@60 15:26:15 INFO - 2016-10-30 15:26:15.609 screenresolution[1957:29397] starting screenresolution argv=screenresolution list 15:26:15 INFO - Available Modes on Display 0 15:26:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:26:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:26:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:26:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:26:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:26:15 INFO - Intel Iris: 15:26:15 INFO - Chipset Model: Intel Iris 15:26:15 INFO - Type: GPU 15:26:15 INFO - Bus: Built-In 15:26:15 INFO - VRAM (Dynamic, Max): 1536 MB 15:26:15 INFO - Vendor: Intel (0x8086) 15:26:15 INFO - Device ID: 0x0a2e 15:26:15 INFO - Revision ID: 0x0009 15:26:15 INFO - Displays: 15:26:15 INFO - Display: 15:26:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:26:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:26:15 INFO - Display Serial Number: 1600x1200 60 15:26:15 INFO - Main Display: Yes 15:26:15 INFO - Mirror: Off 15:26:15 INFO - Online: Yes 15:26:15 INFO - Rotation: Supported 15:26:15 INFO - Return code: 0 15:26:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:26:15 INFO - Python 2.7.3 15:26:15 INFO - Return code: 0 15:26:15 INFO - grabbing minidump binary from tooltool 15:26:15 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')]} 15:26:15 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 0x1059f3d30>, '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 0x105819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059ebb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:26:15 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 15:26:15 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 15:26:15 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 15:26:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:26:15 INFO - Return code: 0 15:26:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:26:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:26:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:26:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:26:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:26:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--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 15:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0306 --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 15:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DIg49BniEo/Render', 15:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:15 INFO - 'HOME': '/Users/cltbld', 15:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:15 INFO - 'LOGNAME': 'cltbld', 15:26:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:26:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:15 INFO - 'MOZ_NO_REMOTE': '1', 15:26:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:26:15 INFO - 'NO_EM_RESTART': '1', 15:26:15 INFO - 'PAGER': '/bin/cat', 15:26:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:15 INFO - 'PWD': '/builds/slave/test', 15:26:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:15 INFO - 'SHELL': '/bin/bash', 15:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NqVFRKgBKJ/Listeners', 15:26:15 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 15:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:15 INFO - 'USER': 'cltbld', 15:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:15 INFO - 'XPC_FLAGS': '0x0', 15:26:15 INFO - 'XPC_SERVICE_NAME': '0', 15:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--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 15:26:16 INFO - mozversion application_buildid: 20161030133222 15:26:16 INFO - mozversion application_changeset: d7d142a7614746f609a0997bb70bc7cfae04915d 15:26:16 INFO - mozversion application_display_name: Nightly 15:26:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:26:16 INFO - mozversion application_name: Firefox 15:26:16 INFO - mozversion application_remotingname: firefox 15:26:16 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 15:26:16 INFO - mozversion application_vendor: Mozilla 15:26:16 INFO - mozversion application_version: 52.0a1 15:26:16 INFO - mozversion platform_buildid: 20161030133222 15:26:16 INFO - mozversion platform_changeset: d7d142a7614746f609a0997bb70bc7cfae04915d 15:26:16 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 15:26:16 INFO - mozversion platform_version: 52.0a1 15:26:16 INFO - using testdate: 1477866376 15:26:16 INFO - actual date: 1477866376 15:26:16 INFO - starting webserver on 'localhost:53681' 15:26:16 INFO - SUITE-START | Running 2 tests 15:26:16 INFO - TEST-START | dromaeo_css 15:26:16 INFO - operating with platform_type : mac_ 15:26:16 INFO - Initialising browser for dromaeo_css test... 15:26:16 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpfFuklt/profile http://localhost:53681/getInfo.html) 15:26:18 INFO - PROCESS | 1967 | __metrics Screen width/height:1600/1200 15:26:18 INFO - PROCESS | 1967 | colorDepth:24 15:26:18 INFO - PROCESS | 1967 | Browser inner width/height: 1024/689 15:26:18 INFO - PROCESS | 1967 | __metrics 15:26:18 INFO - PROCESS | 1967 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:26:18 INFO - PROCESS | 1967 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:26:19 INFO - TEST-INFO | 1967: exit 0 15:26:19 INFO - Browser initialized. 15:26:19 INFO - Running cycle 1/1 for dromaeo_css test... 15:26:19 INFO - TEST-INFO | started process 1969 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpfFuklt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:26:31 INFO - PROCESS | 1969 | RSS: Main: 234774528 15:26:31 INFO - PROCESS | 1969 | 15:27:22 INFO - PROCESS | 1969 | 1477866442969 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] 15:29:36 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:53681/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53681/tests/dromaeo/cssquery-ext.html) 15:29:37 INFO - PROCESS | 1969 | RSS: Main: 348938240 15:29:37 INFO - PROCESS | 1969 | 15:32:40 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:53681/tests/dromaeo/cssquery-ext.html (next: http://localhost:53681/tests/dromaeo/cssquery-jquery.html) 15:32:40 INFO - PROCESS | 1969 | RSS: Main: 376500224 15:32:40 INFO - PROCESS | 1969 | 15:35:43 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:53681/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53681/tests/dromaeo/cssquery-mootools.html) 15:35:43 INFO - PROCESS | 1969 | RSS: Main: 367153152 15:35:43 INFO - PROCESS | 1969 | 15:38:46 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:53681/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53681/tests/dromaeo/cssquery-prototype.html) 15:38:46 INFO - PROCESS | 1969 | RSS: Main: 337453056 15:38:46 INFO - PROCESS | 1969 | 15:41:50 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:53681/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53681/tests/dromaeo/cssquery-yui.html) 15:41:51 INFO - PROCESS | 1969 | RSS: Main: 335474688 15:41:51 INFO - PROCESS | 1969 | 15:44:54 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:53681/tests/dromaeo/cssquery-yui.html (next: http://localhost:53681/tests/dromaeo/cssquery-dojo.html) 15:44:54 INFO - PROCESS | 1969 | RSS: Main: 383811584 15:44:54 INFO - PROCESS | 1969 | 15:44:54 INFO - PROCESS | 1969 | __start_tp_report 15:44:54 INFO - PROCESS | 1969 | _x_x_mozilla_page_load 15:44:54 INFO - PROCESS | 1969 | _x_x_mozilla_page_load_details 15:44:54 INFO - PROCESS | 1969 | |i|pagename|runs| 15:44:54 INFO - PROCESS | 1969 | |0;dojo.html;3262.918427039324;3285.3757786020656;3308.602967643883;3333.233356328046;3355.0773537277246;5551.555875529958;5628.943710562893;5629.099344104944;5664.348599911012;5677.829665110054;5589.273394458714;5631.380548139701;5632.915506267399;5637.605367624268;5655.010373184697;4278.5935336143175;5126.897462050766;5160.071187186305;5179.223116532531;5243.187305967562;5136.332276804029;5195.9480405195845;5212.869678258036;5216.347956505437;5225.425203227642;5161.896762064756;5166.8708282293055;5204.375474943012;5205.531502164805;5236;5269.841904742864;5277.683338999673;5285.656432331924;5286.920696189522;5333.546648534879;5084.161113416282;5098.107831129538;5155;5192.091384007784;5204.453532379083;5546.722663866831;5572.3870374258795;5617.634853734494;5626.212330273765;5647.181158732002;6145.293291271516;6148.6618235997;6152.261728592597;6153.077038444225;6155.446009859134;6099.481544068715;6148.923938310778;6181.81454556364;6275.24697036354;6277.905831412532;5076.416212135578;5590.413006634245;5639.971800141014;5693.3168019837885;5716.5998380113;6269.185005949197;6270.905936410866;6275.246970363494;6286.402791734778;6287.842803929939;5915.438033386821;5969.701514924236;6070.180525628984;6083.422074902877;6087.573869829158;6097.379466367683;6108.22065524731;6152.723127459275;6154.476869466056;6239.937600624026;6134.141220229171;6134.325224225332;6145.631262124287;6154.292256390483;6155.507559395238;5481.808136715196;5869.853253668607;6008.609440386361;6137.7851775187655;6195.4424101831055;4744.4069491313585;4853.174960256765;4853.9029219415415;4870.975645121823;4923.113839508851;4079.91840163201;4399.25212713833;4399.758013309299;4432.534583868647;4455.153520831096;5710.857228569319;5764.884702306014;5783.277090363705;5784.971075144682;5790.334111577138;5903.144044113666;5907.734151963086;5917.763289468459;6170.784022559189;6193.659348735772;5113.619322782828;5702.401247868997;5724.055530837449;5763.135529670499;5769.480746732815;5355.0896347621;5355.464453554691;5368.489993450616;5379.1662292344745;5383.650062745909;6247.812565623039;6276.717547710273;6278.748850046039;6288.056791481315;6303.212098487689;5121.692698438105;5981.252343457068;6100.399513635539;6105.247473117015;6143.72353244114;6231.53263505248;6235.376462353728;6240.906386404116;6242.469390101892;6263.96868015657;5187.273781670494;6105.084237364431;6133.233345831771;6135.601185922901;6139.079138129317;6071.51427885777;6098.916365657296;6120.326764056039;6120.846978825387;6158.3841615838055;5955.970220148872;6050.727717252822;6056.727447264796;6096.756129754937;6101.816945491642;6004.189434426471;6015.6691381974415;6026.8794624106395;6045.941960157043;6056.878862422639;5193.221016847503;5720.856978575569;5739.0243658578975;5787.421257874179;5871.178035075008;5950.256217972754;5967.671778052249;5969.701514924323;6037.547183961133;6066.848328791815;5838.007538718513;5846.590738648254;5853.35613082552;5866.94133058681;5914.112883067574 15:44:54 INFO - PROCESS | 1969 | |1;ext.html;475.62188060491906;508.48896858657065;513.2019709351957;514.3904473199258;568.6957477749405;28713.712862871365;32888.99999999997;39131;40367.39448908269;42571.36142957858;41055;41486.37770433446;41512.79243603785;41589.584104159025;41686.374704379385;8225.341972642174;9386.342955993066;9575.233981281515;9609.375390599624;9625.56684949179;9422.952885235563;9498.81002379952;9534.332596718232;9544.093311135432;9553.379030363005;9490.288228382864;9515.952420237923;9537.046295370477;9544.665936692218;9567.138957493824;9481.857772133424;9539.856902146474;9549.61801527938;9551.474668893208;9557.330986830924;9432.339736218468;9448.716538503855;9502.952485237598;9510.714678559654;9514.666986655504;39479;39731.80134099311;39816.40275395843;39856.601433985576;39921.40117898205;3535.4520049392386;3577.606463289036;3580.534530511017;3587.4798154267746;3806.7144964127524;29581.40837183266;29645.851770741225;30577.388452231055;30813.229669258224;31087.533686994713;3192.6488086310487;3214.517822326646;3250.171206342391;3250.593675790526;3357.731381489475;466280;469612;469737;470697;470958;57328;57560.71219643875;57680;57705.71147144322;57747.42252577421;58833.70583147057;58843.41156588465;58959.70520147372;59046.40953590495;59064.11403828946;15138.621534461727;15394.769078463833;15396.615084622974;15397.923010384877;15416.07503549768;11943.343116128697;11946.701332466584;11953.760924781629;11999.700007499883;12064.974477169539;14689.77965330521;15490;15501.844981550266;15529;15715;5982.790602328898;6082.635041897501;6105.145279660851;6145.139680444741;6152.2924863640355;822.9547374894319;855.4909828651943;962.4610218277787;1032.9845052324222;1045.7333379988206;27671.723282767318;27681.446371072467;29007.41985160327;29114;29741.810327587085;1017.9491025448698;1031;1068.4123731947398;1094.9288296260718;1103.7240689827543;12924.353782310847;13466.865331346562;14437.783433248509;15043.398264069314;15489.457868974532;619510.853806081;624347;625196;626382;626608;54651;54729;54809;54845;54875.72562137164;1222.2788554752738;1263.993680031594;1335.365701291898;1340.1490053815896;1360.9931950340383;29170.708292917647;29803.55294670625;29922.25194370158;30339.393212136074;30659.54010689797;36433;47313.763431182626;47391.763041184575;47396.7630161847;47436.05127897492;389698;392091;392474;392734;393273.03363484144;24733;33194.66805331916;35604;37384.81307593445;37856.810715947344;42352.941176470835;43855.78072109619;43902;43939.78030109829;44672.776636116614;37338.8133059333;37718;37974.43038354372;38149.664761733206;39153.608463916134;37139.81430092832;37618.69325792507;37762.43356349602;38288.617113828506;38599.6140038596;33812.83093584615;36618.8169059153;37074.814625926694;37113;37268.813655931546;24402;25394.365140870887;32392.676073238967;33564.99305020853;33636.66363336335;20887.060082296637;21625.567488649824;24531.264062078168;25121.658721270178;25329.74670253274 15:44:54 INFO - PROCESS | 1969 | |2;jquery.html;357.3514071959394;365.8865751616998;369.2596344329619;386.3972203362756;393.9815576734142;19817.306394276202;19892.90053549732;20351.389458316236;20941.848198349104;21085.84027878466;20100.69848952267;20198.495037624067;20805.687914681293;20974.909892570955;21079.73521588702;4899.510048995107;5199.636025478228;5449.155380915964;5784.855378615526;5981.342052374235;5882.941170588304;6015.428534289235;6018;6024.2168518092585;6026.939730602681;4505.932411013838;5263.6578622389425;5354.116570765819;5370.946290537084;5973.522118230553;5231.86920326993;5289.788408463657;5291.259223708684;5293.814716484924;5303.655262407931;4457.598816106533;5029.522195391469;5044.319016932704;5154.098032844237;5212.40057393397;13068.673283167902;13112.672183195496;13152.342382880817;13245.337733113402;13266.270355130464;2160.730466299573;2359.728631207399;2766.1148432501404;2904.724051215131;2992.880284788606;9899.554520046615;10404.947975260151;10410;10523.52644131016;10564.630237941712;1794.8205179482077;1832.5235438785878;1870.2145099058428;1878.8590855685877;1906.8760530565607;109523;120013;124474.37762811218;129097.99999999905;132896;9217.631294748135;10728.302660327054;11246.718832029264;11327.150463715254;11530.135239856876;14541.709165816625;14559.344829482487;14564.41742330316;14576.344064517121;14619.78070328946;8092.109867914524;8364;8371.079181290053;8425.199606037417;8535.701250456204;6706.161729783777;8233.8353232935;8245.422820402635;8410.74767756956;8422.326213902874;3776.8300426480873;4484;4485.484169320504;4493.123840851003;4496.505384407713;1592.3948899418378;1840.4172411726172;1853.5551467647667;1893.9129219372335;1921.6541022615784;848.7878030492377;866.4757821518017;876.588003638289;876.7632739160391;901.2744740483973;16574.337026518806;20044.899775501028;21503.56992860134;21512.56974860494;21595;837.2548431895618;947.6067432015774;954.2032402943481;955.4140127388501;1013.2755080117604;10856.457177141112;11321.773564528663;11325.773484530428;11335.206535542598;11392.601258956063;506625;586699;598960;603166;603223;88310.55844720735;95737.52131239299;122457;123405.3616246854;132177;830.4215411148418;934.1452570897688;1000.0849222960909;1000.7498125468633;1001.2440607341556;16167.67664646724;18653.44039678812;19110.14004369779;19140.9472479015;19503.804961950766;52527.94944101173;55670.32989010336;56871;58277.70861145667;58661.41338586559;64108.67945660242;80577.19422805696;82485.17514824776;83662;84443.57778211315;6540.542162048611;7656.272654097852;7795.844083118419;7936.841263174589;8247.546384948646;12258.570950016707;12713;13022.674433139247;13148.474061037834;13216.933915330363;9887.901120988698;10142.442165680959;10406.959304069533;10983.121350291798;11119.444027798416;10961.835572466578;11141.275817071864;11241.591495005827;11295.7740845181;11381.374024428405;9585.712428627152;11430.1998860079;11459.770804584028;11788.76422471563;11847.407629618312;5619.550435965195;7296.233895441114;7364.116306043309;7564.243575642392;8320.167983201873;9296.535173241446;11402.714932126763;11580.942095289469;11582.91507528653;11834.644960651194 15:44:54 INFO - PROCESS | 1969 | |3;mootools.html;1379.6964667773086;1541.6223025358788;1560.921953902305;1763.2858692229652;1893.6780747272962;7569.507981981167;7946.761597152087;8217.794555136124;8373.204545568162;8450.788730281745;8254.752357429272;8307.833843323146;8398.790030249247;8404.03353614333;8412.242898139166;2303.389601755536;2334.9766502335015;2338.0881456231996;2338.8245881558864;2345.6364263539094;1950.619629172312;2336.649502574619;2355.729091154522;2361.2207971369407;2361.95276094478;2165.577712346093;2305.5849947009533;2329.2895666821614;2331.440454290975;2335.6146235871147;2235.9217427390045;2276.715410573678;2297.5290065536524;2304.6197377432704;2306.9884650576723;2258.9209377671705;2278.7493375728654;2283.920062797794;2286.9199578014855;2288.5651726171973;8384.455010424364;8398.958005209935;8480.109588493182;8486.872696909551;8487.23614874658;5052.9242061369105;5069.797208111671;5256.474352564713;5264.842054738364;5266.104762190398;3321.30252646942;3574.803385813779;3680.0983758978955;3680.4479328100733;3683.613220611851;1033.8552602635637;1064.5156453813497;1067.3382502848206;1070.9946450267698;1085.0939465546278;10460.37237765729;10763.78472430547;10775.299605525615;10898;10936.179786516042;4281.293586558183;4354.782260886943;4389.1660584488845;4466.642668586505;4472.105578884236;8629.568521574021;8724;8733.03936566977;8742.519161446182;8752.56237188138;5162.096633089194;5162.5095615916425;5168.793248270027;5169.7932082717025;5178.300929374486;4110.506739191256;4322.3516472528745;4467.262901621196;4507.098580283956;4845.515448455127;3580.480830279595;3598.9100272493397;3680.3007428589017;3683.723720720957;3693.926121477555;1113.6269349767756;1120.7970112079702;1122.893325134111;1130.0338210829786;1130.6325444230658;833.8582440985047;855.724969795001;881.9250363719156;915.4049867586123;939.6617217801586;5189.610779191576;5575.804846830341;5668.688222147822;5825.155352473867;5927.229460170151;931.9161275485238;931.9953400232955;936.3352020065829;943.1511639524483;981.0875885426622;3195.984020079885;3371.3088816792497;3843.7885916274313;3994.400839874042;4044.656204222615;9872.259580531489;10159.542820573091;10286.948565257126;10303.742406439898;10304.896951030543;9619.951900240594;10052.949735251277;10221.897781022095;10226.53980570876;10228.488575571191;7764;7990.6004699765945;7994.120646728855;7999.480033797785;8014.959925200336;5614.9719251403485;5616.719164041863;5695.259616249861;5887.470127688529;6090.969545152423;9396.295277854326;9470.526473676151;9515.33392662507;9559.330846840654;9585.041495850639;8476.321894248322;8786.25316848075;9111.589978451118;9533.285003624616;9533.427994320364;6810.8637827244165;7015.649217539205;7198.820029499305;7275.672594733355;7341.74303899361;7713.382929365749;7981.8802717958115;7992.280694737501;8152.510849349058;8169.101398846241;7187.5687458752645;7226.783196504113;7233.638318083969;7290;7415.740449084472;7065.2934706531;7115;7131.572105673677;7134.500584959003;7184.1738200105565;5661.490465858093;6396.232452105591;6591.6045037296;6692.832679182865;6768.510927595921;4720.598749106364;4787.018661174381;4834.444038935568;4868.95131048685;4943.901121977612;4890.339804126397;4972.751362431792;4991.775370108281;5015.648904576645;5017.8243761468175 15:44:54 INFO - PROCESS | 1969 | |4;prototype.html;260.42446193911456;301.50402587743156;301.9018818883862;307.0787637088734;311.5778120646525;4717.339572459859;4738.8389844488065;5151.304573882525;5156.407013193484;5210.921836172451;4219.05071358944;4695.689538875094;5084.4661310562415;5124.333836601237;5134.332536770215;3290.605127384717;3567.7869524361713;3666.2484190740834;3812.961870381302;3850.84596616135;3788.488554045196;3994.26106170358;4006.9799651001704;4010.2452979973687;4023.4970628671417;3436.5532480777465;3593.3531964246426;3904.316744569703;3910.002949247952;3956.1889812588493;3419.247765491588;3767.6420740029653;3897.2657167560446;3951.0517475805746;3969.781662008574;3050.34417600217;3393.9660603393895;3464.6188919218866;3490.3542844573835;3599.9200239928177;5047.712833227541;5051.823186188466;5056.646034777567;5064.822731204427;5111.284420181178;3762.473253744478;4340.414044104037;4439.448441247022;4482.574155455227;4484.372187893698;4476.373307736887;4543.704659197141;5387.703676297802;5445.53712934401;5478.945210547923;3775.6790672792845;3817.48463957365;3819.618038196158;3828.9042773930596;3830.636089571486;5231.010492985511;5405.486448801287;5482.943896038985;5731.971340143315;5767.855803604902;3658.2257605061523;3699.8520059197867;3705.3515634763808;3719.237556301006;3734.3838266686234;3163.9050828475183;3193.632732235776;3740.1023754299317;3740.775553466801;3754.718396120302;3680.8895733128047;4369.75966321849;4391.05799114594;4393.450818647669;4449.06465687427;5008;5356.464353564613;5392.40683524812;5462.863428414322;5482.945170548323;1272.6309370282515;1295.799151131576;1295.9611211663664;1296.7017585955095;1300.8048792680984;695.8086526205334;754.3738696881575;764.2144869332974;793.6507936508034;795.5544894858782;4804.903901921942;4917;4924.43369012561;4945.604351651931;5060.797568097309;4871.14754917888;5533.833984980457;5597.99236137491;5605.439456054362;5624.9187800513255;4250.659947204216;5020.397552293748;5076.035553244946;5146.794128234828;5157.432682404932;2685.5971604259516;2696.6089916961932;2741.3831887825477;2777.9443811351557;2924.6344206974127;4706.905861882743;5313.867153321198;5616.230887270497;5769.480746732815;5816.912746308809;4938.654294199443;5139.897202056013;5374.838754837361;5455.263539422127;5542.75057622408;5050;5613.635113717595;5632.04255276604;5647;5653.745581448762;4273.444452221192;4903.509649035068;5280.524752772115;5544.504972762193;5559.861003474945;5226.8954620908125;5375.489328513785;5384.407715151252;5426.728663566727;5469.097598896296;5053.31780209667;5199.4020687621405;5645.830625081254;5687.668056096981;5738.446391355807;3302.926548871674;3570.803605801706;3675.7426980111136;3699.5930447651276;3712.8514859405323;5128.58971282304;5416.295881535375;5452.372977107525;5493.285872836505;5518.641288316247;4900.681455705368;5300.443453437333;5306.151015837448;5325.973370133124;5376.865578360573;5234.685918844882;5243.921341179809;5254.579633629224;5273.973630131824;5293.523582877406;4782.856514304576;4904.337914381513;4908.4355299139625;5255.421903590678;5373.677579345252;4231.809568569483;5234.371875374979;5288.259643649969;5299.523042926001;5472.863178420572;4137;5205.973970130125;5215.29593504872;5217.373915130209;5227.947720522746 15:44:54 INFO - PROCESS | 1969 | |5;yui.html;723.6634964741394;766.7009866152198;797.174923953708;827.9337652987762;840.1976112812265;14634.560963171109;14966.25168741564;15088.321025553849;15159.772603410944;15226.238688065609;13933.930330348334;14047.929760351184;14168.079074860127;14177;14181.220032898213;886.9866951995725;904.7918978634918;906.8322360363321;907.3376435202306;908.4413085952125;709.2694524639604;735.4190189750118;738.4498548581314;878.2447095497866;897.7396554999042;947.4315410753527;961.5192403798101;961.5769061612868;961.7884065505577;962.93259471837;956.9521523923811;957.9137877590981;958.4441024205944;959.491469521155;960.6589660670431;550.1390324142703;563.5857644631193;681.1145510835894;700.1213477086275;713.0801266366433;14692.265386730727;14860.70278594422;14998.62503437412;15053.849461505355;15056.774148387783;13518.526851560147;13595.524156654568;13695.86304136956;13723.431907703969;13889;12531.56139535121;12628.558000470031;12693.301868397235;12786.296753678544;13010.869891301061;3378.425667636483;3501.299740052;3596.28074385124;3639.017465284385;3649.996251030961;91346.54326728391;91679.54160229223;94460;95242.57136142964;95431.52284238604;10427.791444171075;10490.21323400748;10652.360858348524;10655.546685574558;10928.180386471047;19796.010199490254;19917.800821991594;20344.084516196806;20363.694544581842;20402.183912643624;7505.362044226302;7632.1604623492485;7649.9617501912135;7774.533527988338;7776.105747839071;4311.913761724748;4321.9135617287475;4358.433403657505;4416.911661766747;4542.568455996675;3444.896653100411;3563;3566.8038257896064;3573.714102871764;3576.034470692898;609.9237595300588;610.954178436619;616.0789619518848;632.7057918068028;632.8955722305768;547.6796074296501;572.3046498504337;586.3315819965243;624.4286477872787;670.597641415147;8741.737747867573;9306.67426640078;9421.528923553931;9500.477473738873;9601.903980960102;614.5728718540571;618.6015257520021;629.8456878064811;653.8463461086641;671.9798406047827;4403.361512580658;4569.771511424482;4572.0627271409285;4607.585317321457;4687.906241875144;67994.66002670054;70052;81996.3600727994;94858;101679;97010.51494742482;98345;100293.49853250687;100739.49630251802;101229.98770012206;81308;81515;81688;82192.76710849343;83587.16412835794;7875.881861772193;8139.593020349077;8210.630521626661;8266.504009759434;8392.641931468279;34669.826650867595;52681.736591316796;55116.17325740037;56476.43523564712;57414.425855740905;70731;80004.99987500126;82807.5859620698;82819.5859020701;84690.57654711687;11023.456716059918;11514.424278786195;11709.658068386527;11763.235389699643;12235.143539952118;12444;12575.622731318075;12580.68687029439;12682.556110536087;13086.672833179247;11876.703082423008;11991.820122697993;12016.33910134951;12100.394980250776;12287.87712122903;9098.31762617793;11781.234219775688;12098;12236.082293828176;12258.877411225774;9721.659741909;11950.820737688768;12010.399480026139;12022.398880055787;12056.156069075081;9799.069088436587;9840;9892.802143957224;10287.382757034302;10400;9789.951050244703;10339.327943683636;10340.224483163642;10354.016368444985;10367.84448233262 15:44:54 INFO - PROCESS | 1969 | __end_tp_report 15:44:54 INFO - PROCESS | 1969 | __start_cc_report 15:44:54 INFO - PROCESS | 1969 | _x_x_mozilla_cycle_collect,1278 15:44:54 INFO - PROCESS | 1969 | __end_cc_report 15:44:54 INFO - PROCESS | 1969 | __startTimestamp1477867494573__endTimestamp 15:44:54 INFO - PROCESS | 1969 | 15:44:54 INFO - PROCESS | 1969 | ------- Summary: start ------- 15:44:54 INFO - PROCESS | 1969 | Number of tests: 6 15:44:54 INFO - PROCESS | 1969 | 15:44:54 INFO - PROCESS | 1969 | [#0] dojo.html Cycles:165 Average:5650.61 Median:5838.01 stddev:635.46 (10.9%) stddev-sans-first:609.17 15:44:54 INFO - PROCESS | 1969 | Values: 3262.9 3285.4 3308.6 3333.2 3355.1 5551.6 5628.9 5629.1 5664.3 5677.8 5589.3 5631.4 5632.9 5637.6 5655.0 4278.6 5126.9 5160.1 5179.2 5243.2 5136.3 5195.9 5212.9 5216.3 5225.4 5161.9 5166.9 5204.4 5205.5 5236.0 5269.8 5277.7 5285.7 5286.9 5333.5 5084.2 5098.1 5155.0 5192.1 5204.5 5546.7 5572.4 5617.6 5626.2 5647.2 6145.3 6148.7 6152.3 6153.1 6155.4 6099.5 6148.9 6181.8 6275.2 6277.9 5076.4 5590.4 5640.0 5693.3 5716.6 6269.2 6270.9 6275.2 6286.4 6287.8 5915.4 5969.7 6070.2 6083.4 6087.6 6097.4 6108.2 6152.7 6154.5 6239.9 6134.1 6134.3 6145.6 6154.3 6155.5 5481.8 5869.9 6008.6 6137.8 6195.4 4744.4 4853.2 4853.9 4871.0 4923.1 4079.9 4399.3 4399.8 4432.5 4455.2 5710.9 5764.9 5783.3 5785.0 5790.3 5903.1 5907.7 5917.8 6170.8 6193.7 5113.6 5702.4 5724.1 5763.1 5769.5 5355.1 5355.5 5368.5 5379.2 5383.7 6247.8 6276.7 6278.7 6288.1 6303.2 5121.7 5981.3 6100.4 6105.2 6143.7 6231.5 6235.4 6240.9 6242.5 6264.0 5187.3 6105.1 6133.2 6135.6 6139.1 6071.5 6098.9 6120.3 6120.8 6158.4 5956.0 6050.7 6056.7 6096.8 6101.8 6004.2 6015.7 6026.9 6045.9 6056.9 5193.2 5720.9 5739.0 5787.4 5871.2 5950.3 5967.7 5969.7 6037.5 6066.8 5838.0 5846.6 5853.4 5866.9 5914.1 15:44:54 INFO - PROCESS | 1969 | 15:44:54 INFO - PROCESS | 1969 | [#1] ext.html Cycles:180 Average:63158.38 Median:39142.30 stddev:134709.41 (344.2%) stddev-sans-first:135005.09 15:44:54 INFO - PROCESS | 1969 | Values: 475.6 508.5 513.2 514.4 568.7 28713.7 32889.0 39131.0 40367.4 42571.4 41055.0 41486.4 41512.8 41589.6 41686.4 8225.3 9386.3 9575.2 9609.4 9625.6 9423.0 9498.8 9534.3 9544.1 9553.4 9490.3 9516.0 9537.0 9544.7 9567.1 9481.9 9539.9 9549.6 9551.5 9557.3 9432.3 9448.7 9503.0 9510.7 9514.7 39479.0 39731.8 39816.4 39856.6 39921.4 3535.5 3577.6 3580.5 3587.5 3806.7 29581.4 29645.9 30577.4 30813.2 31087.5 3192.6 3214.5 3250.2 3250.6 3357.7 466280.0 469612.0 469737.0 470697.0 470958.0 57328.0 57560.7 57680.0 57705.7 57747.4 58833.7 58843.4 58959.7 59046.4 59064.1 15138.6 15394.8 15396.6 15397.9 15416.1 11943.3 11946.7 11953.8 11999.7 12065.0 14689.8 15490.0 15501.8 15529.0 15715.0 5982.8 6082.6 6105.1 6145.1 6152.3 823.0 855.5 962.5 1033.0 1045.7 27671.7 27681.4 29007.4 29114.0 29741.8 1017.9 1031.0 1068.4 1094.9 1103.7 12924.4 13466.9 14437.8 15043.4 15489.5 619510.9 624347.0 625196.0 626382.0 626608.0 54651.0 54729.0 54809.0 54845.0 54875.7 1222.3 1264.0 1335.4 1340.1 1361.0 29170.7 29803.6 29922.3 30339.4 30659.5 36433.0 47313.8 47391.8 47396.8 47436.1 389698.0 392091.0 392474.0 392734.0 393273.0 24733.0 33194.7 35604.0 37384.8 37856.8 42352.9 43855.8 43902.0 43939.8 44672.8 37338.8 37718.0 37974.4 38149.7 39153.6 37139.8 37618.7 37762.4 38288.6 38599.6 33812.8 36618.8 37074.8 37113.0 37268.8 24402.0 25394.4 32392.7 33565.0 33636.7 20887.1 21625.6 24531.3 25121.7 25329.7 15:44:54 INFO - PROCESS | 1969 | 15:44:54 INFO - PROCESS | 1969 | [#2] jquery.html Cycles:180 Average:33882.54 Median:2948.80 stddev:97055.19 (3291.3%) stddev-sans-first:97294.81 15:44:54 INFO - PROCESS | 1969 | Values: 357.4 365.9 369.3 386.4 394.0 19817.3 19892.9 20351.4 20941.8 21085.8 20100.7 20198.5 20805.7 20974.9 21079.7 4899.5 5199.6 5449.2 5784.9 5981.3 5882.9 6015.4 6018.0 6024.2 6026.9 4505.9 5263.7 5354.1 5370.9 5973.5 5231.9 5289.8 5291.3 5293.8 5303.7 4457.6 5029.5 5044.3 5154.1 5212.4 13068.7 13112.7 13152.3 13245.3 13266.3 2160.7 2359.7 2766.1 2904.7 2992.9 9899.6 10404.9 10410.0 10523.5 10564.6 1794.8 1832.5 1870.2 1878.9 1906.9 109523.0 120013.0 124474.4 129098.0 132896.0 9217.6 10728.3 11246.7 11327.2 11530.1 14541.7 14559.3 14564.4 14576.3 14619.8 8092.1 8364.0 8371.1 8425.2 8535.7 6706.2 8233.8 8245.4 8410.7 8422.3 3776.8 4484.0 4485.5 4493.1 4496.5 1592.4 1840.4 1853.6 1893.9 1921.7 848.8 866.5 876.6 876.8 901.3 16574.3 20044.9 21503.6 21512.6 21595.0 837.3 947.6 954.2 955.4 1013.3 10856.5 11321.8 11325.8 11335.2 11392.6 506625.0 586699.0 598960.0 603166.0 603223.0 88310.6 95737.5 122457.0 123405.4 132177.0 830.4 934.1 1000.1 1000.7 1001.2 16167.7 18653.4 19110.1 19140.9 19503.8 52527.9 55670.3 56871.0 58277.7 58661.4 64108.7 80577.2 82485.2 83662.0 84443.6 6540.5 7656.3 7795.8 7936.8 8247.5 12258.6 12713.0 13022.7 13148.5 13216.9 9887.9 10142.4 10407.0 10983.1 11119.4 10961.8 11141.3 11241.6 11295.8 11381.4 9585.7 11430.2 11459.8 11788.8 11847.4 5619.6 7296.2 7364.1 7564.2 8320.2 9296.5 11402.7 11580.9 11582.9 11834.6 15:44:54 INFO - PROCESS | 1969 | 15:44:54 INFO - PROCESS | 1969 | [#3] mootools.html Cycles:180 Average:5433.43 Median:5162.30 stddev:2975.46 (57.6%) stddev-sans-first:2968.21 15:44:54 INFO - PROCESS | 1969 | Values: 1379.7 1541.6 1560.9 1763.3 1893.7 7569.5 7946.8 8217.8 8373.2 8450.8 8254.8 8307.8 8398.8 8404.0 8412.2 2303.4 2335.0 2338.1 2338.8 2345.6 1950.6 2336.6 2355.7 2361.2 2362.0 2165.6 2305.6 2329.3 2331.4 2335.6 2235.9 2276.7 2297.5 2304.6 2307.0 2258.9 2278.7 2283.9 2286.9 2288.6 8384.5 8399.0 8480.1 8486.9 8487.2 5052.9 5069.8 5256.5 5264.8 5266.1 3321.3 3574.8 3680.1 3680.4 3683.6 1033.9 1064.5 1067.3 1071.0 1085.1 10460.4 10763.8 10775.3 10898.0 10936.2 4281.3 4354.8 4389.2 4466.6 4472.1 8629.6 8724.0 8733.0 8742.5 8752.6 5162.1 5162.5 5168.8 5169.8 5178.3 4110.5 4322.4 4467.3 4507.1 4845.5 3580.5 3598.9 3680.3 3683.7 3693.9 1113.6 1120.8 1122.9 1130.0 1130.6 833.9 855.7 881.9 915.4 939.7 5189.6 5575.8 5668.7 5825.2 5927.2 931.9 932.0 936.3 943.2 981.1 3196.0 3371.3 3843.8 3994.4 4044.7 9872.3 10159.5 10286.9 10303.7 10304.9 9620.0 10052.9 10221.9 10226.5 10228.5 7764.0 7990.6 7994.1 7999.5 8015.0 5615.0 5616.7 5695.3 5887.5 6091.0 9396.3 9470.5 9515.3 9559.3 9585.0 8476.3 8786.3 9111.6 9533.3 9533.4 6810.9 7015.6 7198.8 7275.7 7341.7 7713.4 7981.9 7992.3 8152.5 8169.1 7187.6 7226.8 7233.6 7290.0 7415.7 7065.3 7115.0 7131.6 7134.5 7184.2 5661.5 6396.2 6591.6 6692.8 6768.5 4720.6 4787.0 4834.4 4869.0 4943.9 4890.3 4972.8 4991.8 5015.6 5017.8 15:44:54 INFO - PROCESS | 1969 | 15:44:54 INFO - PROCESS | 1969 | [#4] prototype.html Cycles:180 Average:4341.11 Median:4942.13 stddev:1337.05 (27.1%) stddev-sans-first:1305.25 15:44:54 INFO - PROCESS | 1969 | Values: 260.4 301.5 301.9 307.1 311.6 4717.3 4738.8 5151.3 5156.4 5210.9 4219.1 4695.7 5084.5 5124.3 5134.3 3290.6 3567.8 3666.2 3813.0 3850.8 3788.5 3994.3 4007.0 4010.2 4023.5 3436.6 3593.4 3904.3 3910.0 3956.2 3419.2 3767.6 3897.3 3951.1 3969.8 3050.3 3394.0 3464.6 3490.4 3599.9 5047.7 5051.8 5056.6 5064.8 5111.3 3762.5 4340.4 4439.4 4482.6 4484.4 4476.4 4543.7 5387.7 5445.5 5478.9 3775.7 3817.5 3819.6 3828.9 3830.6 5231.0 5405.5 5482.9 5732.0 5767.9 3658.2 3699.9 3705.4 3719.2 3734.4 3163.9 3193.6 3740.1 3740.8 3754.7 3680.9 4369.8 4391.1 4393.5 4449.1 5008.0 5356.5 5392.4 5462.9 5482.9 1272.6 1295.8 1296.0 1296.7 1300.8 695.8 754.4 764.2 793.7 795.6 4804.9 4917.0 4924.4 4945.6 5060.8 4871.1 5533.8 5598.0 5605.4 5624.9 4250.7 5020.4 5076.0 5146.8 5157.4 2685.6 2696.6 2741.4 2777.9 2924.6 4706.9 5313.9 5616.2 5769.5 5816.9 4938.7 5139.9 5374.8 5455.3 5542.8 5050.0 5613.6 5632.0 5647.0 5653.7 4273.4 4903.5 5280.5 5544.5 5559.9 5226.9 5375.5 5384.4 5426.7 5469.1 5053.3 5199.4 5645.8 5687.7 5738.4 3302.9 3570.8 3675.7 3699.6 3712.9 5128.6 5416.3 5452.4 5493.3 5518.6 4900.7 5300.4 5306.2 5326.0 5376.9 5234.7 5243.9 5254.6 5274.0 5293.5 4782.9 4904.3 4908.4 5255.4 5373.7 4231.8 5234.4 5288.3 5299.5 5472.9 4137.0 5206.0 5215.3 5217.4 5227.9 15:44:54 INFO - PROCESS | 1969 | 15:44:54 INFO - PROCESS | 1969 | [#5] yui.html Cycles:180 Average:19974.93 Median:548.91 stddev:28967.37 (5277.3%) stddev-sans-first:29012.57 15:44:54 INFO - PROCESS | 1969 | Values: 723.7 766.7 797.2 827.9 840.2 14634.6 14966.3 15088.3 15159.8 15226.2 13933.9 14047.9 14168.1 14177.0 14181.2 887.0 904.8 906.8 907.3 908.4 709.3 735.4 738.4 878.2 897.7 947.4 961.5 961.6 961.8 962.9 957.0 957.9 958.4 959.5 960.7 550.1 563.6 681.1 700.1 713.1 14692.3 14860.7 14998.6 15053.8 15056.8 13518.5 13595.5 13695.9 13723.4 13889.0 12531.6 12628.6 12693.3 12786.3 13010.9 3378.4 3501.3 3596.3 3639.0 3650.0 91346.5 91679.5 94460.0 95242.6 95431.5 10427.8 10490.2 10652.4 10655.5 10928.2 19796.0 19917.8 20344.1 20363.7 20402.2 7505.4 7632.2 7650.0 7774.5 7776.1 4311.9 4321.9 4358.4 4416.9 4542.6 3444.9 3563.0 3566.8 3573.7 3576.0 609.9 611.0 616.1 632.7 632.9 547.7 572.3 586.3 624.4 670.6 8741.7 9306.7 9421.5 9500.5 9601.9 614.6 618.6 629.8 653.8 672.0 4403.4 4569.8 4572.1 4607.6 4687.9 67994.7 70052.0 81996.4 94858.0 101679.0 97010.5 98345.0 100293.5 100739.5 101230.0 81308.0 81515.0 81688.0 82192.8 83587.2 7875.9 8139.6 8210.6 8266.5 8392.6 34669.8 52681.7 55116.2 56476.4 57414.4 70731.0 80005.0 82807.6 82819.6 84690.6 11023.5 11514.4 11709.7 11763.2 12235.1 12444.0 12575.6 12580.7 12682.6 13086.7 11876.7 11991.8 12016.3 12100.4 12287.9 9098.3 11781.2 12098.0 12236.1 12258.9 9721.7 11950.8 12010.4 12022.4 12056.2 9799.1 9840.0 9892.8 10287.4 10400.0 9790.0 10339.3 10340.2 10354.0 10367.8 15:44:54 INFO - PROCESS | 1969 | -------- Summary: end -------- 15:44:54 INFO - PROCESS | 1969 | 15:44:55 INFO - TEST-INFO | 1969: exit 0 15:44:55 INFO - TEST-OK | dromaeo_css | took 1119049ms 15:44:55 INFO - TEST-START | kraken 15:44:55 INFO - operating with platform_type : mac_ 15:44:55 INFO - Initialising browser for kraken test... 15:44:55 INFO - TEST-INFO | started process 1973 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpAp5l68/profile http://localhost:53681/getInfo.html) 15:44:57 INFO - PROCESS | 1973 | __metrics Screen width/height:1600/1200 15:44:57 INFO - PROCESS | 1973 | colorDepth:24 15:44:57 INFO - PROCESS | 1973 | Browser inner width/height: 1024/689 15:44:57 INFO - PROCESS | 1973 | __metrics 15:44:57 INFO - PROCESS | 1973 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:44:57 INFO - PROCESS | 1973 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:44:58 INFO - TEST-INFO | 1973: exit 0 15:44:58 INFO - Browser initialized. 15:44:58 INFO - Running cycle 1/1 for kraken test... 15:44:58 INFO - TEST-INFO | started process 1974 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpAp5l68/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:45:10 INFO - PROCESS | 1974 | RSS: Main: 234803200 15:45:10 INFO - PROCESS | 1974 | 15:45:13 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:53681/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53681/tests/kraken/driver.html?testName=audio-beat-detection) 15:45:14 INFO - PROCESS | 1974 | RSS: Main: 293724160 15:45:14 INFO - PROCESS | 1974 | 15:45:16 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:53681/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53681/tests/kraken/driver.html?testName=audio-dft) 15:45:17 INFO - PROCESS | 1974 | RSS: Main: 294899712 15:45:17 INFO - PROCESS | 1974 | 15:45:20 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:53681/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53681/tests/kraken/driver.html?testName=audio-fft) 15:45:21 INFO - PROCESS | 1974 | RSS: Main: 387973120 15:45:21 INFO - PROCESS | 1974 | 15:45:23 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:53681/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53681/tests/kraken/driver.html?testName=audio-oscillator) 15:45:23 INFO - PROCESS | 1974 | RSS: Main: 291094528 15:45:23 INFO - PROCESS | 1974 | 15:45:25 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:53681/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53681/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:45:26 INFO - PROCESS | 1974 | RSS: Main: 293330944 15:45:26 INFO - PROCESS | 1974 | 15:45:29 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:53681/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53681/tests/kraken/driver.html?testName=imaging-darkroom) 15:45:29 INFO - PROCESS | 1974 | RSS: Main: 329674752 15:45:29 INFO - PROCESS | 1974 | 15:45:32 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:53681/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53681/tests/kraken/driver.html?testName=imaging-desaturate) 15:45:33 INFO - PROCESS | 1974 | RSS: Main: 331603968 15:45:33 INFO - PROCESS | 1974 | 15:45:36 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:53681/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53681/tests/kraken/driver.html?testName=json-parse-financial) 15:45:37 INFO - PROCESS | 1974 | RSS: Main: 330350592 15:45:37 INFO - PROCESS | 1974 | 15:45:38 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:53681/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53681/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:45:39 INFO - PROCESS | 1974 | RSS: Main: 306393088 15:45:39 INFO - PROCESS | 1974 | 15:45:41 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:53681/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53681/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:45:41 INFO - PROCESS | 1974 | RSS: Main: 306774016 15:45:41 INFO - PROCESS | 1974 | 15:45:43 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:53681/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53681/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:45:44 INFO - PROCESS | 1974 | RSS: Main: 310226944 15:45:44 INFO - PROCESS | 1974 | 15:45:47 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:53681/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53681/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:45:48 INFO - PROCESS | 1974 | RSS: Main: 327254016 15:45:48 INFO - PROCESS | 1974 | 15:45:51 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:53681/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53681/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:45:52 INFO - PROCESS | 1974 | RSS: Main: 320888832 15:45:52 INFO - PROCESS | 1974 | 15:45:53 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:53681/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53681/tests/kraken/driver.html?testName=ai-astar) 15:45:53 INFO - PROCESS | 1974 | RSS: Main: 364527616 15:45:53 INFO - PROCESS | 1974 | 15:45:53 INFO - PROCESS | 1974 | __start_tp_report 15:45:53 INFO - PROCESS | 1974 | _x_x_mozilla_page_load 15:45:53 INFO - PROCESS | 1974 | _x_x_mozilla_page_load_details 15:45:53 INFO - PROCESS | 1974 | |i|pagename|runs| 15:45:53 INFO - PROCESS | 1974 | |0;ai-astar;111;114;115;114;116;114;113;113;114;113 15:45:53 INFO - PROCESS | 1974 | |1;audio-beat-detection;147;115;142;153;113;113;111;112;144;114 15:45:53 INFO - PROCESS | 1974 | |2;audio-dft;185;182;183;184;186;186;184;181;184;181 15:45:53 INFO - PROCESS | 1974 | |3;audio-fft;79;59;58;88;62;60;58;62;86;61 15:45:53 INFO - PROCESS | 1974 | |4;audio-oscillator;87;71;95;70;67;68;92;67;69;91 15:45:53 INFO - PROCESS | 1974 | |5;imaging-gaussian-blur;89;87;89;87;89;86;90;87;89;85 15:45:53 INFO - PROCESS | 1974 | |6;imaging-darkroom;95;95;94;94;93;94;95;104;149;119 15:45:53 INFO - PROCESS | 1974 | |7;imaging-desaturate;81;97;122;125;82;81;84;82;81;81 15:45:53 INFO - PROCESS | 1974 | |8;json-parse-financial;55;53;54;53;54;54;54;54;54;53 15:45:53 INFO - PROCESS | 1974 | |9;json-stringify-tinderbox;66;75;81;72;72;70;71;73;74;72 15:45:53 INFO - PROCESS | 1974 | |10;stanford-crypto-aes;86;98;98;86;87;89;87;86;89;92 15:45:53 INFO - PROCESS | 1974 | |11;stanford-crypto-ccm;146;152;152;157;151;144;148;146;179;160 15:45:53 INFO - PROCESS | 1974 | |12;stanford-crypto-pbkdf2;221;173;172;172;172;176;174;171;169;241 15:45:53 INFO - PROCESS | 1974 | |13;stanford-crypto-sha256-iterative;68;67;68;66;67;66;66;67;66;67 15:45:53 INFO - PROCESS | 1974 | __end_tp_report 15:45:53 INFO - PROCESS | 1974 | __start_cc_report 15:45:53 INFO - PROCESS | 1974 | _x_x_mozilla_cycle_collect,2102 15:45:53 INFO - PROCESS | 1974 | __end_cc_report 15:45:53 INFO - PROCESS | 1974 | __startTimestamp1477867553991__endTimestamp 15:45:53 INFO - PROCESS | 1974 | 15:45:53 INFO - PROCESS | 1974 | ------- Summary: start ------- 15:45:53 INFO - PROCESS | 1974 | Number of tests: 14 15:45:53 INFO - PROCESS | 1974 | 15:45:53 INFO - PROCESS | 1974 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:1.34 (1.2%) stddev-sans-first:1.00 15:45:53 INFO - PROCESS | 1974 | Values: 111.0 114.0 115.0 114.0 116.0 114.0 113.0 113.0 114.0 113.0 15:45:54 INFO - PROCESS | 1974 | 15:45:54 INFO - PROCESS | 1974 | [#1] audio-beat-detection Cycles:10 Average:126.40 Median:128.50 stddev:17.55 (13.7%) stddev-sans-first:16.96 15:45:54 INFO - PROCESS | 1974 | Values: 147.0 115.0 142.0 153.0 113.0 113.0 111.0 112.0 144.0 114.0 15:45:54 INFO - PROCESS | 1974 | 15:45:54 INFO - PROCESS | 1974 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.84 (1.0%) stddev-sans-first:1.88 15:45:54 INFO - PROCESS | 1974 | Values: 185.0 182.0 183.0 184.0 186.0 186.0 184.0 181.0 184.0 181.0 15:45:54 INFO - PROCESS | 1974 | 15:45:54 INFO - PROCESS | 1974 | [#3] audio-fft Cycles:10 Average:67.30 Median:62.00 stddev:12.05 (19.4%) stddev-sans-first:12.01 15:45:54 INFO - PROCESS | 1974 | Values: 79.0 59.0 58.0 88.0 62.0 60.0 58.0 62.0 86.0 61.0 15:45:54 INFO - PROCESS | 1974 | 15:45:54 INFO - PROCESS | 1974 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:79.00 stddev:11.88 (15.0%) stddev-sans-first:12.11 15:45:54 INFO - PROCESS | 1974 | Values: 87.0 71.0 95.0 70.0 67.0 68.0 92.0 67.0 69.0 91.0 15:45:54 INFO - PROCESS | 1974 | 15:45:54 INFO - PROCESS | 1974 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:89.00 stddev:1.62 (1.8%) stddev-sans-first:1.66 15:45:54 INFO - PROCESS | 1974 | Values: 89.0 87.0 89.0 87.0 89.0 86.0 90.0 87.0 89.0 85.0 15:45:54 INFO - PROCESS | 1974 | 15:45:54 INFO - PROCESS | 1974 | [#6] imaging-darkroom Cycles:10 Average:103.20 Median:94.00 stddev:17.97 (19.1%) stddev-sans-first:18.82 15:45:54 INFO - PROCESS | 1974 | Values: 95.0 95.0 94.0 94.0 93.0 94.0 95.0 104.0 149.0 119.0 15:45:54 INFO - PROCESS | 1974 | 15:45:54 INFO - PROCESS | 1974 | [#7] imaging-desaturate Cycles:10 Average:91.60 Median:81.50 stddev:17.51 (21.5%) stddev-sans-first:18.15 15:45:54 INFO - PROCESS | 1974 | Values: 81.0 97.0 122.0 125.0 82.0 81.0 84.0 82.0 81.0 81.0 15:45:54 INFO - PROCESS | 1974 | 15:45:54 INFO - PROCESS | 1974 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.50 15:45:54 INFO - PROCESS | 1974 | Values: 55.0 53.0 54.0 53.0 54.0 54.0 54.0 54.0 54.0 53.0 15:45:54 INFO - PROCESS | 1974 | 15:45:54 INFO - PROCESS | 1974 | [#9] json-stringify-tinderbox Cycles:10 Average:72.60 Median:72.50 stddev:3.84 (5.3%) stddev-sans-first:3.24 15:45:54 INFO - PROCESS | 1974 | Values: 66.0 75.0 81.0 72.0 72.0 70.0 71.0 73.0 74.0 72.0 15:45:54 INFO - PROCESS | 1974 | 15:45:54 INFO - PROCESS | 1974 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:89.00 stddev:4.71 (5.3%) stddev-sans-first:4.79 15:45:54 INFO - PROCESS | 1974 | Values: 86.0 98.0 98.0 86.0 87.0 89.0 87.0 86.0 89.0 92.0 15:45:54 INFO - PROCESS | 1974 | 15:45:54 INFO - PROCESS | 1974 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:152.00 stddev:10.27 (6.8%) stddev-sans-first:10.52 15:45:54 INFO - PROCESS | 1974 | Values: 146.0 152.0 152.0 157.0 151.0 144.0 148.0 146.0 179.0 160.0 15:45:54 INFO - PROCESS | 1974 | 15:45:54 INFO - PROCESS | 1974 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.10 Median:173.50 stddev:25.23 (14.5%) stddev-sans-first:22.96 15:45:54 INFO - PROCESS | 1974 | Values: 221.0 173.0 172.0 172.0 172.0 176.0 174.0 171.0 169.0 241.0 15:45:54 INFO - PROCESS | 1974 | 15:45:54 INFO - PROCESS | 1974 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.79 (1.2%) stddev-sans-first:0.71 15:45:54 INFO - PROCESS | 1974 | Values: 68.0 67.0 68.0 66.0 67.0 66.0 66.0 67.0 66.0 67.0 15:45:54 INFO - PROCESS | 1974 | -------- Summary: end -------- 15:45:54 INFO - PROCESS | 1974 | 15:45:54 INFO - TEST-INFO | 1974: exit 0 15:45:54 INFO - TEST-OK | kraken | took 59678ms 15:45:54 INFO - SUITE-END | took 1178s 15:45:55 INFO - Completed test suite (00:19:38) 15:45:55 INFO - javascript benchmark 15:45:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3262.918427039324, 3285.3757786020656, 3308.602967643883, 3333.233356328046, 3355.0773537277246, 5551.555875529958, 5628.943710562893, 5629.099344104944, 5664.348599911012, 5677.829665110054, 5589.273394458714, 5631.380548139701, 5632.915506267399, 5637.605367624268, 5655.010373184697, 4278.5935336143175, 5126.897462050766, 5160.071187186305, 5179.223116532531, 5243.187305967562, 5136.332276804029, 5195.9480405195845, 5212.869678258036, 5216.347956505437, 5225.425203227642, 5161.896762064756, 5166.8708282293055, 5204.375474943012, 5205.531502164805, 5236.0, 5269.841904742864, 5277.683338999673, 5285.656432331924, 5286.920696189522, 5333.546648534879, 5084.161113416282, 5098.107831129538, 5155.0, 5192.091384007784, 5204.453532379083, 5546.722663866831, 5572.3870374258795, 5617.634853734494, 5626.212330273765, 5647.181158732002, 6145.293291271516, 6148.6618235997, 6152.261728592597, 6153.077038444225, 6155.446009859134, 6099.481544068715, 6148.923938310778, 6181.81454556364, 6275.24697036354, 6277.905831412532, 5076.416212135578, 5590.413006634245, 5639.971800141014, 5693.3168019837885, 5716.5998380113, 6269.185005949197, 6270.905936410866, 6275.246970363494, 6286.402791734778, 6287.842803929939, 5915.438033386821, 5969.701514924236, 6070.180525628984, 6083.422074902877, 6087.573869829158, 6097.379466367683, 6108.22065524731, 6152.723127459275, 6154.476869466056, 6239.937600624026, 6134.141220229171, 6134.325224225332, 6145.631262124287, 6154.292256390483, 6155.507559395238, 5481.808136715196, 5869.853253668607, 6008.609440386361, 6137.7851775187655, 6195.4424101831055, 4744.4069491313585, 4853.174960256765, 4853.9029219415415, 4870.975645121823, 4923.113839508851, 4079.91840163201, 4399.25212713833, 4399.758013309299, 4432.534583868647, 4455.153520831096, 5710.857228569319, 5764.884702306014, 5783.277090363705, 5784.971075144682, 5790.334111577138, 5903.144044113666, 5907.734151963086, 5917.763289468459, 6170.784022559189, 6193.659348735772, 5113.619322782828, 5702.401247868997, 5724.055530837449, 5763.135529670499, 5769.480746732815, 5355.0896347621, 5355.464453554691, 5368.489993450616, 5379.1662292344745, 5383.650062745909, 6247.812565623039, 6276.717547710273, 6278.748850046039, 6288.056791481315, 6303.212098487689, 5121.692698438105, 5981.252343457068, 6100.399513635539, 6105.247473117015, 6143.72353244114, 6231.53263505248, 6235.376462353728, 6240.906386404116, 6242.469390101892, 6263.96868015657, 5187.273781670494, 6105.084237364431, 6133.233345831771, 6135.601185922901, 6139.079138129317, 6071.51427885777, 6098.916365657296, 6120.326764056039, 6120.846978825387, 6158.3841615838055, 5955.970220148872, 6050.727717252822, 6056.727447264796, 6096.756129754937, 6101.816945491642, 6004.189434426471, 6015.6691381974415, 6026.8794624106395, 6045.941960157043, 6056.878862422639, 5193.221016847503, 5720.856978575569, 5739.0243658578975, 5787.421257874179, 5871.178035075008, 5950.256217972754, 5967.671778052249, 5969.701514924323, 6037.547183961133, 6066.848328791815, 5838.007538718513, 5846.590738648254, 5853.35613082552, 5866.94133058681, 5914.112883067574], "value": 5610.505193144762, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.62188060491906, 508.48896858657065, 513.2019709351957, 514.3904473199258, 568.6957477749405, 28713.712862871365, 32888.99999999997, 39131.0, 40367.39448908269, 42571.36142957858, 41055.0, 41486.37770433446, 41512.79243603785, 41589.584104159025, 41686.374704379385, 8225.341972642174, 9386.342955993066, 9575.233981281515, 9609.375390599624, 9625.56684949179, 9422.952885235563, 9498.81002379952, 9534.332596718232, 9544.093311135432, 9553.379030363005, 9490.288228382864, 9515.952420237923, 9537.046295370477, 9544.665936692218, 9567.138957493824, 9481.857772133424, 9539.856902146474, 9549.61801527938, 9551.474668893208, 9557.330986830924, 9432.339736218468, 9448.716538503855, 9502.952485237598, 9510.714678559654, 9514.666986655504, 39479.0, 39731.80134099311, 39816.40275395843, 39856.601433985576, 39921.40117898205, 3535.4520049392386, 3577.606463289036, 3580.534530511017, 3587.4798154267746, 3806.7144964127524, 29581.40837183266, 29645.851770741225, 30577.388452231055, 30813.229669258224, 31087.533686994713, 3192.6488086310487, 3214.517822326646, 3250.171206342391, 3250.593675790526, 3357.731381489475, 466280.0, 469612.0, 469737.0, 470697.0, 470958.0, 57328.0, 57560.71219643875, 57680.0, 57705.71147144322, 57747.42252577421, 58833.70583147057, 58843.41156588465, 58959.70520147372, 59046.40953590495, 59064.11403828946, 15138.621534461727, 15394.769078463833, 15396.615084622974, 15397.923010384877, 15416.07503549768, 11943.343116128697, 11946.701332466584, 11953.760924781629, 11999.700007499883, 12064.974477169539, 14689.77965330521, 15490.0, 15501.844981550266, 15529.0, 15715.0, 5982.790602328898, 6082.635041897501, 6105.145279660851, 6145.139680444741, 6152.2924863640355, 822.9547374894319, 855.4909828651943, 962.4610218277787, 1032.9845052324222, 1045.7333379988206, 27671.723282767318, 27681.446371072467, 29007.41985160327, 29114.0, 29741.810327587085, 1017.9491025448698, 1031.0, 1068.4123731947398, 1094.9288296260718, 1103.7240689827543, 12924.353782310847, 13466.865331346562, 14437.783433248509, 15043.398264069314, 15489.457868974532, 619510.853806081, 624347.0, 625196.0, 626382.0, 626608.0, 54651.0, 54729.0, 54809.0, 54845.0, 54875.72562137164, 1222.2788554752738, 1263.993680031594, 1335.365701291898, 1340.1490053815896, 1360.9931950340383, 29170.708292917647, 29803.55294670625, 29922.25194370158, 30339.393212136074, 30659.54010689797, 36433.0, 47313.763431182626, 47391.763041184575, 47396.7630161847, 47436.05127897492, 389698.0, 392091.0, 392474.0, 392734.0, 393273.03363484144, 24733.0, 33194.66805331916, 35604.0, 37384.81307593445, 37856.810715947344, 42352.941176470835, 43855.78072109619, 43902.0, 43939.78030109829, 44672.776636116614, 37338.8133059333, 37718.0, 37974.43038354372, 38149.664761733206, 39153.608463916134, 37139.81430092832, 37618.69325792507, 37762.43356349602, 38288.617113828506, 38599.6140038596, 33812.83093584615, 36618.8169059153, 37074.814625926694, 37113.0, 37268.813655931546, 24402.0, 25394.365140870887, 32392.676073238967, 33564.99305020853, 33636.66363336335, 20887.060082296637, 21625.567488649824, 24531.264062078168, 25121.658721270178, 25329.74670253274], "value": 19071.21667626778, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.3514071959394, 365.8865751616998, 369.2596344329619, 386.3972203362756, 393.9815576734142, 19817.306394276202, 19892.90053549732, 20351.389458316236, 20941.848198349104, 21085.84027878466, 20100.69848952267, 20198.495037624067, 20805.687914681293, 20974.909892570955, 21079.73521588702, 4899.510048995107, 5199.636025478228, 5449.155380915964, 5784.855378615526, 5981.342052374235, 5882.941170588304, 6015.428534289235, 6018.0, 6024.2168518092585, 6026.939730602681, 4505.932411013838, 5263.6578622389425, 5354.116570765819, 5370.946290537084, 5973.522118230553, 5231.86920326993, 5289.788408463657, 5291.259223708684, 5293.814716484924, 5303.655262407931, 4457.598816106533, 5029.522195391469, 5044.319016932704, 5154.098032844237, 5212.40057393397, 13068.673283167902, 13112.672183195496, 13152.342382880817, 13245.337733113402, 13266.270355130464, 2160.730466299573, 2359.728631207399, 2766.1148432501404, 2904.724051215131, 2992.880284788606, 9899.554520046615, 10404.947975260151, 10410.0, 10523.52644131016, 10564.630237941712, 1794.8205179482077, 1832.5235438785878, 1870.2145099058428, 1878.8590855685877, 1906.8760530565607, 109523.0, 120013.0, 124474.37762811218, 129097.99999999905, 132896.0, 9217.631294748135, 10728.302660327054, 11246.718832029264, 11327.150463715254, 11530.135239856876, 14541.709165816625, 14559.344829482487, 14564.41742330316, 14576.344064517121, 14619.78070328946, 8092.109867914524, 8364.0, 8371.079181290053, 8425.199606037417, 8535.701250456204, 6706.161729783777, 8233.8353232935, 8245.422820402635, 8410.74767756956, 8422.326213902874, 3776.8300426480873, 4484.0, 4485.484169320504, 4493.123840851003, 4496.505384407713, 1592.3948899418378, 1840.4172411726172, 1853.5551467647667, 1893.9129219372335, 1921.6541022615784, 848.7878030492377, 866.4757821518017, 876.588003638289, 876.7632739160391, 901.2744740483973, 16574.337026518806, 20044.899775501028, 21503.56992860134, 21512.56974860494, 21595.0, 837.2548431895618, 947.6067432015774, 954.2032402943481, 955.4140127388501, 1013.2755080117604, 10856.457177141112, 11321.773564528663, 11325.773484530428, 11335.206535542598, 11392.601258956063, 506625.0, 586699.0, 598960.0, 603166.0, 603223.0, 88310.55844720735, 95737.52131239299, 122457.0, 123405.3616246854, 132177.0, 830.4215411148418, 934.1452570897688, 1000.0849222960909, 1000.7498125468633, 1001.2440607341556, 16167.67664646724, 18653.44039678812, 19110.14004369779, 19140.9472479015, 19503.804961950766, 52527.94944101173, 55670.32989010336, 56871.0, 58277.70861145667, 58661.41338586559, 64108.67945660242, 80577.19422805696, 82485.17514824776, 83662.0, 84443.57778211315, 6540.542162048611, 7656.272654097852, 7795.844083118419, 7936.841263174589, 8247.546384948646, 12258.570950016707, 12713.0, 13022.674433139247, 13148.474061037834, 13216.933915330363, 9887.901120988698, 10142.442165680959, 10406.959304069533, 10983.121350291798, 11119.444027798416, 10961.835572466578, 11141.275817071864, 11241.591495005827, 11295.7740845181, 11381.374024428405, 9585.712428627152, 11430.1998860079, 11459.770804584028, 11788.76422471563, 11847.407629618312, 5619.550435965195, 7296.233895441114, 7364.116306043309, 7564.243575642392, 8320.167983201873, 9296.535173241446, 11402.714932126763, 11580.942095289469, 11582.91507528653, 11834.644960651194], "value": 9201.274971996001, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1379.6964667773086, 1541.6223025358788, 1560.921953902305, 1763.2858692229652, 1893.6780747272962, 7569.507981981167, 7946.761597152087, 8217.794555136124, 8373.204545568162, 8450.788730281745, 8254.752357429272, 8307.833843323146, 8398.790030249247, 8404.03353614333, 8412.242898139166, 2303.389601755536, 2334.9766502335015, 2338.0881456231996, 2338.8245881558864, 2345.6364263539094, 1950.619629172312, 2336.649502574619, 2355.729091154522, 2361.2207971369407, 2361.95276094478, 2165.577712346093, 2305.5849947009533, 2329.2895666821614, 2331.440454290975, 2335.6146235871147, 2235.9217427390045, 2276.715410573678, 2297.5290065536524, 2304.6197377432704, 2306.9884650576723, 2258.9209377671705, 2278.7493375728654, 2283.920062797794, 2286.9199578014855, 2288.5651726171973, 8384.455010424364, 8398.958005209935, 8480.109588493182, 8486.872696909551, 8487.23614874658, 5052.9242061369105, 5069.797208111671, 5256.474352564713, 5264.842054738364, 5266.104762190398, 3321.30252646942, 3574.803385813779, 3680.0983758978955, 3680.4479328100733, 3683.613220611851, 1033.8552602635637, 1064.5156453813497, 1067.3382502848206, 1070.9946450267698, 1085.0939465546278, 10460.37237765729, 10763.78472430547, 10775.299605525615, 10898.0, 10936.179786516042, 4281.293586558183, 4354.782260886943, 4389.1660584488845, 4466.642668586505, 4472.105578884236, 8629.568521574021, 8724.0, 8733.03936566977, 8742.519161446182, 8752.56237188138, 5162.096633089194, 5162.5095615916425, 5168.793248270027, 5169.7932082717025, 5178.300929374486, 4110.506739191256, 4322.3516472528745, 4467.262901621196, 4507.098580283956, 4845.515448455127, 3580.480830279595, 3598.9100272493397, 3680.3007428589017, 3683.723720720957, 3693.926121477555, 1113.6269349767756, 1120.7970112079702, 1122.893325134111, 1130.0338210829786, 1130.6325444230658, 833.8582440985047, 855.724969795001, 881.9250363719156, 915.4049867586123, 939.6617217801586, 5189.610779191576, 5575.804846830341, 5668.688222147822, 5825.155352473867, 5927.229460170151, 931.9161275485238, 931.9953400232955, 936.3352020065829, 943.1511639524483, 981.0875885426622, 3195.984020079885, 3371.3088816792497, 3843.7885916274313, 3994.400839874042, 4044.656204222615, 9872.259580531489, 10159.542820573091, 10286.948565257126, 10303.742406439898, 10304.896951030543, 9619.951900240594, 10052.949735251277, 10221.897781022095, 10226.53980570876, 10228.488575571191, 7764.0, 7990.6004699765945, 7994.120646728855, 7999.480033797785, 8014.959925200336, 5614.9719251403485, 5616.719164041863, 5695.259616249861, 5887.470127688529, 6090.969545152423, 9396.295277854326, 9470.526473676151, 9515.33392662507, 9559.330846840654, 9585.041495850639, 8476.321894248322, 8786.25316848075, 9111.589978451118, 9533.285003624616, 9533.427994320364, 6810.8637827244165, 7015.649217539205, 7198.820029499305, 7275.672594733355, 7341.74303899361, 7713.382929365749, 7981.8802717958115, 7992.280694737501, 8152.510849349058, 8169.101398846241, 7187.5687458752645, 7226.783196504113, 7233.638318083969, 7290.0, 7415.740449084472, 7065.2934706531, 7115.0, 7131.572105673677, 7134.500584959003, 7184.1738200105565, 5661.490465858093, 6396.232452105591, 6591.6045037296, 6692.832679182865, 6768.510927595921, 4720.598749106364, 4787.018661174381, 4834.444038935568, 4868.95131048685, 4943.901121977612, 4890.339804126397, 4972.751362431792, 4991.775370108281, 5015.648904576645, 5017.8243761468175], "value": 4401.054647008703, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [260.42446193911456, 301.50402587743156, 301.9018818883862, 307.0787637088734, 311.5778120646525, 4717.339572459859, 4738.8389844488065, 5151.304573882525, 5156.407013193484, 5210.921836172451, 4219.05071358944, 4695.689538875094, 5084.4661310562415, 5124.333836601237, 5134.332536770215, 3290.605127384717, 3567.7869524361713, 3666.2484190740834, 3812.961870381302, 3850.84596616135, 3788.488554045196, 3994.26106170358, 4006.9799651001704, 4010.2452979973687, 4023.4970628671417, 3436.5532480777465, 3593.3531964246426, 3904.316744569703, 3910.002949247952, 3956.1889812588493, 3419.247765491588, 3767.6420740029653, 3897.2657167560446, 3951.0517475805746, 3969.781662008574, 3050.34417600217, 3393.9660603393895, 3464.6188919218866, 3490.3542844573835, 3599.9200239928177, 5047.712833227541, 5051.823186188466, 5056.646034777567, 5064.822731204427, 5111.284420181178, 3762.473253744478, 4340.414044104037, 4439.448441247022, 4482.574155455227, 4484.372187893698, 4476.373307736887, 4543.704659197141, 5387.703676297802, 5445.53712934401, 5478.945210547923, 3775.6790672792845, 3817.48463957365, 3819.618038196158, 3828.9042773930596, 3830.636089571486, 5231.010492985511, 5405.486448801287, 5482.943896038985, 5731.971340143315, 5767.855803604902, 3658.2257605061523, 3699.8520059197867, 3705.3515634763808, 3719.237556301006, 3734.3838266686234, 3163.9050828475183, 3193.632732235776, 3740.1023754299317, 3740.775553466801, 3754.718396120302, 3680.8895733128047, 4369.75966321849, 4391.05799114594, 4393.450818647669, 4449.06465687427, 5008.0, 5356.464353564613, 5392.40683524812, 5462.863428414322, 5482.945170548323, 1272.6309370282515, 1295.799151131576, 1295.9611211663664, 1296.7017585955095, 1300.8048792680984, 695.8086526205334, 754.3738696881575, 764.2144869332974, 793.6507936508034, 795.5544894858782, 4804.903901921942, 4917.0, 4924.43369012561, 4945.604351651931, 5060.797568097309, 4871.14754917888, 5533.833984980457, 5597.99236137491, 5605.439456054362, 5624.9187800513255, 4250.659947204216, 5020.397552293748, 5076.035553244946, 5146.794128234828, 5157.432682404932, 2685.5971604259516, 2696.6089916961932, 2741.3831887825477, 2777.9443811351557, 2924.6344206974127, 4706.905861882743, 5313.867153321198, 5616.230887270497, 5769.480746732815, 5816.912746308809, 4938.654294199443, 5139.897202056013, 5374.838754837361, 5455.263539422127, 5542.75057622408, 5050.0, 5613.635113717595, 5632.04255276604, 5647.0, 5653.745581448762, 4273.444452221192, 4903.509649035068, 5280.524752772115, 5544.504972762193, 5559.861003474945, 5226.8954620908125, 5375.489328513785, 5384.407715151252, 5426.728663566727, 5469.097598896296, 5053.31780209667, 5199.4020687621405, 5645.830625081254, 5687.668056096981, 5738.446391355807, 3302.926548871674, 3570.803605801706, 3675.7426980111136, 3699.5930447651276, 3712.8514859405323, 5128.58971282304, 5416.295881535375, 5452.372977107525, 5493.285872836505, 5518.641288316247, 4900.681455705368, 5300.443453437333, 5306.151015837448, 5325.973370133124, 5376.865578360573, 5234.685918844882, 5243.921341179809, 5254.579633629224, 5273.973630131824, 5293.523582877406, 4782.856514304576, 4904.337914381513, 4908.4355299139625, 5255.421903590678, 5373.677579345252, 4231.809568569483, 5234.371875374979, 5288.259643649969, 5299.523042926001, 5472.863178420572, 4137.0, 5205.973970130125, 5215.29593504872, 5217.373915130209, 5227.947720522746], "value": 3909.0535857821487, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.6634964741394, 766.7009866152198, 797.174923953708, 827.9337652987762, 840.1976112812265, 14634.560963171109, 14966.25168741564, 15088.321025553849, 15159.772603410944, 15226.238688065609, 13933.930330348334, 14047.929760351184, 14168.079074860127, 14177.0, 14181.220032898213, 886.9866951995725, 904.7918978634918, 906.8322360363321, 907.3376435202306, 908.4413085952125, 709.2694524639604, 735.4190189750118, 738.4498548581314, 878.2447095497866, 897.7396554999042, 947.4315410753527, 961.5192403798101, 961.5769061612868, 961.7884065505577, 962.93259471837, 956.9521523923811, 957.9137877590981, 958.4441024205944, 959.491469521155, 960.6589660670431, 550.1390324142703, 563.5857644631193, 681.1145510835894, 700.1213477086275, 713.0801266366433, 14692.265386730727, 14860.70278594422, 14998.62503437412, 15053.849461505355, 15056.774148387783, 13518.526851560147, 13595.524156654568, 13695.86304136956, 13723.431907703969, 13889.0, 12531.56139535121, 12628.558000470031, 12693.301868397235, 12786.296753678544, 13010.869891301061, 3378.425667636483, 3501.299740052, 3596.28074385124, 3639.017465284385, 3649.996251030961, 91346.54326728391, 91679.54160229223, 94460.0, 95242.57136142964, 95431.52284238604, 10427.791444171075, 10490.21323400748, 10652.360858348524, 10655.546685574558, 10928.180386471047, 19796.010199490254, 19917.800821991594, 20344.084516196806, 20363.694544581842, 20402.183912643624, 7505.362044226302, 7632.1604623492485, 7649.9617501912135, 7774.533527988338, 7776.105747839071, 4311.913761724748, 4321.9135617287475, 4358.433403657505, 4416.911661766747, 4542.568455996675, 3444.896653100411, 3563.0, 3566.8038257896064, 3573.714102871764, 3576.034470692898, 609.9237595300588, 610.954178436619, 616.0789619518848, 632.7057918068028, 632.8955722305768, 547.6796074296501, 572.3046498504337, 586.3315819965243, 624.4286477872787, 670.597641415147, 8741.737747867573, 9306.67426640078, 9421.528923553931, 9500.477473738873, 9601.903980960102, 614.5728718540571, 618.6015257520021, 629.8456878064811, 653.8463461086641, 671.9798406047827, 4403.361512580658, 4569.771511424482, 4572.0627271409285, 4607.585317321457, 4687.906241875144, 67994.66002670054, 70052.0, 81996.3600727994, 94858.0, 101679.0, 97010.51494742482, 98345.0, 100293.49853250687, 100739.49630251802, 101229.98770012206, 81308.0, 81515.0, 81688.0, 82192.76710849343, 83587.16412835794, 7875.881861772193, 8139.593020349077, 8210.630521626661, 8266.504009759434, 8392.641931468279, 34669.826650867595, 52681.736591316796, 55116.17325740037, 56476.43523564712, 57414.425855740905, 70731.0, 80004.99987500126, 82807.5859620698, 82819.5859020701, 84690.57654711687, 11023.456716059918, 11514.424278786195, 11709.658068386527, 11763.235389699643, 12235.143539952118, 12444.0, 12575.622731318075, 12580.68687029439, 12682.556110536087, 13086.672833179247, 11876.703082423008, 11991.820122697993, 12016.33910134951, 12100.394980250776, 12287.87712122903, 9098.31762617793, 11781.234219775688, 12098.0, 12236.082293828176, 12258.877411225774, 9721.659741909, 11950.820737688768, 12010.399480026139, 12022.398880055787, 12056.156069075081, 9799.069088436587, 9840.0, 9892.802143957224, 10287.382757034302, 10400.0, 9789.951050244703, 10339.327943683636, 10340.224483163642, 10354.016368444985, 10367.84448233262], "value": 7238.907371600328, "unit": "score"}], "name": "dromaeo_css", "value": 7048.491902498457, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 115.0, 114.0, 116.0, 114.0, 113.0, 113.0, 114.0, 113.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 142.0, 153.0, 113.0, 113.0, 111.0, 112.0, 144.0, 114.0], "value": 126.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 183.0, 184.0, 186.0, 186.0, 184.0, 181.0, 184.0, 181.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 58.0, 88.0, 62.0, 60.0, 58.0, 62.0, 86.0, 61.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 95.0, 70.0, 67.0, 68.0, 92.0, 67.0, 69.0, 91.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 87.0, 89.0, 86.0, 90.0, 87.0, 89.0, 85.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 93.0, 94.0, 95.0, 104.0, 149.0, 119.0], "value": 103.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 97.0, 122.0, 125.0, 82.0, 81.0, 84.0, 82.0, 81.0, 81.0], "value": 91.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 54.0, 53.0, 54.0, 54.0, 54.0, 54.0, 54.0, 53.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 75.0, 81.0, 72.0, 72.0, 70.0, 71.0, 73.0, 74.0, 72.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 98.0, 98.0, 86.0, 87.0, 89.0, 87.0, 86.0, 89.0, 92.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 152.0, 152.0, 157.0, 151.0, 144.0, 148.0, 146.0, 179.0, 160.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [221.0, 173.0, 172.0, 172.0, 172.0, 176.0, 174.0, 171.0, 169.0, 241.0], "value": 184.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 66.0, 67.0, 66.0, 66.0, 67.0, 66.0, 67.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1471.8999999999999, "alertThreshold": 2.0}]} 15:45:55 INFO - Return code: 0 15:45:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:45:55 INFO - # TBPL SUCCESS # 15:45:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:45:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:45:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:45:55 INFO - 2016-10-30 15:45:55.325 screenresolution[1978:31699] starting screenresolution argv=screenresolution get 15:45:55 INFO - 2016-10-30 15:45:55.328 screenresolution[1978:31699] Display 0: 1600x1200x32@60 15:45:55 INFO - 2016-10-30 15:45:55.334 screenresolution[1979:31702] starting screenresolution argv=screenresolution list 15:45:55 INFO - Available Modes on Display 0 15:45:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:45:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:45:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:45:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:45:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:45:55 INFO - Intel Iris: 15:45:55 INFO - Chipset Model: Intel Iris 15:45:55 INFO - Type: GPU 15:45:55 INFO - Bus: Built-In 15:45:55 INFO - VRAM (Dynamic, Max): 1536 MB 15:45:55 INFO - Vendor: Intel (0x8086) 15:45:55 INFO - Device ID: 0x0a2e 15:45:55 INFO - Revision ID: 0x0009 15:45:55 INFO - Displays: 15:45:55 INFO - Display: 15:45:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:45:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:45:55 INFO - Display Serial Number: 1600x1200 60 15:45:55 INFO - Main Display: Yes 15:45:55 INFO - Mirror: Off 15:45:55 INFO - Online: Yes 15:45:55 INFO - Rotation: Supported 15:45:55 INFO - Return code: 0 15:45:55 INFO - Running post-action listener: _resource_record_post_action 15:45:55 INFO - [mozharness: 2016-10-30 22:45:55.570578Z] Finished run-tests step (success) 15:45:55 INFO - Running post-run listener: _resource_record_post_run 15:45:55 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 11846656; Write bytes: 719719424; Read time: 140; Write time: 2056 15:45:55 INFO - TinderboxPrint: CPU usage
53.6% 15:45:55 INFO - TinderboxPrint: I/O read bytes / time
11,846,656 / 140 15:45:55 INFO - TinderboxPrint: I/O write bytes / time
719,719,424 / 2,056 15:45:55 INFO - TinderboxPrint: CPU idle
2,252.8 (46.4%) 15:45:55 INFO - TinderboxPrint: CPU system
971.9 (20.0%) 15:45:55 INFO - TinderboxPrint: CPU user
1,633.1 (33.6%) 15:45:55 INFO - TinderboxPrint: Swap in / out
411,787,264 / 0 15:45:55 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 408150528; Write bytes: 411297792; Read time: 31573; Write time: 876 15:45:55 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 11772928; Write bytes: 306906112; Read time: 139; Write time: 1171 15:45:56 INFO - Running post-run listener: _upload_blobber_files 15:45:56 INFO - Blob upload gear active. 15:45:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:45:56 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 15:45:56 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:45:56 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:45:56 INFO - (blobuploader) - INFO - Open directory for files ... 15:45:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:45:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:45:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:45:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:45:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:45:57 INFO - (blobuploader) - INFO - Done attempting. 15:45:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:45:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:45:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:45:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:45:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:45:58 INFO - (blobuploader) - INFO - Done attempting. 15:45:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:45:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:45:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:46:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:00 INFO - (blobuploader) - INFO - Done attempting. 15:46:00 INFO - (blobuploader) - INFO - Iteration through files over. 15:46:00 INFO - Return code: 0 15:46:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:46:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:46:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e4bc089874a37c5fdbfde996f121f628492078a94bca38fccedf6c6a6011a1a7933ae585af34a4bcea01bb42c35dd2df3adb82c72b8c2b2a1d9bbb01924c0b5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cb6a2f55fce3088756356dc93225d57589bc59d9ed6e689c9697032475189b53b071e919588a6e37c761f9785c33775b7d9f44bf56dfb1a88fb8bb0163885584", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/1f6c8b9d8710f5aac5981fd0aedee69fdb8accb8dc475396aa3481e4e254ecb237457bd9961caeff8aaf221a79a75074115a5a428eb6e9ff22f21bd804a57f2c"} 15:46:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:46:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:46:00 INFO - Contents: 15:46:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e4bc089874a37c5fdbfde996f121f628492078a94bca38fccedf6c6a6011a1a7933ae585af34a4bcea01bb42c35dd2df3adb82c72b8c2b2a1d9bbb01924c0b5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cb6a2f55fce3088756356dc93225d57589bc59d9ed6e689c9697032475189b53b071e919588a6e37c761f9785c33775b7d9f44bf56dfb1a88fb8bb0163885584", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/1f6c8b9d8710f5aac5981fd0aedee69fdb8accb8dc475396aa3481e4e254ecb237457bd9961caeff8aaf221a79a75074115a5a428eb6e9ff22f21bd804a57f2c"} 15:46:00 INFO - Running post-run listener: copy_logs_to_upload_dir 15:46:00 INFO - Copying logs to upload dir... 15:46:00 INFO - mkdir: /builds/slave/test/build/upload/logs 15:46:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.135444 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-30 15:46:00.750854) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-30 15:46:00.754844) ========= 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.DIg49BniEo/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.NqVFRKgBKJ/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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/Larch/sha512/e4bc089874a37c5fdbfde996f121f628492078a94bca38fccedf6c6a6011a1a7933ae585af34a4bcea01bb42c35dd2df3adb82c72b8c2b2a1d9bbb01924c0b5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cb6a2f55fce3088756356dc93225d57589bc59d9ed6e689c9697032475189b53b071e919588a6e37c761f9785c33775b7d9f44bf56dfb1a88fb8bb0163885584", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/1f6c8b9d8710f5aac5981fd0aedee69fdb8accb8dc475396aa3481e4e254ecb237457bd9961caeff8aaf221a79a75074115a5a428eb6e9ff22f21bd804a57f2c"} build_url:https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011274 build_url: 'https://queue.taskcluster.net/v1/task/Nsa9__FmQAy5nXjTjsLl_Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e4bc089874a37c5fdbfde996f121f628492078a94bca38fccedf6c6a6011a1a7933ae585af34a4bcea01bb42c35dd2df3adb82c72b8c2b2a1d9bbb01924c0b5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cb6a2f55fce3088756356dc93225d57589bc59d9ed6e689c9697032475189b53b071e919588a6e37c761f9785c33775b7d9f44bf56dfb1a88fb8bb0163885584", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/1f6c8b9d8710f5aac5981fd0aedee69fdb8accb8dc475396aa3481e4e254ecb237457bd9961caeff8aaf221a79a75074115a5a428eb6e9ff22f21bd804a57f2c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-30 15:46:00.788391) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 15:46:00.788755) ========= 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.DIg49BniEo/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.NqVFRKgBKJ/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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.005445 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 15:46:00.838296) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-30 15:46:00.838712) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-30 15:46:00.839118) ========= ========= Total master_lag: 0.18 =========