builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0245 starttime: 1469017632.66 results: success (0) buildid: 20160720023520 builduid: 72d439877f5b4bf0864a7a042603676d revision: ed8e23b5e0c7b739e61173bb180cf3410a306679 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 05:27:12.662473) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 05:27:12.662968) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 05:27:12.663293) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 05:27:12.687738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:27:12.688170) ========= 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.pSLgSmE67z/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.FuQnp7NZA9/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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-07-20 05:27:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 67.3M=0s 2016-07-20 05:27:12 (67.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127875 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:27:12.838723) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:27:12.839052) ========= 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.pSLgSmE67z/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.FuQnp7NZA9/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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.025841 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:27:12.887857) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-20 05:27:12.888231) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ed8e23b5e0c7b739e61173bb180cf3410a306679 --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 ed8e23b5e0c7b739e61173bb180cf3410a306679 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pSLgSmE67z/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.FuQnp7NZA9/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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-07-20 05:27:12,969 truncating revision to first 12 chars 2016-07-20 05:27:12,969 Setting DEBUG logging. 2016-07-20 05:27:12,969 attempt 1/10 2016-07-20 05:27:12,969 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ed8e23b5e0c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-20 05:27:14,965 unpacking tar archive at: fx-team-ed8e23b5e0c7/testing/mozharness/ program finished with exit code 0 elapsedTime=2.260164 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-20 05:27:15.170506) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 05:27:15.170899) ========= script_repo_revision: ed8e23b5e0c7b739e61173bb180cf3410a306679 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 05:27:15.171334) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 05:27:15.171650) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 05:27:15.189998) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-07-20 05:27:15.190387) ========= /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.pSLgSmE67z/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.FuQnp7NZA9/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 05:27:15 INFO - MultiFileLogger online at 20160720 05:27:15 in /builds/slave/test 05:27:15 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 05:27:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:27:15 INFO - {'append_to_log': False, 05:27:15 INFO - 'base_work_dir': '/builds/slave/test', 05:27:15 INFO - 'blob_upload_branch': 'Fx-Team', 05:27:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:27:15 INFO - 'branch': 'Fx-Team', 05:27:15 INFO - 'buildbot_json_path': 'buildprops.json', 05:27:15 INFO - 'config_files': ('talos/mac_config.py',), 05:27:15 INFO - 'default_actions': ('clobber', 05:27:15 INFO - 'read-buildbot-config', 05:27:15 INFO - 'download-and-extract', 05:27:15 INFO - 'populate-webroot', 05:27:15 INFO - 'create-virtualenv', 05:27:15 INFO - 'install', 05:27:15 INFO - 'run-tests'), 05:27:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:27:15 INFO - 'download_minidump_stackwalk': True, 05:27:15 INFO - 'download_symbols': 'ondemand', 05:27:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:27:15 INFO - 'tooltool.py': '/tools/tooltool.py', 05:27:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:27:15 INFO - '/tools/misc-python/virtualenv.py')}, 05:27:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:27:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:27:15 INFO - 'installer_path': 'installer.exe', 05:27:15 INFO - 'log_level': 'info', 05:27:15 INFO - 'log_name': 'talos', 05:27:15 INFO - 'log_to_console': True, 05:27:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:27:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:27:15 INFO - 'opt_config_files': (), 05:27:15 INFO - 'pip_index': False, 05:27:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:27:15 INFO - 'cmd': ('bash', 05:27:15 INFO - '-c', 05:27:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:27:15 INFO - 'enabled': True, 05:27:15 INFO - 'halt_on_failure': False, 05:27:15 INFO - 'name': 'check_screen_resolution'},), 05:27:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:27:15 INFO - 'cmd': ('bash', 05:27:15 INFO - '-c', 05:27:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:27:15 INFO - 'enabled': True, 05:27:15 INFO - 'halt_on_failure': False, 05:27:15 INFO - 'name': 'check_screen_resolution'},), 05:27:15 INFO - 'run_cmd_checks_enabled': True, 05:27:15 INFO - 'sps_profile': False, 05:27:15 INFO - 'sps_profile_interval': 0, 05:27:15 INFO - 'suite': 'dromaeojs', 05:27:15 INFO - 'system_bits': '32', 05:27:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:27:15 INFO - 'title': 't-yosemite-r7-0245', 05:27:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:27:15 INFO - 'use_talos_json': True, 05:27:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:27:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:27:15 INFO - 'work_dir': 'build'} 05:27:15 INFO - [mozharness: 2016-07-20 12:27:15.380696Z] Running clobber step. 05:27:15 INFO - Running pre-action listener: _resource_record_pre_action 05:27:15 INFO - Running main action method: clobber 05:27:15 INFO - rmtree: /builds/slave/test/build 05:27:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:27:17 INFO - Running post-action listener: _resource_record_post_action 05:27:17 INFO - [mozharness: 2016-07-20 12:27:17.959268Z] Finished clobber step (success) 05:27:17 INFO - [mozharness: 2016-07-20 12:27:17.959404Z] Running read-buildbot-config step. 05:27:17 INFO - Running pre-action listener: _resource_record_pre_action 05:27:17 INFO - Running main action method: read_buildbot_config 05:27:17 INFO - Using buildbot properties: 05:27:17 INFO - { 05:27:17 INFO - "project": "", 05:27:17 INFO - "product": "firefox", 05:27:17 INFO - "script_repo_revision": "production", 05:27:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:27:17 INFO - "repository": "", 05:27:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:27:17 INFO - "buildid": "20160720023520", 05:27:17 INFO - "pgo_build": "False", 05:27:17 INFO - "basedir": "/builds/slave/test", 05:27:17 INFO - "buildnumber": 853, 05:27:17 INFO - "slavename": "t-yosemite-r7-0245", 05:27:17 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:27:17 INFO - "platform": "yosemite_r7", 05:27:17 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:27:17 INFO - "branch": "fx-team", 05:27:17 INFO - "repo_path": "integration/fx-team", 05:27:17 INFO - "revision": "ed8e23b5e0c7b739e61173bb180cf3410a306679", 05:27:17 INFO - "stage_platform": "macosx64", 05:27:17 INFO - "builduid": "72d439877f5b4bf0864a7a042603676d", 05:27:17 INFO - "slavebuilddir": "test" 05:27:17 INFO - } 05:27:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 05:27:17 INFO - Running post-action listener: _resource_record_post_action 05:27:17 INFO - [mozharness: 2016-07-20 12:27:17.962834Z] Finished read-buildbot-config step (success) 05:27:17 INFO - [mozharness: 2016-07-20 12:27:17.962947Z] Running download-and-extract step. 05:27:17 INFO - Running pre-action listener: _resource_record_pre_action 05:27:17 INFO - Running main action method: download_and_extract 05:27:17 INFO - mkdir: /builds/slave/test/build/tests 05:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:17 INFO - https://queue.taskcluster.net/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:27:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:27:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:27:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:27:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 05:27:19 INFO - Downloaded 1199 bytes. 05:27:19 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:27:19 INFO - Using the following test package requirements: 05:27:19 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:27:19 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:27:19 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:27:19 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:27:19 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:27:19 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:27:19 INFO - u'jsshell-mac.zip'], 05:27:19 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:27:19 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:27:19 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:27:19 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:27:19 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:27:19 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:27:19 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:27:19 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:27:19 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:27:19 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:27:19 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:27:19 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:27:19 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:19 INFO - https://queue.taskcluster.net/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:27:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:27:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:27:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:27:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:27:20 INFO - Downloaded 18842688 bytes. 05:27:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:27:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:27:23 INFO - Return code: 0 05:27:23 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:27:23 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:23 INFO - https://queue.taskcluster.net/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:27:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:27:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:27:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:27:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:27:24 INFO - Downloaded 18842688 bytes. 05:27:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:27:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:27:28 INFO - Return code: 0 05:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:28 INFO - https://queue.taskcluster.net/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:27:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:27:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:27:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:27:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:27:29 INFO - Downloaded 14403317 bytes. 05:27:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:27:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:27:29 INFO - Return code: 0 05:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:29 INFO - https://queue.taskcluster.net/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:27:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:27:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:27:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:27:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 05:27:32 INFO - Downloaded 112933472 bytes. 05:27:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:27:32 INFO - mkdir: /builds/slave/test/properties 05:27:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:27:32 INFO - Writing to file /builds/slave/test/properties/build_url 05:27:32 INFO - Contents: 05:27:32 INFO - build_url:https://queue.taskcluster.net/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:27:33 INFO - Running post-action listener: _resource_record_post_action 05:27:33 INFO - Running post-action listener: set_extra_try_arguments 05:27:33 INFO - [mozharness: 2016-07-20 12:27:33.672876Z] Finished download-and-extract step (success) 05:27:33 INFO - [mozharness: 2016-07-20 12:27:33.673054Z] Running populate-webroot step. 05:27:33 INFO - Running pre-action listener: _resource_record_pre_action 05:27:33 INFO - Running main action method: populate_webroot 05:27:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:27:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:27:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:27:33 INFO - '--tppagecycles', 05:27:33 INFO - '7'], 05:27:33 INFO - 'tests': ['tsvgm']}}, 05:27:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:27:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:27:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:27:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:27:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:27:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:27:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:27:33 INFO - 'talos_options': ['--disable-e10s'], 05:27:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:27:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:27:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:27:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:27:33 INFO - 'talos_options': ['--disable-e10s'], 05:27:33 INFO - 'tests': ['damp', 'tps']}, 05:27:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:27:33 INFO - 'tests': ['damp', 'tps']}, 05:27:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:27:33 INFO - 'tests': ['dromaeo_dom']}, 05:27:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:27:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:27:33 INFO - 'tests': ['basic_compositor_video']}, 05:27:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:27:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:27:33 INFO - 'tests': ['a11yr', 05:27:33 INFO - 'ts_paint', 05:27:33 INFO - 'tpaint', 05:27:33 INFO - 'sessionrestore', 05:27:33 INFO - 'sessionrestore_no_auto_restore', 05:27:33 INFO - 'tabpaint']}, 05:27:33 INFO - 'other-e10s': {'tests': ['a11yr', 05:27:33 INFO - 'ts_paint', 05:27:33 INFO - 'tpaint', 05:27:33 INFO - 'sessionrestore', 05:27:33 INFO - 'sessionrestore_no_auto_restore', 05:27:33 INFO - 'tabpaint']}, 05:27:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:27:33 INFO - 'ts_paint', 05:27:33 INFO - 'tpaint', 05:27:33 INFO - 'sessionrestore', 05:27:33 INFO - 'sessionrestore_no_auto_restore', 05:27:33 INFO - 'tabpaint']}, 05:27:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:27:33 INFO - 'ts_paint', 05:27:33 INFO - 'tpaint', 05:27:33 INFO - 'sessionrestore', 05:27:33 INFO - 'sessionrestore_no_auto_restore', 05:27:33 INFO - 'tabpaint']}, 05:27:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:27:33 INFO - 'tests': ['a11yr', 05:27:33 INFO - 'ts_paint', 05:27:33 INFO - 'tpaint', 05:27:33 INFO - 'sessionrestore', 05:27:33 INFO - 'sessionrestore_no_auto_restore', 05:27:33 INFO - 'tabpaint']}, 05:27:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:27:33 INFO - 'tests': ['a11yr', 05:27:33 INFO - 'ts_paint', 05:27:33 INFO - 'tpaint', 05:27:33 INFO - 'sessionrestore', 05:27:33 INFO - 'sessionrestore_no_auto_restore', 05:27:33 INFO - 'tabpaint']}, 05:27:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:27:33 INFO - 'tests': ['tsvgx', 05:27:33 INFO - 'tsvgr_opacity', 05:27:33 INFO - 'tart', 05:27:33 INFO - 'tscrollx', 05:27:33 INFO - 'cart']}, 05:27:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:27:33 INFO - 'tsvgr_opacity', 05:27:33 INFO - 'tart', 05:27:33 INFO - 'tscrollx', 05:27:33 INFO - 'cart']}, 05:27:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:27:33 INFO - 'talos_options': ['--disable-e10s'], 05:27:33 INFO - 'tests': ['tp5o']}, 05:27:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:27:33 INFO - 'tests': ['tp5o']}, 05:27:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:27:33 INFO - 'talos_options': ['--disable-e10s', 05:27:33 INFO - '--xperf_path', 05:27:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:27:33 INFO - 'tests': ['tp5n']}, 05:27:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:27:33 INFO - 'talos_options': ['--xperf_path', 05:27:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:27:33 INFO - 'tests': ['tp5n']}}, 05:27:33 INFO - 'talos.zip': {'path': '', 05:27:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:27:33 INFO - Running post-action listener: _resource_record_post_action 05:27:33 INFO - [mozharness: 2016-07-20 12:27:33.692270Z] Finished populate-webroot step (success) 05:27:33 INFO - [mozharness: 2016-07-20 12:27:33.692409Z] Running create-virtualenv step. 05:27:33 INFO - Running pre-action listener: _resource_record_pre_action 05:27:33 INFO - Running main action method: create_virtualenv 05:27:33 INFO - Creating virtualenv /builds/slave/test/build/venv 05:27:33 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 05:27:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:27:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:27:33 INFO - Using real prefix '/tools/python27' 05:27:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:27:34 INFO - Installing distribute.............................................................................................................................................................................................done. 05:27:37 INFO - Installing pip.................done. 05:27:37 INFO - Return code: 0 05:27:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:27:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:37 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 0x10ebc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec5d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff292564c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2cd30>, '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 0x10e891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24b20>, '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.pSLgSmE67z/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.FuQnp7NZA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 05:27:37 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 05:27:37 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 05:27:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pSLgSmE67z/Render', 05:27:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:37 INFO - 'HOME': '/Users/cltbld', 05:27:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:37 INFO - 'LOGNAME': 'cltbld', 05:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:37 INFO - 'MOZ_NO_REMOTE': '1', 05:27:37 INFO - 'NO_EM_RESTART': '1', 05:27:37 INFO - 'PAGER': '/bin/cat', 05:27:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:37 INFO - 'PWD': '/builds/slave/test', 05:27:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:37 INFO - 'SHELL': '/bin/bash', 05:27:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FuQnp7NZA9/Listeners', 05:27:37 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:27:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:37 INFO - 'USER': 'cltbld', 05:27:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:37 INFO - 'XPC_FLAGS': '0x0', 05:27:37 INFO - 'XPC_SERVICE_NAME': '0', 05:27:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:37 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 05:27:37 INFO - Cleaning up... 05:27:37 INFO - Return code: 0 05:27:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:27:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:37 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 0x10ebc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec5d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff292564c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2cd30>, '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 0x10e891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24b20>, '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.pSLgSmE67z/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.FuQnp7NZA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 05:27:37 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 05:27:37 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 05:27:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pSLgSmE67z/Render', 05:27:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:37 INFO - 'HOME': '/Users/cltbld', 05:27:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:37 INFO - 'LOGNAME': 'cltbld', 05:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:37 INFO - 'MOZ_NO_REMOTE': '1', 05:27:37 INFO - 'NO_EM_RESTART': '1', 05:27:37 INFO - 'PAGER': '/bin/cat', 05:27:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:37 INFO - 'PWD': '/builds/slave/test', 05:27:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:37 INFO - 'SHELL': '/bin/bash', 05:27:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FuQnp7NZA9/Listeners', 05:27:37 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:27:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:37 INFO - 'USER': 'cltbld', 05:27:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:37 INFO - 'XPC_FLAGS': '0x0', 05:27:37 INFO - 'XPC_SERVICE_NAME': '0', 05:27:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:37 INFO - Downloading/unpacking psutil>=3.1.1 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:27:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:27:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:27:40 INFO - Installing collected packages: psutil 05:27:40 INFO - Running setup.py install for psutil 05:27:40 INFO - building 'psutil._psutil_osx' extension 05:27: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 05:27: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 05:27: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 05:27: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 05:27:41 INFO - building 'psutil._psutil_posix' extension 05:27: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 05:27:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:27:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:27:41 INFO - ^ 05:27:41 INFO - 1 warning generated. 05:27: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 05:27:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:27:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:27:41 INFO - Successfully installed psutil 05:27:41 INFO - Cleaning up... 05:27:41 INFO - Return code: 0 05:27:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27: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 0x10ebc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec5d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff292564c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2cd30>, '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 0x10e891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24b20>, '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.pSLgSmE67z/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.FuQnp7NZA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 05:27: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 05:27: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 05:27:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pSLgSmE67z/Render', 05:27:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:41 INFO - 'HOME': '/Users/cltbld', 05:27:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:41 INFO - 'LOGNAME': 'cltbld', 05:27:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:41 INFO - 'MOZ_NO_REMOTE': '1', 05:27:41 INFO - 'NO_EM_RESTART': '1', 05:27:41 INFO - 'PAGER': '/bin/cat', 05:27:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:41 INFO - 'PWD': '/builds/slave/test', 05:27:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:41 INFO - 'SHELL': '/bin/bash', 05:27:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FuQnp7NZA9/Listeners', 05:27:41 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:27:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:41 INFO - 'USER': 'cltbld', 05:27:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:41 INFO - 'XPC_FLAGS': '0x0', 05:27:41 INFO - 'XPC_SERVICE_NAME': '0', 05:27:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:27:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:27:44 INFO - Installing collected packages: mozsystemmonitor 05:27:44 INFO - Running setup.py install for mozsystemmonitor 05:27:44 INFO - Successfully installed mozsystemmonitor 05:27:44 INFO - Cleaning up... 05:27:44 INFO - Return code: 0 05:27:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27: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 0x10ebc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec5d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff292564c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2cd30>, '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 0x10e891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24b20>, '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.pSLgSmE67z/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.FuQnp7NZA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 05:27: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 05:27: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 05:27:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pSLgSmE67z/Render', 05:27:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:44 INFO - 'HOME': '/Users/cltbld', 05:27:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:44 INFO - 'LOGNAME': 'cltbld', 05:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:44 INFO - 'MOZ_NO_REMOTE': '1', 05:27:44 INFO - 'NO_EM_RESTART': '1', 05:27:44 INFO - 'PAGER': '/bin/cat', 05:27:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:44 INFO - 'PWD': '/builds/slave/test', 05:27:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:44 INFO - 'SHELL': '/bin/bash', 05:27:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FuQnp7NZA9/Listeners', 05:27:44 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:27:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:44 INFO - 'USER': 'cltbld', 05:27:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:44 INFO - 'XPC_FLAGS': '0x0', 05:27:44 INFO - 'XPC_SERVICE_NAME': '0', 05:27:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:45 INFO - Downloading/unpacking jsonschema==2.5.1 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:27:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27:47 INFO - Downloading functools32-3.2.3-2.tar.gz 05:27:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:27:48 INFO - warning: no files found matching '*.txt' 05:27:48 INFO - no previously-included directories found matching 'build' 05:27:48 INFO - no previously-included directories found matching 'dist' 05:27:48 INFO - no previously-included directories found matching '.git*' 05:27:48 INFO - Installing collected packages: jsonschema, functools32 05:27:48 INFO - Running setup.py install for functools32 05:27:48 INFO - warning: no files found matching '*.txt' 05:27:48 INFO - no previously-included directories found matching 'build' 05:27:48 INFO - no previously-included directories found matching 'dist' 05:27:48 INFO - no previously-included directories found matching '.git*' 05:27:48 INFO - Successfully installed jsonschema functools32 05:27:48 INFO - Cleaning up... 05:27:48 INFO - Return code: 0 05:27:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27: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 0x10ebc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec5d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff292564c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2cd30>, '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 0x10e891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24b20>, '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.pSLgSmE67z/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.FuQnp7NZA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 05:27: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 05:27: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 05:27:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pSLgSmE67z/Render', 05:27:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:48 INFO - 'HOME': '/Users/cltbld', 05:27:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:48 INFO - 'LOGNAME': 'cltbld', 05:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:48 INFO - 'MOZ_NO_REMOTE': '1', 05:27:48 INFO - 'NO_EM_RESTART': '1', 05:27:48 INFO - 'PAGER': '/bin/cat', 05:27:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:48 INFO - 'PWD': '/builds/slave/test', 05:27:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:48 INFO - 'SHELL': '/bin/bash', 05:27:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FuQnp7NZA9/Listeners', 05:27:48 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:27:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:48 INFO - 'USER': 'cltbld', 05:27:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:48 INFO - 'XPC_FLAGS': '0x0', 05:27:48 INFO - 'XPC_SERVICE_NAME': '0', 05:27:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:48 INFO - Downloading/unpacking blobuploader==1.2.4 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27:51 INFO - Downloading blobuploader-1.2.4.tar.gz 05:27:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:27:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:27:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27:52 INFO - Downloading docopt-0.6.1.tar.gz 05:27:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:27:52 INFO - Installing collected packages: blobuploader, requests, docopt 05:27:52 INFO - Running setup.py install for blobuploader 05:27:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:27:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:27:52 INFO - Running setup.py install for requests 05:27:53 INFO - Running setup.py install for docopt 05:27:53 INFO - Successfully installed blobuploader requests docopt 05:27:53 INFO - Cleaning up... 05:27:53 INFO - Return code: 0 05:27:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27: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 0x10ebc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec5d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff292564c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2cd30>, '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 0x10e891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24b20>, '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.pSLgSmE67z/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.FuQnp7NZA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 05:27: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 05:27: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 05:27:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pSLgSmE67z/Render', 05:27:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:53 INFO - 'HOME': '/Users/cltbld', 05:27:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:53 INFO - 'LOGNAME': 'cltbld', 05:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:53 INFO - 'MOZ_NO_REMOTE': '1', 05:27:53 INFO - 'NO_EM_RESTART': '1', 05:27:53 INFO - 'PAGER': '/bin/cat', 05:27:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:53 INFO - 'PWD': '/builds/slave/test', 05:27:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:53 INFO - 'SHELL': '/bin/bash', 05:27:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FuQnp7NZA9/Listeners', 05:27:53 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:27:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:53 INFO - 'USER': 'cltbld', 05:27:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:53 INFO - 'XPC_FLAGS': '0x0', 05:27:53 INFO - 'XPC_SERVICE_NAME': '0', 05:27:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:27:53 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-kCJJdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:27:53 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-ds3dFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:27:53 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-mdsNaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:27:53 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-eOU4y5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:27:53 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-4l4H_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:27:54 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-keokdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:27:54 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-AWw_LH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:27:54 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-LuAtAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:27:54 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-ZI2dT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:27:54 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-AtL91Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:27:54 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-Cg1T2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:27:54 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-y0Y5xJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:27:54 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-ONj54q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:27:54 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-QgU3fA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:27:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:54 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-zcX4BF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:27:55 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-XZKwzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:27:55 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-PPx4u_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:27:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:27:55 INFO - Running setup.py install for manifestparser 05:27:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:27:55 INFO - Running setup.py install for mozcrash 05:27:55 INFO - Running setup.py install for mozdebug 05:27:55 INFO - Running setup.py install for mozdevice 05:27:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:27:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:27:55 INFO - Running setup.py install for mozfile 05:27:56 INFO - Running setup.py install for mozhttpd 05:27:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:27:56 INFO - Running setup.py install for mozinfo 05:27:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:27:56 INFO - Running setup.py install for mozInstall 05:27:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:27:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:27:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:27:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:27:56 INFO - Running setup.py install for mozleak 05:27:56 INFO - Running setup.py install for mozlog 05:27:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:27:56 INFO - Running setup.py install for moznetwork 05:27:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:27:56 INFO - Running setup.py install for mozprocess 05:27:57 INFO - Running setup.py install for mozprofile 05:27:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:27:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:27:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:27:57 INFO - Running setup.py install for mozrunner 05:27:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:27:57 INFO - Running setup.py install for mozscreenshot 05:27:57 INFO - Running setup.py install for moztest 05:27:57 INFO - Running setup.py install for mozversion 05:27:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:27:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:27:57 INFO - Cleaning up... 05:27:57 INFO - Return code: 0 05:27:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ebc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec5d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff292564c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2cd30>, '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 0x10e891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24b20>, '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.pSLgSmE67z/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.FuQnp7NZA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 05:27:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:27:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pSLgSmE67z/Render', 05:27:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:57 INFO - 'HOME': '/Users/cltbld', 05:27:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:57 INFO - 'LOGNAME': 'cltbld', 05:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:57 INFO - 'MOZ_NO_REMOTE': '1', 05:27:57 INFO - 'NO_EM_RESTART': '1', 05:27:57 INFO - 'PAGER': '/bin/cat', 05:27:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:57 INFO - 'PWD': '/builds/slave/test', 05:27:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:57 INFO - 'SHELL': '/bin/bash', 05:27:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FuQnp7NZA9/Listeners', 05:27:57 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:27:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:57 INFO - 'USER': 'cltbld', 05:27:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:57 INFO - 'XPC_FLAGS': '0x0', 05:27:57 INFO - 'XPC_SERVICE_NAME': '0', 05:27:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:27:58 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-2vTmrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:27: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)) 05:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:27:58 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-N_zyHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:27: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)) 05:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:27:58 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-9VA5n6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:27: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)) 05:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:27:58 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-T6eQJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:27:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:27:58 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-ebz9Ty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:27:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:27:58 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-_gOdvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:27:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:27:58 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-cP8ZOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:27:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:27:58 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-KgHCcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:27:58 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)) 05:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:27:58 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-qOTqyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:27: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)) 05:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:27:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-1wwM9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:27:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 05:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:27:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-2aHQzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:27: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)) 05:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:27:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-eSD46N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:27: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)) 05:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:27:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-yMIIU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:27:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:27:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-WT85pM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:27:59 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)) 05:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-3aMlBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:27:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-mDfNVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:27:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:27:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-0u0wlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:27:59 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)) 05:27:59 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)) 05:27:59 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)) 05:27:59 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)) 05:27:59 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)) 05:27:59 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)) 05:27:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:02 INFO - Downloading blessings-1.6.tar.gz 05:28:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:28:02 INFO - Installing collected packages: blessings 05:28:02 INFO - Running setup.py install for blessings 05:28:02 INFO - Successfully installed blessings 05:28:02 INFO - Cleaning up... 05:28:02 INFO - Return code: 0 05:28:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:28:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:28:02 INFO - Reading from file tmpfile_stdout 05:28:02 INFO - Current package versions: 05:28:02 INFO - blessings == 1.6 05:28:02 INFO - blobuploader == 1.2.4 05:28:02 INFO - docopt == 0.6.1 05:28:02 INFO - functools32 == 3.2.3-2 05:28:02 INFO - jsonschema == 2.5.1 05:28:02 INFO - manifestparser == 1.1 05:28:02 INFO - mozInstall == 1.12 05:28:02 INFO - mozcrash == 0.17 05:28:02 INFO - mozdebug == 0.1 05:28:02 INFO - mozdevice == 0.48 05:28:02 INFO - mozfile == 1.2 05:28:02 INFO - mozhttpd == 0.7 05:28:02 INFO - mozinfo == 0.9 05:28:02 INFO - mozleak == 0.1 05:28:02 INFO - mozlog == 3.3 05:28:02 INFO - moznetwork == 0.27 05:28:02 INFO - mozprocess == 0.23 05:28:02 INFO - mozprofile == 0.28 05:28:02 INFO - mozrunner == 6.12 05:28:02 INFO - mozscreenshot == 0.1 05:28:02 INFO - mozsystemmonitor == 0.3 05:28:02 INFO - moztest == 0.7 05:28:02 INFO - mozversion == 1.4 05:28:02 INFO - psutil == 3.1.1 05:28:02 INFO - requests == 1.2.3 05:28:02 INFO - wsgiref == 0.1.2 05:28:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:02 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 0x10ebc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec5d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff292564c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2cd30>, '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 0x10e891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24b20>, '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.pSLgSmE67z/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.FuQnp7NZA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 05:28:02 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 05:28:02 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 05:28:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pSLgSmE67z/Render', 05:28:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:02 INFO - 'HOME': '/Users/cltbld', 05:28:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:02 INFO - 'LOGNAME': 'cltbld', 05:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:02 INFO - 'MOZ_NO_REMOTE': '1', 05:28:02 INFO - 'NO_EM_RESTART': '1', 05:28:02 INFO - 'PAGER': '/bin/cat', 05:28:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:02 INFO - 'PWD': '/builds/slave/test', 05:28:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:02 INFO - 'SHELL': '/bin/bash', 05:28:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FuQnp7NZA9/Listeners', 05:28:02 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:28:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:02 INFO - 'USER': 'cltbld', 05:28:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:02 INFO - 'XPC_FLAGS': '0x0', 05:28:02 INFO - 'XPC_SERVICE_NAME': '0', 05:28:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:03 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)) 05:28:03 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)) 05:28:03 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)) 05:28:03 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)) 05:28:03 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)) 05:28:03 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)) 05:28:03 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)) 05:28:03 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)) 05:28:03 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)) 05:28:03 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)) 05:28:03 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)) 05:28:03 INFO - Cleaning up... 05:28:03 INFO - Return code: 0 05:28:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:28: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:03 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 0x10ebc8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec5d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff292564c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb2cd30>, '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 0x10e891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24b20>, '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.pSLgSmE67z/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.FuQnp7NZA9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 05:28:03 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 05:28:03 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 05:28:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pSLgSmE67z/Render', 05:28:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:03 INFO - 'HOME': '/Users/cltbld', 05:28:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:03 INFO - 'LOGNAME': 'cltbld', 05:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:03 INFO - 'MOZ_NO_REMOTE': '1', 05:28:03 INFO - 'NO_EM_RESTART': '1', 05:28:03 INFO - 'PAGER': '/bin/cat', 05:28:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:03 INFO - 'PWD': '/builds/slave/test', 05:28:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:03 INFO - 'SHELL': '/bin/bash', 05:28:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FuQnp7NZA9/Listeners', 05:28:03 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:28:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:03 INFO - 'USER': 'cltbld', 05:28:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:03 INFO - 'XPC_FLAGS': '0x0', 05:28:03 INFO - 'XPC_SERVICE_NAME': '0', 05:28:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:28:03 INFO - Cleaning up... 05:28:03 INFO - Return code: 0 05:28:03 INFO - Running post-action listener: _resource_record_post_action 05:28:03 INFO - Running post-action listener: _start_resource_monitoring 05:28:03 INFO - Starting resource monitoring. 05:28:03 INFO - [mozharness: 2016-07-20 12:28:03.550964Z] Finished create-virtualenv step (success) 05:28:03 INFO - [mozharness: 2016-07-20 12:28:03.551972Z] Running install step. 05:28:03 INFO - Running pre-action listener: _resource_record_pre_action 05:28:03 INFO - Running main action method: install 05:28:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:28:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:28:03 INFO - Reading from file tmpfile_stdout 05:28:03 INFO - Detecting whether we're running mozinstall >=1.0... 05:28:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:28:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:28:03 INFO - Reading from file tmpfile_stdout 05:28:03 INFO - Output received: 05:28:03 INFO - Usage: mozinstall [options] installer 05:28:03 INFO - Options: 05:28:03 INFO - -h, --help show this help message and exit 05:28:03 INFO - -d DEST, --destination=DEST 05:28:03 INFO - Directory to install application into. [default: 05:28:03 INFO - "/builds/slave/test"] 05:28:03 INFO - --app=APP Application being installed. [default: firefox] 05:28:03 INFO - mkdir: /builds/slave/test/build/application 05:28:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:28:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:28:38 INFO - Reading from file tmpfile_stdout 05:28:38 INFO - Output received: 05:28:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:28:38 INFO - Running post-action listener: _resource_record_post_action 05:28:38 INFO - [mozharness: 2016-07-20 12:28:38.142349Z] Finished install step (success) 05:28:38 INFO - [mozharness: 2016-07-20 12:28:38.142501Z] Running run-tests step. 05:28:38 INFO - Running pre-action listener: _resource_record_pre_action 05:28:38 INFO - Running main action method: run_tests 05:28:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:28:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:28:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:28:38 INFO - 2016-07-20 05:28:38.156 screenresolution[1641:7424] starting screenresolution argv=screenresolution get 05:28:38 INFO - 2016-07-20 05:28:38.159 screenresolution[1641:7424] Display 0: 1600x1200x32@60 05:28:38 INFO - 2016-07-20 05:28:38.165 screenresolution[1642:7426] starting screenresolution argv=screenresolution list 05:28:38 INFO - Available Modes on Display 0 05:28:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:28:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:28:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:28:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:28:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:28:38 INFO - Intel Iris: 05:28:38 INFO - Chipset Model: Intel Iris 05:28:38 INFO - Type: GPU 05:28:38 INFO - Bus: Built-In 05:28:38 INFO - VRAM (Dynamic, Max): 1536 MB 05:28:38 INFO - Vendor: Intel (0x8086) 05:28:38 INFO - Device ID: 0x0a2e 05:28:38 INFO - Revision ID: 0x0009 05:28:38 INFO - Displays: 05:28:38 INFO - Display: 05:28:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:28:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:28:38 INFO - Display Serial Number: 1600x1200 60 05:28:38 INFO - Main Display: Yes 05:28:38 INFO - Mirror: Off 05:28:38 INFO - Online: Yes 05:28:38 INFO - Rotation: Supported 05:28:38 INFO - Return code: 0 05:28:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:28:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:28:38 INFO - Python 2.7.3 05:28:38 INFO - Return code: 0 05:28:38 INFO - grabbing minidump binary from tooltool 05:28:38 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:38 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 0x10eb2cd30>, '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 0x10e891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:28:38 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 05:28:38 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 05:28:38 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 05:28:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:28:38 INFO - Return code: 0 05:28:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:28:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:28:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:28:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:28:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:28:38 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/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0245', '--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 05:28:38 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/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0245 --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 05:28:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pSLgSmE67z/Render', 05:28:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:38 INFO - 'HOME': '/Users/cltbld', 05:28:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:38 INFO - 'LOGNAME': 'cltbld', 05:28:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:28:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:28:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:38 INFO - 'MOZ_NO_REMOTE': '1', 05:28:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:28:38 INFO - 'NO_EM_RESTART': '1', 05:28:38 INFO - 'PAGER': '/bin/cat', 05:28:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:38 INFO - 'PWD': '/builds/slave/test', 05:28:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:28:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:38 INFO - 'SHELL': '/bin/bash', 05:28:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FuQnp7NZA9/Listeners', 05:28:38 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 05:28:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:38 INFO - 'USER': 'cltbld', 05:28:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:38 INFO - 'XPC_FLAGS': '0x0', 05:28:38 INFO - 'XPC_SERVICE_NAME': '0', 05:28:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:38 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/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0245', '--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 05:28:38 INFO - mozversion application_buildid: 20160720023520 05:28:38 INFO - mozversion application_changeset: ed8e23b5e0c7b739e61173bb180cf3410a306679 05:28:38 INFO - mozversion application_display_name: Nightly 05:28:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:28:38 INFO - mozversion application_name: Firefox 05:28:38 INFO - mozversion application_remotingname: firefox 05:28:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:28:38 INFO - mozversion application_vendor: Mozilla 05:28:38 INFO - mozversion application_version: 50.0a1 05:28:38 INFO - mozversion platform_buildid: 20160720023520 05:28:38 INFO - mozversion platform_changeset: ed8e23b5e0c7b739e61173bb180cf3410a306679 05:28:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:28:38 INFO - mozversion platform_version: 50.0a1 05:28:38 INFO - using testdate: 1469017718 05:28:38 INFO - actual date: 1469017718 05:28:38 INFO - starting webserver on 'localhost:49266' 05:28:38 INFO - SUITE-START | Running 2 tests 05:28:38 INFO - TEST-START | dromaeo_css 05:28:38 INFO - operating with platform_type : mac_ 05:28:38 INFO - Initialising browser for dromaeo_css test... 05:28:38 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmp2crGuX/profile http://localhost:49266/getInfo.html) 05:28:41 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 05:28:41 INFO - PROCESS | 1652 | colorDepth:24 05:28:41 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 05:28:41 INFO - PROCESS | 1652 | __metrics 05:28:41 INFO - PROCESS | 1652 | 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" 05:28:41 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:28:42 INFO - TEST-INFO | 1652: exit 0 05:28:42 INFO - Browser initialized. 05:28:42 INFO - Running cycle 1/1 for dromaeo_css test... 05:28:42 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmp2crGuX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:54 INFO - PROCESS | 1654 | RSS: Main: 245547008 05:28:54 INFO - PROCESS | 1654 | 05:29:45 INFO - PROCESS | 1654 | 1469017785867 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] 05:31:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 05:31:57 INFO - PROCESS | 1654 | RSS: Main: 374566912 05:31:57 INFO - PROCESS | 1654 | 05:35:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 05:35:01 INFO - PROCESS | 1654 | RSS: Main: 380235776 05:35:01 INFO - PROCESS | 1654 | 05:38:03 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 05:38:04 INFO - PROCESS | 1654 | RSS: Main: 370638848 05:38:04 INFO - PROCESS | 1654 | 05:41:06 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 05:41:07 INFO - PROCESS | 1654 | RSS: Main: 375115776 05:41:07 INFO - PROCESS | 1654 | 05:44:10 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 05:44:11 INFO - PROCESS | 1654 | RSS: Main: 338206720 05:44:11 INFO - PROCESS | 1654 | 05:47:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 05:47:14 INFO - PROCESS | 1654 | RSS: Main: 384536576 05:47:14 INFO - PROCESS | 1654 | 05:47:14 INFO - PROCESS | 1654 | __start_tp_report 05:47:14 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 05:47:14 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 05:47:14 INFO - PROCESS | 1654 | |i|pagename|runs| 05:47:14 INFO - PROCESS | 1654 | |0;dojo.html;2821.520341541938;3376.6116896556905;3384.103212648647;3389.3390788796196;3445.6037555681105;5581.609287349877;5594.412586678396;5647.802326918559;5683.602147849647;5702.828915132547;5662.235598194243;5710.600257981943;5715.457031582004;5717.456841600052;5737.885242295162;5168.741562921858;5198;5242.161254199329;5253.44838791927;5304.655197412175;5277.12927366984;5291.788328466857;5294.205869119624;5296.09966305727;5297.966896455193;5152.819651312205;5169.819056333029;5173.146430838907;5202.505761952627;5293.470652934695;5231.633785635007;5254.789808407659;5278.920816187722;5279.788808447696;5344.305240318734;5072.898542029138;5079.1365467870555;5127.743612819344;5192.1432963560965;5204.557612602934;5624.746886390122;5650.943490565124;5666.263385759866;5706.914396284059;5726.427357264281;6155.353687862755;6166.568340216187;6214.192155019864;6216.471599913967;6238.87522249557;6073.568857379306;6196.194494715658;6233;6251.187345645082;6266.749330026793;5753.367129615739;5784.623999440023;5810.767569297265;5814.186013958049;5884.293884733859;6278.277998030194;6279.560430769893;6294.779682711083;6329.68351582419;6334.714937827716;6008.429199226154;6070.453659170696;6136.693165341715;6139.201903752483;6151.692415379213;5378.542823859937;5865.941340586625;6165.414285642857;6215.9378406216265;6218.31598524162;6199.56603037783;6211.937880621136;6221.222347206599;6233.189685340877;6234.532410069263;6075.149479072933;6160.322364539897;6245.96877015612;6260.937390626126;6262.718177682015;4943.90112197754;4945.802167913315;4958.157113290749;5005.649604527648;5010.849674509771;4387.934180987288;4455.554444555519;4461.843835465743;4472.485664148665;4504.234280172379;5573.498385145356;5622.943770562242;5696.943030569724;5728.198052272685;5764.048931926269;6172.938270617326;6190.876182476415;6192.195014648067;6232.283287421914;6270.2789179244055;5734.455226753452;5739.799107031317;5741.655500669973;5759.337676167211;5790.478856902815;5431.219313212888;5455.972720136374;5471.343438787371;5472.616916815785;5479.53423958968;6222.595531290361;6223;6246.593971391845;6270.404311590391;6323.557350985387;6276.874462510816;6277.372262773686;6284.937150628435;6285.528585356208;6296.181496405438;6263.6241825490615;6300.338464461165;6301.338359472189;6315.273743519554;6321.841953951188;6081.787137450345;6131.233595800525;6133.8855759873895;6164.876702466016;6206.968965155145;6207.410296021871;6245.7189426476825;6257.311695713559;6261.123442717932;6261.749530018748;5920.940790592039;6147.784827531015;6166.907496387647;6189.566730328834;6199.070139479114;5087.796488140433;5974.40255974399;6056.66688332146;6095.268567771718;6101.84745381369;5937.940620593911;5979.880402392015;6004.789832356021;6026.216591843207;6064.968955277702;6125.927962606437;6157.599756015845;6163.876722465436;6170.568060235741;6176.66028368444;4869.902601947871;5790.363060063474;5819.883602328015;5954.821355359346;5957.314908785545 05:47:14 INFO - PROCESS | 1654 | |1;ext.html;466.96964697294675;544.4881811097569;544.5262621519277;544.9156179203386;556.7717235933268;30042;30871.691283087162;31651.68348316516;41168.99999999996;42205.15589688205;44234.55765442344;44676.55323446765;44691.329630055574;44700.55299447005;44799.32801007987;9514.42913425195;9704.029597040275;9704.22366210705;9710.854337184948;9721.368111072794;8544.316454683641;8843.646254149826;8849.42478738883;8947.731568052935;9005.684801031965;11433.199676022681;11582.362970036645;11599.478023488964;11867.228630139056;11953.940230298836;11905.464254108543;12000.099992500554;12016.939915300454;12032.27806331623;12039.759204815855;8437.535935523543;11748.765024699458;11816.29102253859;11823.054155667525;11825.881741182564;42898.14203715908;43750;43811.342829857575;43838.34242486365;43871.683849484565;3432.5880894292595;3497.702695270905;3510.824458777051;3540.168060505779;3646.2342907989355;31081.533776993365;31754.841225793953;33269.83365083159;33270;33329.83335083309;3066.9233269168226;3212.6947939945903;3355.4295769719206;3373.2916087621384;3408.5398471206317;521937.46437748993;524044;527401;530557;534085;53930.92138157293;62344.68827655833;63875.68062159659;63951.680241599424;65415.67292163509;67501;67676.96969090935;68005.97991030138;68076.31923680799;68158.65920670365;17030.829691702926;17177.227024783915;17192.054437006085;17192.656146876994;17193.91403042977;12788.232706037668;12851.807222891664;12868.806967895489;12871.356432178354;12891.48434062627;17474.55356250126;19539.413817585497;19582.90208548948;19869.403917882486;19905.60188796216;7012.123484564429;7214.134303883568;8084.140647651073;8102.554359510284;8115.959420202979;899.8740176375313;913.6071489259549;1004.6985904228702;1044.7649278912183;1063.4842101580728;29648.85175574108;30559.541606875915;31257.374852502824;31403.214919626735;32172.356552868812;1124.4321617583068;1140.3272069479046;1145.73075327298;1180.0618508285934;1234.327291626066;16229.513114606581;16350.264238109314;16361.672766544603;16645.916770416312;16679.91660041692;174386;175873;176248;616982;619947;61514;62264.754704905645;62339;62342.688286558274;62390.376096238455;1388.167099740148;1434.5911415246812;1455.294182321552;1458.6863824277832;1463.619458940683;29771.553426698185;30389.392212156075;30748.692513074584;30850.92021779227;31618.841905790323;51876.481235187166;52290.738546307024;52322;52360.476395237085;52383.73808130935;408485.9575702103;412097;412511;412988;414891;26944.32639184036;27182.91268349244;27321.45357092807;27459.31351716223;27514;34398.88413329253;41292;42219.15561688688;49894.501054988985;52304.21543676924;40554.78335649935;40683.796581016904;41009;41192.970175745846;42474.36288455738;40943.590564093975;41626.791866040476;41953;42067.7896610515;42278;39658;40056;40657.1868562633;40875.97810054773;40893.977650558976;32184.678153218167;34081.31837363189;35283;36583.90248292556;36636.45045324269;26206;26661;26736.59895101536;27104;27254.454910902066 05:47:14 INFO - PROCESS | 1654 | |2;jquery.html;320.618464027807;350.1945525291829;386.76407391491193;397.09264331003664;403.44324831732223;19921.800781992177;20320.28878989236;21161.894190529063;21181.68227476585;21356.25253116141;12227.572034978777;12353.382330883465;16406.85152039358;21179.68230476544;21939.451513712163;5318.441563635821;5345.625806193559;5570.8607284818;5574.693391863446;5581.441855814406;4839.419269687643;5413.566914646838;5429.945700542983;5437.34751829781;5442.102053161224;5422.40353561108;5430.3755068167075;5436.075867102602;5438.7280635968245;5447.945520544803;5791.10237913122;5919.408059194089;5962.850928726773;5969.910451343233;5972.283326000907;5044.419891712463;5173.405058418292;5237.973810130963;5287.41838397776;5295.894082118374;13484.865151348458;13587.252701101435;13679.726405471834;13689.178649281075;13708.040437169402;2194.7366316042176;2277.760835112306;2335.7547457517057;2362.208660098869;2366.077229880348;9938.503074846229;10072.043155900177;10084.394936303845;10367.533460994273;10409.635662751843;1853.6292741451764;1895.9336423225259;1938.0697265312588;1953.1210955070276;1959.9710152170082;115329;117790.28642590286;124799;125669;129069.06396404062;11218;11446.713832154262;11570.88429115698;11573.363465009337;11731.178817482865;14749.041312314665;14764.040337378035;14775.335109920079;14785.186814725294;14788.556343309718;8538.658453661908;8679.956600216958;8698.304135669132;8710.825783484295;8851.778705532413;8728.127187281221;8730.476171429735;8734.91265087341;8808.779780505538;8967.686130985385;3860.652541271299;4506.436695413074;4520.932186017212;4525.954740452553;4543.863684089482;1672.2809192047293;1901.4330653617506;1933.2943475631293;1938.1684308328495;1938.5347516595916;908.4413085952224;972;986.8569057486624;996.8554559588964;1002.1231422505308;22098.157489036057;23544.17595384153;23776.286711398687;23967.16114935969;24008;854.679495189304;935.9251259899328;1011.8785745710562;1042.854000439939;1048.6487026846091;10462.169120403898;11504.76990460186;11716.70708232284;11738.94130529342;11746.471408786625;317283;319647;319752;320520;630973.6769742288;95507.52246238863;125368.09003144686;129480;133954.99067513994;134457;976.5654283843621;978.1490103609763;1006.7030226083176;1017.5624481473037;1021.1473419694489;18531.98074105937;19105.331313403964;19108.713369299192;19154.7126793101;19214.21393897905;37342;52733.94532109413;53271;53392.73303633457;53486.465135348146;81960.36079278501;83142.58428707818;84924;85348.57325713332;86011.5595376178;5751.769929202785;6669.899951500634;7402.814929626802;7554.584497852671;8004.119546849958;10776.730581735519;10984.340939543654;12247.326397048248;12282.75434491323;12696.047796415121;11096.500657470271;11143.609973651208;11157.21899467062;11194.944025279821;11208.66374008781;10545.630902918361;10555.577776888838;10683;11069.169812263952;11118.221724479208;10722.927707229215;11010.449477526252;11065.280756750786;11378.886211138113;11453.885461145615;7602;7825.4130940178575;7988.12130665638;8022.077461091816;8040.83918321642;10621.362718236931;10640.148788096778;10664.946675266574;11044.558217671203;11084.224104312621 05:47:14 INFO - PROCESS | 1654 | |3;mootools.html;1570.5209910977148;1607.3731244814524;1843.732658764479;1944.3972368565737;1983.305842954965;8430.704925327615;8898.421602595834;8903.95548022259;8928.508932008745;8935.687250946217;7620.6951721931055;7832.216778322165;8378.12029736878;8612.655493780241;8728.170823771747;2326.09282379872;2358.5282943411303;2364.7044119485063;2365.1485465232504;2374.525094981002;2328.7089113860766;2338.859668419892;2372.9288121356385;2374.6319320505263;2380.9642855357183;2081.0843228979274;2158.6114499390105;2158.7949144831214;2166.090242098315;2350;2284.8629082255034;2374.893129809163;2375.798057165143;2381.1784934197676;2385.2367242482414;2355.281639100074;2356.4580146566386;2383.011050414076;2388.152205966874;2403.771641694036;8660.350473714434;8749.387542872002;8754;8754.080821513713;8758;5105.948940510584;5256.053910296145;5260.079486089919;5268.525832675077;5271.499207575274;3357.8153201573905;3476.89569312921;3539.504469374264;3550.343186510504;3564.9108772280642;1044.1959691037857;1078.724925144091;1096.5175322857917;1101.884302148271;1792.9462116136538;11148;11580.305181689042;11591.594294199662;11595.710107247301;11601.303921764722;3850.634189752025;3852.24881148179;4358.847440339573;4700.858974230779;4715.080559290974;10035.849462258071;10042.598296068076;10055.44695041765;10058.899411005943;10067.798644027078;4442.267025940748;5219.5041471060185;5251.789928402821;5256.763445644879;5259.21111833228;4297.591728785823;4442.666799990014;4671.159191345522;4774.474807771142;4808.687435316763;3557.911052223715;3759.9660093473967;3931.0761970936805;3996.980015099906;4037.1723796621623;1251.4931452761646;1268.340462959256;1271.1165739810742;1271.4405661508906;1283.6341642631805;908.6138391183721;956.268454632207;957.2342126299072;965.956766691971;1011.9241056920761;5872.941270587325;5965.463108320272;6058.848528786815;6082.391760823883;6101.054336577838;429.65198189466633;442.8649261975064;448.6814361803091;452.7193140253064;455.07165382619746;4048.4939382577177;4055.986003499125;4065.7560546367313;4163.2298024865495;4174.415581818548;10366.170706343475;10663.306885052447;10728.249022568501;10733.248672592843;10752.462376881125;10694.197935154894;10697.572097116028;10705.892941070646;10712.35725856451;10722.303050301705;6593.7362505500205;7763.767086987513;8159.061707903667;8196.959015204886;8303.335733141334;5949.167116603766;6060.727267272895;6119.938800611937;6206.224221972254;6237.407446292595;10021.799564008825;10128.493575321352;10136.89863101389;10147.086762191439;10162.085412312927;9963.402195868272;10017.499125043865;10130.138938190335;10217.540210690388;10393.06462418386;6835.282295358916;7061.470389720689;7067;7412.184659687322;7598.240175982358;7837.451378403457;7898.68405263806;7903.525788452711;8039.67841286342;8326.333893288402;6310.400511951358;7342.5227360221415;7423.072115985502;7477.401807855467;7582.507137036075;7249.637518123967;7324.267573242633;7332.9266707332245;7359.4480413968095;7387.593682347522;6970.128733908262;7006;7052.647367631701;7063.187733410518;7065.258147894397;4800.3759511263315;4886.877828054327;5059.46875578059;5082.517160869711;5221.269022336952;5032.672876263031;5034.3455350805625;5209;5216.191490319006;5329.973350133379 05:47:14 INFO - PROCESS | 1654 | |4;prototype.html;297.8272601890905;300.95485677148423;309.1282583115614;312.85921335399075;313.7584060273588;4614.8618939121425;4826.493218212086;5120.61595380347;5184.196449550316;5347.192723819997;4321.416608757817;5145.974270128644;5230.372355317358;5277.915223486721;5314.707691076999;4037.899052523681;4047;4059.4722686050845;4069.8168582413714;4080.4287399764057;3893.941590876125;4069.2065047315787;4082.122343696105;4101.569335219804;4141.902395865083;3344.331133773243;4042.818073186699;4057.066874618825;4087.0395457067566;4108.5686002969705;3347.9497807532907;3840.7695538267653;4035.919281614366;4075.9430427179614;4101.923557332001;3008.5487176923416;3518.5074089627474;3548.787072775616;3554.662307080823;3575.8557261676274;4230.809613567397;4551.353902424647;4651.86044418668;5053.646244762831;5120.92318615221;3744.606816284278;3952.9011774705573;4621.4685311189305;4661.277501987197;4676.321933319649;4567.725936443824;4680.672352935296;5422.945770542323;5540.473655002808;5588.161775733632;3272.460044092722;3445.689887910075;3540.9822950885336;3742.850285988557;3946.355941519189;4557.74932378719;5389.9191512127345;5871.618344807616;5917.8816423671715;5924.1113832924975;3688.731430170426;3863.265979463893;3880.835749275206;3887.8833634990997;3897.5517815451017;3644.9817750911607;3932.193900250442;3941.684665226775;3947.50656167979;3953.3081710700512;3955.011247188203;4292.871213863589;4512.368268442421;4568.33759104928;4620.653450991189;5567.329801059666;5657;5662.462066103713;5668.433156684298;5677.545796336283;1130.4743294367993;1256.8303279057393;1275.4451813461174;1300.8959283257316;1337.7926421404698;765.624843826521;867.002946611402;872.646578135856;886.2599729226168;904;4732.550407711262;5208.114620514521;5224.320838290998;5231.346081739783;5237.0835103857435;4835.782389792397;5342.439043900412;5396.730163491809;5729.684867332337;5733.541316694655;4986;4992.226204938241;5155.458676838953;5158.561522270574;5175.637705360588;2191.4411824984527;2330.988345058264;2520.432902596901;2532.6956617342084;2576.9434531841853;4026.859059932888;4046.393041043867;4652.974787229784;4892.434420985322;4901.58336541398;4147.6267135957905;4175.394567787654;4372.835446454025;4515.645306408064;4569.012479571331;4195.727277726938;4519.023259531657;4646.651501137429;4928.644622728707;5055.53389517036;4788.353572267839;4790.7125572464365;4791.904161916812;4792.664513484023;4839.903201936012;3969.285528604879;4396.560343965578;4436.803946483346;4706.552877476634;4718.622510199244;4356.6950313477755;4589.328537170317;4727.149113159664;4951.37577084146;4955.752212389438;2914.1694617034045;3064.272797210529;3157.8000359863104;3208.550802887551;3321.8173000485203;3913.8708662361482;4614.515475874985;4739.7867095981455;4771.95228047715;4940.6788558743565;4898.363212782458;5176.767045482887;5341;5503.229547863382;5683.460071293221;5174.189033838277;5418.674879507243;5599.860003499945;5622.859428514156;5632.633878797865;4683.508231635629;4808.927866082082;5494.2857428534035;5516.806911758069;5575.916361254665;4763.237881938873;5292.941411717748;5540.22436858832;5704.486596206361;5740.297508107164;4572.1312950539295;5182.740862956913;5506.6959824105215;5521.889562208653;5536.861578460571 05:47:14 INFO - PROCESS | 1654 | |5;yui.html;744.4379493482421;744.746786092728;790.4150928313051;826.5123577089516;850.6512329944723;14792.334344954475;15947.122908240042;15961.281742321595;15976.680466390666;15984.280707368182;14686.926565367185;14852.108873467572;14864.925675371634;14873.107613543196;14879.18164500952;714.2179313651543;737.1117803047315;887.9067697891725;898.2454738020061;925.9814803703922;894.025512191711;900.8063266397724;902.3187493442442;908.2643059122132;910.9635614575409;966.7293157915794;969.2391472693937;969.612155137947;969.7575606098476;970.335320305593;962.1388856973005;962.9711108666751;963.1476143612953;964;965.9517024148764;559.1110134885511;573.6042130929687;574.8821491594255;576.6914700635182;719.9136103667541;13422;13844.446222151102;13904.721905561933;13964.581062568139;14042.297885105703;13782.586522404345;14189;14498.782518262235;14582.416703331854;14588.978771486;13198.340082995812;13323.334583177102;13358.532451364155;13488.19070855742;13504.324783760772;4630.583247507707;4637.373954516164;4677.508861569554;4807.062622788558;4855.951440485621;107559.46220268849;108804;108951.36572951333;113194.43402783015;114330;11312.490937907814;11455.255408398427;11509;11579.652610421701;11953.581624643095;22923.88538057299;23158.53682926332;23168.76831231666;23174.884125579265;23175.536489270122;8077.8788318175275;8212;8214.314131126652;8308.12764659714;8340.958295208486;4368.213721530155;4457.3091170868565;4476.977615111904;4488.798004089824;4615.815367385267;3448.3103379323734;3918.4122381642983;4076.2458945095254;4084.223997440536;4108.4864391951005;706.9007693037273;730.6821532633321;731.4294850016995;738.2875525118179;740.3558903753661;605.2132228103447;615.9322474527798;659.6082266417854;664.3622122762106;716.097716876739;6688.230853451818;9252.768680783034;9856.162226210708;10283.691489255334;10488.26582139243;578.4720770234104;637.6715991264502;644.9387308205713;692.7253852910668;702.7821375373651;4362.6946113772365;4717.504662010508;4825.10735513931;4843.467218605951;4854.878628034327;150220;152485;153467;157103.42896571118;160522.39477605076;97016;97401.5389769154;100583;101750;119258.40370798264;49993.000139997;70234;105120.94879051264;120185.39907300583;120616.79383206407;8525.573721314033;9595.23238140933;10225.284230103822;10345;10465.215108867018;64759.67620161869;65157.69684606187;65563.67218163879;66309.33690663225;66450.66774666289;96219;97736;98930;100452;101827;12799.936000319938;13001.879548908004;13693.589192324247;13694.45222191141;14198.43206271779;14756.114633122046;15075.849241507445;15216.543503694907;15325.46360877364;15355.002579672113;14280.357383917957;14343.354549045109;14396.064255823338;14409.06341087826;14503.347349369093;14450.85549144495;14479.710405791615;14500.927495362455;14619.780703289247;14885.479008234659;13928.442862285396;13955.23246221427;14013.159210447406;14045.719085618435;14182.361793719101;11095.667129986114;11199.496022678835;11882.405879706153;11914.344711040976;11978;11100.167487438313;11656.88343116592;11659.242149260272;11743.941280293544;11862.051035917279 05:47:14 INFO - PROCESS | 1654 | __end_tp_report 05:47:14 INFO - PROCESS | 1654 | __start_cc_report 05:47:14 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1441 05:47:14 INFO - PROCESS | 1654 | __end_cc_report 05:47:14 INFO - PROCESS | 1654 | __startTimestamp1469018834739__endTimestamp 05:47:14 INFO - PROCESS | 1654 | 05:47:14 INFO - PROCESS | 1654 | ------- Summary: start ------- 05:47:14 INFO - PROCESS | 1654 | Number of tests: 6 05:47:14 INFO - PROCESS | 1654 | 05:47:14 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5728.67 Median:5937.94 stddev:642.34 (10.8%) stddev-sans-first:602.47 05:47:14 INFO - PROCESS | 1654 | Values: 2821.5 3376.6 3384.1 3389.3 3445.6 5581.6 5594.4 5647.8 5683.6 5702.8 5662.2 5710.6 5715.5 5717.5 5737.9 5168.7 5198.0 5242.2 5253.4 5304.7 5277.1 5291.8 5294.2 5296.1 5298.0 5152.8 5169.8 5173.1 5202.5 5293.5 5231.6 5254.8 5278.9 5279.8 5344.3 5072.9 5079.1 5127.7 5192.1 5204.6 5624.7 5650.9 5666.3 5706.9 5726.4 6155.4 6166.6 6214.2 6216.5 6238.9 6073.6 6196.2 6233.0 6251.2 6266.7 5753.4 5784.6 5810.8 5814.2 5884.3 6278.3 6279.6 6294.8 6329.7 6334.7 6008.4 6070.5 6136.7 6139.2 6151.7 5378.5 5865.9 6165.4 6215.9 6218.3 6199.6 6211.9 6221.2 6233.2 6234.5 6075.1 6160.3 6246.0 6260.9 6262.7 4943.9 4945.8 4958.2 5005.6 5010.8 4387.9 4455.6 4461.8 4472.5 4504.2 5573.5 5622.9 5696.9 5728.2 5764.0 6172.9 6190.9 6192.2 6232.3 6270.3 5734.5 5739.8 5741.7 5759.3 5790.5 5431.2 5456.0 5471.3 5472.6 5479.5 6222.6 6223.0 6246.6 6270.4 6323.6 6276.9 6277.4 6284.9 6285.5 6296.2 6263.6 6300.3 6301.3 6315.3 6321.8 6081.8 6131.2 6133.9 6164.9 6207.0 6207.4 6245.7 6257.3 6261.1 6261.7 5920.9 6147.8 6166.9 6189.6 6199.1 5087.8 5974.4 6056.7 6095.3 6101.8 5937.9 5979.9 6004.8 6026.2 6065.0 6125.9 6157.6 6163.9 6170.6 6176.7 4869.9 5790.4 5819.9 5954.8 5957.3 05:47:14 INFO - PROCESS | 1654 | 05:47:14 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:59680.49 Median:3593.20 stddev:121241.35 (3374.2%) stddev-sans-first:121499.96 05:47:14 INFO - PROCESS | 1654 | Values: 467.0 544.5 544.5 544.9 556.8 30042.0 30871.7 31651.7 41169.0 42205.2 44234.6 44676.6 44691.3 44700.6 44799.3 9514.4 9704.0 9704.2 9710.9 9721.4 8544.3 8843.6 8849.4 8947.7 9005.7 11433.2 11582.4 11599.5 11867.2 11953.9 11905.5 12000.1 12016.9 12032.3 12039.8 8437.5 11748.8 11816.3 11823.1 11825.9 42898.1 43750.0 43811.3 43838.3 43871.7 3432.6 3497.7 3510.8 3540.2 3646.2 31081.5 31754.8 33269.8 33270.0 33329.8 3066.9 3212.7 3355.4 3373.3 3408.5 521937.5 524044.0 527401.0 530557.0 534085.0 53930.9 62344.7 63875.7 63951.7 65415.7 67501.0 67677.0 68006.0 68076.3 68158.7 17030.8 17177.2 17192.1 17192.7 17193.9 12788.2 12851.8 12868.8 12871.4 12891.5 17474.6 19539.4 19582.9 19869.4 19905.6 7012.1 7214.1 8084.1 8102.6 8116.0 899.9 913.6 1004.7 1044.8 1063.5 29648.9 30559.5 31257.4 31403.2 32172.4 1124.4 1140.3 1145.7 1180.1 1234.3 16229.5 16350.3 16361.7 16645.9 16679.9 174386.0 175873.0 176248.0 616982.0 619947.0 61514.0 62264.8 62339.0 62342.7 62390.4 1388.2 1434.6 1455.3 1458.7 1463.6 29771.6 30389.4 30748.7 30850.9 31618.8 51876.5 52290.7 52322.0 52360.5 52383.7 408486.0 412097.0 412511.0 412988.0 414891.0 26944.3 27182.9 27321.5 27459.3 27514.0 34398.9 41292.0 42219.2 49894.5 52304.2 40554.8 40683.8 41009.0 41193.0 42474.4 40943.6 41626.8 41953.0 42067.8 42278.0 39658.0 40056.0 40657.2 40876.0 40894.0 32184.7 34081.3 35283.0 36583.9 36636.5 26206.0 26661.0 26736.6 27104.0 27254.5 05:47:14 INFO - PROCESS | 1654 | 05:47:14 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:28700.01 Median:12146.45 stddev:69964.43 (576.0%) stddev-sans-first:70128.25 05:47:14 INFO - PROCESS | 1654 | Values: 320.6 350.2 386.8 397.1 403.4 19921.8 20320.3 21161.9 21181.7 21356.3 12227.6 12353.4 16406.9 21179.7 21939.5 5318.4 5345.6 5570.9 5574.7 5581.4 4839.4 5413.6 5429.9 5437.3 5442.1 5422.4 5430.4 5436.1 5438.7 5447.9 5791.1 5919.4 5962.9 5969.9 5972.3 5044.4 5173.4 5238.0 5287.4 5295.9 13484.9 13587.3 13679.7 13689.2 13708.0 2194.7 2277.8 2335.8 2362.2 2366.1 9938.5 10072.0 10084.4 10367.5 10409.6 1853.6 1895.9 1938.1 1953.1 1960.0 115329.0 117790.3 124799.0 125669.0 129069.1 11218.0 11446.7 11570.9 11573.4 11731.2 14749.0 14764.0 14775.3 14785.2 14788.6 8538.7 8680.0 8698.3 8710.8 8851.8 8728.1 8730.5 8734.9 8808.8 8967.7 3860.7 4506.4 4520.9 4526.0 4543.9 1672.3 1901.4 1933.3 1938.2 1938.5 908.4 972.0 986.9 996.9 1002.1 22098.2 23544.2 23776.3 23967.2 24008.0 854.7 935.9 1011.9 1042.9 1048.6 10462.2 11504.8 11716.7 11738.9 11746.5 317283.0 319647.0 319752.0 320520.0 630973.7 95507.5 125368.1 129480.0 133955.0 134457.0 976.6 978.1 1006.7 1017.6 1021.1 18532.0 19105.3 19108.7 19154.7 19214.2 37342.0 52733.9 53271.0 53392.7 53486.5 81960.4 83142.6 84924.0 85348.6 86011.6 5751.8 6669.9 7402.8 7554.6 8004.1 10776.7 10984.3 12247.3 12282.8 12696.0 11096.5 11143.6 11157.2 11194.9 11208.7 10545.6 10555.6 10683.0 11069.2 11118.2 10722.9 11010.4 11065.3 11378.9 11453.9 7602.0 7825.4 7988.1 8022.1 8040.8 10621.4 10640.1 10664.9 11044.6 11084.2 05:47:14 INFO - PROCESS | 1654 | 05:47:14 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5662.54 Median:2442.57 stddev:3191.23 (130.7%) stddev-sans-first:3185.36 05:47:14 INFO - PROCESS | 1654 | Values: 1570.5 1607.4 1843.7 1944.4 1983.3 8430.7 8898.4 8904.0 8928.5 8935.7 7620.7 7832.2 8378.1 8612.7 8728.2 2326.1 2358.5 2364.7 2365.1 2374.5 2328.7 2338.9 2372.9 2374.6 2381.0 2081.1 2158.6 2158.8 2166.1 2350.0 2284.9 2374.9 2375.8 2381.2 2385.2 2355.3 2356.5 2383.0 2388.2 2403.8 8660.4 8749.4 8754.0 8754.1 8758.0 5105.9 5256.1 5260.1 5268.5 5271.5 3357.8 3476.9 3539.5 3550.3 3564.9 1044.2 1078.7 1096.5 1101.9 1792.9 11148.0 11580.3 11591.6 11595.7 11601.3 3850.6 3852.2 4358.8 4700.9 4715.1 10035.8 10042.6 10055.4 10058.9 10067.8 4442.3 5219.5 5251.8 5256.8 5259.2 4297.6 4442.7 4671.2 4774.5 4808.7 3557.9 3760.0 3931.1 3997.0 4037.2 1251.5 1268.3 1271.1 1271.4 1283.6 908.6 956.3 957.2 966.0 1011.9 5872.9 5965.5 6058.8 6082.4 6101.1 429.7 442.9 448.7 452.7 455.1 4048.5 4056.0 4065.8 4163.2 4174.4 10366.2 10663.3 10728.2 10733.2 10752.5 10694.2 10697.6 10705.9 10712.4 10722.3 6593.7 7763.8 8159.1 8197.0 8303.3 5949.2 6060.7 6119.9 6206.2 6237.4 10021.8 10128.5 10136.9 10147.1 10162.1 9963.4 10017.5 10130.1 10217.5 10393.1 6835.3 7061.5 7067.0 7412.2 7598.2 7837.5 7898.7 7903.5 8039.7 8326.3 6310.4 7342.5 7423.1 7477.4 7582.5 7249.6 7324.3 7332.9 7359.4 7387.6 6970.1 7006.0 7052.6 7063.2 7065.3 4800.4 4886.9 5059.5 5082.5 5221.3 5032.7 5034.3 5209.0 5216.2 5330.0 05:47:14 INFO - PROCESS | 1654 | 05:47:14 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4248.51 Median:4634.06 stddev:1299.47 (28.0%) stddev-sans-first:1268.84 05:47:14 INFO - PROCESS | 1654 | Values: 297.8 301.0 309.1 312.9 313.8 4614.9 4826.5 5120.6 5184.2 5347.2 4321.4 5146.0 5230.4 5277.9 5314.7 4037.9 4047.0 4059.5 4069.8 4080.4 3893.9 4069.2 4082.1 4101.6 4141.9 3344.3 4042.8 4057.1 4087.0 4108.6 3347.9 3840.8 4035.9 4075.9 4101.9 3008.5 3518.5 3548.8 3554.7 3575.9 4230.8 4551.4 4651.9 5053.6 5120.9 3744.6 3952.9 4621.5 4661.3 4676.3 4567.7 4680.7 5422.9 5540.5 5588.2 3272.5 3445.7 3541.0 3742.9 3946.4 4557.7 5389.9 5871.6 5917.9 5924.1 3688.7 3863.3 3880.8 3887.9 3897.6 3645.0 3932.2 3941.7 3947.5 3953.3 3955.0 4292.9 4512.4 4568.3 4620.7 5567.3 5657.0 5662.5 5668.4 5677.5 1130.5 1256.8 1275.4 1300.9 1337.8 765.6 867.0 872.6 886.3 904.0 4732.6 5208.1 5224.3 5231.3 5237.1 4835.8 5342.4 5396.7 5729.7 5733.5 4986.0 4992.2 5155.5 5158.6 5175.6 2191.4 2331.0 2520.4 2532.7 2576.9 4026.9 4046.4 4653.0 4892.4 4901.6 4147.6 4175.4 4372.8 4515.6 4569.0 4195.7 4519.0 4646.7 4928.6 5055.5 4788.4 4790.7 4791.9 4792.7 4839.9 3969.3 4396.6 4436.8 4706.6 4718.6 4356.7 4589.3 4727.1 4951.4 4955.8 2914.2 3064.3 3157.8 3208.6 3321.8 3913.9 4614.5 4739.8 4772.0 4940.7 4898.4 5176.8 5341.0 5503.2 5683.5 5174.2 5418.7 5599.9 5622.9 5632.6 4683.5 4808.9 5494.3 5516.8 5575.9 4763.2 5292.9 5540.2 5704.5 5740.3 4572.1 5182.7 5506.7 5521.9 5536.9 05:47:14 INFO - PROCESS | 1654 | 05:47:14 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:24455.17 Median:23171.83 stddev:38239.91 (165.0%) stddev-sans-first:38305.74 05:47:14 INFO - PROCESS | 1654 | Values: 744.4 744.7 790.4 826.5 850.7 14792.3 15947.1 15961.3 15976.7 15984.3 14686.9 14852.1 14864.9 14873.1 14879.2 714.2 737.1 887.9 898.2 926.0 894.0 900.8 902.3 908.3 911.0 966.7 969.2 969.6 969.8 970.3 962.1 963.0 963.1 964.0 966.0 559.1 573.6 574.9 576.7 719.9 13422.0 13844.4 13904.7 13964.6 14042.3 13782.6 14189.0 14498.8 14582.4 14589.0 13198.3 13323.3 13358.5 13488.2 13504.3 4630.6 4637.4 4677.5 4807.1 4856.0 107559.5 108804.0 108951.4 113194.4 114330.0 11312.5 11455.3 11509.0 11579.7 11953.6 22923.9 23158.5 23168.8 23174.9 23175.5 8077.9 8212.0 8214.3 8308.1 8341.0 4368.2 4457.3 4477.0 4488.8 4615.8 3448.3 3918.4 4076.2 4084.2 4108.5 706.9 730.7 731.4 738.3 740.4 605.2 615.9 659.6 664.4 716.1 6688.2 9252.8 9856.2 10283.7 10488.3 578.5 637.7 644.9 692.7 702.8 4362.7 4717.5 4825.1 4843.5 4854.9 150220.0 152485.0 153467.0 157103.4 160522.4 97016.0 97401.5 100583.0 101750.0 119258.4 49993.0 70234.0 105120.9 120185.4 120616.8 8525.6 9595.2 10225.3 10345.0 10465.2 64759.7 65157.7 65563.7 66309.3 66450.7 96219.0 97736.0 98930.0 100452.0 101827.0 12799.9 13001.9 13693.6 13694.5 14198.4 14756.1 15075.8 15216.5 15325.5 15355.0 14280.4 14343.4 14396.1 14409.1 14503.3 14450.9 14479.7 14500.9 14619.8 14885.5 13928.4 13955.2 14013.2 14045.7 14182.4 11095.7 11199.5 11882.4 11914.3 11978.0 11100.2 11656.9 11659.2 11743.9 11862.1 05:47:14 INFO - PROCESS | 1654 | -------- Summary: end -------- 05:47:14 INFO - PROCESS | 1654 | 05:47:15 INFO - TEST-INFO | 1654: exit 0 05:47:15 INFO - TEST-OK | dromaeo_css | took 1116623ms 05:47:15 INFO - TEST-START | kraken 05:47:15 INFO - operating with platform_type : mac_ 05:47:15 INFO - Initialising browser for kraken test... 05:47:15 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpIptjfr/profile http://localhost:49266/getInfo.html) 05:47:17 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 05:47:17 INFO - PROCESS | 1665 | colorDepth:24 05:47:17 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 05:47:17 INFO - PROCESS | 1665 | __metrics 05:47:17 INFO - PROCESS | 1665 | 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" 05:47:17 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:47:18 INFO - TEST-INFO | 1665: exit 0 05:47:18 INFO - Browser initialized. 05:47:18 INFO - Running cycle 1/1 for kraken test... 05:47:18 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpIptjfr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:47:30 INFO - PROCESS | 1666 | RSS: Main: 245448704 05:47:30 INFO - PROCESS | 1666 | 05:47:33 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 05:47:33 INFO - PROCESS | 1666 | RSS: Main: 295522304 05:47:33 INFO - PROCESS | 1666 | 05:47:36 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 05:47:37 INFO - PROCESS | 1666 | RSS: Main: 305250304 05:47:37 INFO - PROCESS | 1666 | 05:47:40 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 05:47:41 INFO - PROCESS | 1666 | RSS: Main: 391880704 05:47:41 INFO - PROCESS | 1666 | 05:47:43 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 05:47:44 INFO - PROCESS | 1666 | RSS: Main: 292069376 05:47:44 INFO - PROCESS | 1666 | 05:47:46 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:47:46 INFO - PROCESS | 1666 | RSS: Main: 296148992 05:47:46 INFO - PROCESS | 1666 | 05:47:49 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 05:47:50 INFO - PROCESS | 1666 | RSS: Main: 334118912 05:47:50 INFO - PROCESS | 1666 | 05:47:53 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 05:47:53 INFO - PROCESS | 1666 | RSS: Main: 335183872 05:47:53 INFO - PROCESS | 1666 | 05:47:56 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 05:47:57 INFO - PROCESS | 1666 | RSS: Main: 333967360 05:47:57 INFO - PROCESS | 1666 | 05:47:58 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:47:59 INFO - PROCESS | 1666 | RSS: Main: 312389632 05:47:59 INFO - PROCESS | 1666 | 05:48:01 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:48:01 INFO - PROCESS | 1666 | RSS: Main: 319102976 05:48:01 INFO - PROCESS | 1666 | 05:48:04 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:48:04 INFO - PROCESS | 1666 | RSS: Main: 316727296 05:48:04 INFO - PROCESS | 1666 | 05:48:07 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:48:08 INFO - PROCESS | 1666 | RSS: Main: 325795840 05:48:08 INFO - PROCESS | 1666 | 05:48:10 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:48:11 INFO - PROCESS | 1666 | RSS: Main: 327249920 05:48:11 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 05:48:13 INFO - PROCESS | 1666 | RSS: Main: 370860032 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | __start_tp_report 05:48:13 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 05:48:13 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 05:48:13 INFO - PROCESS | 1666 | |i|pagename|runs| 05:48:13 INFO - PROCESS | 1666 | |0;ai-astar;101;110;112;113;113;110;112;110;112;112 05:48:13 INFO - PROCESS | 1666 | |1;audio-beat-detection;144;107;107;164;106;106;125;105;158;137 05:48:13 INFO - PROCESS | 1666 | |2;audio-dft;184;182;183;181;183;184;181;182;184;181 05:48:13 INFO - PROCESS | 1666 | |3;audio-fft;93;95;82;112;58;57;59;57;99;57 05:48:13 INFO - PROCESS | 1666 | |4;audio-oscillator;84;87;93;70;77;68;116;101;99;107 05:48:13 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;89;130;127;86;89;84;90;87;87;85 05:48:13 INFO - PROCESS | 1666 | |6;imaging-darkroom;96;94;95;94;94;94;95;95;95;94 05:48:13 INFO - PROCESS | 1666 | |7;imaging-desaturate;85;83;86;90;84;84;86;86;83;87 05:48:13 INFO - PROCESS | 1666 | |8;json-parse-financial;52;55;56;55;52;54;55;55;53;52 05:48:13 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;67;75;69;77;72;71;72;70;70;72 05:48:13 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;90;80;83;83;81;84;86;82;80;83 05:48:13 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;136;140;131;134;132;132;136;136;177;136 05:48:13 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;162;163;162;164;163;165;162;161;161;162 05:48:13 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;65;67;68;66;64;65;65;67;66;67 05:48:13 INFO - PROCESS | 1666 | __end_tp_report 05:48:13 INFO - PROCESS | 1666 | __start_cc_report 05:48:13 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2059 05:48:13 INFO - PROCESS | 1666 | __end_cc_report 05:48:13 INFO - PROCESS | 1666 | __startTimestamp1469018893526__endTimestamp 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | ------- Summary: start ------- 05:48:13 INFO - PROCESS | 1666 | Number of tests: 14 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:110.50 Median:112.00 stddev:3.54 (3.2%) stddev-sans-first:1.24 05:48:13 INFO - PROCESS | 1666 | Values: 101.0 110.0 112.0 113.0 113.0 110.0 112.0 110.0 112.0 112.0 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:125.90 Median:131.00 stddev:23.28 (17.8%) stddev-sans-first:23.75 05:48:13 INFO - PROCESS | 1666 | Values: 144.0 107.0 107.0 164.0 106.0 106.0 125.0 105.0 158.0 137.0 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.00 stddev:1.27 (0.7%) stddev-sans-first:1.22 05:48:13 INFO - PROCESS | 1666 | Values: 184.0 182.0 183.0 181.0 183.0 184.0 181.0 182.0 184.0 181.0 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:76.90 Median:70.50 stddev:21.60 (30.6%) stddev-sans-first:22.11 05:48:13 INFO - PROCESS | 1666 | Values: 93.0 95.0 82.0 112.0 58.0 57.0 59.0 57.0 99.0 57.0 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:80.50 stddev:15.89 (19.7%) stddev-sans-first:16.70 05:48:13 INFO - PROCESS | 1666 | Values: 84.0 87.0 93.0 70.0 77.0 68.0 116.0 101.0 99.0 107.0 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:95.40 Median:87.00 stddev:17.56 (20.2%) stddev-sans-first:18.47 05:48:13 INFO - PROCESS | 1666 | Values: 89.0 130.0 127.0 86.0 89.0 84.0 90.0 87.0 87.0 85.0 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.53 05:48:13 INFO - PROCESS | 1666 | Values: 96.0 94.0 95.0 94.0 94.0 94.0 95.0 95.0 95.0 94.0 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:2.12 (2.5%) stddev-sans-first:2.24 05:48:13 INFO - PROCESS | 1666 | Values: 85.0 83.0 86.0 90.0 84.0 84.0 86.0 86.0 83.0 87.0 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:55.00 stddev:1.52 (2.8%) stddev-sans-first:1.45 05:48:13 INFO - PROCESS | 1666 | Values: 52.0 55.0 56.0 55.0 52.0 54.0 55.0 55.0 53.0 52.0 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.00 stddev:2.88 (4.0%) stddev-sans-first:2.55 05:48:13 INFO - PROCESS | 1666 | Values: 67.0 75.0 69.0 77.0 72.0 71.0 72.0 70.0 70.0 72.0 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.00 stddev:3.01 (3.6%) stddev-sans-first:1.94 05:48:13 INFO - PROCESS | 1666 | Values: 90.0 80.0 83.0 83.0 81.0 84.0 86.0 82.0 80.0 83.0 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:139.00 Median:136.00 stddev:13.61 (10.0%) stddev-sans-first:14.40 05:48:13 INFO - PROCESS | 1666 | Values: 136.0 140.0 131.0 134.0 132.0 132.0 136.0 136.0 177.0 136.0 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.50 Median:162.50 stddev:1.27 (0.8%) stddev-sans-first:1.33 05:48:13 INFO - PROCESS | 1666 | Values: 162.0 163.0 162.0 164.0 163.0 165.0 162.0 161.0 161.0 162.0 05:48:13 INFO - PROCESS | 1666 | 05:48:13 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.50 stddev:1.25 (1.9%) stddev-sans-first:1.27 05:48:13 INFO - PROCESS | 1666 | Values: 65.0 67.0 68.0 66.0 64.0 65.0 65.0 67.0 66.0 67.0 05:48:13 INFO - PROCESS | 1666 | -------- Summary: end -------- 05:48:13 INFO - PROCESS | 1666 | 05:48:14 INFO - TEST-INFO | 1666: exit 0 05:48:14 INFO - TEST-OK | kraken | took 58977ms 05:48:14 INFO - SUITE-END | took 1175s 05:48:14 INFO - Completed test suite (00:19:35) 05:48:14 INFO - javascript benchmark 05:48:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2821.520341541938, 3376.6116896556905, 3384.103212648647, 3389.3390788796196, 3445.6037555681105, 5581.609287349877, 5594.412586678396, 5647.802326918559, 5683.602147849647, 5702.828915132547, 5662.235598194243, 5710.600257981943, 5715.457031582004, 5717.456841600052, 5737.885242295162, 5168.741562921858, 5198.0, 5242.161254199329, 5253.44838791927, 5304.655197412175, 5277.12927366984, 5291.788328466857, 5294.205869119624, 5296.09966305727, 5297.966896455193, 5152.819651312205, 5169.819056333029, 5173.146430838907, 5202.505761952627, 5293.470652934695, 5231.633785635007, 5254.789808407659, 5278.920816187722, 5279.788808447696, 5344.305240318734, 5072.898542029138, 5079.1365467870555, 5127.743612819344, 5192.1432963560965, 5204.557612602934, 5624.746886390122, 5650.943490565124, 5666.263385759866, 5706.914396284059, 5726.427357264281, 6155.353687862755, 6166.568340216187, 6214.192155019864, 6216.471599913967, 6238.87522249557, 6073.568857379306, 6196.194494715658, 6233.0, 6251.187345645082, 6266.749330026793, 5753.367129615739, 5784.623999440023, 5810.767569297265, 5814.186013958049, 5884.293884733859, 6278.277998030194, 6279.560430769893, 6294.779682711083, 6329.68351582419, 6334.714937827716, 6008.429199226154, 6070.453659170696, 6136.693165341715, 6139.201903752483, 6151.692415379213, 5378.542823859937, 5865.941340586625, 6165.414285642857, 6215.9378406216265, 6218.31598524162, 6199.56603037783, 6211.937880621136, 6221.222347206599, 6233.189685340877, 6234.532410069263, 6075.149479072933, 6160.322364539897, 6245.96877015612, 6260.937390626126, 6262.718177682015, 4943.90112197754, 4945.802167913315, 4958.157113290749, 5005.649604527648, 5010.849674509771, 4387.934180987288, 4455.554444555519, 4461.843835465743, 4472.485664148665, 4504.234280172379, 5573.498385145356, 5622.943770562242, 5696.943030569724, 5728.198052272685, 5764.048931926269, 6172.938270617326, 6190.876182476415, 6192.195014648067, 6232.283287421914, 6270.2789179244055, 5734.455226753452, 5739.799107031317, 5741.655500669973, 5759.337676167211, 5790.478856902815, 5431.219313212888, 5455.972720136374, 5471.343438787371, 5472.616916815785, 5479.53423958968, 6222.595531290361, 6223.0, 6246.593971391845, 6270.404311590391, 6323.557350985387, 6276.874462510816, 6277.372262773686, 6284.937150628435, 6285.528585356208, 6296.181496405438, 6263.6241825490615, 6300.338464461165, 6301.338359472189, 6315.273743519554, 6321.841953951188, 6081.787137450345, 6131.233595800525, 6133.8855759873895, 6164.876702466016, 6206.968965155145, 6207.410296021871, 6245.7189426476825, 6257.311695713559, 6261.123442717932, 6261.749530018748, 5920.940790592039, 6147.784827531015, 6166.907496387647, 6189.566730328834, 6199.070139479114, 5087.796488140433, 5974.40255974399, 6056.66688332146, 6095.268567771718, 6101.84745381369, 5937.940620593911, 5979.880402392015, 6004.789832356021, 6026.216591843207, 6064.968955277702, 6125.927962606437, 6157.599756015845, 6163.876722465436, 6170.568060235741, 6176.66028368444, 4869.902601947871, 5790.363060063474, 5819.883602328015, 5954.821355359346, 5957.314908785545], "value": 5687.094557223005, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.96964697294675, 544.4881811097569, 544.5262621519277, 544.9156179203386, 556.7717235933268, 30042.0, 30871.691283087162, 31651.68348316516, 41168.99999999996, 42205.15589688205, 44234.55765442344, 44676.55323446765, 44691.329630055574, 44700.55299447005, 44799.32801007987, 9514.42913425195, 9704.029597040275, 9704.22366210705, 9710.854337184948, 9721.368111072794, 8544.316454683641, 8843.646254149826, 8849.42478738883, 8947.731568052935, 9005.684801031965, 11433.199676022681, 11582.362970036645, 11599.478023488964, 11867.228630139056, 11953.940230298836, 11905.464254108543, 12000.099992500554, 12016.939915300454, 12032.27806331623, 12039.759204815855, 8437.535935523543, 11748.765024699458, 11816.29102253859, 11823.054155667525, 11825.881741182564, 42898.14203715908, 43750.0, 43811.342829857575, 43838.34242486365, 43871.683849484565, 3432.5880894292595, 3497.702695270905, 3510.824458777051, 3540.168060505779, 3646.2342907989355, 31081.533776993365, 31754.841225793953, 33269.83365083159, 33270.0, 33329.83335083309, 3066.9233269168226, 3212.6947939945903, 3355.4295769719206, 3373.2916087621384, 3408.5398471206317, 521937.46437748993, 524044.0, 527401.0, 530557.0, 534085.0, 53930.92138157293, 62344.68827655833, 63875.68062159659, 63951.680241599424, 65415.67292163509, 67501.0, 67676.96969090935, 68005.97991030138, 68076.31923680799, 68158.65920670365, 17030.829691702926, 17177.227024783915, 17192.054437006085, 17192.656146876994, 17193.91403042977, 12788.232706037668, 12851.807222891664, 12868.806967895489, 12871.356432178354, 12891.48434062627, 17474.55356250126, 19539.413817585497, 19582.90208548948, 19869.403917882486, 19905.60188796216, 7012.123484564429, 7214.134303883568, 8084.140647651073, 8102.554359510284, 8115.959420202979, 899.8740176375313, 913.6071489259549, 1004.6985904228702, 1044.7649278912183, 1063.4842101580728, 29648.85175574108, 30559.541606875915, 31257.374852502824, 31403.214919626735, 32172.356552868812, 1124.4321617583068, 1140.3272069479046, 1145.73075327298, 1180.0618508285934, 1234.327291626066, 16229.513114606581, 16350.264238109314, 16361.672766544603, 16645.916770416312, 16679.91660041692, 174386.0, 175873.0, 176248.0, 616982.0, 619947.0, 61514.0, 62264.754704905645, 62339.0, 62342.688286558274, 62390.376096238455, 1388.167099740148, 1434.5911415246812, 1455.294182321552, 1458.6863824277832, 1463.619458940683, 29771.553426698185, 30389.392212156075, 30748.692513074584, 30850.92021779227, 31618.841905790323, 51876.481235187166, 52290.738546307024, 52322.0, 52360.476395237085, 52383.73808130935, 408485.9575702103, 412097.0, 412511.0, 412988.0, 414891.0, 26944.32639184036, 27182.91268349244, 27321.45357092807, 27459.31351716223, 27514.0, 34398.88413329253, 41292.0, 42219.15561688688, 49894.501054988985, 52304.21543676924, 40554.78335649935, 40683.796581016904, 41009.0, 41192.970175745846, 42474.36288455738, 40943.590564093975, 41626.791866040476, 41953.0, 42067.7896610515, 42278.0, 39658.0, 40056.0, 40657.1868562633, 40875.97810054773, 40893.977650558976, 32184.678153218167, 34081.31837363189, 35283.0, 36583.90248292556, 36636.45045324269, 26206.0, 26661.0, 26736.59895101536, 27104.0, 27254.454910902066], "value": 20346.778168878824, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.618464027807, 350.1945525291829, 386.76407391491193, 397.09264331003664, 403.44324831732223, 19921.800781992177, 20320.28878989236, 21161.894190529063, 21181.68227476585, 21356.25253116141, 12227.572034978777, 12353.382330883465, 16406.85152039358, 21179.68230476544, 21939.451513712163, 5318.441563635821, 5345.625806193559, 5570.8607284818, 5574.693391863446, 5581.441855814406, 4839.419269687643, 5413.566914646838, 5429.945700542983, 5437.34751829781, 5442.102053161224, 5422.40353561108, 5430.3755068167075, 5436.075867102602, 5438.7280635968245, 5447.945520544803, 5791.10237913122, 5919.408059194089, 5962.850928726773, 5969.910451343233, 5972.283326000907, 5044.419891712463, 5173.405058418292, 5237.973810130963, 5287.41838397776, 5295.894082118374, 13484.865151348458, 13587.252701101435, 13679.726405471834, 13689.178649281075, 13708.040437169402, 2194.7366316042176, 2277.760835112306, 2335.7547457517057, 2362.208660098869, 2366.077229880348, 9938.503074846229, 10072.043155900177, 10084.394936303845, 10367.533460994273, 10409.635662751843, 1853.6292741451764, 1895.9336423225259, 1938.0697265312588, 1953.1210955070276, 1959.9710152170082, 115329.0, 117790.28642590286, 124799.0, 125669.0, 129069.06396404062, 11218.0, 11446.713832154262, 11570.88429115698, 11573.363465009337, 11731.178817482865, 14749.041312314665, 14764.040337378035, 14775.335109920079, 14785.186814725294, 14788.556343309718, 8538.658453661908, 8679.956600216958, 8698.304135669132, 8710.825783484295, 8851.778705532413, 8728.127187281221, 8730.476171429735, 8734.91265087341, 8808.779780505538, 8967.686130985385, 3860.652541271299, 4506.436695413074, 4520.932186017212, 4525.954740452553, 4543.863684089482, 1672.2809192047293, 1901.4330653617506, 1933.2943475631293, 1938.1684308328495, 1938.5347516595916, 908.4413085952224, 972.0, 986.8569057486624, 996.8554559588964, 1002.1231422505308, 22098.157489036057, 23544.17595384153, 23776.286711398687, 23967.16114935969, 24008.0, 854.679495189304, 935.9251259899328, 1011.8785745710562, 1042.854000439939, 1048.6487026846091, 10462.169120403898, 11504.76990460186, 11716.70708232284, 11738.94130529342, 11746.471408786625, 317283.0, 319647.0, 319752.0, 320520.0, 630973.6769742288, 95507.52246238863, 125368.09003144686, 129480.0, 133954.99067513994, 134457.0, 976.5654283843621, 978.1490103609763, 1006.7030226083176, 1017.5624481473037, 1021.1473419694489, 18531.98074105937, 19105.331313403964, 19108.713369299192, 19154.7126793101, 19214.21393897905, 37342.0, 52733.94532109413, 53271.0, 53392.73303633457, 53486.465135348146, 81960.36079278501, 83142.58428707818, 84924.0, 85348.57325713332, 86011.5595376178, 5751.769929202785, 6669.899951500634, 7402.814929626802, 7554.584497852671, 8004.119546849958, 10776.730581735519, 10984.340939543654, 12247.326397048248, 12282.75434491323, 12696.047796415121, 11096.500657470271, 11143.609973651208, 11157.21899467062, 11194.944025279821, 11208.66374008781, 10545.630902918361, 10555.577776888838, 10683.0, 11069.169812263952, 11118.221724479208, 10722.927707229215, 11010.449477526252, 11065.280756750786, 11378.886211138113, 11453.885461145615, 7602.0, 7825.4130940178575, 7988.12130665638, 8022.077461091816, 8040.83918321642, 10621.362718236931, 10640.148788096778, 10664.946675266574, 11044.558217671203, 11084.224104312621], "value": 9189.128564976701, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1570.5209910977148, 1607.3731244814524, 1843.732658764479, 1944.3972368565737, 1983.305842954965, 8430.704925327615, 8898.421602595834, 8903.95548022259, 8928.508932008745, 8935.687250946217, 7620.6951721931055, 7832.216778322165, 8378.12029736878, 8612.655493780241, 8728.170823771747, 2326.09282379872, 2358.5282943411303, 2364.7044119485063, 2365.1485465232504, 2374.525094981002, 2328.7089113860766, 2338.859668419892, 2372.9288121356385, 2374.6319320505263, 2380.9642855357183, 2081.0843228979274, 2158.6114499390105, 2158.7949144831214, 2166.090242098315, 2350.0, 2284.8629082255034, 2374.893129809163, 2375.798057165143, 2381.1784934197676, 2385.2367242482414, 2355.281639100074, 2356.4580146566386, 2383.011050414076, 2388.152205966874, 2403.771641694036, 8660.350473714434, 8749.387542872002, 8754.0, 8754.080821513713, 8758.0, 5105.948940510584, 5256.053910296145, 5260.079486089919, 5268.525832675077, 5271.499207575274, 3357.8153201573905, 3476.89569312921, 3539.504469374264, 3550.343186510504, 3564.9108772280642, 1044.1959691037857, 1078.724925144091, 1096.5175322857917, 1101.884302148271, 1792.9462116136538, 11148.0, 11580.305181689042, 11591.594294199662, 11595.710107247301, 11601.303921764722, 3850.634189752025, 3852.24881148179, 4358.847440339573, 4700.858974230779, 4715.080559290974, 10035.849462258071, 10042.598296068076, 10055.44695041765, 10058.899411005943, 10067.798644027078, 4442.267025940748, 5219.5041471060185, 5251.789928402821, 5256.763445644879, 5259.21111833228, 4297.591728785823, 4442.666799990014, 4671.159191345522, 4774.474807771142, 4808.687435316763, 3557.911052223715, 3759.9660093473967, 3931.0761970936805, 3996.980015099906, 4037.1723796621623, 1251.4931452761646, 1268.340462959256, 1271.1165739810742, 1271.4405661508906, 1283.6341642631805, 908.6138391183721, 956.268454632207, 957.2342126299072, 965.956766691971, 1011.9241056920761, 5872.941270587325, 5965.463108320272, 6058.848528786815, 6082.391760823883, 6101.054336577838, 429.65198189466633, 442.8649261975064, 448.6814361803091, 452.7193140253064, 455.07165382619746, 4048.4939382577177, 4055.986003499125, 4065.7560546367313, 4163.2298024865495, 4174.415581818548, 10366.170706343475, 10663.306885052447, 10728.249022568501, 10733.248672592843, 10752.462376881125, 10694.197935154894, 10697.572097116028, 10705.892941070646, 10712.35725856451, 10722.303050301705, 6593.7362505500205, 7763.767086987513, 8159.061707903667, 8196.959015204886, 8303.335733141334, 5949.167116603766, 6060.727267272895, 6119.938800611937, 6206.224221972254, 6237.407446292595, 10021.799564008825, 10128.493575321352, 10136.89863101389, 10147.086762191439, 10162.085412312927, 9963.402195868272, 10017.499125043865, 10130.138938190335, 10217.540210690388, 10393.06462418386, 6835.282295358916, 7061.470389720689, 7067.0, 7412.184659687322, 7598.240175982358, 7837.451378403457, 7898.68405263806, 7903.525788452711, 8039.67841286342, 8326.333893288402, 6310.400511951358, 7342.5227360221415, 7423.072115985502, 7477.401807855467, 7582.507137036075, 7249.637518123967, 7324.267573242633, 7332.9266707332245, 7359.4480413968095, 7387.593682347522, 6970.128733908262, 7006.0, 7052.647367631701, 7063.187733410518, 7065.258147894397, 4800.3759511263315, 4886.877828054327, 5059.46875578059, 5082.517160869711, 5221.269022336952, 5032.672876263031, 5034.3455350805625, 5209.0, 5216.191490319006, 5329.973350133379], "value": 4498.301249460326, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.8272601890905, 300.95485677148423, 309.1282583115614, 312.85921335399075, 313.7584060273588, 4614.8618939121425, 4826.493218212086, 5120.61595380347, 5184.196449550316, 5347.192723819997, 4321.416608757817, 5145.974270128644, 5230.372355317358, 5277.915223486721, 5314.707691076999, 4037.899052523681, 4047.0, 4059.4722686050845, 4069.8168582413714, 4080.4287399764057, 3893.941590876125, 4069.2065047315787, 4082.122343696105, 4101.569335219804, 4141.902395865083, 3344.331133773243, 4042.818073186699, 4057.066874618825, 4087.0395457067566, 4108.5686002969705, 3347.9497807532907, 3840.7695538267653, 4035.919281614366, 4075.9430427179614, 4101.923557332001, 3008.5487176923416, 3518.5074089627474, 3548.787072775616, 3554.662307080823, 3575.8557261676274, 4230.809613567397, 4551.353902424647, 4651.86044418668, 5053.646244762831, 5120.92318615221, 3744.606816284278, 3952.9011774705573, 4621.4685311189305, 4661.277501987197, 4676.321933319649, 4567.725936443824, 4680.672352935296, 5422.945770542323, 5540.473655002808, 5588.161775733632, 3272.460044092722, 3445.689887910075, 3540.9822950885336, 3742.850285988557, 3946.355941519189, 4557.74932378719, 5389.9191512127345, 5871.618344807616, 5917.8816423671715, 5924.1113832924975, 3688.731430170426, 3863.265979463893, 3880.835749275206, 3887.8833634990997, 3897.5517815451017, 3644.9817750911607, 3932.193900250442, 3941.684665226775, 3947.50656167979, 3953.3081710700512, 3955.011247188203, 4292.871213863589, 4512.368268442421, 4568.33759104928, 4620.653450991189, 5567.329801059666, 5657.0, 5662.462066103713, 5668.433156684298, 5677.545796336283, 1130.4743294367993, 1256.8303279057393, 1275.4451813461174, 1300.8959283257316, 1337.7926421404698, 765.624843826521, 867.002946611402, 872.646578135856, 886.2599729226168, 904.0, 4732.550407711262, 5208.114620514521, 5224.320838290998, 5231.346081739783, 5237.0835103857435, 4835.782389792397, 5342.439043900412, 5396.730163491809, 5729.684867332337, 5733.541316694655, 4986.0, 4992.226204938241, 5155.458676838953, 5158.561522270574, 5175.637705360588, 2191.4411824984527, 2330.988345058264, 2520.432902596901, 2532.6956617342084, 2576.9434531841853, 4026.859059932888, 4046.393041043867, 4652.974787229784, 4892.434420985322, 4901.58336541398, 4147.6267135957905, 4175.394567787654, 4372.835446454025, 4515.645306408064, 4569.012479571331, 4195.727277726938, 4519.023259531657, 4646.651501137429, 4928.644622728707, 5055.53389517036, 4788.353572267839, 4790.7125572464365, 4791.904161916812, 4792.664513484023, 4839.903201936012, 3969.285528604879, 4396.560343965578, 4436.803946483346, 4706.552877476634, 4718.622510199244, 4356.6950313477755, 4589.328537170317, 4727.149113159664, 4951.37577084146, 4955.752212389438, 2914.1694617034045, 3064.272797210529, 3157.8000359863104, 3208.550802887551, 3321.8173000485203, 3913.8708662361482, 4614.515475874985, 4739.7867095981455, 4771.95228047715, 4940.6788558743565, 4898.363212782458, 5176.767045482887, 5341.0, 5503.229547863382, 5683.460071293221, 5174.189033838277, 5418.674879507243, 5599.860003499945, 5622.859428514156, 5632.633878797865, 4683.508231635629, 4808.927866082082, 5494.2857428534035, 5516.806911758069, 5575.916361254665, 4763.237881938873, 5292.941411717748, 5540.22436858832, 5704.486596206361, 5740.297508107164, 4572.1312950539295, 5182.740862956913, 5506.6959824105215, 5521.889562208653, 5536.861578460571], "value": 3843.3365803684133, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.4379493482421, 744.746786092728, 790.4150928313051, 826.5123577089516, 850.6512329944723, 14792.334344954475, 15947.122908240042, 15961.281742321595, 15976.680466390666, 15984.280707368182, 14686.926565367185, 14852.108873467572, 14864.925675371634, 14873.107613543196, 14879.18164500952, 714.2179313651543, 737.1117803047315, 887.9067697891725, 898.2454738020061, 925.9814803703922, 894.025512191711, 900.8063266397724, 902.3187493442442, 908.2643059122132, 910.9635614575409, 966.7293157915794, 969.2391472693937, 969.612155137947, 969.7575606098476, 970.335320305593, 962.1388856973005, 962.9711108666751, 963.1476143612953, 964.0, 965.9517024148764, 559.1110134885511, 573.6042130929687, 574.8821491594255, 576.6914700635182, 719.9136103667541, 13422.0, 13844.446222151102, 13904.721905561933, 13964.581062568139, 14042.297885105703, 13782.586522404345, 14189.0, 14498.782518262235, 14582.416703331854, 14588.978771486, 13198.340082995812, 13323.334583177102, 13358.532451364155, 13488.19070855742, 13504.324783760772, 4630.583247507707, 4637.373954516164, 4677.508861569554, 4807.062622788558, 4855.951440485621, 107559.46220268849, 108804.0, 108951.36572951333, 113194.43402783015, 114330.0, 11312.490937907814, 11455.255408398427, 11509.0, 11579.652610421701, 11953.581624643095, 22923.88538057299, 23158.53682926332, 23168.76831231666, 23174.884125579265, 23175.536489270122, 8077.8788318175275, 8212.0, 8214.314131126652, 8308.12764659714, 8340.958295208486, 4368.213721530155, 4457.3091170868565, 4476.977615111904, 4488.798004089824, 4615.815367385267, 3448.3103379323734, 3918.4122381642983, 4076.2458945095254, 4084.223997440536, 4108.4864391951005, 706.9007693037273, 730.6821532633321, 731.4294850016995, 738.2875525118179, 740.3558903753661, 605.2132228103447, 615.9322474527798, 659.6082266417854, 664.3622122762106, 716.097716876739, 6688.230853451818, 9252.768680783034, 9856.162226210708, 10283.691489255334, 10488.26582139243, 578.4720770234104, 637.6715991264502, 644.9387308205713, 692.7253852910668, 702.7821375373651, 4362.6946113772365, 4717.504662010508, 4825.10735513931, 4843.467218605951, 4854.878628034327, 150220.0, 152485.0, 153467.0, 157103.42896571118, 160522.39477605076, 97016.0, 97401.5389769154, 100583.0, 101750.0, 119258.40370798264, 49993.000139997, 70234.0, 105120.94879051264, 120185.39907300583, 120616.79383206407, 8525.573721314033, 9595.23238140933, 10225.284230103822, 10345.0, 10465.215108867018, 64759.67620161869, 65157.69684606187, 65563.67218163879, 66309.33690663225, 66450.66774666289, 96219.0, 97736.0, 98930.0, 100452.0, 101827.0, 12799.936000319938, 13001.879548908004, 13693.589192324247, 13694.45222191141, 14198.43206271779, 14756.114633122046, 15075.849241507445, 15216.543503694907, 15325.46360877364, 15355.002579672113, 14280.357383917957, 14343.354549045109, 14396.064255823338, 14409.06341087826, 14503.347349369093, 14450.85549144495, 14479.710405791615, 14500.927495362455, 14619.780703289247, 14885.479008234659, 13928.442862285396, 13955.23246221427, 14013.159210447406, 14045.719085618435, 14182.361793719101, 11095.667129986114, 11199.496022678835, 11882.405879706153, 11914.344711040976, 11978.0, 11100.167487438313, 11656.88343116592, 11659.242149260272, 11743.941280293544, 11862.051035917279], "value": 8069.384019924189, "unit": "score"}], "name": "dromaeo_css", "value": 7275.865440311644, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 112.0, 113.0, 113.0, 110.0, 112.0, 110.0, 112.0, 112.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 107.0, 107.0, 164.0, 106.0, 106.0, 125.0, 105.0, 158.0, 137.0], "value": 125.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 183.0, 181.0, 183.0, 184.0, 181.0, 182.0, 184.0, 181.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 82.0, 112.0, 58.0, 57.0, 59.0, 57.0, 99.0, 57.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 93.0, 70.0, 77.0, 68.0, 116.0, 101.0, 99.0, 107.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 130.0, 127.0, 86.0, 89.0, 84.0, 90.0, 87.0, 87.0, 85.0], "value": 95.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 86.0, 90.0, 84.0, 84.0, 86.0, 86.0, 83.0, 87.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 56.0, 55.0, 52.0, 54.0, 55.0, 55.0, 53.0, 52.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 75.0, 69.0, 77.0, 72.0, 71.0, 72.0, 70.0, 70.0, 72.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 80.0, 83.0, 83.0, 81.0, 84.0, 86.0, 82.0, 80.0, 83.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 140.0, 131.0, 134.0, 132.0, 132.0, 136.0, 136.0, 177.0, 136.0], "value": 139.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 164.0, 163.0, 165.0, 162.0, 161.0, 161.0, 162.0], "value": 162.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 68.0, 66.0, 64.0, 65.0, 65.0, 67.0, 66.0, 67.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1437.5, "alertThreshold": 2.0}]} 05:48:14 INFO - Return code: 0 05:48:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:48:14 INFO - # TBPL SUCCESS # 05:48:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:48:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:48:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:48:14 INFO - 2016-07-20 05:48:14.870 screenresolution[1670:9654] starting screenresolution argv=screenresolution get 05:48:14 INFO - 2016-07-20 05:48:14.872 screenresolution[1670:9654] Display 0: 1600x1200x32@60 05:48:14 INFO - 2016-07-20 05:48:14.878 screenresolution[1671:9657] starting screenresolution argv=screenresolution list 05:48:14 INFO - Available Modes on Display 0 05:48:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:48:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:48:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:48:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:48:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:48:15 INFO - Intel Iris: 05:48:15 INFO - Chipset Model: Intel Iris 05:48:15 INFO - Type: GPU 05:48:15 INFO - Bus: Built-In 05:48:15 INFO - VRAM (Dynamic, Max): 1536 MB 05:48:15 INFO - Vendor: Intel (0x8086) 05:48:15 INFO - Device ID: 0x0a2e 05:48:15 INFO - Revision ID: 0x0009 05:48:15 INFO - Displays: 05:48:15 INFO - Display: 05:48:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:48:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:48:15 INFO - Display Serial Number: 1600x1200 60 05:48:15 INFO - Main Display: Yes 05:48:15 INFO - Mirror: Off 05:48:15 INFO - Online: Yes 05:48:15 INFO - Rotation: Supported 05:48:15 INFO - Return code: 0 05:48:15 INFO - Running post-action listener: _resource_record_post_action 05:48:15 INFO - [mozharness: 2016-07-20 12:48:15.113696Z] Finished run-tests step (success) 05:48:15 INFO - Running post-run listener: _resource_record_post_run 05:48:15 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37155840; Write bytes: 706338816; Read time: 237; Write time: 1913 05:48:15 INFO - TinderboxPrint: CPU usage
53.4% 05:48:15 INFO - TinderboxPrint: I/O read bytes / time
37,155,840 / 237 05:48:15 INFO - TinderboxPrint: I/O write bytes / time
706,338,816 / 1,913 05:48:15 INFO - TinderboxPrint: CPU idle
2,257.5 (46.6%) 05:48:15 INFO - TinderboxPrint: CPU system
965.6 (19.9%) 05:48:15 INFO - TinderboxPrint: CPU user
1,622.6 (33.5%) 05:48:15 INFO - TinderboxPrint: Swap in / out
445,128,704 / 0 05:48:15 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 397515264; Write bytes: 392140800; Read time: 29487; Write time: 769 05:48:15 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 35357696; Write bytes: 294393856; Read time: 221; Write time: 1093 05:48:15 INFO - Running post-run listener: _upload_blobber_files 05:48:15 INFO - Blob upload gear active. 05:48:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:48:15 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 05:48:15 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'] 05:48:15 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 05:48:15 INFO - (blobuploader) - INFO - Open directory for files ... 05:48:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:48:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:48:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:17 INFO - (blobuploader) - INFO - Done attempting. 05:48:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:48:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:48:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:18 INFO - (blobuploader) - INFO - Done attempting. 05:48:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:48:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:48:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:23 INFO - (blobuploader) - INFO - Done attempting. 05:48:23 INFO - (blobuploader) - INFO - Iteration through files over. 05:48:23 INFO - Return code: 0 05:48:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:48:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:48:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/289a3196fe7ae6471e9d44558e02c42c0fb3092f91634be3e6baa2c2a9b8ea7d3b58d7d82ea85f1c074352078be5b342f96c3e31966987a3376a75707b9389cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9f96151ba4c0584d8e523ce26c72c3c316c0f47673246db112fb3a0e2ae2e4eb621881c892b7c85358543c844dc3a2fbd50c253df9a8431dd0c968677219a14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a9540890a1b3a72d51089b8b57947908115a378552ce3da2b96456519c1bdc297e38a996daeea25f695feddef80b3eebb9a65f1a640403ea980eef9bf01fff7"} 05:48:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:48:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:48:23 INFO - Contents: 05:48:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/289a3196fe7ae6471e9d44558e02c42c0fb3092f91634be3e6baa2c2a9b8ea7d3b58d7d82ea85f1c074352078be5b342f96c3e31966987a3376a75707b9389cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9f96151ba4c0584d8e523ce26c72c3c316c0f47673246db112fb3a0e2ae2e4eb621881c892b7c85358543c844dc3a2fbd50c253df9a8431dd0c968677219a14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a9540890a1b3a72d51089b8b57947908115a378552ce3da2b96456519c1bdc297e38a996daeea25f695feddef80b3eebb9a65f1a640403ea980eef9bf01fff7"} 05:48:23 INFO - Running post-run listener: copy_logs_to_upload_dir 05:48:23 INFO - Copying logs to upload dir... 05:48:23 INFO - mkdir: /builds/slave/test/build/upload/logs 05:48:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.125800 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-07-20 05:48:23.354412) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 05:48:23.358409) ========= 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.pSLgSmE67z/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.FuQnp7NZA9/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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/289a3196fe7ae6471e9d44558e02c42c0fb3092f91634be3e6baa2c2a9b8ea7d3b58d7d82ea85f1c074352078be5b342f96c3e31966987a3376a75707b9389cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9f96151ba4c0584d8e523ce26c72c3c316c0f47673246db112fb3a0e2ae2e4eb621881c892b7c85358543c844dc3a2fbd50c253df9a8431dd0c968677219a14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a9540890a1b3a72d51089b8b57947908115a378552ce3da2b96456519c1bdc297e38a996daeea25f695feddef80b3eebb9a65f1a640403ea980eef9bf01fff7"} build_url:https://queue.taskcluster.net/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011145 build_url: 'https://queue.taskcluster.net/v1/task/O8nw9w9pQMGnSwoQjkhz9Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/289a3196fe7ae6471e9d44558e02c42c0fb3092f91634be3e6baa2c2a9b8ea7d3b58d7d82ea85f1c074352078be5b342f96c3e31966987a3376a75707b9389cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9f96151ba4c0584d8e523ce26c72c3c316c0f47673246db112fb3a0e2ae2e4eb621881c892b7c85358543c844dc3a2fbd50c253df9a8431dd0c968677219a14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a9540890a1b3a72d51089b8b57947908115a378552ce3da2b96456519c1bdc297e38a996daeea25f695feddef80b3eebb9a65f1a640403ea980eef9bf01fff7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 05:48:23.390851) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:48:23.391215) ========= 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.pSLgSmE67z/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.FuQnp7NZA9/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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.005081 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 05:48:23.440938) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 05:48:23.441440) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 05:48:23.442056) ========= ========= Total master_lag: 0.17 =========