builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0254 starttime: 1475183526.89 results: success (0) buildid: 20160929115520 builduid: a39988539646422c848b52ef3cbf9922 revision: 846e444511768c210822f1843f3b4e26ff7e852e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 14:12:06.887657) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 14:12:06.888267) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 14:12:06.888662) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 14:12:06.913110) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 14:12:06.913460) ========= 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.mZYaa3mr5y/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.FSOLinWfjx/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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-09-29 14:12:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.8M=0.001s 2016-09-29 14:12:07 (18.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.167589 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 14:12:07.103392) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 14:12:07.103709) ========= 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.mZYaa3mr5y/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.FSOLinWfjx/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.025773 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 14:12:07.151766) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 14:12:07.152223) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 846e444511768c210822f1843f3b4e26ff7e852e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 846e444511768c210822f1843f3b4e26ff7e852e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mZYaa3mr5y/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.FSOLinWfjx/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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-09-29 14:12:07,230 truncating revision to first 12 chars 2016-09-29 14:12:07,230 Setting DEBUG logging. 2016-09-29 14:12:07,230 attempt 1/10 2016-09-29 14:12:07,230 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/846e44451176?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 14:12:08,263 unpacking tar archive at: fx-team-846e44451176/testing/mozharness/ program finished with exit code 0 elapsedTime=1.291599 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 14:12:08.466396) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 14:12:08.466753) ========= script_repo_revision: 846e444511768c210822f1843f3b4e26ff7e852e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 14:12:08.467258) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 14:12:08.467570) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 14:12:08.486241) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-29 14:12:08.486642) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mZYaa3mr5y/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.FSOLinWfjx/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 14:12:08 INFO - MultiFileLogger online at 20160929 14:12:08 in /builds/slave/test 14:12:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 14:12:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:12:08 INFO - {'append_to_log': False, 14:12:08 INFO - 'base_work_dir': '/builds/slave/test', 14:12:08 INFO - 'blob_upload_branch': 'Fx-Team', 14:12:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:12:08 INFO - 'branch': 'Fx-Team', 14:12:08 INFO - 'buildbot_json_path': 'buildprops.json', 14:12:08 INFO - 'config_files': ('talos/mac_config.py',), 14:12:08 INFO - 'default_actions': ('clobber', 14:12:08 INFO - 'read-buildbot-config', 14:12:08 INFO - 'download-and-extract', 14:12:08 INFO - 'populate-webroot', 14:12:08 INFO - 'create-virtualenv', 14:12:08 INFO - 'install', 14:12:08 INFO - 'run-tests'), 14:12:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:12:08 INFO - 'download_minidump_stackwalk': True, 14:12:08 INFO - 'download_symbols': 'ondemand', 14:12:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:12:08 INFO - 'tooltool.py': '/tools/tooltool.py', 14:12:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:12:08 INFO - '/tools/misc-python/virtualenv.py')}, 14:12:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:12:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:12:08 INFO - 'installer_path': 'installer.exe', 14:12:08 INFO - 'log_level': 'info', 14:12:08 INFO - 'log_name': 'talos', 14:12:08 INFO - 'log_to_console': True, 14:12:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:12:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:12:08 INFO - 'opt_config_files': (), 14:12:08 INFO - 'pip_index': False, 14:12:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:12:08 INFO - 'cmd': ('bash', 14:12:08 INFO - '-c', 14:12:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:12:08 INFO - 'enabled': True, 14:12:08 INFO - 'halt_on_failure': False, 14:12:08 INFO - 'name': 'check_screen_resolution'},), 14:12:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:12:08 INFO - 'cmd': ('bash', 14:12:08 INFO - '-c', 14:12:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:12:08 INFO - 'enabled': True, 14:12:08 INFO - 'halt_on_failure': False, 14:12:08 INFO - 'name': 'check_screen_resolution'},), 14:12:08 INFO - 'run_cmd_checks_enabled': True, 14:12:08 INFO - 'sps_profile': False, 14:12:08 INFO - 'sps_profile_interval': 0, 14:12:08 INFO - 'suite': 'dromaeojs', 14:12:08 INFO - 'system_bits': '32', 14:12:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:12:08 INFO - 'title': 't-yosemite-r7-0254', 14:12:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:12:08 INFO - 'use_talos_json': True, 14:12:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:12:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:12:08 INFO - 'work_dir': 'build'} 14:12:08 INFO - [mozharness: 2016-09-29 21:12:08.686794Z] Running clobber step. 14:12:08 INFO - Running pre-action listener: _resource_record_pre_action 14:12:08 INFO - Running main action method: clobber 14:12:08 INFO - rmtree: /builds/slave/test/build 14:12:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:12:10 INFO - Running post-action listener: _resource_record_post_action 14:12:10 INFO - [mozharness: 2016-09-29 21:12:10.938879Z] Finished clobber step (success) 14:12:10 INFO - [mozharness: 2016-09-29 21:12:10.938995Z] Running read-buildbot-config step. 14:12:10 INFO - Running pre-action listener: _resource_record_pre_action 14:12:10 INFO - Running main action method: read_buildbot_config 14:12:10 INFO - Using buildbot properties: 14:12:10 INFO - { 14:12:10 INFO - "project": "", 14:12:10 INFO - "product": "firefox", 14:12:10 INFO - "script_repo_revision": "production", 14:12:10 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 14:12:10 INFO - "repository": "", 14:12:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 14:12:10 INFO - "buildid": "20160929115520", 14:12:10 INFO - "pgo_build": "False", 14:12:10 INFO - "basedir": "/builds/slave/test", 14:12:10 INFO - "buildnumber": 32, 14:12:10 INFO - "slavename": "t-yosemite-r7-0254", 14:12:10 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 14:12:10 INFO - "platform": "yosemite_r7", 14:12:10 INFO - "scheduler": "tests-fx-team-macosx64-talos", 14:12:10 INFO - "branch": "fx-team", 14:12:10 INFO - "repo_path": "integration/fx-team", 14:12:10 INFO - "revision": "846e444511768c210822f1843f3b4e26ff7e852e", 14:12:10 INFO - "stage_platform": "macosx64", 14:12:10 INFO - "builduid": "a39988539646422c848b52ef3cbf9922", 14:12:10 INFO - "slavebuilddir": "test" 14:12:10 INFO - } 14:12:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 14:12:10 INFO - Running post-action listener: _resource_record_post_action 14:12:10 INFO - [mozharness: 2016-09-29 21:12:10.942134Z] Finished read-buildbot-config step (success) 14:12:10 INFO - [mozharness: 2016-09-29 21:12:10.942269Z] Running download-and-extract step. 14:12:10 INFO - Running pre-action listener: _resource_record_pre_action 14:12:10 INFO - Running main action method: download_and_extract 14:12:10 INFO - mkdir: /builds/slave/test/build/tests 14:12:10 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')]} 14:12:10 INFO - trying https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 14:12:10 INFO - Downloading https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/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 14:12:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/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 14:12:11 INFO - Downloaded 1199 bytes. 14:12:11 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 14:12:11 INFO - Using the following test package requirements: 14:12:11 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:12:11 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:11 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 14:12:11 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:11 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 14:12:11 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:11 INFO - u'jsshell-mac.zip'], 14:12:11 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:11 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 14:12:11 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:12:11 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:11 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 14:12:11 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:11 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 14:12:11 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:11 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 14:12:11 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:12:11 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 14:12:11 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:12:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:12:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:12:11 INFO - Fetch https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:12:12 INFO - Http code: 200 14:12:12 INFO - Content-Encoding: None 14:12:12 INFO - Content-Type: application/zip 14:12:12 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 14:12:12 INFO - x-amz-cf-id: pZAgvjRZ_o2H8eNHo880Bj-w6-qKJwZmI5MiEjz8TeE-w6i40Ap5pQ== 14:12:12 INFO - x-amz-version-id: FL2Q7jytul__eit9gT6SPDZ5NeRyAhWR 14:12:12 INFO - x-cache: Hit from cloudfront 14:12:12 INFO - Expected file size: 16473027 14:12:12 INFO - Obtained file size: 16473027 14:12:19 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 14:12:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:12:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:12:19 INFO - Fetch https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:12:20 INFO - Http code: 200 14:12:20 INFO - Content-Encoding: None 14:12:20 INFO - Content-Type: application/zip 14:12:20 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 14:12:20 INFO - x-amz-cf-id: 2qMcerfITPvYZ38DLbNxTj-CLJoY4ZWZo5RRTy8vE_5_q1WUuNR5AA== 14:12:20 INFO - x-amz-version-id: FL2Q7jytul__eit9gT6SPDZ5NeRyAhWR 14:12:20 INFO - x-cache: Hit from cloudfront 14:12:20 INFO - Expected file size: 16473027 14:12:20 INFO - Obtained file size: 16473027 14:12:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 14:12:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:12:26 INFO - Fetch https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 14:12:27 INFO - Http code: 200 14:12:27 INFO - Content-Encoding: None 14:12:27 INFO - Content-Type: application/zip 14:12:27 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 14:12:27 INFO - x-amz-cf-id: WbQFDV2yuek9lv3BrGiTuHFu4zze7B_DF0rIRPl16yyBBfa2L0icbA== 14:12:27 INFO - x-amz-version-id: F1Ri2YbYcT9CRnItZR0WbosUjuWjn1R0 14:12:27 INFO - x-cache: Miss from cloudfront 14:12:28 INFO - Expected file size: 14414067 14:12:28 INFO - Obtained file size: 14414067 14:12:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:29 INFO - trying https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:12:29 INFO - Downloading https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 14:12:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/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 14:12:31 INFO - Downloaded 111789963 bytes. 14:12:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:12:31 INFO - mkdir: /builds/slave/test/properties 14:12:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:12:31 INFO - Writing to file /builds/slave/test/properties/build_url 14:12:31 INFO - Contents: 14:12:31 INFO - build_url:https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:12:32 INFO - Running post-action listener: _resource_record_post_action 14:12:32 INFO - Running post-action listener: set_extra_try_arguments 14:12:32 INFO - [mozharness: 2016-09-29 21:12:32.494364Z] Finished download-and-extract step (success) 14:12:32 INFO - [mozharness: 2016-09-29 21:12:32.494497Z] Running populate-webroot step. 14:12:32 INFO - Running pre-action listener: _resource_record_pre_action 14:12:32 INFO - Running main action method: populate_webroot 14:12:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:12:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:12:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:12:32 INFO - '--tppagecycles', 14:12:32 INFO - '7'], 14:12:32 INFO - 'tests': ['tsvgm']}}, 14:12:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:12:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:12:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:12:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:12:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:12:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:12:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:32 INFO - 'talos_options': ['--disable-e10s'], 14:12:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:12:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:12:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:32 INFO - 'talos_options': ['--disable-e10s'], 14:12:32 INFO - 'tests': ['damp', 'tps']}, 14:12:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:32 INFO - 'tests': ['damp', 'tps']}, 14:12:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:12:32 INFO - 'tests': ['dromaeo_dom']}, 14:12:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:12:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:12:32 INFO - 'tests': ['basic_compositor_video']}, 14:12:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:12:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:12:32 INFO - 'tests': ['a11yr', 14:12:32 INFO - 'ts_paint', 14:12:32 INFO - 'tpaint', 14:12:32 INFO - 'sessionrestore', 14:12:32 INFO - 'sessionrestore_no_auto_restore', 14:12:32 INFO - 'tabpaint']}, 14:12:32 INFO - 'other-e10s': {'tests': ['a11yr', 14:12:32 INFO - 'ts_paint', 14:12:32 INFO - 'tpaint', 14:12:32 INFO - 'sessionrestore', 14:12:32 INFO - 'sessionrestore_no_auto_restore', 14:12:32 INFO - 'tabpaint']}, 14:12:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:12:32 INFO - 'ts_paint', 14:12:32 INFO - 'tpaint', 14:12:32 INFO - 'sessionrestore', 14:12:32 INFO - 'sessionrestore_no_auto_restore', 14:12:32 INFO - 'tabpaint']}, 14:12:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:12:32 INFO - 'ts_paint', 14:12:32 INFO - 'tpaint', 14:12:32 INFO - 'sessionrestore', 14:12:32 INFO - 'sessionrestore_no_auto_restore', 14:12:32 INFO - 'tabpaint']}, 14:12:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:12:32 INFO - 'tests': ['a11yr', 14:12:32 INFO - 'ts_paint', 14:12:32 INFO - 'tpaint', 14:12:32 INFO - 'sessionrestore', 14:12:32 INFO - 'sessionrestore_no_auto_restore', 14:12:32 INFO - 'tabpaint']}, 14:12:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:12:32 INFO - 'tests': ['a11yr', 14:12:32 INFO - 'ts_paint', 14:12:32 INFO - 'tpaint', 14:12:32 INFO - 'sessionrestore', 14:12:32 INFO - 'sessionrestore_no_auto_restore', 14:12:32 INFO - 'tabpaint']}, 14:12:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:12:32 INFO - 'tests': ['tsvgx', 14:12:32 INFO - 'tsvgr_opacity', 14:12:32 INFO - 'tart', 14:12:32 INFO - 'tscrollx', 14:12:32 INFO - 'cart']}, 14:12:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:12:32 INFO - 'tsvgr_opacity', 14:12:32 INFO - 'tart', 14:12:32 INFO - 'tscrollx', 14:12:32 INFO - 'cart']}, 14:12:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:32 INFO - 'talos_options': ['--disable-e10s'], 14:12:32 INFO - 'tests': ['tp5o']}, 14:12:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:32 INFO - 'tests': ['tp5o']}, 14:12:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:32 INFO - 'talos_options': ['--disable-e10s', 14:12:32 INFO - '--xperf_path', 14:12:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:12:32 INFO - 'tests': ['tp5n']}, 14:12:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:12:32 INFO - 'talos_options': ['--xperf_path', 14:12:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:12:32 INFO - 'tests': ['tp5n']}}, 14:12:32 INFO - 'talos.zip': {'path': '', 14:12:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:12:32 INFO - Running post-action listener: _resource_record_post_action 14:12:32 INFO - [mozharness: 2016-09-29 21:12:32.513324Z] Finished populate-webroot step (success) 14:12:32 INFO - [mozharness: 2016-09-29 21:12:32.513452Z] Running create-virtualenv step. 14:12:32 INFO - Running pre-action listener: _resource_record_pre_action 14:12:32 INFO - Running main action method: create_virtualenv 14:12:32 INFO - Creating virtualenv /builds/slave/test/build/venv 14:12:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:12:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:12:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:12:32 INFO - Using real prefix '/tools/python27' 14:12:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:12:33 INFO - Installing distribute.............................................................................................................................................................................................done. 14:12:36 INFO - Installing pip.................done. 14:12:36 INFO - Return code: 0 14:12:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:12:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:12:36 INFO - Reading from file tmpfile_stdout 14:12:36 INFO - Output received: 14:12:36 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:12:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:12:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11031d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110293570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8269456260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110131d30>, '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 0x10ff57db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110129b20>, '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.mZYaa3mr5y/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.FSOLinWfjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 14:12:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:12:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:12:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mZYaa3mr5y/Render', 14:12:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:36 INFO - 'HOME': '/Users/cltbld', 14:12:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:36 INFO - 'LOGNAME': 'cltbld', 14:12:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:36 INFO - 'MOZ_NO_REMOTE': '1', 14:12:36 INFO - 'NO_EM_RESTART': '1', 14:12:36 INFO - 'PAGER': '/bin/cat', 14:12:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:36 INFO - 'PWD': '/builds/slave/test', 14:12:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:36 INFO - 'SHELL': '/bin/bash', 14:12:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FSOLinWfjx/Listeners', 14:12:36 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 14:12:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:36 INFO - 'USER': 'cltbld', 14:12:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:36 INFO - 'XPC_FLAGS': '0x0', 14:12:36 INFO - 'XPC_SERVICE_NAME': '0', 14:12:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:12:36 INFO - Cleaning up... 14:12:36 INFO - Return code: 0 14:12:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:12:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11031d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110293570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8269456260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110131d30>, '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 0x10ff57db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110129b20>, '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.mZYaa3mr5y/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.FSOLinWfjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 14:12:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:12:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:12:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mZYaa3mr5y/Render', 14:12:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:36 INFO - 'HOME': '/Users/cltbld', 14:12:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:36 INFO - 'LOGNAME': 'cltbld', 14:12:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:36 INFO - 'MOZ_NO_REMOTE': '1', 14:12:36 INFO - 'NO_EM_RESTART': '1', 14:12:36 INFO - 'PAGER': '/bin/cat', 14:12:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:36 INFO - 'PWD': '/builds/slave/test', 14:12:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:36 INFO - 'SHELL': '/bin/bash', 14:12:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FSOLinWfjx/Listeners', 14:12:36 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 14:12:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:36 INFO - 'USER': 'cltbld', 14:12:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:36 INFO - 'XPC_FLAGS': '0x0', 14:12:36 INFO - 'XPC_SERVICE_NAME': '0', 14:12:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:37 INFO - Downloading/unpacking psutil>=3.1.1 14:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:12:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:12:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:12:40 INFO - Installing collected packages: psutil 14:12:40 INFO - Running setup.py install for psutil 14:12:40 INFO - building 'psutil._psutil_osx' extension 14:12:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:12:41 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 14:12:41 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 14:12:41 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 14:12:41 INFO - building 'psutil._psutil_posix' extension 14:12:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:12:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:12:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:12:41 INFO - ^ 14:12:41 INFO - 1 warning generated. 14:12:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:12:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:12:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:12:41 INFO - Successfully installed psutil 14:12:41 INFO - Cleaning up... 14:12:41 INFO - Return code: 0 14:12:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:12:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11031d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110293570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8269456260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110131d30>, '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 0x10ff57db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110129b20>, '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.mZYaa3mr5y/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.FSOLinWfjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 14:12:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:12:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:12:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mZYaa3mr5y/Render', 14:12:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:41 INFO - 'HOME': '/Users/cltbld', 14:12:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:41 INFO - 'LOGNAME': 'cltbld', 14:12:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:41 INFO - 'MOZ_NO_REMOTE': '1', 14:12:41 INFO - 'NO_EM_RESTART': '1', 14:12:41 INFO - 'PAGER': '/bin/cat', 14:12:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:41 INFO - 'PWD': '/builds/slave/test', 14:12:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:41 INFO - 'SHELL': '/bin/bash', 14:12:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FSOLinWfjx/Listeners', 14:12:41 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 14:12:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:41 INFO - 'USER': 'cltbld', 14:12:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:41 INFO - 'XPC_FLAGS': '0x0', 14:12:41 INFO - 'XPC_SERVICE_NAME': '0', 14:12:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:12:42 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 14:12:42 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 14:12:42 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 14:12:42 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 14:12:42 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 14:12:42 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 14:12:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:12:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:12:44 INFO - Installing collected packages: mozsystemmonitor 14:12:44 INFO - Running setup.py install for mozsystemmonitor 14:12:44 INFO - Successfully installed mozsystemmonitor 14:12:44 INFO - Cleaning up... 14:12:44 INFO - Return code: 0 14:12:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:12:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11031d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110293570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8269456260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110131d30>, '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 0x10ff57db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110129b20>, '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.mZYaa3mr5y/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.FSOLinWfjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 14:12:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:12:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:12:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mZYaa3mr5y/Render', 14:12:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:44 INFO - 'HOME': '/Users/cltbld', 14:12:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:44 INFO - 'LOGNAME': 'cltbld', 14:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:44 INFO - 'MOZ_NO_REMOTE': '1', 14:12:44 INFO - 'NO_EM_RESTART': '1', 14:12:44 INFO - 'PAGER': '/bin/cat', 14:12:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:44 INFO - 'PWD': '/builds/slave/test', 14:12:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:44 INFO - 'SHELL': '/bin/bash', 14:12:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FSOLinWfjx/Listeners', 14:12:44 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 14:12:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:44 INFO - 'USER': 'cltbld', 14:12:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:44 INFO - 'XPC_FLAGS': '0x0', 14:12:44 INFO - 'XPC_SERVICE_NAME': '0', 14:12:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:45 INFO - Downloading/unpacking jsonschema==2.5.1 14:12:45 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 14:12:45 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 14:12:45 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 14:12:45 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 14:12:45 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 14:12:45 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 14:12:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:12:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:48 INFO - Downloading functools32-3.2.3-2.tar.gz 14:12:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:12:48 INFO - warning: no files found matching '*.txt' 14:12:48 INFO - no previously-included directories found matching 'build' 14:12:48 INFO - no previously-included directories found matching 'dist' 14:12:48 INFO - no previously-included directories found matching '.git*' 14:12:48 INFO - Installing collected packages: jsonschema, functools32 14:12:48 INFO - Running setup.py install for functools32 14:12:48 INFO - warning: no files found matching '*.txt' 14:12:48 INFO - no previously-included directories found matching 'build' 14:12:48 INFO - no previously-included directories found matching 'dist' 14:12:48 INFO - no previously-included directories found matching '.git*' 14:12:48 INFO - Successfully installed jsonschema functools32 14:12:48 INFO - Cleaning up... 14:12:48 INFO - Return code: 0 14:12:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:12: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')]} 14:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12: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')]} 14:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11031d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110293570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8269456260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110131d30>, '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 0x10ff57db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110129b20>, '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.mZYaa3mr5y/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.FSOLinWfjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 14:12:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:12:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mZYaa3mr5y/Render', 14:12:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:48 INFO - 'HOME': '/Users/cltbld', 14:12:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:48 INFO - 'LOGNAME': 'cltbld', 14:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:48 INFO - 'MOZ_NO_REMOTE': '1', 14:12:48 INFO - 'NO_EM_RESTART': '1', 14:12:48 INFO - 'PAGER': '/bin/cat', 14:12:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:48 INFO - 'PWD': '/builds/slave/test', 14:12:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:48 INFO - 'SHELL': '/bin/bash', 14:12:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FSOLinWfjx/Listeners', 14:12:48 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 14:12:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:48 INFO - 'USER': 'cltbld', 14:12:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:48 INFO - 'XPC_FLAGS': '0x0', 14:12:48 INFO - 'XPC_SERVICE_NAME': '0', 14:12:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:12:48 INFO - Cleaning up... 14:12:48 INFO - Return code: 0 14:12:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:12: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')]} 14:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12: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')]} 14:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11031d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110293570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8269456260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110131d30>, '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 0x10ff57db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110129b20>, '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.mZYaa3mr5y/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.FSOLinWfjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 14:12:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:12:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mZYaa3mr5y/Render', 14:12:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:48 INFO - 'HOME': '/Users/cltbld', 14:12:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:48 INFO - 'LOGNAME': 'cltbld', 14:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:48 INFO - 'MOZ_NO_REMOTE': '1', 14:12:48 INFO - 'NO_EM_RESTART': '1', 14:12:48 INFO - 'PAGER': '/bin/cat', 14:12:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:48 INFO - 'PWD': '/builds/slave/test', 14:12:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:48 INFO - 'SHELL': '/bin/bash', 14:12:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FSOLinWfjx/Listeners', 14:12:48 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 14:12:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:48 INFO - 'USER': 'cltbld', 14:12:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:48 INFO - 'XPC_FLAGS': '0x0', 14:12:48 INFO - 'XPC_SERVICE_NAME': '0', 14:12:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:48 INFO - Downloading/unpacking blobuploader==1.2.4 14:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:51 INFO - Downloading blobuploader-1.2.4.tar.gz 14:12:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:12:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:12:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:12:52 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 14:12:52 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 14:12:52 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 14:12:52 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 14:12:52 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 14:12:52 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 14:12:52 INFO - Downloading docopt-0.6.1.tar.gz 14:12:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:12:52 INFO - Installing collected packages: blobuploader, requests, docopt 14:12:52 INFO - Running setup.py install for blobuploader 14:12:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:12:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:12:52 INFO - Running setup.py install for requests 14:12:53 INFO - Running setup.py install for docopt 14:12:53 INFO - Successfully installed blobuploader requests docopt 14:12:53 INFO - Cleaning up... 14:12:53 INFO - Return code: 0 14:12:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11031d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110293570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8269456260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110131d30>, '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 0x10ff57db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110129b20>, '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.mZYaa3mr5y/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.FSOLinWfjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 14:12:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:12:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mZYaa3mr5y/Render', 14:12:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:53 INFO - 'HOME': '/Users/cltbld', 14:12:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:53 INFO - 'LOGNAME': 'cltbld', 14:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:53 INFO - 'MOZ_NO_REMOTE': '1', 14:12:53 INFO - 'NO_EM_RESTART': '1', 14:12:53 INFO - 'PAGER': '/bin/cat', 14:12:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:53 INFO - 'PWD': '/builds/slave/test', 14:12:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:53 INFO - 'SHELL': '/bin/bash', 14:12:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FSOLinWfjx/Listeners', 14:12:53 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 14:12:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:53 INFO - 'USER': 'cltbld', 14:12:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:53 INFO - 'XPC_FLAGS': '0x0', 14:12:53 INFO - 'XPC_SERVICE_NAME': '0', 14:12:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:12:53 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-5Ukdsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:12:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-9U4v3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:12:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-6mN2FW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:12:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-mlDp9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:12:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Nx90le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:12:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-KWl5r5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:12:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-WTm16b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:12:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-i3VpWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:12:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-LVNSo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:12:54 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-baDzEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:12:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-u5_mLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:12:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-n5xo3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:12:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-yX8Ago-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:12:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-ZOSBwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:12:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-gHlMbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:12:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-fnV1Vb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:12:55 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-8aSmen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:12:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:12:55 INFO - Running setup.py install for manifestparser 14:12:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:12:55 INFO - Running setup.py install for mozcrash 14:12:56 INFO - Running setup.py install for mozdebug 14:12:56 INFO - Running setup.py install for mozdevice 14:12:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:12:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:12:56 INFO - Running setup.py install for mozfile 14:12:56 INFO - Running setup.py install for mozhttpd 14:12:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:12:56 INFO - Running setup.py install for mozinfo 14:12:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:12:56 INFO - Running setup.py install for mozInstall 14:12:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:12:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:12:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:12:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:12:56 INFO - Running setup.py install for mozleak 14:12:57 INFO - Running setup.py install for mozlog 14:12:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:12:57 INFO - Running setup.py install for moznetwork 14:12:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:12:57 INFO - Running setup.py install for mozprocess 14:12:57 INFO - Running setup.py install for mozprofile 14:12:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:12:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:12:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:12:57 INFO - Running setup.py install for mozrunner 14:12:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:12:57 INFO - Running setup.py install for mozscreenshot 14:12:58 INFO - Running setup.py install for moztest 14:12:58 INFO - Running setup.py install for mozversion 14:12:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:12:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:12:58 INFO - Cleaning up... 14:12:58 INFO - Return code: 0 14:12:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:12:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:58 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 0x11031d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110293570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8269456260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110131d30>, '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 0x10ff57db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110129b20>, '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.mZYaa3mr5y/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.FSOLinWfjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 14:12:58 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 14:12:58 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 14:12:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mZYaa3mr5y/Render', 14:12:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:58 INFO - 'HOME': '/Users/cltbld', 14:12:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:58 INFO - 'LOGNAME': 'cltbld', 14:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:58 INFO - 'MOZ_NO_REMOTE': '1', 14:12:58 INFO - 'NO_EM_RESTART': '1', 14:12:58 INFO - 'PAGER': '/bin/cat', 14:12:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:58 INFO - 'PWD': '/builds/slave/test', 14:12:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:58 INFO - 'SHELL': '/bin/bash', 14:12:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FSOLinWfjx/Listeners', 14:12:58 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 14:12:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:58 INFO - 'USER': 'cltbld', 14:12:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:58 INFO - 'XPC_FLAGS': '0x0', 14:12:58 INFO - 'XPC_SERVICE_NAME': '0', 14:12:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:12:58 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-UXvl7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:12:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:12:58 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Baimcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:12:58 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)) 14:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:12:58 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-QVtfzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:12:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:12:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:12:58 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-daS4wN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:12:59 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)) 14:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:12:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Ow6d_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:12:59 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)) 14:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:12:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-wf2pRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:12:59 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)) 14:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:12:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-qYaKGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:12:59 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)) 14:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:12:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-H_s_zD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:12:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:12:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-RhWaBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:12:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:12:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-eH1jlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:12:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:12:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-qq4yjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:12:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:12:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Qx1APy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:12:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:12:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:12:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-0398Co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:13:00 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)) 14:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:13:00 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-U674ex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:13:00 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)) 14:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:00 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-RW3lb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:00 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)) 14:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:13:00 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-pFCIeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:13:00 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)) 14:13:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:13:00 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-AgLkkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:13:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:02 INFO - Downloading blessings-1.6.tar.gz 14:13:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:13:03 INFO - Installing collected packages: blessings 14:13:03 INFO - Running setup.py install for blessings 14:13:03 INFO - Successfully installed blessings 14:13:03 INFO - Cleaning up... 14:13:03 INFO - Return code: 0 14:13:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:13:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:13:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:13:03 INFO - Reading from file tmpfile_stdout 14:13:03 INFO - Current package versions: 14:13:03 INFO - blessings == 1.6 14:13:03 INFO - blobuploader == 1.2.4 14:13:03 INFO - docopt == 0.6.1 14:13:03 INFO - functools32 == 3.2.3-2 14:13:03 INFO - jsonschema == 2.5.1 14:13:03 INFO - manifestparser == 1.1 14:13:03 INFO - mozInstall == 1.12 14:13:03 INFO - mozcrash == 0.17 14:13:03 INFO - mozdebug == 0.1 14:13:03 INFO - mozdevice == 0.48 14:13:03 INFO - mozfile == 1.2 14:13:03 INFO - mozhttpd == 0.7 14:13:03 INFO - mozinfo == 0.9 14:13:03 INFO - mozleak == 0.1 14:13:03 INFO - mozlog == 3.4 14:13:03 INFO - moznetwork == 0.27 14:13:03 INFO - mozprocess == 0.23 14:13:03 INFO - mozprofile == 0.28 14:13:03 INFO - mozrunner == 6.12 14:13:03 INFO - mozscreenshot == 0.1 14:13:03 INFO - mozsystemmonitor == 0.3 14:13:03 INFO - moztest == 0.7 14:13:03 INFO - mozversion == 1.4 14:13:03 INFO - psutil == 3.1.1 14:13:03 INFO - requests == 1.2.3 14:13:03 INFO - wsgiref == 0.1.2 14:13:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:04 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 0x11031d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110293570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8269456260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110131d30>, '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 0x10ff57db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110129b20>, '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.mZYaa3mr5y/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.FSOLinWfjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 14:13:04 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 14:13:04 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 14:13:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mZYaa3mr5y/Render', 14:13:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:04 INFO - 'HOME': '/Users/cltbld', 14:13:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:04 INFO - 'LOGNAME': 'cltbld', 14:13:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:04 INFO - 'MOZ_NO_REMOTE': '1', 14:13:04 INFO - 'NO_EM_RESTART': '1', 14:13:04 INFO - 'PAGER': '/bin/cat', 14:13:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:04 INFO - 'PWD': '/builds/slave/test', 14:13:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:04 INFO - 'SHELL': '/bin/bash', 14:13:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FSOLinWfjx/Listeners', 14:13:04 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 14:13:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:04 INFO - 'USER': 'cltbld', 14:13:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:04 INFO - 'XPC_FLAGS': '0x0', 14:13:04 INFO - 'XPC_SERVICE_NAME': '0', 14:13:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:13:04 INFO - Cleaning up... 14:13:04 INFO - Return code: 0 14:13:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11031d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110293570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8269456260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110131d30>, '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 0x10ff57db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110129b20>, '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.mZYaa3mr5y/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.FSOLinWfjx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 14:13:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:13:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:13:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mZYaa3mr5y/Render', 14:13:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:04 INFO - 'HOME': '/Users/cltbld', 14:13:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:04 INFO - 'LOGNAME': 'cltbld', 14:13:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:04 INFO - 'MOZ_NO_REMOTE': '1', 14:13:04 INFO - 'NO_EM_RESTART': '1', 14:13:04 INFO - 'PAGER': '/bin/cat', 14:13:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:04 INFO - 'PWD': '/builds/slave/test', 14:13:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:04 INFO - 'SHELL': '/bin/bash', 14:13:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FSOLinWfjx/Listeners', 14:13:04 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 14:13:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:04 INFO - 'USER': 'cltbld', 14:13:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:04 INFO - 'XPC_FLAGS': '0x0', 14:13:04 INFO - 'XPC_SERVICE_NAME': '0', 14:13:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:13:05 INFO - Cleaning up... 14:13:05 INFO - Return code: 0 14:13:05 INFO - Running post-action listener: _resource_record_post_action 14:13:05 INFO - Running post-action listener: _start_resource_monitoring 14:13:05 INFO - Starting resource monitoring. 14:13:05 INFO - [mozharness: 2016-09-29 21:13:05.272348Z] Finished create-virtualenv step (success) 14:13:05 INFO - [mozharness: 2016-09-29 21:13:05.273319Z] Running install step. 14:13:05 INFO - Running pre-action listener: _resource_record_pre_action 14:13:05 INFO - Running main action method: install 14:13:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:13:05 INFO - Reading from file tmpfile_stdout 14:13:05 INFO - Detecting whether we're running mozinstall >=1.0... 14:13:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:13:05 INFO - Reading from file tmpfile_stdout 14:13:05 INFO - Output received: 14:13:05 INFO - Usage: mozinstall [options] installer 14:13:05 INFO - Options: 14:13:05 INFO - -h, --help show this help message and exit 14:13:05 INFO - -d DEST, --destination=DEST 14:13:05 INFO - Directory to install application into. [default: 14:13:05 INFO - "/builds/slave/test"] 14:13:05 INFO - --app=APP Application being installed. [default: firefox] 14:13:05 INFO - mkdir: /builds/slave/test/build/application 14:13:05 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'] 14:13:05 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 14:13:40 INFO - Reading from file tmpfile_stdout 14:13:40 INFO - Output received: 14:13:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:13:40 INFO - Running post-action listener: _resource_record_post_action 14:13:40 INFO - [mozharness: 2016-09-29 21:13:40.543884Z] Finished install step (success) 14:13:40 INFO - [mozharness: 2016-09-29 21:13:40.544025Z] Running run-tests step. 14:13:40 INFO - Running pre-action listener: _resource_record_pre_action 14:13:40 INFO - Running main action method: run_tests 14:13:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:13:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:13:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:13:40 INFO - 2016-09-29 14:13:40.559 screenresolution[1634:7526] starting screenresolution argv=screenresolution get 14:13:40 INFO - 2016-09-29 14:13:40.561 screenresolution[1634:7526] Display 0: 1600x1200x32@60 14:13:40 INFO - 2016-09-29 14:13:40.567 screenresolution[1635:7529] starting screenresolution argv=screenresolution list 14:13:40 INFO - Available Modes on Display 0 14:13:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:13:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:13:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:13:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:13:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:13:40 INFO - Intel Iris: 14:13:40 INFO - Chipset Model: Intel Iris 14:13:40 INFO - Type: GPU 14:13:40 INFO - Bus: Built-In 14:13:40 INFO - VRAM (Dynamic, Max): 1536 MB 14:13:40 INFO - Vendor: Intel (0x8086) 14:13:40 INFO - Device ID: 0x0a2e 14:13:40 INFO - Revision ID: 0x0009 14:13:40 INFO - Displays: 14:13:40 INFO - Display: 14:13:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:13:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:13:40 INFO - Display Serial Number: 1600x1200 60 14:13:40 INFO - Main Display: Yes 14:13:40 INFO - Mirror: Off 14:13:40 INFO - Online: Yes 14:13:40 INFO - Rotation: Supported 14:13:40 INFO - Return code: 0 14:13:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:13:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:13:40 INFO - Python 2.7.3 14:13:40 INFO - Return code: 0 14:13:40 INFO - grabbing minidump binary from tooltool 14:13: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')]} 14:13:40 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 0x110131d30>, '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 0x10ff57db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110129b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:13:40 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 14:13:40 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 14:13:40 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 14:13:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:13:40 INFO - Return code: 0 14:13:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:13:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:13:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:13:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:13:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:13:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--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 14:13:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0254 --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 14:13:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mZYaa3mr5y/Render', 14:13:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:40 INFO - 'HOME': '/Users/cltbld', 14:13:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:40 INFO - 'LOGNAME': 'cltbld', 14:13:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:13:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:13:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:40 INFO - 'MOZ_NO_REMOTE': '1', 14:13:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:13:40 INFO - 'NO_EM_RESTART': '1', 14:13:40 INFO - 'PAGER': '/bin/cat', 14:13:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:40 INFO - 'PWD': '/builds/slave/test', 14:13:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:13:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:40 INFO - 'SHELL': '/bin/bash', 14:13:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FSOLinWfjx/Listeners', 14:13:40 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 14:13:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:40 INFO - 'USER': 'cltbld', 14:13:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:40 INFO - 'XPC_FLAGS': '0x0', 14:13:40 INFO - 'XPC_SERVICE_NAME': '0', 14:13:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--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 14:13:41 INFO - mozversion application_buildid: 20160929115520 14:13:41 INFO - mozversion application_changeset: 846e444511768c210822f1843f3b4e26ff7e852e 14:13:41 INFO - mozversion application_display_name: Nightly 14:13:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:13:41 INFO - mozversion application_name: Firefox 14:13:41 INFO - mozversion application_remotingname: firefox 14:13:41 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:13:41 INFO - mozversion application_vendor: Mozilla 14:13:41 INFO - mozversion application_version: 52.0a1 14:13:41 INFO - mozversion platform_buildid: 20160929115520 14:13:41 INFO - mozversion platform_changeset: 846e444511768c210822f1843f3b4e26ff7e852e 14:13:41 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:13:41 INFO - mozversion platform_version: 52.0a1 14:13:41 INFO - using testdate: 1475183621 14:13:41 INFO - actual date: 1475183621 14:13:41 INFO - starting webserver on 'localhost:49270' 14:13:41 INFO - SUITE-START | Running 2 tests 14:13:41 INFO - TEST-START | dromaeo_css 14:13:41 INFO - operating with platform_type : mac_ 14:13:41 INFO - Initialising browser for dromaeo_css test... 14:13:41 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpriGQ3Q/profile http://localhost:49270/getInfo.html) 14:13:43 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 14:13:43 INFO - PROCESS | 1645 | colorDepth:24 14:13:43 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 14:13:43 INFO - PROCESS | 1645 | __metrics 14:13:43 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:13:43 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:13:44 INFO - TEST-INFO | 1645: exit 0 14:13:44 INFO - Browser initialized. 14:13:44 INFO - Running cycle 1/1 for dromaeo_css test... 14:13:44 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpriGQ3Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:13:56 INFO - PROCESS | 1647 | RSS: Main: 252497920 14:13:56 INFO - PROCESS | 1647 | 14:14:48 INFO - PROCESS | 1647 | 1475183688487 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] 14:16:59 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 14:17:01 INFO - PROCESS | 1647 | RSS: Main: 459182080 14:17:01 INFO - PROCESS | 1647 | 14:20:04 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 14:20:05 INFO - PROCESS | 1647 | RSS: Main: 388206592 14:20:05 INFO - PROCESS | 1647 | 14:23:07 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 14:23:08 INFO - PROCESS | 1647 | RSS: Main: 350146560 14:23:08 INFO - PROCESS | 1647 | 14:26:10 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 14:26:11 INFO - PROCESS | 1647 | RSS: Main: 369000448 14:26:11 INFO - PROCESS | 1647 | 14:29:14 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 14:29:15 INFO - PROCESS | 1647 | RSS: Main: 345214976 14:29:15 INFO - PROCESS | 1647 | 14:32:18 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 14:32:18 INFO - PROCESS | 1647 | RSS: Main: 460791808 14:32:18 INFO - PROCESS | 1647 | 14:32:18 INFO - PROCESS | 1647 | __start_tp_report 14:32:18 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 14:32:18 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 14:32:18 INFO - PROCESS | 1647 | |i|pagename|runs| 14:32:18 INFO - PROCESS | 1647 | |0;dojo.html;2921.4741346557626;3343.0973637117986;3375.3080618473214;3381.1040074380307;3405;5612.7754889804355;5706;5736.655800651964;5781.884362312751;5817.272840894888;5693.629914055594;5770.711464426783;5849.7367618457165;5851.853703657411;5873.500752436048;5132.204508301219;5192.273081768555;5224.686518808883;5255.316808814868;5260.447652996438;4219.3881887126345;5051.61080702806;5338.199270109476;5352.357717073956;5377.870647164081;5302.840914772563;5306.8407947761625;5316.043112239795;5340.198970154469;5400.027994960906;5170.767315470813;5203.115470370046;5244.082285600005;5244.344456942882;5247.47525247476;5178.844634660966;5212.582993360522;5221.1124108901195;5265.526102650741;5274.709890955995;5708.486236238717;5736.053551164053;5736.368999410061;5752.654840709571;5761.971190144064;6063.454289113957;6127.436311768148;6235.501159907242;6240.469560087398;6253.6560489173535;5766.221560089376;6188.628682279078;6259.499240060784;6265.8120256392385;6271.968640156816;5787.826365209051;5791.82624521265;5793.391693872125;5798.594098413155;5800.216970708984;6218;6237.875242495124;6265.624062556261;6308.0537919311555;6315.084312774622;6116.034291236468;6128.26460824695;6134.405972711579;6176.413240742122;6187.412195841388;6248.687565621701;6262.843428914222;6263.154474145932;6268.655223962726;6280.0893870389455;6094.603850749687;6217.751289948352;6220.844478888064;6224.06639004153;6227.159333490012;6095.136089334496;6105.178419690066;6170.321264660884;6209;6221;4877.731724755173;4900.044491324157;4904.509549045067;4929.556339929423;4930.482299358588;4412.161689279091;4422.823087076546;4428.6678499112695;4447.221736196153;4490.50604433512;5605.467480589337;5638.633488823213;5699.971500142556;5716.714164291852;5751.166080918327;6181.381861813783;6193.876122477525;6250.624962502265;6261.123442718023;6306.716197771111;5729.770809167587;5781.479666829921;5784.219130417424;5810.331811841608;5823.475887170175;5346.278252435949;5349.304590403222;5368.060589396918;5383.488568585978;5395.487428694268;6201.410865967726;6208;6226.875462490634;6238.095476156022;6267.561270711007;6312.210973628296;6314.55798094129;6317.778877739257;6319.684015799284;6333.746650133943;6152.50779937595;6276;6280.842978925563;6286.717097730521;6295.464885484785;6094.471245618374;6123.2652081750475;6126.365000174519;6174.413430724074;6175.5677102602385;6284.402981716729;6318.336574659595;6321.52588555851;6325.4939604832425;6327.715252813542;5087.694738315713;6104.8779024420155;6107.908381374372;6116.66358350295;6123.295820980644;5874.735636896265;6020.759169633166;6081.908771368345;6082.148499210026;6085.939140608537;5779.537636989115;5836.920169768607;5912.615679980788;5939.584229103922;5952.821415357546;6094.380596348918;6100.30868792748;6101.641069425342;6132.693365331805;6137.1101190328045;5352.5450336721815;5918.970405147947;5942.376050514807;5949.077892926603;6054.243219597551 14:32:18 INFO - PROCESS | 1647 | |1;ext.html;486.8125771577941;514.0772313696914;527.4066674990634;527.8548399190222;551.7875617887112;30919.690803091962;41144.38283425747;42069.789651051775;42110.94722631936;44391.112177756426;43438.348424773656;43832;43836.561634383645;43875.56124438754;43978;9765.95117024414;9966.451845148515;9979.900200998007;9984.800303993916;9986.600535978552;8868.556572171397;9095.363324567283;12068.456919438646;12318.384080795924;12348.753024939495;12280.754384912296;12367.319797411139;12367.443465044049;12386.752264954695;12388.132830701854;8818.559072046372;10418.843717344245;12251.448684809206;12328.691782705415;12334.136610437228;8780.209781119729;8905.198532132139;8909.109089091104;8930;11888.881111188864;40823.387649185286;41162.176756465;41277.587224127674;41285.793571032256;41326.380104298456;3265.755068369858;3288.588926384202;3359.1937934895695;3417.4532074867902;3674.9342690619687;31261.218469538217;31290.843545782125;31348.529772053436;31719.365612687616;32314.6768532314;2945.3520225550346;2978.80637758545;3014.201236672284;3060.5715199872034;3135.717785399302;442705;443808.9999999968;444896;445567;450112;51438.22842657363;52684.73657631687;58482.707586461795;59173.112403313986;59915;60940;61142.38857611456;61172;61283.38716612866;61550;15945.760813587573;16116;16130.193490325437;16135.193240337936;16153.273102710406;11414.486348114355;11600.129990250596;12449.94175495093;12544.68638284032;12565;14640.267986600627;16397.42609008679;16486.75269870953;16759.581010474834;16996.32014719422;6297.149884765589;6300.27546832111;6368.808935731936;6391.616503009835;6407.839804004937;842.9452085614415;915.8323137998972;964.8890377606525;1069.3049517813483;1122.1527746551465;28868.855655722007;30111;30130.096097117123;30209;30444.286219310223;1050.9474526273655;1052.4211683574156;1067.4716015572342;1078.2991055813784;1091.6779550032759;15092.54722358331;15557.766633500507;15590.610234744223;15693.514196713842;15941.521754347388;594679;595480;597235;598066;600482;57662;57936.8412631745;57955.42044579585;57973;57973;1230.1081715756006;1275.4260582737804;1343.9798403023767;1376.9449222031008;1397.1407584335561;29544.852275738485;31137.688623113478;31180.064598062094;31249.8437507811;32267.677323226468;48796;49186;49466;49475.50524494853;49489.75255123701;463744;463824;463879;464606;466312;26241.475170496866;26863.328416789736;26964;27785.44429111447;39291.80354098211;46956.7652161737;47850;48017;48514;48682.75658621826;37585.43621845729;37612.2477550442;38873.22535492856;39062.414063789634;40398.9900252496;38766.61233387743;39044.21911561696;40044.59955400525;40498;40555.39166912558;37807;38053.80973095228;38609.613903860605;38766.8369948902;38904.2219155621;31514;34502.82748586241;34601.134971625914;34670.65329346674;34793.65206347904;21786.673199901696;24226.394340141636;24806.139539534248;25505.48989020172;25829.095981640552 14:32:18 INFO - PROCESS | 1647 | |2;jquery.html;326.3977960662578;347.42674586931577;354.8172691064102;361.7811224209354;390.07357525876046;15335.00322479038;15473.535793926187;16512.009279443228;18093.728594071068;21249.831259280734;20191.899040504777;20379.592408151864;20587;20596.382108536767;20845.583088338226;5674.773009079642;6142.938570614281;6165.383461653843;6209.441150296472;6216.036514340262;4738;6004.789832355868;6073.119397687333;6080.60146166382;6089.634621922677;5732.942670573282;6022.638641681491;6023.096535519663;6026.789062382817;6029.276486821587;5950.523958083365;6043.909341359883;6048.092786082123;6053.485453736438;6066.120412540179;5003.624728145367;5209.583233341324;5222;5244.16093425054;5261.868453288698;25522.87238563814;25591.61612575813;25630.359241019123;26001.479970400676;26253.606195907076;2390.25901970388;2399.184277345711;2399.8080153587844;2417.3835671903726;2588.572885473913;12773.744525109445;15548.144852033134;15676.451324203712;15775.684486310209;15991.040537567667;1847.9722804157814;1877.3241633012105;1902.8192321729414;1940.6118776244807;1944.1834429539626;100950.49524752403;120008.39974800857;120354.79645203524;120928.79071209264;123786.38106809498;10381.377117372831;10453.21600879937;10856.45717714127;10891.945540272407;11014.944925275482;13501;13530.255835928921;13535.255560944242;13535.932320338532;13537;8162.265396114378;8263.95868020656;8415.242628163494;8563.529005904735;8605.569721513899;7042.232730242934;8622.568871556397;8672.956635216784;8818.691345802994;8899.599518021705;6465;6579.572327798678;6588.637624930675;6593.241777195588;6682.63245521501;1670.198304813672;1818.5815064249641;1828.3600739740984;1836.2012524551862;1872.1668857358345;703.3845385287873;752.9736459223901;828.204923273664;875.7547886591765;995.5967833027636;20642;22116.557668846854;22173.33479995603;22369.776302237096;22400;913.4015472922385;916.9495677737789;938.3900464698;941.4163218804385;998.8951160128228;9856.704298871045;11566.595169169039;11697.707557311136;11706.590269340702;11748.412579370997;590158;590160;593208;599184;605954;113674;117168;118453.40773296251;121810;121863;996.2976101848179;999.6401295533457;1018.5365658625308;1019.4189312092185;1020.2501161646184;15398.152221733015;19336.806631934065;19459.51351216231;19984.50038749043;19994.400167994983;48156.759216203696;48372;55844;56595.71702141463;60156.699216503635;79025;80143;85417;85770.57114714387;86492;11686.415679216176;11956.222845515014;12365.629031129081;12536.310502922426;12773.808392873929;13612.251326177155;13633.863661363259;13737.725245495234;14136.363863625957;14157.929210353883;11642.76714465689;11660.88339116598;11806.645800625995;11891.940540297242;12134.393280336124;11597.130215233723;11659.358735269641;11930.821037684615;11936.10479214045;12055.397230138282;11277.379744114152;11843.467043983213;11933.28400295985;12106.213096148722;12361.752764944831;12031.15781895294;12160.45277962476;12282.263064216175;12453.75092498163;12554.623361299176;10332.380057196593;11503.769924601294;11590.5363785451;11623.883761162619;11717.121215909012 14:32:18 INFO - PROCESS | 1647 | |3;mootools.html;1483.5920121966462;1505.969880602388;1695.389380088915;1820.5357633803394;1880.6238752249544;8539.231469167773;8983;8993;8999.370044096915;9000.954995225014;7893.565853878049;8138.30824379927;8139.511629302246;8539.78650533737;8734.650613975433;2373.1100837186054;2411.5176964607062;2414.99777592299;2416.0818888822223;2420.8547487150827;2371.5256948610263;2402.171250918439;2410.867402292873;2414.0705828256073;2420.7095148582193;2107.1255428997024;2189.562087582482;2190.156789635994;2194.5281764420647;2393.533261014103;2159.503314237726;2181.81454576362;2314.6065168921323;2394.2817154853647;2399.820013498977;2332.0788288626068;2338.4387746940783;2346.296111166643;2348.072511358004;2349.34218418843;7320.853582928312;8892.288616910631;8921.687740929035;8966.641334346617;8983.326250531236;5228.869278268074;5411.594130440233;5415.0523658359625;5415.48552887479;5423.267858839085;3483.1466290758926;3621.185233322508;3699.408094704834;3706.129059670975;3707.3697471429923;1050.5745173204864;1081.1620993729844;1086.869575650927;1106.3859557945336;1107.0921844087852;11788.056955443544;11817.704557386049;11825.172237943347;11832.053435725034;11841.822372664417;4509.143262780061;4623.537646235417;4820.879478013007;4823.252395878644;4846.636502262344;10027.348222365523;10039;10040.748981275381;10048.995100489892;10065.094141527297;5401.3248343957;5403.081476149064;5412.188171774186;5416.268803711528;5431.34823821144;4756.809727610926;4903.632227582945;4934.309196712463;4950.455449900508;4956.306117143603;3714.814259287025;3756.2111956489443;3777.1501412182038;3807.3337165996395;3824.388097904322;1152.9884701153048;1158.2529268621693;1167.7796702445964;1171.2152857585438;1173.0908545876844;662.4203821656051;760.152430040512;871.9956400218085;892.6607889002138;929.8605209218671;5183.559397451183;5340.946590534123;5660.320761508646;5909.084091965752;6058.485028772604;912.7535565397305;920.6271460058687;948.3314263444255;966.5215718219386;973.3770386952356;3528.8764893228613;3740.7007439404783;3766.3785475396253;3838.980805095957;3894.6105389460827;10151;10884.673459796219;11026.393548354918;11036.28264162843;11045.061169800496;10603.045725884707;11002.724931876766;11007.394593297286;11010.889891101147;11099.223054386277;6841.863162736718;8281.627326770309;8284.96437945257;8293.543855088028;8315.83368332642;5602.831915042555;5627.521660658726;5666.32004159487;5682.289713785777;5749.195112684311;9812.362196457207;10037;10096.444695541815;10111.89888101139;10197.796044079225;9827.705168844946;10252.282340236112;10280.640177594047;10368.533415996148;10378.948105259426;7099.680514376762;7227.638618069181;7227.927720722936;7241.565506069653;7244.529105608119;8353.498790072616;8423.157684231528;8431.451955622864;8431.915680843113;8438.704645337384;7304.744333948286;7441.665125069277;7483.503299340045;7580.696772129053;7645.082590089225;7228.96385518069;7335.816604584928;7340.265973402617;7378.926210737824;7379.667914943733;6772.322767723188;6918.238993710587;6918.408765983952;6924;6938.306169383223;4735.881602959816;4908.656394052382;4913.7543122843;4939.728314942569;4988.401391832858;4862.294967229803;4891.95108048915;4949.901001980013;5078.21287700407;5135.178371460548 14:32:18 INFO - PROCESS | 1647 | |4;prototype.html;291.91826288639186;300.61671369004523;308.88262460265094;314.4057730888621;315.6007650322341;4642.234031384822;5407.891842163154;5427.62006659534;5501.4223506531835;5523.530499907508;5374.247605335247;5400.702961337125;5404.675719456835;5418.512333889949;5429.429909859467;4092.6930480213955;4115.300398932189;4118;4127.587241275878;4131.2150691368615;3631.6368363163606;3991.880243592697;4113.547509773923;4130.876073717794;4131.938020929689;3917.6082391760738;4015.698822588303;4070.287699652564;4083.9591604083876;4133.545310015896;3658.871664726647;4063.7968101595097;4073.4704488416314;4074.0833312504747;4077.4495443115097;3069.3861227754314;3566.6433356664124;3567.7324200685052;3578.141246100943;3583.6953858922025;4454.643628509712;4752.667313288071;4966.424174594286;5302.704809104637;5357.785688572452;3912.2957867583823;3984.880453586397;4485.327200919855;4634.119517291704;4668.906621867578;4790.736509491977;5663.773449062032;5672.290963629546;5690.06113991191;5700.828975130753;3368.2589830237307;3460.9506876954024;3466.7119700997223;3742.5883152853166;3853.9401664542197;5587.916181257285;5763.711814409263;5803.593748437611;5873.853153671193;5935.666963116905;3207.4226639205167;3737.663610275088;3913.6477717005605;3917.216556688674;3924.3524818404644;3273.3126043531124;3649.0694872807794;3963.227170701743;3988.6210809973586;3997.5009371485694;3891.8637847675764;4168.3955826405;4407.779611019436;4503.459584849839;4538.750368729751;4470.173017991682;5635.718214089361;5639.943600564024;5647.8704259148335;5650.237217975603;1257.5724253753767;1321.6365499487717;1335.946562137523;1338;1340.2293681133388;724.6413025552386;758.8634045871686;774.8915151878741;832.8958880139983;833.0378412933072;3765.3975363941636;3794.9261098510297;4415.823367065345;4451.732896026248;4507.706999045052;4852.587530059914;4862.9756851216225;4870.8310005598405;4897.314375987365;4904.950950490521;4296.656267498593;4350.521442641307;4437.42313499243;4440.755758433317;4472.463304403493;2204.118352658917;2320.7215134183666;2322.3381336319067;2520;2549.923502294934;4339.891502712458;4590.701604395704;4866.270059491034;4932.161532539405;4943.579795717333;4320;4393.340998850134;4464.799084041226;4513.984353520432;4933.694424735436;4569.544693923398;4754.668692766031;4810.5670489656095;5883.882322353615;6028.2766068072115;4999.525045120708;5867.911981320369;5947.167396564571;5948.643081415129;5954.07711804671;5529.419410961791;5575.383138889677;5874.383189765013;5935.584509084322;5936.732847021808;5199.324087868553;5724.971375143097;5932.614380065282;5991.161237426851;6000.489958353589;3399.6940275374345;3740.551133863954;3870.3226935286775;3912.158885839558;3947.6644485219076;5191.532762051434;5860.531157507304;5877.471857317043;5899.705014749331;5919.467247947706;5122.00120976406;5432.538234250133;5544.833654990357;5640.379558248672;5725.541956643542;5151.248575484354;5467.851751132307;5625.240592520121;5643.463870932255;5690.813144639015;5295.549878260237;5423.484768946943;5526.889462210814;5612.438756124355;5614.7192640367;4840.927386089282;4845.63657725665;5694.088945768657;5697.344805347272;5712.457316554921;4720.560229130148;5160.961420238909;5322.49436303566;5505;5542.97228513871 14:32:18 INFO - PROCESS | 1647 | |5;yui.html;711.4024219655488;727.8689835829551;759.168710262263;832.4006715165079;838.995805020975;15229;15957.361705531765;15989.920050399745;16003.759943600855;16054.75917861233;12176.695582610439;14331.355089020994;14332.856671433283;14342.13947163171;14346.354414051366;675.5642610516214;697.6825544377297;703.1527009952995;842.2125312832521;843.4854738609458;854.3037424499045;861.1431625532604;869.0657543141117;870.3254977392539;875.3347455933473;917.0050495212669;918;919.3242966419709;922.0226559846551;922.8338898998178;715.5778090926326;740.4224704730319;913.2009491694768;914.5701520285488;920.9769755756126;648.6367634124905;665.72705190872;672.1665135232325;673.3031312591444;673.5689158938284;14948;14972.925135374362;14995.625109372244;15008.174550399724;15027.173505457196;13403.195808251536;13674.17954922698;13812.516561920385;13874.098183618133;14147.221902795342;12355.752884942342;12471.812922806164;12596;12606.495740170381;12989.350532473338;3747.850085996557;4505.887352816173;4562.903377598583;4637.791299391535;4660.184467718136;109319;109435;110489;110505.89494105037;110557;10345.84481232767;10352.792944141225;10764.03123718869;10830.620928267474;10837.078848297982;19235.51911202203;20356.69464957997;21408.57182856334;21429.357119286447;21451.78548214529;7865.921340786634;8033.236842499953;8063.717769878026;8090.919090809017;8127.431079824474;4468.553144685506;4532.4334458192725;4546;4561.566651168134;4625.167469855458;3436.415809312423;3539.681428671432;3547.7516573839584;3550.5739311282296;3566.7503274771034;532.6298222735239;646.5894157210205;653.0954627840475;667.8363800868818;671.4796033074407;549.1131822107347;562.2072877243068;625.9342769009188;645.1868663343311;657.3492242679713;7526.02161718973;7774.766756997299;7828.708263136518;9072.67920387085;9245.861312080324;594.9583529152918;602.077465313483;634.4924060751381;641.2497378067673;646.9099567229234;3675.4119340905327;4210.368444733314;4456.665750068758;4465.508794032654;4611.631069514431;92366.61450078254;94268.58597121049;158324.9172557933;161596.38403615812;166729;129220.06169907459;132819;137725;137959.31020344835;141773;71938;114621.85378146279;115126.4243678793;118834.4058279703;122053;8600.1399860016;8601.13988601135;8638.006629237718;8645.524496152771;8826.117388261122;52837.471625284794;54168;60295.39704602898;60998;67023.66488167529;61252.774944501754;64799;66100.67798643904;71849;80893.19106808856;13197.340132993504;13209.603711888658;13227.66930826737;13557.25435101079;13900.304984750519;11987.520499179935;14625.414983400546;14732.705345892808;14773.482928097465;14784.13254739515;13819.30903454803;13862.237576932963;14041.578752637437;14230.921704658078;14525.055871368328;11084;12676.429560669976;12893.742125157256;13051.543195988095;13452.596422107352;13541.661458463223;13634.250116243702;13644.317784110954;14050.718985620435;14186.929065354607;9993.950030249802;11150.888491115309;11238.559372399883;11332.263402878787;11361;11272.492737826653;11294.265872718248;11535.36555489456;11535.538578457103;11723 14:32:18 INFO - PROCESS | 1647 | __end_tp_report 14:32:18 INFO - PROCESS | 1647 | __start_cc_report 14:32:18 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,2061 14:32:18 INFO - PROCESS | 1647 | __end_cc_report 14:32:18 INFO - PROCESS | 1647 | __startTimestamp1475184738688__endTimestamp 14:32:18 INFO - PROCESS | 1647 | 14:32:18 INFO - PROCESS | 1647 | ------- Summary: start ------- 14:32:18 INFO - PROCESS | 1647 | Number of tests: 6 14:32:18 INFO - PROCESS | 1647 | 14:32:18 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5735.17 Median:5918.97 stddev:650.18 (11.0%) stddev-sans-first:613.57 14:32:18 INFO - PROCESS | 1647 | Values: 2921.5 3343.1 3375.3 3381.1 3405.0 5612.8 5706.0 5736.7 5781.9 5817.3 5693.6 5770.7 5849.7 5851.9 5873.5 5132.2 5192.3 5224.7 5255.3 5260.4 4219.4 5051.6 5338.2 5352.4 5377.9 5302.8 5306.8 5316.0 5340.2 5400.0 5170.8 5203.1 5244.1 5244.3 5247.5 5178.8 5212.6 5221.1 5265.5 5274.7 5708.5 5736.1 5736.4 5752.7 5762.0 6063.5 6127.4 6235.5 6240.5 6253.7 5766.2 6188.6 6259.5 6265.8 6272.0 5787.8 5791.8 5793.4 5798.6 5800.2 6218.0 6237.9 6265.6 6308.1 6315.1 6116.0 6128.3 6134.4 6176.4 6187.4 6248.7 6262.8 6263.2 6268.7 6280.1 6094.6 6217.8 6220.8 6224.1 6227.2 6095.1 6105.2 6170.3 6209.0 6221.0 4877.7 4900.0 4904.5 4929.6 4930.5 4412.2 4422.8 4428.7 4447.2 4490.5 5605.5 5638.6 5700.0 5716.7 5751.2 6181.4 6193.9 6250.6 6261.1 6306.7 5729.8 5781.5 5784.2 5810.3 5823.5 5346.3 5349.3 5368.1 5383.5 5395.5 6201.4 6208.0 6226.9 6238.1 6267.6 6312.2 6314.6 6317.8 6319.7 6333.7 6152.5 6276.0 6280.8 6286.7 6295.5 6094.5 6123.3 6126.4 6174.4 6175.6 6284.4 6318.3 6321.5 6325.5 6327.7 5087.7 6104.9 6107.9 6116.7 6123.3 5874.7 6020.8 6081.9 6082.1 6085.9 5779.5 5836.9 5912.6 5939.6 5952.8 6094.4 6100.3 6101.6 6132.7 6137.1 5352.5 5919.0 5942.4 5949.1 6054.2 14:32:18 INFO - PROCESS | 1647 | 14:32:18 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:64805.39 Median:38766.72 stddev:134900.63 (348.0%) stddev-sans-first:135192.62 14:32:18 INFO - PROCESS | 1647 | Values: 486.8 514.1 527.4 527.9 551.8 30919.7 41144.4 42069.8 42110.9 44391.1 43438.3 43832.0 43836.6 43875.6 43978.0 9766.0 9966.5 9979.9 9984.8 9986.6 8868.6 9095.4 12068.5 12318.4 12348.8 12280.8 12367.3 12367.4 12386.8 12388.1 8818.6 10418.8 12251.4 12328.7 12334.1 8780.2 8905.2 8909.1 8930.0 11888.9 40823.4 41162.2 41277.6 41285.8 41326.4 3265.8 3288.6 3359.2 3417.5 3674.9 31261.2 31290.8 31348.5 31719.4 32314.7 2945.4 2978.8 3014.2 3060.6 3135.7 442705.0 443809.0 444896.0 445567.0 450112.0 51438.2 52684.7 58482.7 59173.1 59915.0 60940.0 61142.4 61172.0 61283.4 61550.0 15945.8 16116.0 16130.2 16135.2 16153.3 11414.5 11600.1 12449.9 12544.7 12565.0 14640.3 16397.4 16486.8 16759.6 16996.3 6297.1 6300.3 6368.8 6391.6 6407.8 842.9 915.8 964.9 1069.3 1122.2 28868.9 30111.0 30130.1 30209.0 30444.3 1050.9 1052.4 1067.5 1078.3 1091.7 15092.5 15557.8 15590.6 15693.5 15941.5 594679.0 595480.0 597235.0 598066.0 600482.0 57662.0 57936.8 57955.4 57973.0 57973.0 1230.1 1275.4 1344.0 1376.9 1397.1 29544.9 31137.7 31180.1 31249.8 32267.7 48796.0 49186.0 49466.0 49475.5 49489.8 463744.0 463824.0 463879.0 464606.0 466312.0 26241.5 26863.3 26964.0 27785.4 39291.8 46956.8 47850.0 48017.0 48514.0 48682.8 37585.4 37612.2 38873.2 39062.4 40399.0 38766.6 39044.2 40044.6 40498.0 40555.4 37807.0 38053.8 38609.6 38766.8 38904.2 31514.0 34502.8 34601.1 34670.7 34793.7 21786.7 24226.4 24806.1 25505.5 25829.1 14:32:18 INFO - PROCESS | 1647 | 14:32:18 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:35304.32 Median:20743.79 stddev:99176.52 (478.1%) stddev-sans-first:99419.96 14:32:18 INFO - PROCESS | 1647 | Values: 326.4 347.4 354.8 361.8 390.1 15335.0 15473.5 16512.0 18093.7 21249.8 20191.9 20379.6 20587.0 20596.4 20845.6 5674.8 6142.9 6165.4 6209.4 6216.0 4738.0 6004.8 6073.1 6080.6 6089.6 5732.9 6022.6 6023.1 6026.8 6029.3 5950.5 6043.9 6048.1 6053.5 6066.1 5003.6 5209.6 5222.0 5244.2 5261.9 25522.9 25591.6 25630.4 26001.5 26253.6 2390.3 2399.2 2399.8 2417.4 2588.6 12773.7 15548.1 15676.5 15775.7 15991.0 1848.0 1877.3 1902.8 1940.6 1944.2 100950.5 120008.4 120354.8 120928.8 123786.4 10381.4 10453.2 10856.5 10891.9 11014.9 13501.0 13530.3 13535.3 13535.9 13537.0 8162.3 8264.0 8415.2 8563.5 8605.6 7042.2 8622.6 8673.0 8818.7 8899.6 6465.0 6579.6 6588.6 6593.2 6682.6 1670.2 1818.6 1828.4 1836.2 1872.2 703.4 753.0 828.2 875.8 995.6 20642.0 22116.6 22173.3 22369.8 22400.0 913.4 916.9 938.4 941.4 998.9 9856.7 11566.6 11697.7 11706.6 11748.4 590158.0 590160.0 593208.0 599184.0 605954.0 113674.0 117168.0 118453.4 121810.0 121863.0 996.3 999.6 1018.5 1019.4 1020.3 15398.2 19336.8 19459.5 19984.5 19994.4 48156.8 48372.0 55844.0 56595.7 60156.7 79025.0 80143.0 85417.0 85770.6 86492.0 11686.4 11956.2 12365.6 12536.3 12773.8 13612.3 13633.9 13737.7 14136.4 14157.9 11642.8 11660.9 11806.6 11891.9 12134.4 11597.1 11659.4 11930.8 11936.1 12055.4 11277.4 11843.5 11933.3 12106.2 12361.8 12031.2 12160.5 12282.3 12453.8 12554.6 10332.4 11503.8 11590.5 11623.9 11717.1 14:32:18 INFO - PROCESS | 1647 | 14:32:18 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5699.67 Median:4877.12 stddev:3234.14 (66.3%) stddev-sans-first:3227.69 14:32:18 INFO - PROCESS | 1647 | Values: 1483.6 1506.0 1695.4 1820.5 1880.6 8539.2 8983.0 8993.0 8999.4 9001.0 7893.6 8138.3 8139.5 8539.8 8734.7 2373.1 2411.5 2415.0 2416.1 2420.9 2371.5 2402.2 2410.9 2414.1 2420.7 2107.1 2189.6 2190.2 2194.5 2393.5 2159.5 2181.8 2314.6 2394.3 2399.8 2332.1 2338.4 2346.3 2348.1 2349.3 7320.9 8892.3 8921.7 8966.6 8983.3 5228.9 5411.6 5415.1 5415.5 5423.3 3483.1 3621.2 3699.4 3706.1 3707.4 1050.6 1081.2 1086.9 1106.4 1107.1 11788.1 11817.7 11825.2 11832.1 11841.8 4509.1 4623.5 4820.9 4823.3 4846.6 10027.3 10039.0 10040.7 10049.0 10065.1 5401.3 5403.1 5412.2 5416.3 5431.3 4756.8 4903.6 4934.3 4950.5 4956.3 3714.8 3756.2 3777.2 3807.3 3824.4 1153.0 1158.3 1167.8 1171.2 1173.1 662.4 760.2 872.0 892.7 929.9 5183.6 5340.9 5660.3 5909.1 6058.5 912.8 920.6 948.3 966.5 973.4 3528.9 3740.7 3766.4 3839.0 3894.6 10151.0 10884.7 11026.4 11036.3 11045.1 10603.0 11002.7 11007.4 11010.9 11099.2 6841.9 8281.6 8285.0 8293.5 8315.8 5602.8 5627.5 5666.3 5682.3 5749.2 9812.4 10037.0 10096.4 10111.9 10197.8 9827.7 10252.3 10280.6 10368.5 10378.9 7099.7 7227.6 7227.9 7241.6 7244.5 8353.5 8423.2 8431.5 8431.9 8438.7 7304.7 7441.7 7483.5 7580.7 7645.1 7229.0 7335.8 7340.3 7378.9 7379.7 6772.3 6918.2 6918.4 6924.0 6938.3 4735.9 4908.7 4913.8 4939.7 4988.4 4862.3 4892.0 4949.9 5078.2 5135.2 14:32:18 INFO - PROCESS | 1647 | 14:32:18 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4399.44 Median:4736.61 stddev:1389.69 (29.3%) stddev-sans-first:1358.96 14:32:18 INFO - PROCESS | 1647 | Values: 291.9 300.6 308.9 314.4 315.6 4642.2 5407.9 5427.6 5501.4 5523.5 5374.2 5400.7 5404.7 5418.5 5429.4 4092.7 4115.3 4118.0 4127.6 4131.2 3631.6 3991.9 4113.5 4130.9 4131.9 3917.6 4015.7 4070.3 4084.0 4133.5 3658.9 4063.8 4073.5 4074.1 4077.4 3069.4 3566.6 3567.7 3578.1 3583.7 4454.6 4752.7 4966.4 5302.7 5357.8 3912.3 3984.9 4485.3 4634.1 4668.9 4790.7 5663.8 5672.3 5690.1 5700.8 3368.3 3461.0 3466.7 3742.6 3853.9 5587.9 5763.7 5803.6 5873.9 5935.7 3207.4 3737.7 3913.6 3917.2 3924.4 3273.3 3649.1 3963.2 3988.6 3997.5 3891.9 4168.4 4407.8 4503.5 4538.8 4470.2 5635.7 5639.9 5647.9 5650.2 1257.6 1321.6 1335.9 1338.0 1340.2 724.6 758.9 774.9 832.9 833.0 3765.4 3794.9 4415.8 4451.7 4507.7 4852.6 4863.0 4870.8 4897.3 4905.0 4296.7 4350.5 4437.4 4440.8 4472.5 2204.1 2320.7 2322.3 2520.0 2549.9 4339.9 4590.7 4866.3 4932.2 4943.6 4320.0 4393.3 4464.8 4514.0 4933.7 4569.5 4754.7 4810.6 5883.9 6028.3 4999.5 5867.9 5947.2 5948.6 5954.1 5529.4 5575.4 5874.4 5935.6 5936.7 5199.3 5725.0 5932.6 5991.2 6000.5 3399.7 3740.6 3870.3 3912.2 3947.7 5191.5 5860.5 5877.5 5899.7 5919.5 5122.0 5432.5 5544.8 5640.4 5725.5 5151.2 5467.9 5625.2 5643.5 5690.8 5295.5 5423.5 5526.9 5612.4 5614.7 4840.9 4845.6 5694.1 5697.3 5712.5 4720.6 5161.0 5322.5 5505.0 5543.0 14:32:18 INFO - PROCESS | 1647 | 14:32:18 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:23855.25 Median:3621.08 stddev:38428.17 (1061.2%) stddev-sans-first:38496.68 14:32:18 INFO - PROCESS | 1647 | Values: 711.4 727.9 759.2 832.4 839.0 15229.0 15957.4 15989.9 16003.8 16054.8 12176.7 14331.4 14332.9 14342.1 14346.4 675.6 697.7 703.2 842.2 843.5 854.3 861.1 869.1 870.3 875.3 917.0 918.0 919.3 922.0 922.8 715.6 740.4 913.2 914.6 921.0 648.6 665.7 672.2 673.3 673.6 14948.0 14972.9 14995.6 15008.2 15027.2 13403.2 13674.2 13812.5 13874.1 14147.2 12355.8 12471.8 12596.0 12606.5 12989.4 3747.9 4505.9 4562.9 4637.8 4660.2 109319.0 109435.0 110489.0 110505.9 110557.0 10345.8 10352.8 10764.0 10830.6 10837.1 19235.5 20356.7 21408.6 21429.4 21451.8 7865.9 8033.2 8063.7 8090.9 8127.4 4468.6 4532.4 4546.0 4561.6 4625.2 3436.4 3539.7 3547.8 3550.6 3566.8 532.6 646.6 653.1 667.8 671.5 549.1 562.2 625.9 645.2 657.3 7526.0 7774.8 7828.7 9072.7 9245.9 595.0 602.1 634.5 641.2 646.9 3675.4 4210.4 4456.7 4465.5 4611.6 92366.6 94268.6 158324.9 161596.4 166729.0 129220.1 132819.0 137725.0 137959.3 141773.0 71938.0 114621.9 115126.4 118834.4 122053.0 8600.1 8601.1 8638.0 8645.5 8826.1 52837.5 54168.0 60295.4 60998.0 67023.7 61252.8 64799.0 66100.7 71849.0 80893.2 13197.3 13209.6 13227.7 13557.3 13900.3 11987.5 14625.4 14732.7 14773.5 14784.1 13819.3 13862.2 14041.6 14230.9 14525.1 11084.0 12676.4 12893.7 13051.5 13452.6 13541.7 13634.3 13644.3 14050.7 14186.9 9994.0 11150.9 11238.6 11332.3 11361.0 11272.5 11294.3 11535.4 11535.5 11723.0 14:32:18 INFO - PROCESS | 1647 | -------- Summary: end -------- 14:32:18 INFO - PROCESS | 1647 | 14:32:19 INFO - TEST-INFO | 1647: exit 0 14:32:19 INFO - TEST-OK | dromaeo_css | took 1118180ms 14:32:19 INFO - TEST-START | kraken 14:32:19 INFO - operating with platform_type : mac_ 14:32:19 INFO - Initialising browser for kraken test... 14:32:19 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpu5gSj3/profile http://localhost:49270/getInfo.html) 14:32:21 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 14:32:21 INFO - PROCESS | 1657 | colorDepth:24 14:32:21 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 14:32:21 INFO - PROCESS | 1657 | __metrics 14:32:21 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:32:21 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:32:22 INFO - TEST-INFO | 1657: exit 0 14:32:22 INFO - Browser initialized. 14:32:22 INFO - Running cycle 1/1 for kraken test... 14:32:22 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpu5gSj3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:32:34 INFO - PROCESS | 1658 | RSS: Main: 255692800 14:32:34 INFO - PROCESS | 1658 | 14:32:37 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 14:32:38 INFO - PROCESS | 1658 | RSS: Main: 311861248 14:32:38 INFO - PROCESS | 1658 | 14:32:40 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 14:32:41 INFO - PROCESS | 1658 | RSS: Main: 311521280 14:32:41 INFO - PROCESS | 1658 | 14:32:45 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 14:32:45 INFO - PROCESS | 1658 | RSS: Main: 397283328 14:32:45 INFO - PROCESS | 1658 | 14:32:47 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 14:32:48 INFO - PROCESS | 1658 | RSS: Main: 298541056 14:32:48 INFO - PROCESS | 1658 | 14:32:50 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:32:50 INFO - PROCESS | 1658 | RSS: Main: 303026176 14:32:50 INFO - PROCESS | 1658 | 14:32:53 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 14:32:54 INFO - PROCESS | 1658 | RSS: Main: 340701184 14:32:54 INFO - PROCESS | 1658 | 14:32:57 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 14:32:57 INFO - PROCESS | 1658 | RSS: Main: 342102016 14:32:57 INFO - PROCESS | 1658 | 14:33:00 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 14:33:01 INFO - PROCESS | 1658 | RSS: Main: 342192128 14:33:01 INFO - PROCESS | 1658 | 14:33:02 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:33:03 INFO - PROCESS | 1658 | RSS: Main: 316461056 14:33:03 INFO - PROCESS | 1658 | 14:33:05 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:33:05 INFO - PROCESS | 1658 | RSS: Main: 317804544 14:33:05 INFO - PROCESS | 1658 | 14:33:08 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:33:08 INFO - PROCESS | 1658 | RSS: Main: 318050304 14:33:08 INFO - PROCESS | 1658 | 14:33:11 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:33:12 INFO - PROCESS | 1658 | RSS: Main: 330170368 14:33:12 INFO - PROCESS | 1658 | 14:33:15 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:33:15 INFO - PROCESS | 1658 | RSS: Main: 332316672 14:33:15 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 14:33:17 INFO - PROCESS | 1658 | RSS: Main: 375627776 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | __start_tp_report 14:33:17 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 14:33:17 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 14:33:17 INFO - PROCESS | 1658 | |i|pagename|runs| 14:33:17 INFO - PROCESS | 1658 | |0;ai-astar;113;114;113;113;113;113;112;113;112;115 14:33:17 INFO - PROCESS | 1658 | |1;audio-beat-detection;144;108;110;148;111;108;110;107;141;109 14:33:17 INFO - PROCESS | 1658 | |2;audio-dft;184;184;182;185;184;182;183;185;181;184 14:33:17 INFO - PROCESS | 1658 | |3;audio-fft;81;59;60;88;61;81;57;59;82;58 14:33:17 INFO - PROCESS | 1658 | |4;audio-oscillator;88;71;91;70;69;70;92;68;69;89 14:33:17 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;89;86;90;86;89;86;90;87;91;86 14:33:17 INFO - PROCESS | 1658 | |6;imaging-darkroom;94;94;95;94;101;142;94;95;95;95 14:33:17 INFO - PROCESS | 1658 | |7;imaging-desaturate;87;86;86;83;84;86;84;85;83;84 14:33:17 INFO - PROCESS | 1658 | |8;json-parse-financial;56;56;56;56;56;54;53;56;54;55 14:33:17 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;66;67;74;77;72;71;70;71;70;72 14:33:17 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;86;89;87;81;80;73;84;92;104;106 14:33:17 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;140;146;141;139;134;136;139;137;167;138 14:33:17 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;169;169;168;222;173;168;166;166;167;167 14:33:17 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;67;67;67;68;67;67;66;66;66;67 14:33:17 INFO - PROCESS | 1658 | __end_tp_report 14:33:17 INFO - PROCESS | 1658 | __start_cc_report 14:33:17 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1932 14:33:17 INFO - PROCESS | 1658 | __end_cc_report 14:33:17 INFO - PROCESS | 1658 | __startTimestamp1475184797774__endTimestamp 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | ------- Summary: start ------- 14:33:17 INFO - PROCESS | 1658 | Number of tests: 14 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:113.10 Median:113.00 stddev:0.88 (0.8%) stddev-sans-first:0.93 14:33:17 INFO - PROCESS | 1658 | Values: 113.0 114.0 113.0 113.0 113.0 113.0 112.0 113.0 112.0 115.0 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:119.60 Median:110.50 stddev:17.19 (15.6%) stddev-sans-first:15.80 14:33:17 INFO - PROCESS | 1658 | Values: 144.0 108.0 110.0 148.0 111.0 108.0 110.0 107.0 141.0 109.0 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.35 (0.7%) stddev-sans-first:1.41 14:33:17 INFO - PROCESS | 1658 | Values: 184.0 184.0 182.0 185.0 184.0 182.0 183.0 185.0 181.0 184.0 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:68.60 Median:71.00 stddev:12.59 (17.7%) stddev-sans-first:12.53 14:33:17 INFO - PROCESS | 1658 | Values: 81.0 59.0 60.0 88.0 61.0 81.0 57.0 59.0 82.0 58.0 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:79.50 stddev:10.67 (13.4%) stddev-sans-first:10.64 14:33:17 INFO - PROCESS | 1658 | Values: 88.0 71.0 91.0 70.0 69.0 70.0 92.0 68.0 69.0 89.0 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:88.00 Median:89.00 stddev:2.00 (2.2%) stddev-sans-first:2.09 14:33:17 INFO - PROCESS | 1658 | Values: 89.0 86.0 90.0 86.0 89.0 86.0 90.0 87.0 91.0 86.0 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:99.90 Median:94.50 stddev:14.94 (15.8%) stddev-sans-first:15.69 14:33:17 INFO - PROCESS | 1658 | Values: 94.0 94.0 95.0 94.0 101.0 142.0 94.0 95.0 95.0 95.0 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.50 stddev:1.40 (1.6%) stddev-sans-first:1.24 14:33:17 INFO - PROCESS | 1658 | Values: 87.0 86.0 86.0 83.0 84.0 86.0 84.0 85.0 83.0 84.0 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:56.00 stddev:1.14 (2.0%) stddev-sans-first:1.17 14:33:17 INFO - PROCESS | 1658 | Values: 56.0 56.0 56.0 56.0 56.0 54.0 53.0 56.0 54.0 55.0 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.50 stddev:3.16 (4.4%) stddev-sans-first:2.79 14:33:17 INFO - PROCESS | 1658 | Values: 66.0 67.0 74.0 77.0 72.0 71.0 70.0 71.0 70.0 72.0 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:85.00 stddev:10.30 (12.1%) stddev-sans-first:10.90 14:33:17 INFO - PROCESS | 1658 | Values: 86.0 89.0 87.0 81.0 80.0 73.0 84.0 92.0 104.0 106.0 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:141.70 Median:139.50 stddev:9.45 (6.8%) stddev-sans-first:10.01 14:33:17 INFO - PROCESS | 1658 | Values: 140.0 146.0 141.0 139.0 134.0 136.0 139.0 137.0 167.0 138.0 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:168.50 stddev:17.16 (10.2%) stddev-sans-first:18.12 14:33:17 INFO - PROCESS | 1658 | Values: 169.0 169.0 168.0 222.0 173.0 168.0 166.0 166.0 167.0 167.0 14:33:17 INFO - PROCESS | 1658 | 14:33:17 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.63 (0.9%) stddev-sans-first:0.67 14:33:17 INFO - PROCESS | 1658 | Values: 67.0 67.0 67.0 68.0 67.0 67.0 66.0 66.0 66.0 67.0 14:33:17 INFO - PROCESS | 1658 | -------- Summary: end -------- 14:33:17 INFO - PROCESS | 1658 | 14:33:18 INFO - TEST-INFO | 1658: exit 0 14:33:18 INFO - TEST-OK | kraken | took 59346ms 14:33:18 INFO - SUITE-END | took 1177s 14:33:18 INFO - Completed test suite (00:19:37) 14:33:18 INFO - javascript benchmark 14:33:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2921.4741346557626, 3343.0973637117986, 3375.3080618473214, 3381.1040074380307, 3405.0, 5612.7754889804355, 5706.0, 5736.655800651964, 5781.884362312751, 5817.272840894888, 5693.629914055594, 5770.711464426783, 5849.7367618457165, 5851.853703657411, 5873.500752436048, 5132.204508301219, 5192.273081768555, 5224.686518808883, 5255.316808814868, 5260.447652996438, 4219.3881887126345, 5051.61080702806, 5338.199270109476, 5352.357717073956, 5377.870647164081, 5302.840914772563, 5306.8407947761625, 5316.043112239795, 5340.198970154469, 5400.027994960906, 5170.767315470813, 5203.115470370046, 5244.082285600005, 5244.344456942882, 5247.47525247476, 5178.844634660966, 5212.582993360522, 5221.1124108901195, 5265.526102650741, 5274.709890955995, 5708.486236238717, 5736.053551164053, 5736.368999410061, 5752.654840709571, 5761.971190144064, 6063.454289113957, 6127.436311768148, 6235.501159907242, 6240.469560087398, 6253.6560489173535, 5766.221560089376, 6188.628682279078, 6259.499240060784, 6265.8120256392385, 6271.968640156816, 5787.826365209051, 5791.82624521265, 5793.391693872125, 5798.594098413155, 5800.216970708984, 6218.0, 6237.875242495124, 6265.624062556261, 6308.0537919311555, 6315.084312774622, 6116.034291236468, 6128.26460824695, 6134.405972711579, 6176.413240742122, 6187.412195841388, 6248.687565621701, 6262.843428914222, 6263.154474145932, 6268.655223962726, 6280.0893870389455, 6094.603850749687, 6217.751289948352, 6220.844478888064, 6224.06639004153, 6227.159333490012, 6095.136089334496, 6105.178419690066, 6170.321264660884, 6209.0, 6221.0, 4877.731724755173, 4900.044491324157, 4904.509549045067, 4929.556339929423, 4930.482299358588, 4412.161689279091, 4422.823087076546, 4428.6678499112695, 4447.221736196153, 4490.50604433512, 5605.467480589337, 5638.633488823213, 5699.971500142556, 5716.714164291852, 5751.166080918327, 6181.381861813783, 6193.876122477525, 6250.624962502265, 6261.123442718023, 6306.716197771111, 5729.770809167587, 5781.479666829921, 5784.219130417424, 5810.331811841608, 5823.475887170175, 5346.278252435949, 5349.304590403222, 5368.060589396918, 5383.488568585978, 5395.487428694268, 6201.410865967726, 6208.0, 6226.875462490634, 6238.095476156022, 6267.561270711007, 6312.210973628296, 6314.55798094129, 6317.778877739257, 6319.684015799284, 6333.746650133943, 6152.50779937595, 6276.0, 6280.842978925563, 6286.717097730521, 6295.464885484785, 6094.471245618374, 6123.2652081750475, 6126.365000174519, 6174.413430724074, 6175.5677102602385, 6284.402981716729, 6318.336574659595, 6321.52588555851, 6325.4939604832425, 6327.715252813542, 5087.694738315713, 6104.8779024420155, 6107.908381374372, 6116.66358350295, 6123.295820980644, 5874.735636896265, 6020.759169633166, 6081.908771368345, 6082.148499210026, 6085.939140608537, 5779.537636989115, 5836.920169768607, 5912.615679980788, 5939.584229103922, 5952.821415357546, 6094.380596348918, 6100.30868792748, 6101.641069425342, 6132.693365331805, 6137.1101190328045, 5352.5450336721815, 5918.970405147947, 5942.376050514807, 5949.077892926603, 6054.243219597551], "value": 5692.626911881259, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.8125771577941, 514.0772313696914, 527.4066674990634, 527.8548399190222, 551.7875617887112, 30919.690803091962, 41144.38283425747, 42069.789651051775, 42110.94722631936, 44391.112177756426, 43438.348424773656, 43832.0, 43836.561634383645, 43875.56124438754, 43978.0, 9765.95117024414, 9966.451845148515, 9979.900200998007, 9984.800303993916, 9986.600535978552, 8868.556572171397, 9095.363324567283, 12068.456919438646, 12318.384080795924, 12348.753024939495, 12280.754384912296, 12367.319797411139, 12367.443465044049, 12386.752264954695, 12388.132830701854, 8818.559072046372, 10418.843717344245, 12251.448684809206, 12328.691782705415, 12334.136610437228, 8780.209781119729, 8905.198532132139, 8909.109089091104, 8930.0, 11888.881111188864, 40823.387649185286, 41162.176756465, 41277.587224127674, 41285.793571032256, 41326.380104298456, 3265.755068369858, 3288.588926384202, 3359.1937934895695, 3417.4532074867902, 3674.9342690619687, 31261.218469538217, 31290.843545782125, 31348.529772053436, 31719.365612687616, 32314.6768532314, 2945.3520225550346, 2978.80637758545, 3014.201236672284, 3060.5715199872034, 3135.717785399302, 442705.0, 443808.9999999968, 444896.0, 445567.0, 450112.0, 51438.22842657363, 52684.73657631687, 58482.707586461795, 59173.112403313986, 59915.0, 60940.0, 61142.38857611456, 61172.0, 61283.38716612866, 61550.0, 15945.760813587573, 16116.0, 16130.193490325437, 16135.193240337936, 16153.273102710406, 11414.486348114355, 11600.129990250596, 12449.94175495093, 12544.68638284032, 12565.0, 14640.267986600627, 16397.42609008679, 16486.75269870953, 16759.581010474834, 16996.32014719422, 6297.149884765589, 6300.27546832111, 6368.808935731936, 6391.616503009835, 6407.839804004937, 842.9452085614415, 915.8323137998972, 964.8890377606525, 1069.3049517813483, 1122.1527746551465, 28868.855655722007, 30111.0, 30130.096097117123, 30209.0, 30444.286219310223, 1050.9474526273655, 1052.4211683574156, 1067.4716015572342, 1078.2991055813784, 1091.6779550032759, 15092.54722358331, 15557.766633500507, 15590.610234744223, 15693.514196713842, 15941.521754347388, 594679.0, 595480.0, 597235.0, 598066.0, 600482.0, 57662.0, 57936.8412631745, 57955.42044579585, 57973.0, 57973.0, 1230.1081715756006, 1275.4260582737804, 1343.9798403023767, 1376.9449222031008, 1397.1407584335561, 29544.852275738485, 31137.688623113478, 31180.064598062094, 31249.8437507811, 32267.677323226468, 48796.0, 49186.0, 49466.0, 49475.50524494853, 49489.75255123701, 463744.0, 463824.0, 463879.0, 464606.0, 466312.0, 26241.475170496866, 26863.328416789736, 26964.0, 27785.44429111447, 39291.80354098211, 46956.7652161737, 47850.0, 48017.0, 48514.0, 48682.75658621826, 37585.43621845729, 37612.2477550442, 38873.22535492856, 39062.414063789634, 40398.9900252496, 38766.61233387743, 39044.21911561696, 40044.59955400525, 40498.0, 40555.39166912558, 37807.0, 38053.80973095228, 38609.613903860605, 38766.8369948902, 38904.2219155621, 31514.0, 34502.82748586241, 34601.134971625914, 34670.65329346674, 34793.65206347904, 21786.673199901696, 24226.394340141636, 24806.139539534248, 25505.48989020172, 25829.095981640552], "value": 19929.54539915435, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.3977960662578, 347.42674586931577, 354.8172691064102, 361.7811224209354, 390.07357525876046, 15335.00322479038, 15473.535793926187, 16512.009279443228, 18093.728594071068, 21249.831259280734, 20191.899040504777, 20379.592408151864, 20587.0, 20596.382108536767, 20845.583088338226, 5674.773009079642, 6142.938570614281, 6165.383461653843, 6209.441150296472, 6216.036514340262, 4738.0, 6004.789832355868, 6073.119397687333, 6080.60146166382, 6089.634621922677, 5732.942670573282, 6022.638641681491, 6023.096535519663, 6026.789062382817, 6029.276486821587, 5950.523958083365, 6043.909341359883, 6048.092786082123, 6053.485453736438, 6066.120412540179, 5003.624728145367, 5209.583233341324, 5222.0, 5244.16093425054, 5261.868453288698, 25522.87238563814, 25591.61612575813, 25630.359241019123, 26001.479970400676, 26253.606195907076, 2390.25901970388, 2399.184277345711, 2399.8080153587844, 2417.3835671903726, 2588.572885473913, 12773.744525109445, 15548.144852033134, 15676.451324203712, 15775.684486310209, 15991.040537567667, 1847.9722804157814, 1877.3241633012105, 1902.8192321729414, 1940.6118776244807, 1944.1834429539626, 100950.49524752403, 120008.39974800857, 120354.79645203524, 120928.79071209264, 123786.38106809498, 10381.377117372831, 10453.21600879937, 10856.45717714127, 10891.945540272407, 11014.944925275482, 13501.0, 13530.255835928921, 13535.255560944242, 13535.932320338532, 13537.0, 8162.265396114378, 8263.95868020656, 8415.242628163494, 8563.529005904735, 8605.569721513899, 7042.232730242934, 8622.568871556397, 8672.956635216784, 8818.691345802994, 8899.599518021705, 6465.0, 6579.572327798678, 6588.637624930675, 6593.241777195588, 6682.63245521501, 1670.198304813672, 1818.5815064249641, 1828.3600739740984, 1836.2012524551862, 1872.1668857358345, 703.3845385287873, 752.9736459223901, 828.204923273664, 875.7547886591765, 995.5967833027636, 20642.0, 22116.557668846854, 22173.33479995603, 22369.776302237096, 22400.0, 913.4015472922385, 916.9495677737789, 938.3900464698, 941.4163218804385, 998.8951160128228, 9856.704298871045, 11566.595169169039, 11697.707557311136, 11706.590269340702, 11748.412579370997, 590158.0, 590160.0, 593208.0, 599184.0, 605954.0, 113674.0, 117168.0, 118453.40773296251, 121810.0, 121863.0, 996.2976101848179, 999.6401295533457, 1018.5365658625308, 1019.4189312092185, 1020.2501161646184, 15398.152221733015, 19336.806631934065, 19459.51351216231, 19984.50038749043, 19994.400167994983, 48156.759216203696, 48372.0, 55844.0, 56595.71702141463, 60156.699216503635, 79025.0, 80143.0, 85417.0, 85770.57114714387, 86492.0, 11686.415679216176, 11956.222845515014, 12365.629031129081, 12536.310502922426, 12773.808392873929, 13612.251326177155, 13633.863661363259, 13737.725245495234, 14136.363863625957, 14157.929210353883, 11642.76714465689, 11660.88339116598, 11806.645800625995, 11891.940540297242, 12134.393280336124, 11597.130215233723, 11659.358735269641, 11930.821037684615, 11936.10479214045, 12055.397230138282, 11277.379744114152, 11843.467043983213, 11933.28400295985, 12106.213096148722, 12361.752764944831, 12031.15781895294, 12160.45277962476, 12282.263064216175, 12453.75092498163, 12554.623361299176, 10332.380057196593, 11503.769924601294, 11590.5363785451, 11623.883761162619, 11717.121215909012], "value": 10002.107120179357, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1483.5920121966462, 1505.969880602388, 1695.389380088915, 1820.5357633803394, 1880.6238752249544, 8539.231469167773, 8983.0, 8993.0, 8999.370044096915, 9000.954995225014, 7893.565853878049, 8138.30824379927, 8139.511629302246, 8539.78650533737, 8734.650613975433, 2373.1100837186054, 2411.5176964607062, 2414.99777592299, 2416.0818888822223, 2420.8547487150827, 2371.5256948610263, 2402.171250918439, 2410.867402292873, 2414.0705828256073, 2420.7095148582193, 2107.1255428997024, 2189.562087582482, 2190.156789635994, 2194.5281764420647, 2393.533261014103, 2159.503314237726, 2181.81454576362, 2314.6065168921323, 2394.2817154853647, 2399.820013498977, 2332.0788288626068, 2338.4387746940783, 2346.296111166643, 2348.072511358004, 2349.34218418843, 7320.853582928312, 8892.288616910631, 8921.687740929035, 8966.641334346617, 8983.326250531236, 5228.869278268074, 5411.594130440233, 5415.0523658359625, 5415.48552887479, 5423.267858839085, 3483.1466290758926, 3621.185233322508, 3699.408094704834, 3706.129059670975, 3707.3697471429923, 1050.5745173204864, 1081.1620993729844, 1086.869575650927, 1106.3859557945336, 1107.0921844087852, 11788.056955443544, 11817.704557386049, 11825.172237943347, 11832.053435725034, 11841.822372664417, 4509.143262780061, 4623.537646235417, 4820.879478013007, 4823.252395878644, 4846.636502262344, 10027.348222365523, 10039.0, 10040.748981275381, 10048.995100489892, 10065.094141527297, 5401.3248343957, 5403.081476149064, 5412.188171774186, 5416.268803711528, 5431.34823821144, 4756.809727610926, 4903.632227582945, 4934.309196712463, 4950.455449900508, 4956.306117143603, 3714.814259287025, 3756.2111956489443, 3777.1501412182038, 3807.3337165996395, 3824.388097904322, 1152.9884701153048, 1158.2529268621693, 1167.7796702445964, 1171.2152857585438, 1173.0908545876844, 662.4203821656051, 760.152430040512, 871.9956400218085, 892.6607889002138, 929.8605209218671, 5183.559397451183, 5340.946590534123, 5660.320761508646, 5909.084091965752, 6058.485028772604, 912.7535565397305, 920.6271460058687, 948.3314263444255, 966.5215718219386, 973.3770386952356, 3528.8764893228613, 3740.7007439404783, 3766.3785475396253, 3838.980805095957, 3894.6105389460827, 10151.0, 10884.673459796219, 11026.393548354918, 11036.28264162843, 11045.061169800496, 10603.045725884707, 11002.724931876766, 11007.394593297286, 11010.889891101147, 11099.223054386277, 6841.863162736718, 8281.627326770309, 8284.96437945257, 8293.543855088028, 8315.83368332642, 5602.831915042555, 5627.521660658726, 5666.32004159487, 5682.289713785777, 5749.195112684311, 9812.362196457207, 10037.0, 10096.444695541815, 10111.89888101139, 10197.796044079225, 9827.705168844946, 10252.282340236112, 10280.640177594047, 10368.533415996148, 10378.948105259426, 7099.680514376762, 7227.638618069181, 7227.927720722936, 7241.565506069653, 7244.529105608119, 8353.498790072616, 8423.157684231528, 8431.451955622864, 8431.915680843113, 8438.704645337384, 7304.744333948286, 7441.665125069277, 7483.503299340045, 7580.696772129053, 7645.082590089225, 7228.96385518069, 7335.816604584928, 7340.265973402617, 7378.926210737824, 7379.667914943733, 6772.322767723188, 6918.238993710587, 6918.408765983952, 6924.0, 6938.306169383223, 4735.881602959816, 4908.656394052382, 4913.7543122843, 4939.728314942569, 4988.401391832858, 4862.294967229803, 4891.95108048915, 4949.901001980013, 5078.21287700407, 5135.178371460548], "value": 4558.393046039709, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.91826288639186, 300.61671369004523, 308.88262460265094, 314.4057730888621, 315.6007650322341, 4642.234031384822, 5407.891842163154, 5427.62006659534, 5501.4223506531835, 5523.530499907508, 5374.247605335247, 5400.702961337125, 5404.675719456835, 5418.512333889949, 5429.429909859467, 4092.6930480213955, 4115.300398932189, 4118.0, 4127.587241275878, 4131.2150691368615, 3631.6368363163606, 3991.880243592697, 4113.547509773923, 4130.876073717794, 4131.938020929689, 3917.6082391760738, 4015.698822588303, 4070.287699652564, 4083.9591604083876, 4133.545310015896, 3658.871664726647, 4063.7968101595097, 4073.4704488416314, 4074.0833312504747, 4077.4495443115097, 3069.3861227754314, 3566.6433356664124, 3567.7324200685052, 3578.141246100943, 3583.6953858922025, 4454.643628509712, 4752.667313288071, 4966.424174594286, 5302.704809104637, 5357.785688572452, 3912.2957867583823, 3984.880453586397, 4485.327200919855, 4634.119517291704, 4668.906621867578, 4790.736509491977, 5663.773449062032, 5672.290963629546, 5690.06113991191, 5700.828975130753, 3368.2589830237307, 3460.9506876954024, 3466.7119700997223, 3742.5883152853166, 3853.9401664542197, 5587.916181257285, 5763.711814409263, 5803.593748437611, 5873.853153671193, 5935.666963116905, 3207.4226639205167, 3737.663610275088, 3913.6477717005605, 3917.216556688674, 3924.3524818404644, 3273.3126043531124, 3649.0694872807794, 3963.227170701743, 3988.6210809973586, 3997.5009371485694, 3891.8637847675764, 4168.3955826405, 4407.779611019436, 4503.459584849839, 4538.750368729751, 4470.173017991682, 5635.718214089361, 5639.943600564024, 5647.8704259148335, 5650.237217975603, 1257.5724253753767, 1321.6365499487717, 1335.946562137523, 1338.0, 1340.2293681133388, 724.6413025552386, 758.8634045871686, 774.8915151878741, 832.8958880139983, 833.0378412933072, 3765.3975363941636, 3794.9261098510297, 4415.823367065345, 4451.732896026248, 4507.706999045052, 4852.587530059914, 4862.9756851216225, 4870.8310005598405, 4897.314375987365, 4904.950950490521, 4296.656267498593, 4350.521442641307, 4437.42313499243, 4440.755758433317, 4472.463304403493, 2204.118352658917, 2320.7215134183666, 2322.3381336319067, 2520.0, 2549.923502294934, 4339.891502712458, 4590.701604395704, 4866.270059491034, 4932.161532539405, 4943.579795717333, 4320.0, 4393.340998850134, 4464.799084041226, 4513.984353520432, 4933.694424735436, 4569.544693923398, 4754.668692766031, 4810.5670489656095, 5883.882322353615, 6028.2766068072115, 4999.525045120708, 5867.911981320369, 5947.167396564571, 5948.643081415129, 5954.07711804671, 5529.419410961791, 5575.383138889677, 5874.383189765013, 5935.584509084322, 5936.732847021808, 5199.324087868553, 5724.971375143097, 5932.614380065282, 5991.161237426851, 6000.489958353589, 3399.6940275374345, 3740.551133863954, 3870.3226935286775, 3912.158885839558, 3947.6644485219076, 5191.532762051434, 5860.531157507304, 5877.471857317043, 5899.705014749331, 5919.467247947706, 5122.00120976406, 5432.538234250133, 5544.833654990357, 5640.379558248672, 5725.541956643542, 5151.248575484354, 5467.851751132307, 5625.240592520121, 5643.463870932255, 5690.813144639015, 5295.549878260237, 5423.484768946943, 5526.889462210814, 5612.438756124355, 5614.7192640367, 4840.927386089282, 4845.63657725665, 5694.088945768657, 5697.344805347272, 5712.457316554921, 4720.560229130148, 5160.961420238909, 5322.49436303566, 5505.0, 5542.97228513871], "value": 3955.393198367204, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.4024219655488, 727.8689835829551, 759.168710262263, 832.4006715165079, 838.995805020975, 15229.0, 15957.361705531765, 15989.920050399745, 16003.759943600855, 16054.75917861233, 12176.695582610439, 14331.355089020994, 14332.856671433283, 14342.13947163171, 14346.354414051366, 675.5642610516214, 697.6825544377297, 703.1527009952995, 842.2125312832521, 843.4854738609458, 854.3037424499045, 861.1431625532604, 869.0657543141117, 870.3254977392539, 875.3347455933473, 917.0050495212669, 918.0, 919.3242966419709, 922.0226559846551, 922.8338898998178, 715.5778090926326, 740.4224704730319, 913.2009491694768, 914.5701520285488, 920.9769755756126, 648.6367634124905, 665.72705190872, 672.1665135232325, 673.3031312591444, 673.5689158938284, 14948.0, 14972.925135374362, 14995.625109372244, 15008.174550399724, 15027.173505457196, 13403.195808251536, 13674.17954922698, 13812.516561920385, 13874.098183618133, 14147.221902795342, 12355.752884942342, 12471.812922806164, 12596.0, 12606.495740170381, 12989.350532473338, 3747.850085996557, 4505.887352816173, 4562.903377598583, 4637.791299391535, 4660.184467718136, 109319.0, 109435.0, 110489.0, 110505.89494105037, 110557.0, 10345.84481232767, 10352.792944141225, 10764.03123718869, 10830.620928267474, 10837.078848297982, 19235.51911202203, 20356.69464957997, 21408.57182856334, 21429.357119286447, 21451.78548214529, 7865.921340786634, 8033.236842499953, 8063.717769878026, 8090.919090809017, 8127.431079824474, 4468.553144685506, 4532.4334458192725, 4546.0, 4561.566651168134, 4625.167469855458, 3436.415809312423, 3539.681428671432, 3547.7516573839584, 3550.5739311282296, 3566.7503274771034, 532.6298222735239, 646.5894157210205, 653.0954627840475, 667.8363800868818, 671.4796033074407, 549.1131822107347, 562.2072877243068, 625.9342769009188, 645.1868663343311, 657.3492242679713, 7526.02161718973, 7774.766756997299, 7828.708263136518, 9072.67920387085, 9245.861312080324, 594.9583529152918, 602.077465313483, 634.4924060751381, 641.2497378067673, 646.9099567229234, 3675.4119340905327, 4210.368444733314, 4456.665750068758, 4465.508794032654, 4611.631069514431, 92366.61450078254, 94268.58597121049, 158324.9172557933, 161596.38403615812, 166729.0, 129220.06169907459, 132819.0, 137725.0, 137959.31020344835, 141773.0, 71938.0, 114621.85378146279, 115126.4243678793, 118834.4058279703, 122053.0, 8600.1399860016, 8601.13988601135, 8638.006629237718, 8645.524496152771, 8826.117388261122, 52837.471625284794, 54168.0, 60295.39704602898, 60998.0, 67023.66488167529, 61252.774944501754, 64799.0, 66100.67798643904, 71849.0, 80893.19106808856, 13197.340132993504, 13209.603711888658, 13227.66930826737, 13557.25435101079, 13900.304984750519, 11987.520499179935, 14625.414983400546, 14732.705345892808, 14773.482928097465, 14784.13254739515, 13819.30903454803, 13862.237576932963, 14041.578752637437, 14230.921704658078, 14525.055871368328, 11084.0, 12676.429560669976, 12893.742125157256, 13051.543195988095, 13452.596422107352, 13541.661458463223, 13634.250116243702, 13644.317784110954, 14050.718985620435, 14186.929065354607, 9993.950030249802, 11150.888491115309, 11238.559372399883, 11332.263402878787, 11361.0, 11272.492737826653, 11294.265872718248, 11535.36555489456, 11535.538578457103, 11723.0], "value": 7678.046886089473, "unit": "score"}], "name": "dromaeo_css", "value": 7345.7086589485825, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 112.0, 115.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 108.0, 110.0, 148.0, 111.0, 108.0, 110.0, 107.0, 141.0, 109.0], "value": 119.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 182.0, 185.0, 184.0, 182.0, 183.0, 185.0, 181.0, 184.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 88.0, 61.0, 81.0, 57.0, 59.0, 82.0, 58.0], "value": 68.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 91.0, 70.0, 69.0, 70.0, 92.0, 68.0, 69.0, 89.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 86.0, 89.0, 86.0, 90.0, 87.0, 91.0, 86.0], "value": 88.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 101.0, 142.0, 94.0, 95.0, 95.0, 95.0], "value": 99.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 83.0, 84.0, 86.0, 84.0, 85.0, 83.0, 84.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 56.0, 56.0, 54.0, 53.0, 56.0, 54.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 74.0, 77.0, 72.0, 71.0, 70.0, 71.0, 70.0, 72.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 87.0, 81.0, 80.0, 73.0, 84.0, 92.0, 104.0, 106.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 146.0, 141.0, 139.0, 134.0, 136.0, 139.0, 137.0, 167.0, 138.0], "value": 141.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 168.0, 222.0, 173.0, 168.0, 166.0, 166.0, 167.0, 167.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 67.0, 67.0, 66.0, 66.0, 66.0, 67.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1431.5, "alertThreshold": 2.0}]} 14:33:19 INFO - Return code: 0 14:33:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:33:19 INFO - # TBPL SUCCESS # 14:33:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:33:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:33:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:33:19 INFO - 2016-09-29 14:33:19.109 screenresolution[1662:9796] starting screenresolution argv=screenresolution get 14:33:19 INFO - 2016-09-29 14:33:19.111 screenresolution[1662:9796] Display 0: 1600x1200x32@60 14:33:19 INFO - 2016-09-29 14:33:19.117 screenresolution[1663:9799] starting screenresolution argv=screenresolution list 14:33:19 INFO - Available Modes on Display 0 14:33:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:33:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:33:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:33:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:33:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:33:19 INFO - Intel Iris: 14:33:19 INFO - Chipset Model: Intel Iris 14:33:19 INFO - Type: GPU 14:33:19 INFO - Bus: Built-In 14:33:19 INFO - VRAM (Dynamic, Max): 1536 MB 14:33:19 INFO - Vendor: Intel (0x8086) 14:33:19 INFO - Device ID: 0x0a2e 14:33:19 INFO - Revision ID: 0x0009 14:33:19 INFO - Displays: 14:33:19 INFO - Display: 14:33:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:33:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:33:19 INFO - Display Serial Number: 1600x1200 60 14:33:19 INFO - Main Display: Yes 14:33:19 INFO - Mirror: Off 14:33:19 INFO - Online: Yes 14:33:19 INFO - Rotation: Supported 14:33:19 INFO - Return code: 0 14:33:19 INFO - Running post-action listener: _resource_record_post_action 14:33:19 INFO - [mozharness: 2016-09-29 21:33:19.355418Z] Finished run-tests step (success) 14:33:19 INFO - Running post-run listener: _resource_record_post_run 14:33:19 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37336064; Write bytes: 706236416; Read time: 256; Write time: 1940 14:33:19 INFO - TinderboxPrint: CPU usage
53.9% 14:33:19 INFO - TinderboxPrint: I/O read bytes / time
37,336,064 / 256 14:33:19 INFO - TinderboxPrint: I/O write bytes / time
706,236,416 / 1,940 14:33:19 INFO - TinderboxPrint: CPU idle
2,236.9 (46.1%) 14:33:19 INFO - TinderboxPrint: CPU system
977.0 (20.1%) 14:33:19 INFO - TinderboxPrint: CPU user
1,639.9 (33.8%) 14:33:19 INFO - TinderboxPrint: Swap in / out
444,456,960 / 0 14:33:19 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 406198784; Write bytes: 401221632; Read time: 30885; Write time: 822 14:33:19 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34558976; Write bytes: 294156288; Read time: 234; Write time: 1086 14:33:19 INFO - Running post-run listener: _upload_blobber_files 14:33:19 INFO - Blob upload gear active. 14:33:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:33:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:33:19 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:33:19 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:33:20 INFO - (blobuploader) - INFO - Open directory for files ... 14:33:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:33:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:33:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:21 INFO - (blobuploader) - INFO - Done attempting. 14:33:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:33:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:33:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:22 INFO - (blobuploader) - INFO - Done attempting. 14:33:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:33:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:33:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:24 INFO - (blobuploader) - INFO - Done attempting. 14:33:24 INFO - (blobuploader) - INFO - Iteration through files over. 14:33:24 INFO - Return code: 0 14:33:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:33:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:33:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c0cf87aec49b864ee65da84e84309d3af50c03ccd97f188b2b40e02579b1e1bfda999eb85979999e803e87c0bc840188c345f5f6888930a6dc0b89481dc10dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9c8d46fac0449e5d56258617f4a9b9705b770ef7682dbe5c1b1de1737406337d6e67290d013621729c6c57af62c144524f2f0c74657153197c0c06a8e0c94176", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40954440cb4ffe536c377ae9c7302e55bfede3312b4319886485c3825ee30c122a20991acf40f62d6ad72e86408b35b368eca9059f97d23ece4211aac924e00e"} 14:33:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:33:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:33:24 INFO - Contents: 14:33:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c0cf87aec49b864ee65da84e84309d3af50c03ccd97f188b2b40e02579b1e1bfda999eb85979999e803e87c0bc840188c345f5f6888930a6dc0b89481dc10dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9c8d46fac0449e5d56258617f4a9b9705b770ef7682dbe5c1b1de1737406337d6e67290d013621729c6c57af62c144524f2f0c74657153197c0c06a8e0c94176", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40954440cb4ffe536c377ae9c7302e55bfede3312b4319886485c3825ee30c122a20991acf40f62d6ad72e86408b35b368eca9059f97d23ece4211aac924e00e"} 14:33:24 INFO - Running post-run listener: copy_logs_to_upload_dir 14:33:24 INFO - Copying logs to upload dir... 14:33:24 INFO - mkdir: /builds/slave/test/build/upload/logs 14:33:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.400204 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-29 14:33:24.932087) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 14:33:24.935626) ========= 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.mZYaa3mr5y/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.FSOLinWfjx/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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/Fx-Team/sha512/3c0cf87aec49b864ee65da84e84309d3af50c03ccd97f188b2b40e02579b1e1bfda999eb85979999e803e87c0bc840188c345f5f6888930a6dc0b89481dc10dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9c8d46fac0449e5d56258617f4a9b9705b770ef7682dbe5c1b1de1737406337d6e67290d013621729c6c57af62c144524f2f0c74657153197c0c06a8e0c94176", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40954440cb4ffe536c377ae9c7302e55bfede3312b4319886485c3825ee30c122a20991acf40f62d6ad72e86408b35b368eca9059f97d23ece4211aac924e00e"} build_url:https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010747 build_url: 'https://queue.taskcluster.net/v1/task/WDwfPYT0Q7Gr2QlrGBY9Gw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c0cf87aec49b864ee65da84e84309d3af50c03ccd97f188b2b40e02579b1e1bfda999eb85979999e803e87c0bc840188c345f5f6888930a6dc0b89481dc10dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9c8d46fac0449e5d56258617f4a9b9705b770ef7682dbe5c1b1de1737406337d6e67290d013621729c6c57af62c144524f2f0c74657153197c0c06a8e0c94176", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/40954440cb4ffe536c377ae9c7302e55bfede3312b4319886485c3825ee30c122a20991acf40f62d6ad72e86408b35b368eca9059f97d23ece4211aac924e00e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 14:33:24.972257) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 14:33:24.972702) ========= 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.mZYaa3mr5y/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.FSOLinWfjx/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.005187 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 14:33:25.022124) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 14:33:25.022447) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 14:33:25.022805) ========= ========= Total master_lag: 0.18 =========