builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0229 starttime: 1469121028.46 results: success (0) buildid: 20160721072953 builduid: 18da2a4bcc4045f387c2b02238e07450 revision: f5154aaeaec4546dd04c66ac61a0d0ee69f4dfdf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 10:10:28.456990) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 10:10:28.457459) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 10:10:28.457748) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 10:10:28.482372) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 10:10:28.482789) ========= 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.50psy7skgF/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.RJytpEIPLs/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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-21 10:10:28-- 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% 17.9M=0.001s 2016-07-21 10:10:28 (17.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097479 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 10:10:28.602810) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 10:10:28.603159) ========= 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.50psy7skgF/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.RJytpEIPLs/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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.026000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 10:10:28.651612) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 10:10:28.652019) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f5154aaeaec4546dd04c66ac61a0d0ee69f4dfdf --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 f5154aaeaec4546dd04c66ac61a0d0ee69f4dfdf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.50psy7skgF/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.RJytpEIPLs/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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-21 10:10:28,734 truncating revision to first 12 chars 2016-07-21 10:10:28,734 Setting DEBUG logging. 2016-07-21 10:10:28,734 attempt 1/10 2016-07-21 10:10:28,734 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f5154aaeaec4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 10:10:29,459 unpacking tar archive at: fx-team-f5154aaeaec4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.018207 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 10:10:29.693115) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 10:10:29.693668) ========= script_repo_revision: f5154aaeaec4546dd04c66ac61a0d0ee69f4dfdf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 10:10:29.694292) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 10:10:29.694674) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 10:10:29.712287) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-21 10:10:29.712720) ========= /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.50psy7skgF/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.RJytpEIPLs/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 10:10:29 INFO - MultiFileLogger online at 20160721 10:10:29 in /builds/slave/test 10:10:29 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 10:10:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:10:29 INFO - {'append_to_log': False, 10:10:29 INFO - 'base_work_dir': '/builds/slave/test', 10:10:29 INFO - 'blob_upload_branch': 'Fx-Team', 10:10:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:10:29 INFO - 'branch': 'Fx-Team', 10:10:29 INFO - 'buildbot_json_path': 'buildprops.json', 10:10:29 INFO - 'config_files': ('talos/mac_config.py',), 10:10:29 INFO - 'default_actions': ('clobber', 10:10:29 INFO - 'read-buildbot-config', 10:10:29 INFO - 'download-and-extract', 10:10:29 INFO - 'populate-webroot', 10:10:29 INFO - 'create-virtualenv', 10:10:29 INFO - 'install', 10:10:29 INFO - 'run-tests'), 10:10:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:10:29 INFO - 'download_minidump_stackwalk': True, 10:10:29 INFO - 'download_symbols': 'ondemand', 10:10:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:10:29 INFO - 'tooltool.py': '/tools/tooltool.py', 10:10:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:10:29 INFO - '/tools/misc-python/virtualenv.py')}, 10:10:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:10:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:10:29 INFO - 'installer_path': 'installer.exe', 10:10:29 INFO - 'log_level': 'info', 10:10:29 INFO - 'log_name': 'talos', 10:10:29 INFO - 'log_to_console': True, 10:10:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:10:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:10:29 INFO - 'opt_config_files': (), 10:10:29 INFO - 'pip_index': False, 10:10:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:10:29 INFO - 'cmd': ('bash', 10:10:29 INFO - '-c', 10:10:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:10:29 INFO - 'enabled': True, 10:10:29 INFO - 'halt_on_failure': False, 10:10:29 INFO - 'name': 'check_screen_resolution'},), 10:10:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:10:29 INFO - 'cmd': ('bash', 10:10:29 INFO - '-c', 10:10:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:10:29 INFO - 'enabled': True, 10:10:29 INFO - 'halt_on_failure': False, 10:10:29 INFO - 'name': 'check_screen_resolution'},), 10:10:29 INFO - 'run_cmd_checks_enabled': True, 10:10:29 INFO - 'sps_profile': False, 10:10:29 INFO - 'sps_profile_interval': 0, 10:10:29 INFO - 'suite': 'dromaeojs', 10:10:29 INFO - 'system_bits': '32', 10:10:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:10:29 INFO - 'title': 't-yosemite-r7-0229', 10:10:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:10:29 INFO - 'use_talos_json': True, 10:10:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:10:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:10:29 INFO - 'work_dir': 'build'} 10:10:29 INFO - [mozharness: 2016-07-21 17:10:29.903471Z] Running clobber step. 10:10:29 INFO - Running pre-action listener: _resource_record_pre_action 10:10:29 INFO - Running main action method: clobber 10:10:29 INFO - rmtree: /builds/slave/test/build 10:10:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:10:32 INFO - Running post-action listener: _resource_record_post_action 10:10:32 INFO - [mozharness: 2016-07-21 17:10:32.178646Z] Finished clobber step (success) 10:10:32 INFO - [mozharness: 2016-07-21 17:10:32.178816Z] Running read-buildbot-config step. 10:10:32 INFO - Running pre-action listener: _resource_record_pre_action 10:10:32 INFO - Running main action method: read_buildbot_config 10:10:32 INFO - Using buildbot properties: 10:10:32 INFO - { 10:10:32 INFO - "project": "", 10:10:32 INFO - "product": "firefox", 10:10:32 INFO - "script_repo_revision": "production", 10:10:32 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 10:10:32 INFO - "repository": "", 10:10:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 10:10:32 INFO - "buildid": "20160721072953", 10:10:32 INFO - "pgo_build": "False", 10:10:32 INFO - "basedir": "/builds/slave/test", 10:10:32 INFO - "buildnumber": 103, 10:10:32 INFO - "slavename": "t-yosemite-r7-0229", 10:10:32 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:10:32 INFO - "platform": "yosemite_r7", 10:10:32 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 10:10:32 INFO - "branch": "fx-team", 10:10:32 INFO - "repo_path": "integration/fx-team", 10:10:32 INFO - "revision": "f5154aaeaec4546dd04c66ac61a0d0ee69f4dfdf", 10:10:32 INFO - "stage_platform": "macosx64", 10:10:32 INFO - "builduid": "18da2a4bcc4045f387c2b02238e07450", 10:10:32 INFO - "slavebuilddir": "test" 10:10:32 INFO - } 10:10:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 10:10:32 INFO - Running post-action listener: _resource_record_post_action 10:10:32 INFO - [mozharness: 2016-07-21 17:10:32.184572Z] Finished read-buildbot-config step (success) 10:10:32 INFO - [mozharness: 2016-07-21 17:10:32.184799Z] Running download-and-extract step. 10:10:32 INFO - Running pre-action listener: _resource_record_pre_action 10:10:32 INFO - Running main action method: download_and_extract 10:10:32 INFO - mkdir: /builds/slave/test/build/tests 10:10:32 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')]} 10:10:32 INFO - https://queue.taskcluster.net/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:10:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:10:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:10:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/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 10:10:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/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 10:10:34 INFO - Downloaded 1199 bytes. 10:10:34 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:10:34 INFO - Using the following test package requirements: 10:10:34 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:10:34 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:10:34 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 10:10:34 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:10:34 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 10:10:34 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:10:34 INFO - u'jsshell-mac.zip'], 10:10:34 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:10:34 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 10:10:34 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:10:34 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:10:34 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 10:10:34 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:10:34 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 10:10:34 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:10:34 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 10:10:34 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:10:34 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 10:10:34 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:10:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:10:34 INFO - https://queue.taskcluster.net/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:10:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:10:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:10:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/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 10:10:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/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 10:10:35 INFO - Downloaded 18806589 bytes. 10:10:35 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'] 10:10:35 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 10:10:38 INFO - Return code: 0 10:10:38 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 10:10: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')]} 10:10:38 INFO - https://queue.taskcluster.net/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:10:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:10:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:10:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/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 10:10:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/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 10:10:39 INFO - Downloaded 18806589 bytes. 10:10:39 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'] 10:10:39 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 10:10:42 INFO - Return code: 0 10:10:42 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')]} 10:10:42 INFO - https://queue.taskcluster.net/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:10:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:10:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:10:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/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 10:10:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/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 10:10:43 INFO - Downloaded 14403317 bytes. 10:10:43 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'] 10:10:43 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 10:10:44 INFO - Return code: 0 10:10: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')]} 10:10:44 INFO - https://queue.taskcluster.net/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:10:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:10:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:10:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 10:10:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCwP7W5dRqyR2sHdIzxi2g/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 10:10:47 INFO - Downloaded 112770781 bytes. 10:10:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:10:47 INFO - mkdir: /builds/slave/test/properties 10:10:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:10:47 INFO - Writing to file /builds/slave/test/properties/build_url 10:10:47 INFO - Contents: 10:10:47 INFO - build_url:https://queue.taskcluster.net/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:10:48 INFO - Running post-action listener: _resource_record_post_action 10:10:48 INFO - Running post-action listener: set_extra_try_arguments 10:10:48 INFO - [mozharness: 2016-07-21 17:10:48.202882Z] Finished download-and-extract step (success) 10:10:48 INFO - [mozharness: 2016-07-21 17:10:48.203049Z] Running populate-webroot step. 10:10:48 INFO - Running pre-action listener: _resource_record_pre_action 10:10:48 INFO - Running main action method: populate_webroot 10:10:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:10:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:10:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:10:48 INFO - '--tppagecycles', 10:10:48 INFO - '7'], 10:10:48 INFO - 'tests': ['tsvgm']}}, 10:10:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:10:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:10:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:10:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:10:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:10:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:10:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:48 INFO - 'talos_options': ['--disable-e10s'], 10:10:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:10:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:10:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:48 INFO - 'talos_options': ['--disable-e10s'], 10:10:48 INFO - 'tests': ['damp', 'tps']}, 10:10:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:48 INFO - 'tests': ['damp', 'tps']}, 10:10:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:10:48 INFO - 'tests': ['dromaeo_dom']}, 10:10:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:10:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:10:48 INFO - 'tests': ['basic_compositor_video']}, 10:10:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:10:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:10:48 INFO - 'tests': ['a11yr', 10:10:48 INFO - 'ts_paint', 10:10:48 INFO - 'tpaint', 10:10:48 INFO - 'sessionrestore', 10:10:48 INFO - 'sessionrestore_no_auto_restore', 10:10:48 INFO - 'tabpaint']}, 10:10:48 INFO - 'other-e10s': {'tests': ['a11yr', 10:10:48 INFO - 'ts_paint', 10:10:48 INFO - 'tpaint', 10:10:48 INFO - 'sessionrestore', 10:10:48 INFO - 'sessionrestore_no_auto_restore', 10:10:48 INFO - 'tabpaint']}, 10:10:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:10:48 INFO - 'ts_paint', 10:10:48 INFO - 'tpaint', 10:10:48 INFO - 'sessionrestore', 10:10:48 INFO - 'sessionrestore_no_auto_restore', 10:10:48 INFO - 'tabpaint']}, 10:10:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:10:48 INFO - 'ts_paint', 10:10:48 INFO - 'tpaint', 10:10:48 INFO - 'sessionrestore', 10:10:48 INFO - 'sessionrestore_no_auto_restore', 10:10:48 INFO - 'tabpaint']}, 10:10:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:10:48 INFO - 'tests': ['a11yr', 10:10:48 INFO - 'ts_paint', 10:10:48 INFO - 'tpaint', 10:10:48 INFO - 'sessionrestore', 10:10:48 INFO - 'sessionrestore_no_auto_restore', 10:10:48 INFO - 'tabpaint']}, 10:10:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:10:48 INFO - 'tests': ['a11yr', 10:10:48 INFO - 'ts_paint', 10:10:48 INFO - 'tpaint', 10:10:48 INFO - 'sessionrestore', 10:10:48 INFO - 'sessionrestore_no_auto_restore', 10:10:48 INFO - 'tabpaint']}, 10:10:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:10:48 INFO - 'tests': ['tsvgx', 10:10:48 INFO - 'tsvgr_opacity', 10:10:48 INFO - 'tart', 10:10:48 INFO - 'tscrollx', 10:10:48 INFO - 'cart']}, 10:10:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:10:48 INFO - 'tsvgr_opacity', 10:10:48 INFO - 'tart', 10:10:48 INFO - 'tscrollx', 10:10:48 INFO - 'cart']}, 10:10:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:48 INFO - 'talos_options': ['--disable-e10s'], 10:10:48 INFO - 'tests': ['tp5o']}, 10:10:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:48 INFO - 'tests': ['tp5o']}, 10:10:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:48 INFO - 'talos_options': ['--disable-e10s', 10:10:48 INFO - '--xperf_path', 10:10:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:10:48 INFO - 'tests': ['tp5n']}, 10:10:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:48 INFO - 'talos_options': ['--xperf_path', 10:10:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:10:48 INFO - 'tests': ['tp5n']}}, 10:10:48 INFO - 'talos.zip': {'path': '', 10:10:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:10:48 INFO - Running post-action listener: _resource_record_post_action 10:10:48 INFO - [mozharness: 2016-07-21 17:10:48.222176Z] Finished populate-webroot step (success) 10:10:48 INFO - [mozharness: 2016-07-21 17:10:48.222305Z] Running create-virtualenv step. 10:10:48 INFO - Running pre-action listener: _resource_record_pre_action 10:10:48 INFO - Running main action method: create_virtualenv 10:10:48 INFO - Creating virtualenv /builds/slave/test/build/venv 10:10:48 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 10:10:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:10:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:10:48 INFO - Using real prefix '/tools/python27' 10:10:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:10:49 INFO - Installing distribute.............................................................................................................................................................................................done. 10:10:51 INFO - Installing pip.................done. 10:10:51 INFO - Return code: 0 10:10:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:10:51 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')]} 10:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:51 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')]} 10:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:51 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 0x10e25ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8224b0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c2d30>, '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 0x10df27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1bab20>, '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.50psy7skgF/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.RJytpEIPLs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 10:10:51 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 10:10:51 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 10:10:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50psy7skgF/Render', 10:10:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:51 INFO - 'HOME': '/Users/cltbld', 10:10:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:51 INFO - 'LOGNAME': 'cltbld', 10:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:51 INFO - 'MOZ_NO_REMOTE': '1', 10:10:51 INFO - 'NO_EM_RESTART': '1', 10:10:51 INFO - 'PAGER': '/bin/cat', 10:10:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:51 INFO - 'PWD': '/builds/slave/test', 10:10:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:51 INFO - 'SHELL': '/bin/bash', 10:10:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJytpEIPLs/Listeners', 10:10:51 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 10:10:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:51 INFO - 'USER': 'cltbld', 10:10:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:51 INFO - 'XPC_FLAGS': '0x0', 10:10:51 INFO - 'XPC_SERVICE_NAME': '0', 10:10:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:52 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 10:10:52 INFO - Cleaning up... 10:10:52 INFO - Return code: 0 10:10:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:10:52 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')]} 10:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:52 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')]} 10:10:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:52 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 0x10e25ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8224b0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c2d30>, '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 0x10df27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1bab20>, '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.50psy7skgF/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.RJytpEIPLs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 10:10:52 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 10:10:52 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 10:10:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50psy7skgF/Render', 10:10:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:52 INFO - 'HOME': '/Users/cltbld', 10:10:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:52 INFO - 'LOGNAME': 'cltbld', 10:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:52 INFO - 'MOZ_NO_REMOTE': '1', 10:10:52 INFO - 'NO_EM_RESTART': '1', 10:10:52 INFO - 'PAGER': '/bin/cat', 10:10:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:52 INFO - 'PWD': '/builds/slave/test', 10:10:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:52 INFO - 'SHELL': '/bin/bash', 10:10:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJytpEIPLs/Listeners', 10:10:52 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 10:10:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:52 INFO - 'USER': 'cltbld', 10:10:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:52 INFO - 'XPC_FLAGS': '0x0', 10:10:52 INFO - 'XPC_SERVICE_NAME': '0', 10:10:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:52 INFO - Downloading/unpacking psutil>=3.1.1 10:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:10:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:10:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:10:55 INFO - Installing collected packages: psutil 10:10:55 INFO - Running setup.py install for psutil 10:10:55 INFO - building 'psutil._psutil_osx' extension 10:10:55 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 10:10:55 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 10:10:55 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 10:10:55 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 10:10:55 INFO - building 'psutil._psutil_posix' extension 10:10:55 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 10:10:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:10:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:10:56 INFO - ^ 10:10:56 INFO - 1 warning generated. 10:10:56 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 10:10:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:10:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:10:56 INFO - Successfully installed psutil 10:10:56 INFO - Cleaning up... 10:10:56 INFO - Return code: 0 10:10:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:10:56 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')]} 10:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:56 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')]} 10:10:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:56 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 0x10e25ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8224b0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c2d30>, '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 0x10df27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1bab20>, '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.50psy7skgF/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.RJytpEIPLs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 10:10:56 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 10:10:56 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 10:10:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50psy7skgF/Render', 10:10:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:56 INFO - 'HOME': '/Users/cltbld', 10:10:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:56 INFO - 'LOGNAME': 'cltbld', 10:10:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:56 INFO - 'MOZ_NO_REMOTE': '1', 10:10:56 INFO - 'NO_EM_RESTART': '1', 10:10:56 INFO - 'PAGER': '/bin/cat', 10:10:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:56 INFO - 'PWD': '/builds/slave/test', 10:10:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:56 INFO - 'SHELL': '/bin/bash', 10:10:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJytpEIPLs/Listeners', 10:10:56 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 10:10:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:56 INFO - 'USER': 'cltbld', 10:10:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:56 INFO - 'XPC_FLAGS': '0x0', 10:10:56 INFO - 'XPC_SERVICE_NAME': '0', 10:10:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:10:56 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 10:10:56 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 10:10:56 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 10:10:56 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 10:10:56 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 10:10:56 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 10:10:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:10:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:10:59 INFO - Installing collected packages: mozsystemmonitor 10:10:59 INFO - Running setup.py install for mozsystemmonitor 10:10:59 INFO - Successfully installed mozsystemmonitor 10:10:59 INFO - Cleaning up... 10:10:59 INFO - Return code: 0 10:10:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:10:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e25ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8224b0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c2d30>, '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 0x10df27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1bab20>, '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.50psy7skgF/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.RJytpEIPLs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 10:10:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:10:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:10:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50psy7skgF/Render', 10:10:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:59 INFO - 'HOME': '/Users/cltbld', 10:10:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:59 INFO - 'LOGNAME': 'cltbld', 10:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:59 INFO - 'MOZ_NO_REMOTE': '1', 10:10:59 INFO - 'NO_EM_RESTART': '1', 10:10:59 INFO - 'PAGER': '/bin/cat', 10:10:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:59 INFO - 'PWD': '/builds/slave/test', 10:10:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:59 INFO - 'SHELL': '/bin/bash', 10:10:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJytpEIPLs/Listeners', 10:10:59 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 10:10:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:59 INFO - 'USER': 'cltbld', 10:10:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:59 INFO - 'XPC_FLAGS': '0x0', 10:10:59 INFO - 'XPC_SERVICE_NAME': '0', 10:10:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:59 INFO - Downloading/unpacking jsonschema==2.5.1 10:10: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 10:10: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 10:10: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 10:10: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 10:10: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 10:10: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 10:11:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:11:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:11:01 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 10:11:01 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 10:11:01 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 10:11:01 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 10:11:01 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 10:11:01 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 10:11:02 INFO - Downloading functools32-3.2.3-2.tar.gz 10:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:11:02 INFO - warning: no files found matching '*.txt' 10:11:02 INFO - no previously-included directories found matching 'build' 10:11:02 INFO - no previously-included directories found matching 'dist' 10:11:02 INFO - no previously-included directories found matching '.git*' 10:11:02 INFO - Installing collected packages: jsonschema, functools32 10:11:02 INFO - Running setup.py install for functools32 10:11:02 INFO - warning: no files found matching '*.txt' 10:11:02 INFO - no previously-included directories found matching 'build' 10:11:02 INFO - no previously-included directories found matching 'dist' 10:11:02 INFO - no previously-included directories found matching '.git*' 10:11:02 INFO - Successfully installed jsonschema functools32 10:11:02 INFO - Cleaning up... 10:11:02 INFO - Return code: 0 10:11:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:11: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')]} 10:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11: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')]} 10:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:02 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 0x10e25ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8224b0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c2d30>, '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 0x10df27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1bab20>, '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.50psy7skgF/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.RJytpEIPLs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 10:11:02 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 10:11:02 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 10:11:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50psy7skgF/Render', 10:11:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:02 INFO - 'HOME': '/Users/cltbld', 10:11:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:02 INFO - 'LOGNAME': 'cltbld', 10:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:02 INFO - 'MOZ_NO_REMOTE': '1', 10:11:02 INFO - 'NO_EM_RESTART': '1', 10:11:02 INFO - 'PAGER': '/bin/cat', 10:11:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:02 INFO - 'PWD': '/builds/slave/test', 10:11:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:02 INFO - 'SHELL': '/bin/bash', 10:11:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJytpEIPLs/Listeners', 10:11:02 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 10:11:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:02 INFO - 'USER': 'cltbld', 10:11:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:02 INFO - 'XPC_FLAGS': '0x0', 10:11:02 INFO - 'XPC_SERVICE_NAME': '0', 10:11:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:03 INFO - Downloading/unpacking blobuploader==1.2.4 10:11:03 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 10:11:03 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 10:11:03 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 10:11:03 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 10:11:03 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 10:11:03 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 10:11:05 INFO - Downloading blobuploader-1.2.4.tar.gz 10:11:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:11:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:11:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:11:06 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 10:11:06 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 10:11:06 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 10:11:06 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 10:11:06 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 10:11:06 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 10:11:06 INFO - Downloading docopt-0.6.1.tar.gz 10:11:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:11:06 INFO - Installing collected packages: blobuploader, requests, docopt 10:11:06 INFO - Running setup.py install for blobuploader 10:11:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:11:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:11:06 INFO - Running setup.py install for requests 10:11:07 INFO - Running setup.py install for docopt 10:11:07 INFO - Successfully installed blobuploader requests docopt 10:11:07 INFO - Cleaning up... 10:11:07 INFO - Return code: 0 10:11:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:11:07 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')]} 10:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:07 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')]} 10:11:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:07 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 0x10e25ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8224b0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c2d30>, '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 0x10df27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1bab20>, '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.50psy7skgF/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.RJytpEIPLs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 10:11:07 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 10:11:07 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 10:11:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50psy7skgF/Render', 10:11:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:07 INFO - 'HOME': '/Users/cltbld', 10:11:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:07 INFO - 'LOGNAME': 'cltbld', 10:11:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:07 INFO - 'MOZ_NO_REMOTE': '1', 10:11:07 INFO - 'NO_EM_RESTART': '1', 10:11:07 INFO - 'PAGER': '/bin/cat', 10:11:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:07 INFO - 'PWD': '/builds/slave/test', 10:11:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:07 INFO - 'SHELL': '/bin/bash', 10:11:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJytpEIPLs/Listeners', 10:11:07 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 10:11:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:07 INFO - 'USER': 'cltbld', 10:11:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:07 INFO - 'XPC_FLAGS': '0x0', 10:11:07 INFO - 'XPC_SERVICE_NAME': '0', 10:11:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:11:07 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-ohNzcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:11:08 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-GJc9wZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:11:08 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-qcJeHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:11:08 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-RLcEfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:11:08 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-wqZvUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:11:08 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-HHD8Ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:11:08 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-saJZsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:11:08 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-bnSnOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:11:08 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-H0FNNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:11:08 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-uAtmB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:11:08 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-LBGBVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:11:09 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-HI6mIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:11:09 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-7dRlOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:11:09 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-Bw41m3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:09 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-RbqNYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:11:09 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-XEvjvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:11:09 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-TCV_UK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:11:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:11:09 INFO - Running setup.py install for manifestparser 10:11:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:11:09 INFO - Running setup.py install for mozcrash 10:11:10 INFO - Running setup.py install for mozdebug 10:11:10 INFO - Running setup.py install for mozdevice 10:11:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:11:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:11:10 INFO - Running setup.py install for mozfile 10:11:10 INFO - Running setup.py install for mozhttpd 10:11:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:11:10 INFO - Running setup.py install for mozinfo 10:11:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:11:10 INFO - Running setup.py install for mozInstall 10:11:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:11:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:11:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:11:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:11:10 INFO - Running setup.py install for mozleak 10:11:11 INFO - Running setup.py install for mozlog 10:11:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:11:11 INFO - Running setup.py install for moznetwork 10:11:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:11:11 INFO - Running setup.py install for mozprocess 10:11:11 INFO - Running setup.py install for mozprofile 10:11:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:11:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:11:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:11:11 INFO - Running setup.py install for mozrunner 10:11:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:11:11 INFO - Running setup.py install for mozscreenshot 10:11:12 INFO - Running setup.py install for moztest 10:11:12 INFO - Running setup.py install for mozversion 10:11:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:11:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:11:12 INFO - Cleaning up... 10:11:12 INFO - Return code: 0 10:11:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:11:12 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')]} 10:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:12 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')]} 10:11:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:12 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 0x10e25ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8224b0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c2d30>, '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 0x10df27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1bab20>, '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.50psy7skgF/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.RJytpEIPLs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 10:11:12 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 10:11:12 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 10:11:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50psy7skgF/Render', 10:11:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:12 INFO - 'HOME': '/Users/cltbld', 10:11:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:12 INFO - 'LOGNAME': 'cltbld', 10:11:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:12 INFO - 'MOZ_NO_REMOTE': '1', 10:11:12 INFO - 'NO_EM_RESTART': '1', 10:11:12 INFO - 'PAGER': '/bin/cat', 10:11:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:12 INFO - 'PWD': '/builds/slave/test', 10:11:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:12 INFO - 'SHELL': '/bin/bash', 10:11:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJytpEIPLs/Listeners', 10:11:12 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 10:11:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:12 INFO - 'USER': 'cltbld', 10:11:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:12 INFO - 'XPC_FLAGS': '0x0', 10:11:12 INFO - 'XPC_SERVICE_NAME': '0', 10:11:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:11:12 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-6M1OJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:11:12 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)) 10:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:11:12 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-BDaG2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:11:12 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)) 10:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:11:12 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-i6Zkgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:11:12 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)) 10:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:11:12 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-eP43Xj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:11:13 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)) 10:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:11:13 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-WJpUNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:11:13 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)) 10:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:11:13 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-5F6630-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:11:13 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)) 10:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:11:13 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-49SD41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:11:13 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)) 10:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:11:13 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-9_2rS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:11:13 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)) 10:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:11:13 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-nB2eOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:11:13 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)) 10:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:11:13 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-qQtVV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:11:13 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)) 10:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:11:13 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-5sGr65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:11:13 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)) 10:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:11:13 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-YBpxnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:11:13 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)) 10:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:11:13 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-BB06eb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:11:13 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)) 10:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:11:13 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-aTcMpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:11:14 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)) 10:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:14 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-MyxjTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:14 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)) 10:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:11:14 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-Tcejcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:11:14 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)) 10:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:11:14 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-gIQj8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:11:14 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)) 10:11:14 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)) 10:11:14 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)) 10:11:14 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)) 10:11:14 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)) 10:11:14 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)) 10:11:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:16 INFO - Downloading blessings-1.6.tar.gz 10:11:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:11:16 INFO - Installing collected packages: blessings 10:11:16 INFO - Running setup.py install for blessings 10:11:17 INFO - Successfully installed blessings 10:11:17 INFO - Cleaning up... 10:11:17 INFO - Return code: 0 10:11:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:11:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:11:17 INFO - Reading from file tmpfile_stdout 10:11:17 INFO - Current package versions: 10:11:17 INFO - blessings == 1.6 10:11:17 INFO - blobuploader == 1.2.4 10:11:17 INFO - docopt == 0.6.1 10:11:17 INFO - functools32 == 3.2.3-2 10:11:17 INFO - jsonschema == 2.5.1 10:11:17 INFO - manifestparser == 1.1 10:11:17 INFO - mozInstall == 1.12 10:11:17 INFO - mozcrash == 0.17 10:11:17 INFO - mozdebug == 0.1 10:11:17 INFO - mozdevice == 0.48 10:11:17 INFO - mozfile == 1.2 10:11:17 INFO - mozhttpd == 0.7 10:11:17 INFO - mozinfo == 0.9 10:11:17 INFO - mozleak == 0.1 10:11:17 INFO - mozlog == 3.3 10:11:17 INFO - moznetwork == 0.27 10:11:17 INFO - mozprocess == 0.23 10:11:17 INFO - mozprofile == 0.28 10:11:17 INFO - mozrunner == 6.12 10:11:17 INFO - mozscreenshot == 0.1 10:11:17 INFO - mozsystemmonitor == 0.3 10:11:17 INFO - moztest == 0.7 10:11:17 INFO - mozversion == 1.4 10:11:17 INFO - psutil == 3.1.1 10:11:17 INFO - requests == 1.2.3 10:11:17 INFO - wsgiref == 0.1.2 10:11:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:11: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')]} 10:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11: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')]} 10:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:17 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 0x10e25ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8224b0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c2d30>, '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 0x10df27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1bab20>, '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.50psy7skgF/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.RJytpEIPLs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 10:11:17 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 10:11:17 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 10:11:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50psy7skgF/Render', 10:11:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:17 INFO - 'HOME': '/Users/cltbld', 10:11:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:17 INFO - 'LOGNAME': 'cltbld', 10:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:17 INFO - 'MOZ_NO_REMOTE': '1', 10:11:17 INFO - 'NO_EM_RESTART': '1', 10:11:17 INFO - 'PAGER': '/bin/cat', 10:11:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:17 INFO - 'PWD': '/builds/slave/test', 10:11:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:17 INFO - 'SHELL': '/bin/bash', 10:11:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJytpEIPLs/Listeners', 10:11:17 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 10:11:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:17 INFO - 'USER': 'cltbld', 10:11:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:17 INFO - 'XPC_FLAGS': '0x0', 10:11:17 INFO - 'XPC_SERVICE_NAME': '0', 10:11:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:17 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)) 10:11:17 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)) 10:11:17 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)) 10:11:17 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)) 10:11:17 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)) 10:11:17 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)) 10:11:17 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)) 10:11:17 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)) 10:11:17 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)) 10:11:17 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)) 10:11:17 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)) 10:11:17 INFO - Cleaning up... 10:11:17 INFO - Return code: 0 10:11:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:11: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')]} 10:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11: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')]} 10:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:17 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 0x10e25ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8224b0430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c2d30>, '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 0x10df27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1bab20>, '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.50psy7skgF/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.RJytpEIPLs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 10:11:17 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 10:11:17 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 10:11:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50psy7skgF/Render', 10:11:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:17 INFO - 'HOME': '/Users/cltbld', 10:11:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:17 INFO - 'LOGNAME': 'cltbld', 10:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:17 INFO - 'MOZ_NO_REMOTE': '1', 10:11:17 INFO - 'NO_EM_RESTART': '1', 10:11:17 INFO - 'PAGER': '/bin/cat', 10:11:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:17 INFO - 'PWD': '/builds/slave/test', 10:11:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:17 INFO - 'SHELL': '/bin/bash', 10:11:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJytpEIPLs/Listeners', 10:11:17 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 10:11:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:17 INFO - 'USER': 'cltbld', 10:11:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:17 INFO - 'XPC_FLAGS': '0x0', 10:11:17 INFO - 'XPC_SERVICE_NAME': '0', 10:11:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:11:18 INFO - Cleaning up... 10:11:18 INFO - Return code: 0 10:11:18 INFO - Running post-action listener: _resource_record_post_action 10:11:18 INFO - Running post-action listener: _start_resource_monitoring 10:11:18 INFO - Starting resource monitoring. 10:11:18 INFO - [mozharness: 2016-07-21 17:11:18.050280Z] Finished create-virtualenv step (success) 10:11:18 INFO - [mozharness: 2016-07-21 17:11:18.051312Z] Running install step. 10:11:18 INFO - Running pre-action listener: _resource_record_pre_action 10:11:18 INFO - Running main action method: install 10:11:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:11:18 INFO - Reading from file tmpfile_stdout 10:11:18 INFO - Detecting whether we're running mozinstall >=1.0... 10:11:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:11:18 INFO - Reading from file tmpfile_stdout 10:11:18 INFO - Output received: 10:11:18 INFO - Usage: mozinstall [options] installer 10:11:18 INFO - Options: 10:11:18 INFO - -h, --help show this help message and exit 10:11:18 INFO - -d DEST, --destination=DEST 10:11:18 INFO - Directory to install application into. [default: 10:11:18 INFO - "/builds/slave/test"] 10:11:18 INFO - --app=APP Application being installed. [default: firefox] 10:11:18 INFO - mkdir: /builds/slave/test/build/application 10:11:18 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'] 10:11:18 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 10:11:53 INFO - Reading from file tmpfile_stdout 10:11:53 INFO - Output received: 10:11:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:11:53 INFO - Running post-action listener: _resource_record_post_action 10:11:53 INFO - [mozharness: 2016-07-21 17:11:53.080770Z] Finished install step (success) 10:11:53 INFO - [mozharness: 2016-07-21 17:11:53.080910Z] Running run-tests step. 10:11:53 INFO - Running pre-action listener: _resource_record_pre_action 10:11:53 INFO - Running main action method: run_tests 10:11:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:11:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:11:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:11:53 INFO - 2016-07-21 10:11:53.093 screenresolution[1642:7716] starting screenresolution argv=screenresolution get 10:11:53 INFO - 2016-07-21 10:11:53.095 screenresolution[1642:7716] Display 0: 1600x1200x32@60 10:11:53 INFO - 2016-07-21 10:11:53.100 screenresolution[1643:7718] starting screenresolution argv=screenresolution list 10:11:53 INFO - Available Modes on Display 0 10:11:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:11:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:11:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:11:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:11:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:11:53 INFO - Intel Iris: 10:11:53 INFO - Chipset Model: Intel Iris 10:11:53 INFO - Type: GPU 10:11:53 INFO - Bus: Built-In 10:11:53 INFO - VRAM (Dynamic, Max): 1536 MB 10:11:53 INFO - Vendor: Intel (0x8086) 10:11:53 INFO - Device ID: 0x0a2e 10:11:53 INFO - Revision ID: 0x0009 10:11:53 INFO - Displays: 10:11:53 INFO - Display: 10:11:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:11:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:11:53 INFO - Display Serial Number: 1600x1200 60 10:11:53 INFO - Main Display: Yes 10:11:53 INFO - Mirror: Off 10:11:53 INFO - Online: Yes 10:11:53 INFO - Rotation: Supported 10:11:53 INFO - Return code: 0 10:11:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:11:53 INFO - Python 2.7.3 10:11:53 INFO - Return code: 0 10:11:53 INFO - grabbing minidump binary from tooltool 10:11: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')]} 10:11:53 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 0x10e1c2d30>, '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 0x10df27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1bab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:11:53 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 10:11:53 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 10:11:53 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 10:11:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:11:53 INFO - Return code: 0 10:11:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:11:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:11:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:11:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:11:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:11:53 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/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0229', '--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 10:11:53 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/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0229 --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 10:11:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50psy7skgF/Render', 10:11:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:53 INFO - 'HOME': '/Users/cltbld', 10:11:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:53 INFO - 'LOGNAME': 'cltbld', 10:11:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:11:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:11:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:53 INFO - 'MOZ_NO_REMOTE': '1', 10:11:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:11:53 INFO - 'NO_EM_RESTART': '1', 10:11:53 INFO - 'PAGER': '/bin/cat', 10:11:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:53 INFO - 'PWD': '/builds/slave/test', 10:11:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:11:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:53 INFO - 'SHELL': '/bin/bash', 10:11:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RJytpEIPLs/Listeners', 10:11:53 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 10:11:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:53 INFO - 'USER': 'cltbld', 10:11:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:53 INFO - 'XPC_FLAGS': '0x0', 10:11:53 INFO - 'XPC_SERVICE_NAME': '0', 10:11:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:53 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/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0229', '--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 10:11:53 INFO - mozversion application_buildid: 20160721072953 10:11:53 INFO - mozversion application_changeset: f5154aaeaec4546dd04c66ac61a0d0ee69f4dfdf 10:11:53 INFO - mozversion application_display_name: Nightly 10:11:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:11:53 INFO - mozversion application_name: Firefox 10:11:53 INFO - mozversion application_remotingname: firefox 10:11:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:11:53 INFO - mozversion application_vendor: Mozilla 10:11:53 INFO - mozversion application_version: 50.0a1 10:11:53 INFO - mozversion platform_buildid: 20160721072953 10:11:53 INFO - mozversion platform_changeset: f5154aaeaec4546dd04c66ac61a0d0ee69f4dfdf 10:11:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:11:53 INFO - mozversion platform_version: 50.0a1 10:11:53 INFO - using testdate: 1469121113 10:11:53 INFO - actual date: 1469121113 10:11:53 INFO - starting webserver on 'localhost:49275' 10:11:53 INFO - SUITE-START | Running 2 tests 10:11:53 INFO - TEST-START | dromaeo_css 10:11:53 INFO - operating with platform_type : mac_ 10:11:53 INFO - Initialising browser for dromaeo_css test... 10:11:53 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpDe1qyP/profile http://localhost:49275/getInfo.html) 10:11:56 INFO - PROCESS | 1653 | JavaScript error: , line 0: uncaught exception: undefined 10:11:56 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 10:11:56 INFO - PROCESS | 1653 | colorDepth:24 10:11:56 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 10:11:56 INFO - PROCESS | 1653 | __metrics 10:11:56 INFO - PROCESS | 1653 | 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" 10:11:56 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:11:57 INFO - TEST-INFO | 1653: exit 0 10:11:57 INFO - Browser initialized. 10:11:57 INFO - Running cycle 1/1 for dromaeo_css test... 10:11:57 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpDe1qyP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:12:09 INFO - PROCESS | 1655 | RSS: Main: 245395456 10:12:09 INFO - PROCESS | 1655 | 10:13:00 INFO - PROCESS | 1655 | 1469121180757 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] 10:15:11 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 10:15:12 INFO - PROCESS | 1655 | RSS: Main: 402055168 10:15:12 INFO - PROCESS | 1655 | 10:18:15 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 10:18:16 INFO - PROCESS | 1655 | RSS: Main: 383287296 10:18:16 INFO - PROCESS | 1655 | 10:21:18 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 10:21:19 INFO - PROCESS | 1655 | RSS: Main: 381063168 10:21:19 INFO - PROCESS | 1655 | 10:24:22 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 10:24:22 INFO - PROCESS | 1655 | RSS: Main: 380399616 10:24:22 INFO - PROCESS | 1655 | 10:27:25 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 10:27:26 INFO - PROCESS | 1655 | RSS: Main: 350564352 10:27:26 INFO - PROCESS | 1655 | 10:30:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 10:30:29 INFO - PROCESS | 1655 | RSS: Main: 422957056 10:30:29 INFO - PROCESS | 1655 | 10:30:29 INFO - PROCESS | 1655 | __start_tp_report 10:30:29 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 10:30:29 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 10:30:29 INFO - PROCESS | 1655 | |i|pagename|runs| 10:30:29 INFO - PROCESS | 1655 | |0;dojo.html;3382.0361197058837;3402.7447941404384;3403.1322012886717;3416.5558477397935;3458.619551849296;5670.546356291497;5769.278840144982;5793.82618521444;5865.090910908811;5900.970495147523;5760.45275698809;5821.8835623287505;5840.912386314198;5851.736671849766;5856.707164641773;5243.344581927259;5274.393444753845;5308.601854860882;5314.601404894629;5363.410024897258;5251.947480525184;5343.251944727741;5406.648567843097;5465.180222966547;5477.178423236526;5370.865728356784;5371.7582708778;5397.028534863723;5413.675179489243;5419.891602167954;5368.91946620801;5374.032674118657;5386.865328366803;5390.299261096033;5425.321834770653;4887.413510378742;5011.997600479882;5196.116660167742;5271.024860400838;5274.997750427407;5737.426257374229;5781;5875.970620146872;5895.4399332063385;5975.312839023524;6187.038155065131;6240.656763877985;6245.87508249837;6268.059791031336;6274.592151510137;6263.185785847811;6279.748810047593;6286.339934306923;6296.68516574174;6303;5776.624519406225;5854.560907931922;5872.119182122716;5939.910901336483;5948.226730525091;6404.455621272151;6411;6414.166158399472;6414.967925160345;6419.390157934989;6238.376162383816;6238.625682459067;6239.220097487814;6250.093736408196;6254.34329395416;5158.793648254103;6104.186323682826;6177.536684748662;6207.906881396782;6256.655883926428;6173.938260617337;6214.5960512566535;6227.252729672468;6228.4705800007505;6236.812895613139;6190.907136392958;6203.348648391944;6212.502999760009;6215.875682486416;6242.188515492957;4915.115279249698;5022.196448568212;5035.924461133086;5074.188129899198;5121.436641969381;4528.818847246073;4535.047639995638;4544.068465964469;4548.044910568751;4562.201614717477;5697.686627235459;5731.570132240098;5755.913661295084;5761.567882408836;5779.2775903012125;6172.135900973867;6193.411625895533;6213.285472170668;6214.875702486015;6231.40801623845;5770.278715160605;5770.711464426762;5785.623934444247;5797;5830.416958304136;5425;5480.506754392044;5501.587380946365;5506.476884695948;5513.889722205534;6298.086777417248;6326.240851097989;6343.207099112611;6358.395952384516;6361.427471527492;6237.282712488122;6254.8123556293385;6255.655938923403;6273.65494897785;6274.529410294155;6422.839429014125;6448.065030570635;6458.031295305742;6464.967675161594;6468.644224567505;6095.861538842981;6126.3955342902;6188.659623720739;6212.658303793335;6225.875482490415;6105.004892149239;6181.103739957591;6184.072389141665;6205.875882482234;6250.343713909974;6143.72353244114;6190.535709821691;6191.071339299141;6201;6210.472109870711;6162.506999439945;6167.290761562478;6193.907091393543;6197.194364732556;6204.534659900435;5941.019731744386;6018.307894592178;6047.969760151171;6049.848753781191;6075.979647063026;5067.417247016493;6198.752049917953;6205.689715514116;6205.813825585059;6223.782167624111;6043.848903777441;6078.422549857756;6094.2077529920825;6111.7249723763425;6132.6627035513475 10:30:29 INFO - PROCESS | 1655 | |1;ext.html;469.49529256049743;493.8271604938272;512.0833707663282;530.8805518758278;541.0531569752931;28365.43269134616;42479.15041699168;43981.78009109954;44024.496937882766;46098.76950615251;45083.77458112705;45428.864278393055;45477.7726111369;45528.77235613826;45599.86000349993;9645.758856028602;9838.754031149243;9841.70474885751;9845.40927544346;9853.965333639972;7133.464990125735;8846.911530884705;8860.512671803048;8975.237104846095;8985.820283594325;11789;11909.761804763899;11949.1038172137;12183.817242741366;12243.204191727555;11889.227200231957;11992.16054876159;11995.520179192821;11998.760024799456;12004.859538343844;7084.645767711594;11936.701582460508;12020.218685785396;12028.51885924562;12040.458179381949;41836.744897653116;42013;42097.78951105256;44260;44315.33526997098;3474.878379256739;3500.649935006479;3532.275883443888;3956.1692044670654;4424.026714122888;26534.071307504146;31234.062978110695;33182;33256.33487330264;33362.833185833915;3254.4142054430195;3430.1081718753053;3434.9313013739834;3436.742244331685;3446.5347178130883;529776;532247;534675;534679.3266033684;535451;67003.32996670068;67278.66360668263;67283.66358168177;67304;67346.66326668434;70869.29130708627;71059.64470177617;71069.28930710627;71074.64462677758;71121.6443917777;16762.91618541924;17058.74411883823;17062.317507299816;17065.488035358958;17068.2319295632;12919.418626161845;12975.156614820007;12982.610521684364;12999.870001299865;13000.219986800823;16835.366854516677;18060.458186254433;18655.603873247834;20490.897545512176;20637.896810515853;6568.080468734381;7930.841383172305;7959.482633628795;8058;8081.15147909461;793.9722109726132;841.0790184747691;891.5453118909273;1003.9498025098716;1024.0578667625803;30470.54294185589;31317.530237046463;31337;31645.236950021368;31801.52297715536;1057.4448414582253;1123.6404350607727;1136.772645470909;1183.8816118388265;1202.4108186988487;15425.179307491888;16250.268737906823;16270.26783794732;16481.17121429733;16608.086555239337;705350;715241;715352;715955;717181;63626;64169.679151603945;64308;64396.356036439036;64424.677876610316;1258.8615252322238;1311.8950483961069;1366.2417358366201;1407.3737186951912;1440.9351579178963;30564.847175763978;31008.379832403676;33040;33054.338913222084;33125.509352079695;54035.1368376848;54684.17973730477;54776;54794.90410191854;54800;590653;595090.0490995208;595215;597065;597764.0447190945;27726.752296147126;28160.577591336558;28381.432371352872;28988.855055724587;31035.53446698256;41200.793996029824;51311;51895.74052129715;52184;55620.44379556153;40409.797951010056;42750;42983.570164297955;44315;44572;39513.80243098881;42164.367534486395;42165.367519486615;43459.56540434555;43699.56300436955;32744.83627581942;39996.80001599973;40165.397519036655;40412.393814092226;40979;35714.821425892704;36494.635053650185;36571;37628.05929851776;37691;26811.463770724866;26838.463230735666;27036.459270814867;27271.318217044733;27951.72048279491 10:30:29 INFO - PROCESS | 1655 | |2;jquery.html;332.4531146264397;375.83651111766386;383.7064645546157;391.790392140205;410.53609421353883;15612.843871561281;16076.83923160768;21471.463213419673;21936.670949935764;22001.889990550044;21400.785992140074;21725;22192.8890355548;22240.443988900246;22452.32643020708;4966.478519755427;6047.123167140773;6101.328853826076;6193.721282542297;6204.627722336674;4236.385724070008;5871.765129394819;6136.417040381157;6140.877182456348;6149.907751383733;4388.956110438902;6501.317361677026;6769.153855768029;6839.384455399013;6907.723691052352;5798.275215598051;6128.754849806002;6185.443310102068;6891.310868913119;6891.345322194408;6062.151298818169;6065.666388348639;6068.180795592582;6069.423404776539;6084.817455476343;24205.757942420703;26064.86967565169;26667.333316667045;26978.5953210702;26983.730162698514;2254.177225312749;2254.2786308381324;2314.837961342707;2329.592321343775;2802.005288122728;9908.35595686278;10137.493125343703;10341.534630941625;10665.146788256998;10837.620683276047;1715.2710098208336;1860.0978525415162;1860.897650629215;1944.1057113727702;2011.647961606713;124236.37881810624;131953;133328.33335833356;134709;136157.999999999;9207.493587852732;9276;9517.476538790434;11371.31772093677;11908.761824763456;14978.850211497745;14985.0259733117;14998.925005375122;15004;15015.174165421007;8355.415120941598;8647.56762161902;8683.782905427288;8822.161894620001;8826.470411775314;8639.27488289106;8646.221840034426;8688.695895643748;8879;8903.910960890309;4507.481639611421;4615.630749540029;4625.814967401334;4685.109829132454;4726.763661816895;1626.3088187520257;1851.3982955539504;1934.0813113770846;1937.9903100484407;1950.746402967605;713.0373995106668;727.207343995048;887.9689210877717;963.643451922793;987.53585814667;22536.661950070764;22889.96995135223;23105;23134.84325783704;23217.76782232156;975;995.3679413572289;1006.7432804634772;1037.092544023979;1064.2550214849637;11257.084105279378;11801.350925698996;11955.282683039046;11991.460384282727;12005;583257;586877;605548;612204;612270;130288;133780;134074;137739.31130344485;138029;959.8944116147218;962.2350231565922;974.8440249560036;978.280963491833;999.7700528878398;19683.70474442856;19685.606287873878;20424.89787551112;20583.58832823365;21320.680189796854;57765.13352299635;58211.70894145502;58526.70736646289;58954;60475.09287360781;77791.61104194443;79128.81306780518;79383;81137;82474.87625618814;8093.433459657767;8334.83330333402;8367.414281000272;8450.619722112397;8582.65669373218;11870.881291186977;12782.61652150437;13127.474901003852;13261.602151935458;13916.443342266197;9692.70921872345;11093.24918380904;11567.942160289145;11988.280703157838;12086.214396064228;9924.702258932244;10933.415807011632;11195.552177913118;11900.940495297467;11944.761104777683;11644.126690498417;11776.882231177578;11987.76024479523;11988.820167697666;12156.696082598006;8317.209865062809;8319.209675080858;8518.574071296287;8538.530380828866;8542.78643033929;10437;11226.66320010401;11253.043491303331;11845.334799670005;12036.458359373673 10:30:29 INFO - PROCESS | 1655 | |3;mootools.html;1543.7915881356014;1577.5582836805695;1812.719028550575;1948.11360830822;1991.2433275355354;8584.442011269262;9004;9014.90985090149;9016.684416045424;9045.954770226148;7856.567888766115;8084.959575202116;8484.703035393746;8723.65105395785;8824.426412283197;2063.3912995666237;2326.848754830939;2363.5391098735754;2401.651760494727;2405.9759402406016;2360.3745007573007;2371.087131454385;2393.3537944754908;2413.420779013033;2415.9396015099587;2376.7979721723673;2389.5220955808823;2393.8324317297797;2399.1003373734848;2407.6749638798715;1823.5896923192176;2284.1091974130104;2297.7127859017623;2307.261676263596;2307.573098976695;2384.1297926256966;2385.4155731845635;2392.2583998960376;2392.294273189413;2392.6530653055383;8918.55407229636;8960.462372257685;9001.50491722955;9012.188902998696;9029.909700902972;4547.408836851188;5250.7112108834;5400.351957765054;5407.486288802557;5409.5672346212605;3469.4969229461585;3568.982839890619;3683.7605555638797;3687.2809802088605;3696.7227457940494;1075.8655168103987;1086.2884810449168;1096.8409580610767;1102.47081400928;1119.496226697981;11646.184767066308;11706.004989575897;11735.295882247177;11740.00209982144;11756.706082347924;4558.270676691714;4716.551927566876;4789.808407663724;4816.494268101869;4824.300476430968;9896.703098907044;9901.455419951828;9902.55438505269;9905;9924.40453572788;5334.759935802898;5344.599155063386;5361.303030605996;5368.409474957752;5384.78460861569;4177.832886684499;4328.394024836525;4581.862544123682;4926.310316555685;4935.087008903364;3897.0841852164717;3965.7422267552515;3989.842945545817;4041.6160464755803;4054.7567145971334;1278.5333353326155;1280.8783165599252;1288.671388795851;1295.2940647347218;1299.7725398055302;839.2991851803812;844.7845799321257;951.45766912859;986.8601764961481;991.8313886639289;5829.446202610745;6172.290186628596;6183.227096612924;6198.2871969723155;6385.840353991094;444.786502478812;450.8196721311502;452.9456465224194;461.9445666519972;462.29038426016575;4017.2367250222364;4038.8586399475876;4077.4903137107863;4164.8334066637;4188.246115699203;10484.842727359095;10743.247972641997;10870.293430926964;10877.945610271898;10926.726831829268;10791.892081079246;10809.83785243222;10839.945800270949;10848.02367786903;10852.511636976196;7910.248526390099;8234.794130146794;8259.256666900008;8327.375446841512;8345.082040975487;5943.881122377615;6028.920582376079;6157.784477543264;6191.938080619317;6217.595856269328;9927.056929591678;10164.0344167304;10184.236182286506;10190.490475476048;10209.693709188736;9409.905900940903;9772.657956971472;9821.263405244492;10165.288429810138;10312.329698569569;7484.43866709988;7708;7731.845363092594;7757.185495522889;7835.451518393657;8463.407561470638;8482.491050536988;8551.486910785372;8563.61463734146;8565.871511927202;7239.276072392719;7409.962950185214;7451.739189128354;7750.263724946121;7757.650905709143;7535.208803075599;7612.923870761221;7621.923780762343;7631.69473221065;7717.344025757873;7092.929070709227;7146.464015198985;7229.457790665826;7242.674079666533;7248.985142080008;5060.620453465931;5111.591072714248;5113.411957624772;5181.740912954413;5272.6836389816735;4371.213181627338;4923.901521969469;5059.949400505948;5140.742962851918;5373.516383525502 10:30:29 INFO - PROCESS | 1655 | |4;prototype.html;303.6098613281933;304.4869395069308;316.2425989754538;320.4824208902623;323.3145731050174;4636.327732478792;5464.715791788926;5466.890662186754;5505.889882202354;5539.307586551681;4393;5010.549050585447;5072.26452164436;5363;5459.754311056002;3597.8380972856285;4105.876823695294;4175.812088456027;4180.352045432963;4183.79081045948;3443.621201667827;3592.6766591006804;4114.526829414625;4179.665626749853;4183.53171628739;3214.517822326646;3401.9829900850586;3999.4000899864964;4050.6354428101463;4125.360569111793;3561.664375859053;3629.618890016563;4118.835246590133;4126.628603425676;4128.256913755523;3197.0249074032413;3603.783772973604;3606.6213047630145;3618.185908170667;3626.38351480249;4039.454673619053;4563.7489938053395;5287.86780330491;5306.681599104066;5310.92033619496;3709.5548534176073;3923.7645741255615;4273.487181538204;4760.833370832039;4771.188897887368;5717.656940583578;5719.828405147852;5731.5037826925545;5735.913961290584;5761.078227483583;3291.6175206413122;3905.847774906381;3939.940900886489;3950.8345038213797;3994.1811928554575;5446.1558458439;5807.834730072163;5824.388439213864;5939.970300148515;5965.373635768268;3344.4782623906062;3679.6504332088407;3977.940330895039;3995.5405128410025;4052.615001574846;3500.5099286099967;3627.6916462101017;4062.6140516651462;4076.7961601919787;4119.608637179464;4239.851605193803;4304.440422745023;4543.864033991502;4569.725816451024;4749.145153872336;5652.576056795675;5670;5676.90608618304;5689.573282003784;5709.65742055478;1364.4883168811696;1394.470101361476;1396.7485852546436;1400.8303066939015;1413.4204975960013;840.3613253927059;858.2061593026425;924.4222361024359;935.2658163341744;949.0746522140857;4155.833766649361;4587.045990330435;5153.201253805666;5177.767000484987;5239.113918989161;5304;5407.026135625469;5428.281627085459;5682.70955428657;5797.4782269595935;4856.41722993236;5161.303224064778;5171.362605071441;5253.763580638804;5267.683938963674;3038.4075105354686;3056.465118604235;3065.785395022327;3079.9384012320074;3094.3656550407113;5802.535797136219;5861.472467477947;6038.6678732670125;6038.849028774316;6040.244969378828;5156.793728250903;5865.20819689345;5895.469407753323;5927.792527261525;5953.642781433041;5226.843194704165;5551.141162301418;5628.324601047901;5973.880522389615;5993.790217342372;4784.923392236719;5459.481349271813;5653.886922261449;5692.1461780733225;5791.710414479344;4857.931255123867;5057.418396884405;5579.48492449754;5616.21373007771;5686.374498805211;4975.975120124376;5002.499750024968;5424.105022671215;5494.09347457681;5698.886022279614;3572.8928132156075;3952.8536724349615;4009.7794621294943;4031.7580945143386;4038.192361527765;4781;5774.884502310014;5796.913046304393;5800.4489573490455;5825.3494659930475;4109.506859176918;5008.84973450797;5322.41453440129;5609.831705048855;5690.886182276248;4648.581627653527;5286.392064912584;5470.370907345706;5475.315585551806;5574.693391863324;4788.677491416591;5342.717747740592;5478.287822583039;5487.4238204987905;5502.9174562380795;5142.948570514397;5153.639245252796;5537.698640819323;5564.302887619315;5570.2201691762375;4615.838445654386;5534.418886016911;5536.574157325763;5545.944540554704;5546.944530554804 10:30:29 INFO - PROCESS | 1655 | |5;yui.html;703.6446594469792;739.8594267089253;822.2065706593137;826.7271800305899;834.8872902158214;15076.924615376922;16316.510504684864;16345;16345;16354.83645163548;15682;16142;16279;16317.510474685749;16359.018458892475;910.977225569361;935.1256575102267;937.4094320578026;938.211902002318;938.4650749073031;710.7974227345208;740.3928778401686;758.8709919313729;792.2354927494966;930.8976012638639;978.2125389061803;980.6028558433845;981.4258658684643;981.793823297105;984.5569493727816;973.5862258540092;974.3812678948848;975.4147511493082;975.4829940131741;976.506864033662;558.3216392083638;579.9594028418012;694.6492021529147;719.2124623537219;726.9563826170447;15495.14776687293;15566.299516521784;15591.064536127755;15596.766048509282;15617.219139043116;14413.567592972227;14607.342669579788;14698.26508674562;14722.779158312635;14793.704125917422;13236.536721214807;13314.400851961685;13367.264800435973;13731.931340343333;14056;4640.373549570799;4648.814047438098;4666.370040044584;4684.414448193976;4702.835400760991;107947.92052079536;108328;109516.4524177374;110523.36859788439;112170;10825.729356766144;10889.346639201673;10987.670369888916;11045.39250341223;11343.829842552368;21590.676139857915;21590.676139857915;21619.243326483498;21621.135154593954;21663.566728665653;7920.683172673029;8141.14517975604;8185.058718247358;8196.508209507449;8206.179382061866;4507.954920450819;4534.251848444969;4681.67228294023;4716.551927566876;4733.195356789354;3310.6027276726945;3467.913302167466;3573.392523270998;3578;3584.1577229351083;559.6669981361084;636.7039326713008;644.0661041489858;647.792706333978;655.8819412505795;531.875306994257;569.4789267819898;608.6044071353567;647.2071712152575;664.1863716946701;8433.873491897626;8664.436811607351;8664.610092545725;9229.307801914883;9428.434293942386;536.273349611279;595.3243069116584;629.6002038705456;634.4480302137171;651.9445847102954;4249.68127390447;4466.754328511898;4472.955270447319;4502.032167297746;4634.768261586907;159627;160947;164094;165174;170160.1491992532;88469;142142;142269;143087;145209.3832493398;111540.44229778962;112869;113721.43139284416;115640.84359156301;117781;8744.825103498022;8798.516081615573;8811.898512685913;8933.955330223567;8993.460392376479;62371.376286236555;64246.35753642404;64510;66690.66654666695;67999;93506.53246733722;95616;96587;98194.5090274544;99859;11661.825072624088;11956.222845515014;11961.521539138337;12006.159568830448;12467.314297713712;12377.504899804268;12455.190412623151;12561.811572826598;12698.682532936751;12985.610431687064;11816.586419475278;11963.521459141884;11980;12302.569410070604;12389.814152787536;11821.645350639494;11825.290482571074;11889.291205793501;12052;12128.514859405524;11752.353620550952;11943.82084268754;12104.09219308573;12183.329916854658;12477.126601137834;9097.40866843653;10771.299865508718;11558.457139429649;11693.766124677288;12098.09264305163;10925.672229833117;11661.358625275352;11814.841735286362;11925.224860384047;12072.504437326856 10:30:29 INFO - PROCESS | 1655 | __end_tp_report 10:30:29 INFO - PROCESS | 1655 | __start_cc_report 10:30:29 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1300 10:30:29 INFO - PROCESS | 1655 | __end_cc_report 10:30:29 INFO - PROCESS | 1655 | __startTimestamp1469122229930__endTimestamp 10:30:29 INFO - PROCESS | 1655 | 10:30:29 INFO - PROCESS | 1655 | ------- Summary: start ------- 10:30:29 INFO - PROCESS | 1655 | Number of tests: 6 10:30:29 INFO - PROCESS | 1655 | 10:30:29 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5807.51 Median:6075.98 stddev:625.99 (10.3%) stddev-sans-first:598.29 10:30:29 INFO - PROCESS | 1655 | Values: 3382.0 3402.7 3403.1 3416.6 3458.6 5670.5 5769.3 5793.8 5865.1 5901.0 5760.5 5821.9 5840.9 5851.7 5856.7 5243.3 5274.4 5308.6 5314.6 5363.4 5251.9 5343.3 5406.6 5465.2 5477.2 5370.9 5371.8 5397.0 5413.7 5419.9 5368.9 5374.0 5386.9 5390.3 5425.3 4887.4 5012.0 5196.1 5271.0 5275.0 5737.4 5781.0 5876.0 5895.4 5975.3 6187.0 6240.7 6245.9 6268.1 6274.6 6263.2 6279.7 6286.3 6296.7 6303.0 5776.6 5854.6 5872.1 5939.9 5948.2 6404.5 6411.0 6414.2 6415.0 6419.4 6238.4 6238.6 6239.2 6250.1 6254.3 5158.8 6104.2 6177.5 6207.9 6256.7 6173.9 6214.6 6227.3 6228.5 6236.8 6190.9 6203.3 6212.5 6215.9 6242.2 4915.1 5022.2 5035.9 5074.2 5121.4 4528.8 4535.0 4544.1 4548.0 4562.2 5697.7 5731.6 5755.9 5761.6 5779.3 6172.1 6193.4 6213.3 6214.9 6231.4 5770.3 5770.7 5785.6 5797.0 5830.4 5425.0 5480.5 5501.6 5506.5 5513.9 6298.1 6326.2 6343.2 6358.4 6361.4 6237.3 6254.8 6255.7 6273.7 6274.5 6422.8 6448.1 6458.0 6465.0 6468.6 6095.9 6126.4 6188.7 6212.7 6225.9 6105.0 6181.1 6184.1 6205.9 6250.3 6143.7 6190.5 6191.1 6201.0 6210.5 6162.5 6167.3 6193.9 6197.2 6204.5 5941.0 6018.3 6048.0 6049.8 6076.0 5067.4 6198.8 6205.7 6205.8 6223.8 6043.8 6078.4 6094.2 6111.7 6132.7 10:30:29 INFO - PROCESS | 1655 | 10:30:29 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:75789.30 Median:40411.10 stddev:165383.33 (409.3%) stddev-sans-first:165750.59 10:30:29 INFO - PROCESS | 1655 | Values: 469.5 493.8 512.1 530.9 541.1 28365.4 42479.2 43981.8 44024.5 46098.8 45083.8 45428.9 45477.8 45528.8 45599.9 9645.8 9838.8 9841.7 9845.4 9854.0 7133.5 8846.9 8860.5 8975.2 8985.8 11789.0 11909.8 11949.1 12183.8 12243.2 11889.2 11992.2 11995.5 11998.8 12004.9 7084.6 11936.7 12020.2 12028.5 12040.5 41836.7 42013.0 42097.8 44260.0 44315.3 3474.9 3500.6 3532.3 3956.2 4424.0 26534.1 31234.1 33182.0 33256.3 33362.8 3254.4 3430.1 3434.9 3436.7 3446.5 529776.0 532247.0 534675.0 534679.3 535451.0 67003.3 67278.7 67283.7 67304.0 67346.7 70869.3 71059.6 71069.3 71074.6 71121.6 16762.9 17058.7 17062.3 17065.5 17068.2 12919.4 12975.2 12982.6 12999.9 13000.2 16835.4 18060.5 18655.6 20490.9 20637.9 6568.1 7930.8 7959.5 8058.0 8081.2 794.0 841.1 891.5 1003.9 1024.1 30470.5 31317.5 31337.0 31645.2 31801.5 1057.4 1123.6 1136.8 1183.9 1202.4 15425.2 16250.3 16270.3 16481.2 16608.1 705350.0 715241.0 715352.0 715955.0 717181.0 63626.0 64169.7 64308.0 64396.4 64424.7 1258.9 1311.9 1366.2 1407.4 1440.9 30564.8 31008.4 33040.0 33054.3 33125.5 54035.1 54684.2 54776.0 54794.9 54800.0 590653.0 595090.0 595215.0 597065.0 597764.0 27726.8 28160.6 28381.4 28988.9 31035.5 41200.8 51311.0 51895.7 52184.0 55620.4 40409.8 42750.0 42983.6 44315.0 44572.0 39513.8 42164.4 42165.4 43459.6 43699.6 32744.8 39996.8 40165.4 40412.4 40979.0 35714.8 36494.6 36571.0 37628.1 37691.0 26811.5 26838.5 27036.5 27271.3 27951.7 10:30:29 INFO - PROCESS | 1655 | 10:30:29 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:36147.17 Median:14892.87 stddev:100687.07 (676.1%) stddev-sans-first:100933.61 10:30:29 INFO - PROCESS | 1655 | Values: 332.5 375.8 383.7 391.8 410.5 15612.8 16076.8 21471.5 21936.7 22001.9 21400.8 21725.0 22192.9 22240.4 22452.3 4966.5 6047.1 6101.3 6193.7 6204.6 4236.4 5871.8 6136.4 6140.9 6149.9 4389.0 6501.3 6769.2 6839.4 6907.7 5798.3 6128.8 6185.4 6891.3 6891.3 6062.2 6065.7 6068.2 6069.4 6084.8 24205.8 26064.9 26667.3 26978.6 26983.7 2254.2 2254.3 2314.8 2329.6 2802.0 9908.4 10137.5 10341.5 10665.1 10837.6 1715.3 1860.1 1860.9 1944.1 2011.6 124236.4 131953.0 133328.3 134709.0 136158.0 9207.5 9276.0 9517.5 11371.3 11908.8 14978.9 14985.0 14998.9 15004.0 15015.2 8355.4 8647.6 8683.8 8822.2 8826.5 8639.3 8646.2 8688.7 8879.0 8903.9 4507.5 4615.6 4625.8 4685.1 4726.8 1626.3 1851.4 1934.1 1938.0 1950.7 713.0 727.2 888.0 963.6 987.5 22536.7 22890.0 23105.0 23134.8 23217.8 975.0 995.4 1006.7 1037.1 1064.3 11257.1 11801.4 11955.3 11991.5 12005.0 583257.0 586877.0 605548.0 612204.0 612270.0 130288.0 133780.0 134074.0 137739.3 138029.0 959.9 962.2 974.8 978.3 999.8 19683.7 19685.6 20424.9 20583.6 21320.7 57765.1 58211.7 58526.7 58954.0 60475.1 77791.6 79128.8 79383.0 81137.0 82474.9 8093.4 8334.8 8367.4 8450.6 8582.7 11870.9 12782.6 13127.5 13261.6 13916.4 9692.7 11093.2 11567.9 11988.3 12086.2 9924.7 10933.4 11195.6 11900.9 11944.8 11644.1 11776.9 11987.8 11988.8 12156.7 8317.2 8319.2 8518.6 8538.5 8542.8 10437.0 11226.7 11253.0 11845.3 12036.5 10:30:29 INFO - PROCESS | 1655 | 10:30:29 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5765.82 Median:4874.10 stddev:3243.22 (66.5%) stddev-sans-first:3236.80 10:30:29 INFO - PROCESS | 1655 | Values: 1543.8 1577.6 1812.7 1948.1 1991.2 8584.4 9004.0 9014.9 9016.7 9046.0 7856.6 8085.0 8484.7 8723.7 8824.4 2063.4 2326.8 2363.5 2401.7 2406.0 2360.4 2371.1 2393.4 2413.4 2415.9 2376.8 2389.5 2393.8 2399.1 2407.7 1823.6 2284.1 2297.7 2307.3 2307.6 2384.1 2385.4 2392.3 2392.3 2392.7 8918.6 8960.5 9001.5 9012.2 9029.9 4547.4 5250.7 5400.4 5407.5 5409.6 3469.5 3569.0 3683.8 3687.3 3696.7 1075.9 1086.3 1096.8 1102.5 1119.5 11646.2 11706.0 11735.3 11740.0 11756.7 4558.3 4716.6 4789.8 4816.5 4824.3 9896.7 9901.5 9902.6 9905.0 9924.4 5334.8 5344.6 5361.3 5368.4 5384.8 4177.8 4328.4 4581.9 4926.3 4935.1 3897.1 3965.7 3989.8 4041.6 4054.8 1278.5 1280.9 1288.7 1295.3 1299.8 839.3 844.8 951.5 986.9 991.8 5829.4 6172.3 6183.2 6198.3 6385.8 444.8 450.8 452.9 461.9 462.3 4017.2 4038.9 4077.5 4164.8 4188.2 10484.8 10743.2 10870.3 10877.9 10926.7 10791.9 10809.8 10839.9 10848.0 10852.5 7910.2 8234.8 8259.3 8327.4 8345.1 5943.9 6028.9 6157.8 6191.9 6217.6 9927.1 10164.0 10184.2 10190.5 10209.7 9409.9 9772.7 9821.3 10165.3 10312.3 7484.4 7708.0 7731.8 7757.2 7835.5 8463.4 8482.5 8551.5 8563.6 8565.9 7239.3 7410.0 7451.7 7750.3 7757.7 7535.2 7612.9 7621.9 7631.7 7717.3 7092.9 7146.5 7229.5 7242.7 7249.0 5060.6 5111.6 5113.4 5181.7 5272.7 4371.2 4923.9 5059.9 5140.7 5373.5 10:30:29 INFO - PROCESS | 1655 | 10:30:29 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4519.35 Median:5148.07 stddev:1383.48 (26.9%) stddev-sans-first:1350.69 10:30:29 INFO - PROCESS | 1655 | Values: 303.6 304.5 316.2 320.5 323.3 4636.3 5464.7 5466.9 5505.9 5539.3 4393.0 5010.5 5072.3 5363.0 5459.8 3597.8 4105.9 4175.8 4180.4 4183.8 3443.6 3592.7 4114.5 4179.7 4183.5 3214.5 3402.0 3999.4 4050.6 4125.4 3561.7 3629.6 4118.8 4126.6 4128.3 3197.0 3603.8 3606.6 3618.2 3626.4 4039.5 4563.7 5287.9 5306.7 5310.9 3709.6 3923.8 4273.5 4760.8 4771.2 5717.7 5719.8 5731.5 5735.9 5761.1 3291.6 3905.8 3939.9 3950.8 3994.2 5446.2 5807.8 5824.4 5940.0 5965.4 3344.5 3679.7 3977.9 3995.5 4052.6 3500.5 3627.7 4062.6 4076.8 4119.6 4239.9 4304.4 4543.9 4569.7 4749.1 5652.6 5670.0 5676.9 5689.6 5709.7 1364.5 1394.5 1396.7 1400.8 1413.4 840.4 858.2 924.4 935.3 949.1 4155.8 4587.0 5153.2 5177.8 5239.1 5304.0 5407.0 5428.3 5682.7 5797.5 4856.4 5161.3 5171.4 5253.8 5267.7 3038.4 3056.5 3065.8 3079.9 3094.4 5802.5 5861.5 6038.7 6038.8 6040.2 5156.8 5865.2 5895.5 5927.8 5953.6 5226.8 5551.1 5628.3 5973.9 5993.8 4784.9 5459.5 5653.9 5692.1 5791.7 4857.9 5057.4 5579.5 5616.2 5686.4 4976.0 5002.5 5424.1 5494.1 5698.9 3572.9 3952.9 4009.8 4031.8 4038.2 4781.0 5774.9 5796.9 5800.4 5825.3 4109.5 5008.8 5322.4 5609.8 5690.9 4648.6 5286.4 5470.4 5475.3 5574.7 4788.7 5342.7 5478.3 5487.4 5502.9 5142.9 5153.6 5537.7 5564.3 5570.2 4615.8 5534.4 5536.6 5545.9 5546.9 10:30:29 INFO - PROCESS | 1655 | 10:30:29 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:25677.14 Median:3575.70 stddev:41921.90 (1172.4%) stddev-sans-first:41997.57 10:30:29 INFO - PROCESS | 1655 | Values: 703.6 739.9 822.2 826.7 834.9 15076.9 16316.5 16345.0 16345.0 16354.8 15682.0 16142.0 16279.0 16317.5 16359.0 911.0 935.1 937.4 938.2 938.5 710.8 740.4 758.9 792.2 930.9 978.2 980.6 981.4 981.8 984.6 973.6 974.4 975.4 975.5 976.5 558.3 580.0 694.6 719.2 727.0 15495.1 15566.3 15591.1 15596.8 15617.2 14413.6 14607.3 14698.3 14722.8 14793.7 13236.5 13314.4 13367.3 13731.9 14056.0 4640.4 4648.8 4666.4 4684.4 4702.8 107947.9 108328.0 109516.5 110523.4 112170.0 10825.7 10889.3 10987.7 11045.4 11343.8 21590.7 21590.7 21619.2 21621.1 21663.6 7920.7 8141.1 8185.1 8196.5 8206.2 4508.0 4534.3 4681.7 4716.6 4733.2 3310.6 3467.9 3573.4 3578.0 3584.2 559.7 636.7 644.1 647.8 655.9 531.9 569.5 608.6 647.2 664.2 8433.9 8664.4 8664.6 9229.3 9428.4 536.3 595.3 629.6 634.4 651.9 4249.7 4466.8 4473.0 4502.0 4634.8 159627.0 160947.0 164094.0 165174.0 170160.1 88469.0 142142.0 142269.0 143087.0 145209.4 111540.4 112869.0 113721.4 115640.8 117781.0 8744.8 8798.5 8811.9 8934.0 8993.5 62371.4 64246.4 64510.0 66690.7 67999.0 93506.5 95616.0 96587.0 98194.5 99859.0 11661.8 11956.2 11961.5 12006.2 12467.3 12377.5 12455.2 12561.8 12698.7 12985.6 11816.6 11963.5 11980.0 12302.6 12389.8 11821.6 11825.3 11889.3 12052.0 12128.5 11752.4 11943.8 12104.1 12183.3 12477.1 9097.4 10771.3 11558.5 11693.8 12098.1 10925.7 11661.4 11814.8 11925.2 12072.5 10:30:29 INFO - PROCESS | 1655 | -------- Summary: end -------- 10:30:29 INFO - PROCESS | 1655 | 10:30:30 INFO - TEST-INFO | 1655: exit 0 10:30:30 INFO - TEST-OK | dromaeo_css | took 1116864ms 10:30:30 INFO - TEST-START | kraken 10:30:30 INFO - operating with platform_type : mac_ 10:30:30 INFO - Initialising browser for kraken test... 10:30:30 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpKkZ1aq/profile http://localhost:49275/getInfo.html) 10:30:32 INFO - PROCESS | 1665 | JavaScript error: , line 0: uncaught exception: undefined 10:30:32 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 10:30:32 INFO - PROCESS | 1665 | colorDepth:24 10:30:32 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 10:30:32 INFO - PROCESS | 1665 | __metrics 10:30:32 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" 10:30:32 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] 10:30:33 INFO - TEST-INFO | 1665: exit 0 10:30:33 INFO - Browser initialized. 10:30:33 INFO - Running cycle 1/1 for kraken test... 10:30:33 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpKkZ1aq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:30:45 INFO - PROCESS | 1666 | RSS: Main: 245612544 10:30:45 INFO - PROCESS | 1666 | 10:30:48 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 10:30:49 INFO - PROCESS | 1666 | RSS: Main: 324882432 10:30:49 INFO - PROCESS | 1666 | 10:30:51 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 10:30:52 INFO - PROCESS | 1666 | RSS: Main: 335392768 10:30:52 INFO - PROCESS | 1666 | 10:30:56 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 10:30:56 INFO - PROCESS | 1666 | RSS: Main: 426369024 10:30:56 INFO - PROCESS | 1666 | 10:30:58 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 10:30:59 INFO - PROCESS | 1666 | RSS: Main: 328736768 10:30:59 INFO - PROCESS | 1666 | 10:31:01 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:31:02 INFO - PROCESS | 1666 | RSS: Main: 332509184 10:31:02 INFO - PROCESS | 1666 | 10:31:04 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 10:31:05 INFO - PROCESS | 1666 | RSS: Main: 372011008 10:31:05 INFO - PROCESS | 1666 | 10:31:08 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 10:31:09 INFO - PROCESS | 1666 | RSS: Main: 371957760 10:31:09 INFO - PROCESS | 1666 | 10:31:12 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 10:31:12 INFO - PROCESS | 1666 | RSS: Main: 370585600 10:31:12 INFO - PROCESS | 1666 | 10:31:14 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:31:14 INFO - PROCESS | 1666 | RSS: Main: 350056448 10:31:14 INFO - PROCESS | 1666 | 10:31:16 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:31:17 INFO - PROCESS | 1666 | RSS: Main: 356118528 10:31:17 INFO - PROCESS | 1666 | 10:31:19 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:31:20 INFO - PROCESS | 1666 | RSS: Main: 354369536 10:31:20 INFO - PROCESS | 1666 | 10:31:23 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:31:23 INFO - PROCESS | 1666 | RSS: Main: 363511808 10:31:23 INFO - PROCESS | 1666 | 10:31:26 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:31:27 INFO - PROCESS | 1666 | RSS: Main: 364900352 10:31:27 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 10:31:29 INFO - PROCESS | 1666 | RSS: Main: 408326144 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | __start_tp_report 10:31:29 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 10:31:29 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 10:31:29 INFO - PROCESS | 1666 | |i|pagename|runs| 10:31:29 INFO - PROCESS | 1666 | |0;ai-astar;101;113;111;113;114;113;113;112;112;113 10:31:29 INFO - PROCESS | 1666 | |1;audio-beat-detection;166;147;118;161;107;128;115;112;151;124 10:31:29 INFO - PROCESS | 1666 | |2;audio-dft;185;184;184;184;183;180;196;260;182;183 10:31:29 INFO - PROCESS | 1666 | |3;audio-fft;74;59;59;106;57;57;62;66;85;57 10:31:29 INFO - PROCESS | 1666 | |4;audio-oscillator;83;69;99;70;80;68;98;70;67;97 10:31:29 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;88;86;87;84;87;83;89;85;87;84 10:31:29 INFO - PROCESS | 1666 | |6;imaging-darkroom;137;151;90;150;148;94;95;95;93;94 10:31:29 INFO - PROCESS | 1666 | |7;imaging-desaturate;87;88;86;86;87;88;87;86;87;86 10:31:29 INFO - PROCESS | 1666 | |8;json-parse-financial;57;56;57;56;57;56;56;54;54;54 10:31:29 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;66;73;72;78;73;70;71;70;70;71 10:31:29 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;86;82;80;81;83;85;79;82;79;86 10:31:29 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;139;149;151;144;134;130;137;136;174;134 10:31:29 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;226;162;172;157;181;232;230;161;164;163 10:31:29 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;67;67;67;68;82;84;88;88;65;67 10:31:29 INFO - PROCESS | 1666 | __end_tp_report 10:31:29 INFO - PROCESS | 1666 | __start_cc_report 10:31:29 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2111 10:31:29 INFO - PROCESS | 1666 | __end_cc_report 10:31:29 INFO - PROCESS | 1666 | __startTimestamp1469122289521__endTimestamp 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | ------- Summary: start ------- 10:31:29 INFO - PROCESS | 1666 | Number of tests: 14 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:111.50 Median:113.00 stddev:3.78 (3.3%) stddev-sans-first:0.87 10:31:29 INFO - PROCESS | 1666 | Values: 101.0 113.0 111.0 113.0 114.0 113.0 113.0 112.0 112.0 113.0 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:132.90 Median:137.50 stddev:21.51 (15.6%) stddev-sans-first:19.19 10:31:29 INFO - PROCESS | 1666 | Values: 166.0 147.0 118.0 161.0 107.0 128.0 115.0 112.0 151.0 124.0 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:192.10 Median:184.00 stddev:24.24 (13.2%) stddev-sans-first:25.57 10:31:29 INFO - PROCESS | 1666 | Values: 185.0 184.0 184.0 184.0 183.0 180.0 196.0 260.0 182.0 183.0 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:68.20 Median:60.50 stddev:16.10 (26.6%) stddev-sans-first:16.94 10:31:29 INFO - PROCESS | 1666 | Values: 74.0 59.0 59.0 106.0 57.0 57.0 62.0 66.0 85.0 57.0 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:81.50 stddev:13.40 (16.4%) stddev-sans-first:14.18 10:31:29 INFO - PROCESS | 1666 | Values: 83.0 69.0 99.0 70.0 80.0 68.0 98.0 70.0 67.0 97.0 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.94 (2.2%) stddev-sans-first:1.92 10:31:29 INFO - PROCESS | 1666 | Values: 88.0 86.0 87.0 84.0 87.0 83.0 89.0 85.0 87.0 84.0 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:114.70 Median:93.50 stddev:27.66 (29.6%) stddev-sans-first:28.13 10:31:29 INFO - PROCESS | 1666 | Values: 137.0 151.0 90.0 150.0 148.0 94.0 95.0 95.0 93.0 94.0 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:86.80 Median:87.00 stddev:0.79 (0.9%) stddev-sans-first:0.83 10:31:29 INFO - PROCESS | 1666 | Values: 87.0 88.0 86.0 86.0 87.0 88.0 87.0 86.0 87.0 86.0 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:1.25 (2.2%) stddev-sans-first:1.24 10:31:29 INFO - PROCESS | 1666 | Values: 57.0 56.0 57.0 56.0 57.0 56.0 56.0 54.0 54.0 54.0 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:71.50 stddev:3.06 (4.3%) stddev-sans-first:2.55 10:31:29 INFO - PROCESS | 1666 | Values: 66.0 73.0 72.0 78.0 73.0 70.0 71.0 70.0 70.0 71.0 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:82.50 stddev:2.67 (3.2%) stddev-sans-first:2.47 10:31:29 INFO - PROCESS | 1666 | Values: 86.0 82.0 80.0 81.0 83.0 85.0 79.0 82.0 79.0 86.0 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:142.80 Median:141.50 stddev:12.88 (9.1%) stddev-sans-first:13.59 10:31:29 INFO - PROCESS | 1666 | Values: 139.0 149.0 151.0 144.0 134.0 130.0 137.0 136.0 174.0 134.0 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.80 Median:176.50 stddev:31.47 (17.8%) stddev-sans-first:29.64 10:31:29 INFO - PROCESS | 1666 | Values: 226.0 162.0 172.0 157.0 181.0 232.0 230.0 161.0 164.0 163.0 10:31:29 INFO - PROCESS | 1666 | 10:31:29 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:75.00 stddev:9.82 (13.1%) stddev-sans-first:10.06 10:31:29 INFO - PROCESS | 1666 | Values: 67.0 67.0 67.0 68.0 82.0 84.0 88.0 88.0 65.0 67.0 10:31:29 INFO - PROCESS | 1666 | -------- Summary: end -------- 10:31:29 INFO - PROCESS | 1666 | 10:31:30 INFO - TEST-INFO | 1666: exit 0 10:31:30 INFO - TEST-OK | kraken | took 59878ms 10:31:30 INFO - SUITE-END | took 1176s 10:31:30 INFO - Completed test suite (00:19:36) 10:31:30 INFO - javascript benchmark 10:31:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3382.0361197058837, 3402.7447941404384, 3403.1322012886717, 3416.5558477397935, 3458.619551849296, 5670.546356291497, 5769.278840144982, 5793.82618521444, 5865.090910908811, 5900.970495147523, 5760.45275698809, 5821.8835623287505, 5840.912386314198, 5851.736671849766, 5856.707164641773, 5243.344581927259, 5274.393444753845, 5308.601854860882, 5314.601404894629, 5363.410024897258, 5251.947480525184, 5343.251944727741, 5406.648567843097, 5465.180222966547, 5477.178423236526, 5370.865728356784, 5371.7582708778, 5397.028534863723, 5413.675179489243, 5419.891602167954, 5368.91946620801, 5374.032674118657, 5386.865328366803, 5390.299261096033, 5425.321834770653, 4887.413510378742, 5011.997600479882, 5196.116660167742, 5271.024860400838, 5274.997750427407, 5737.426257374229, 5781.0, 5875.970620146872, 5895.4399332063385, 5975.312839023524, 6187.038155065131, 6240.656763877985, 6245.87508249837, 6268.059791031336, 6274.592151510137, 6263.185785847811, 6279.748810047593, 6286.339934306923, 6296.68516574174, 6303.0, 5776.624519406225, 5854.560907931922, 5872.119182122716, 5939.910901336483, 5948.226730525091, 6404.455621272151, 6411.0, 6414.166158399472, 6414.967925160345, 6419.390157934989, 6238.376162383816, 6238.625682459067, 6239.220097487814, 6250.093736408196, 6254.34329395416, 5158.793648254103, 6104.186323682826, 6177.536684748662, 6207.906881396782, 6256.655883926428, 6173.938260617337, 6214.5960512566535, 6227.252729672468, 6228.4705800007505, 6236.812895613139, 6190.907136392958, 6203.348648391944, 6212.502999760009, 6215.875682486416, 6242.188515492957, 4915.115279249698, 5022.196448568212, 5035.924461133086, 5074.188129899198, 5121.436641969381, 4528.818847246073, 4535.047639995638, 4544.068465964469, 4548.044910568751, 4562.201614717477, 5697.686627235459, 5731.570132240098, 5755.913661295084, 5761.567882408836, 5779.2775903012125, 6172.135900973867, 6193.411625895533, 6213.285472170668, 6214.875702486015, 6231.40801623845, 5770.278715160605, 5770.711464426762, 5785.623934444247, 5797.0, 5830.416958304136, 5425.0, 5480.506754392044, 5501.587380946365, 5506.476884695948, 5513.889722205534, 6298.086777417248, 6326.240851097989, 6343.207099112611, 6358.395952384516, 6361.427471527492, 6237.282712488122, 6254.8123556293385, 6255.655938923403, 6273.65494897785, 6274.529410294155, 6422.839429014125, 6448.065030570635, 6458.031295305742, 6464.967675161594, 6468.644224567505, 6095.861538842981, 6126.3955342902, 6188.659623720739, 6212.658303793335, 6225.875482490415, 6105.004892149239, 6181.103739957591, 6184.072389141665, 6205.875882482234, 6250.343713909974, 6143.72353244114, 6190.535709821691, 6191.071339299141, 6201.0, 6210.472109870711, 6162.506999439945, 6167.290761562478, 6193.907091393543, 6197.194364732556, 6204.534659900435, 5941.019731744386, 6018.307894592178, 6047.969760151171, 6049.848753781191, 6075.979647063026, 5067.417247016493, 6198.752049917953, 6205.689715514116, 6205.813825585059, 6223.782167624111, 6043.848903777441, 6078.422549857756, 6094.2077529920825, 6111.7249723763425, 6132.6627035513475], "value": 5768.57577253458, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.49529256049743, 493.8271604938272, 512.0833707663282, 530.8805518758278, 541.0531569752931, 28365.43269134616, 42479.15041699168, 43981.78009109954, 44024.496937882766, 46098.76950615251, 45083.77458112705, 45428.864278393055, 45477.7726111369, 45528.77235613826, 45599.86000349993, 9645.758856028602, 9838.754031149243, 9841.70474885751, 9845.40927544346, 9853.965333639972, 7133.464990125735, 8846.911530884705, 8860.512671803048, 8975.237104846095, 8985.820283594325, 11789.0, 11909.761804763899, 11949.1038172137, 12183.817242741366, 12243.204191727555, 11889.227200231957, 11992.16054876159, 11995.520179192821, 11998.760024799456, 12004.859538343844, 7084.645767711594, 11936.701582460508, 12020.218685785396, 12028.51885924562, 12040.458179381949, 41836.744897653116, 42013.0, 42097.78951105256, 44260.0, 44315.33526997098, 3474.878379256739, 3500.649935006479, 3532.275883443888, 3956.1692044670654, 4424.026714122888, 26534.071307504146, 31234.062978110695, 33182.0, 33256.33487330264, 33362.833185833915, 3254.4142054430195, 3430.1081718753053, 3434.9313013739834, 3436.742244331685, 3446.5347178130883, 529776.0, 532247.0, 534675.0, 534679.3266033684, 535451.0, 67003.32996670068, 67278.66360668263, 67283.66358168177, 67304.0, 67346.66326668434, 70869.29130708627, 71059.64470177617, 71069.28930710627, 71074.64462677758, 71121.6443917777, 16762.91618541924, 17058.74411883823, 17062.317507299816, 17065.488035358958, 17068.2319295632, 12919.418626161845, 12975.156614820007, 12982.610521684364, 12999.870001299865, 13000.219986800823, 16835.366854516677, 18060.458186254433, 18655.603873247834, 20490.897545512176, 20637.896810515853, 6568.080468734381, 7930.841383172305, 7959.482633628795, 8058.0, 8081.15147909461, 793.9722109726132, 841.0790184747691, 891.5453118909273, 1003.9498025098716, 1024.0578667625803, 30470.54294185589, 31317.530237046463, 31337.0, 31645.236950021368, 31801.52297715536, 1057.4448414582253, 1123.6404350607727, 1136.772645470909, 1183.8816118388265, 1202.4108186988487, 15425.179307491888, 16250.268737906823, 16270.26783794732, 16481.17121429733, 16608.086555239337, 705350.0, 715241.0, 715352.0, 715955.0, 717181.0, 63626.0, 64169.679151603945, 64308.0, 64396.356036439036, 64424.677876610316, 1258.8615252322238, 1311.8950483961069, 1366.2417358366201, 1407.3737186951912, 1440.9351579178963, 30564.847175763978, 31008.379832403676, 33040.0, 33054.338913222084, 33125.509352079695, 54035.1368376848, 54684.17973730477, 54776.0, 54794.90410191854, 54800.0, 590653.0, 595090.0490995208, 595215.0, 597065.0, 597764.0447190945, 27726.752296147126, 28160.577591336558, 28381.432371352872, 28988.855055724587, 31035.53446698256, 41200.793996029824, 51311.0, 51895.74052129715, 52184.0, 55620.44379556153, 40409.797951010056, 42750.0, 42983.570164297955, 44315.0, 44572.0, 39513.80243098881, 42164.367534486395, 42165.367519486615, 43459.56540434555, 43699.56300436955, 32744.83627581942, 39996.80001599973, 40165.397519036655, 40412.393814092226, 40979.0, 35714.821425892704, 36494.635053650185, 36571.0, 37628.05929851776, 37691.0, 26811.463770724866, 26838.463230735666, 27036.459270814867, 27271.318217044733, 27951.72048279491], "value": 21276.91630821535, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.4531146264397, 375.83651111766386, 383.7064645546157, 391.790392140205, 410.53609421353883, 15612.843871561281, 16076.83923160768, 21471.463213419673, 21936.670949935764, 22001.889990550044, 21400.785992140074, 21725.0, 22192.8890355548, 22240.443988900246, 22452.32643020708, 4966.478519755427, 6047.123167140773, 6101.328853826076, 6193.721282542297, 6204.627722336674, 4236.385724070008, 5871.765129394819, 6136.417040381157, 6140.877182456348, 6149.907751383733, 4388.956110438902, 6501.317361677026, 6769.153855768029, 6839.384455399013, 6907.723691052352, 5798.275215598051, 6128.754849806002, 6185.443310102068, 6891.310868913119, 6891.345322194408, 6062.151298818169, 6065.666388348639, 6068.180795592582, 6069.423404776539, 6084.817455476343, 24205.757942420703, 26064.86967565169, 26667.333316667045, 26978.5953210702, 26983.730162698514, 2254.177225312749, 2254.2786308381324, 2314.837961342707, 2329.592321343775, 2802.005288122728, 9908.35595686278, 10137.493125343703, 10341.534630941625, 10665.146788256998, 10837.620683276047, 1715.2710098208336, 1860.0978525415162, 1860.897650629215, 1944.1057113727702, 2011.647961606713, 124236.37881810624, 131953.0, 133328.33335833356, 134709.0, 136157.999999999, 9207.493587852732, 9276.0, 9517.476538790434, 11371.31772093677, 11908.761824763456, 14978.850211497745, 14985.0259733117, 14998.925005375122, 15004.0, 15015.174165421007, 8355.415120941598, 8647.56762161902, 8683.782905427288, 8822.161894620001, 8826.470411775314, 8639.27488289106, 8646.221840034426, 8688.695895643748, 8879.0, 8903.910960890309, 4507.481639611421, 4615.630749540029, 4625.814967401334, 4685.109829132454, 4726.763661816895, 1626.3088187520257, 1851.3982955539504, 1934.0813113770846, 1937.9903100484407, 1950.746402967605, 713.0373995106668, 727.207343995048, 887.9689210877717, 963.643451922793, 987.53585814667, 22536.661950070764, 22889.96995135223, 23105.0, 23134.84325783704, 23217.76782232156, 975.0, 995.3679413572289, 1006.7432804634772, 1037.092544023979, 1064.2550214849637, 11257.084105279378, 11801.350925698996, 11955.282683039046, 11991.460384282727, 12005.0, 583257.0, 586877.0, 605548.0, 612204.0, 612270.0, 130288.0, 133780.0, 134074.0, 137739.31130344485, 138029.0, 959.8944116147218, 962.2350231565922, 974.8440249560036, 978.280963491833, 999.7700528878398, 19683.70474442856, 19685.606287873878, 20424.89787551112, 20583.58832823365, 21320.680189796854, 57765.13352299635, 58211.70894145502, 58526.70736646289, 58954.0, 60475.09287360781, 77791.61104194443, 79128.81306780518, 79383.0, 81137.0, 82474.87625618814, 8093.433459657767, 8334.83330333402, 8367.414281000272, 8450.619722112397, 8582.65669373218, 11870.881291186977, 12782.61652150437, 13127.474901003852, 13261.602151935458, 13916.443342266197, 9692.70921872345, 11093.24918380904, 11567.942160289145, 11988.280703157838, 12086.214396064228, 9924.702258932244, 10933.415807011632, 11195.552177913118, 11900.940495297467, 11944.761104777683, 11644.126690498417, 11776.882231177578, 11987.76024479523, 11988.820167697666, 12156.696082598006, 8317.209865062809, 8319.209675080858, 8518.574071296287, 8538.530380828866, 8542.78643033929, 10437.0, 11226.66320010401, 11253.043491303331, 11845.334799670005, 12036.458359373673], "value": 9860.206512582023, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1543.7915881356014, 1577.5582836805695, 1812.719028550575, 1948.11360830822, 1991.2433275355354, 8584.442011269262, 9004.0, 9014.90985090149, 9016.684416045424, 9045.954770226148, 7856.567888766115, 8084.959575202116, 8484.703035393746, 8723.65105395785, 8824.426412283197, 2063.3912995666237, 2326.848754830939, 2363.5391098735754, 2401.651760494727, 2405.9759402406016, 2360.3745007573007, 2371.087131454385, 2393.3537944754908, 2413.420779013033, 2415.9396015099587, 2376.7979721723673, 2389.5220955808823, 2393.8324317297797, 2399.1003373734848, 2407.6749638798715, 1823.5896923192176, 2284.1091974130104, 2297.7127859017623, 2307.261676263596, 2307.573098976695, 2384.1297926256966, 2385.4155731845635, 2392.2583998960376, 2392.294273189413, 2392.6530653055383, 8918.55407229636, 8960.462372257685, 9001.50491722955, 9012.188902998696, 9029.909700902972, 4547.408836851188, 5250.7112108834, 5400.351957765054, 5407.486288802557, 5409.5672346212605, 3469.4969229461585, 3568.982839890619, 3683.7605555638797, 3687.2809802088605, 3696.7227457940494, 1075.8655168103987, 1086.2884810449168, 1096.8409580610767, 1102.47081400928, 1119.496226697981, 11646.184767066308, 11706.004989575897, 11735.295882247177, 11740.00209982144, 11756.706082347924, 4558.270676691714, 4716.551927566876, 4789.808407663724, 4816.494268101869, 4824.300476430968, 9896.703098907044, 9901.455419951828, 9902.55438505269, 9905.0, 9924.40453572788, 5334.759935802898, 5344.599155063386, 5361.303030605996, 5368.409474957752, 5384.78460861569, 4177.832886684499, 4328.394024836525, 4581.862544123682, 4926.310316555685, 4935.087008903364, 3897.0841852164717, 3965.7422267552515, 3989.842945545817, 4041.6160464755803, 4054.7567145971334, 1278.5333353326155, 1280.8783165599252, 1288.671388795851, 1295.2940647347218, 1299.7725398055302, 839.2991851803812, 844.7845799321257, 951.45766912859, 986.8601764961481, 991.8313886639289, 5829.446202610745, 6172.290186628596, 6183.227096612924, 6198.2871969723155, 6385.840353991094, 444.786502478812, 450.8196721311502, 452.9456465224194, 461.9445666519972, 462.29038426016575, 4017.2367250222364, 4038.8586399475876, 4077.4903137107863, 4164.8334066637, 4188.246115699203, 10484.842727359095, 10743.247972641997, 10870.293430926964, 10877.945610271898, 10926.726831829268, 10791.892081079246, 10809.83785243222, 10839.945800270949, 10848.02367786903, 10852.511636976196, 7910.248526390099, 8234.794130146794, 8259.256666900008, 8327.375446841512, 8345.082040975487, 5943.881122377615, 6028.920582376079, 6157.784477543264, 6191.938080619317, 6217.595856269328, 9927.056929591678, 10164.0344167304, 10184.236182286506, 10190.490475476048, 10209.693709188736, 9409.905900940903, 9772.657956971472, 9821.263405244492, 10165.288429810138, 10312.329698569569, 7484.43866709988, 7708.0, 7731.845363092594, 7757.185495522889, 7835.451518393657, 8463.407561470638, 8482.491050536988, 8551.486910785372, 8563.61463734146, 8565.871511927202, 7239.276072392719, 7409.962950185214, 7451.739189128354, 7750.263724946121, 7757.650905709143, 7535.208803075599, 7612.923870761221, 7621.923780762343, 7631.69473221065, 7717.344025757873, 7092.929070709227, 7146.464015198985, 7229.457790665826, 7242.674079666533, 7248.985142080008, 5060.620453465931, 5111.591072714248, 5113.411957624772, 5181.740912954413, 5272.6836389816735, 4371.213181627338, 4923.901521969469, 5059.949400505948, 5140.742962851918, 5373.516383525502], "value": 4557.56849527973, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.6098613281933, 304.4869395069308, 316.2425989754538, 320.4824208902623, 323.3145731050174, 4636.327732478792, 5464.715791788926, 5466.890662186754, 5505.889882202354, 5539.307586551681, 4393.0, 5010.549050585447, 5072.26452164436, 5363.0, 5459.754311056002, 3597.8380972856285, 4105.876823695294, 4175.812088456027, 4180.352045432963, 4183.79081045948, 3443.621201667827, 3592.6766591006804, 4114.526829414625, 4179.665626749853, 4183.53171628739, 3214.517822326646, 3401.9829900850586, 3999.4000899864964, 4050.6354428101463, 4125.360569111793, 3561.664375859053, 3629.618890016563, 4118.835246590133, 4126.628603425676, 4128.256913755523, 3197.0249074032413, 3603.783772973604, 3606.6213047630145, 3618.185908170667, 3626.38351480249, 4039.454673619053, 4563.7489938053395, 5287.86780330491, 5306.681599104066, 5310.92033619496, 3709.5548534176073, 3923.7645741255615, 4273.487181538204, 4760.833370832039, 4771.188897887368, 5717.656940583578, 5719.828405147852, 5731.5037826925545, 5735.913961290584, 5761.078227483583, 3291.6175206413122, 3905.847774906381, 3939.940900886489, 3950.8345038213797, 3994.1811928554575, 5446.1558458439, 5807.834730072163, 5824.388439213864, 5939.970300148515, 5965.373635768268, 3344.4782623906062, 3679.6504332088407, 3977.940330895039, 3995.5405128410025, 4052.615001574846, 3500.5099286099967, 3627.6916462101017, 4062.6140516651462, 4076.7961601919787, 4119.608637179464, 4239.851605193803, 4304.440422745023, 4543.864033991502, 4569.725816451024, 4749.145153872336, 5652.576056795675, 5670.0, 5676.90608618304, 5689.573282003784, 5709.65742055478, 1364.4883168811696, 1394.470101361476, 1396.7485852546436, 1400.8303066939015, 1413.4204975960013, 840.3613253927059, 858.2061593026425, 924.4222361024359, 935.2658163341744, 949.0746522140857, 4155.833766649361, 4587.045990330435, 5153.201253805666, 5177.767000484987, 5239.113918989161, 5304.0, 5407.026135625469, 5428.281627085459, 5682.70955428657, 5797.4782269595935, 4856.41722993236, 5161.303224064778, 5171.362605071441, 5253.763580638804, 5267.683938963674, 3038.4075105354686, 3056.465118604235, 3065.785395022327, 3079.9384012320074, 3094.3656550407113, 5802.535797136219, 5861.472467477947, 6038.6678732670125, 6038.849028774316, 6040.244969378828, 5156.793728250903, 5865.20819689345, 5895.469407753323, 5927.792527261525, 5953.642781433041, 5226.843194704165, 5551.141162301418, 5628.324601047901, 5973.880522389615, 5993.790217342372, 4784.923392236719, 5459.481349271813, 5653.886922261449, 5692.1461780733225, 5791.710414479344, 4857.931255123867, 5057.418396884405, 5579.48492449754, 5616.21373007771, 5686.374498805211, 4975.975120124376, 5002.499750024968, 5424.105022671215, 5494.09347457681, 5698.886022279614, 3572.8928132156075, 3952.8536724349615, 4009.7794621294943, 4031.7580945143386, 4038.192361527765, 4781.0, 5774.884502310014, 5796.913046304393, 5800.4489573490455, 5825.3494659930475, 4109.506859176918, 5008.84973450797, 5322.41453440129, 5609.831705048855, 5690.886182276248, 4648.581627653527, 5286.392064912584, 5470.370907345706, 5475.315585551806, 5574.693391863324, 4788.677491416591, 5342.717747740592, 5478.287822583039, 5487.4238204987905, 5502.9174562380795, 5142.948570514397, 5153.639245252796, 5537.698640819323, 5564.302887619315, 5570.2201691762375, 4615.838445654386, 5534.418886016911, 5536.574157325763, 5545.944540554704, 5546.944530554804], "value": 4088.217336982733, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.6446594469792, 739.8594267089253, 822.2065706593137, 826.7271800305899, 834.8872902158214, 15076.924615376922, 16316.510504684864, 16345.0, 16345.0, 16354.83645163548, 15682.0, 16142.0, 16279.0, 16317.510474685749, 16359.018458892475, 910.977225569361, 935.1256575102267, 937.4094320578026, 938.211902002318, 938.4650749073031, 710.7974227345208, 740.3928778401686, 758.8709919313729, 792.2354927494966, 930.8976012638639, 978.2125389061803, 980.6028558433845, 981.4258658684643, 981.793823297105, 984.5569493727816, 973.5862258540092, 974.3812678948848, 975.4147511493082, 975.4829940131741, 976.506864033662, 558.3216392083638, 579.9594028418012, 694.6492021529147, 719.2124623537219, 726.9563826170447, 15495.14776687293, 15566.299516521784, 15591.064536127755, 15596.766048509282, 15617.219139043116, 14413.567592972227, 14607.342669579788, 14698.26508674562, 14722.779158312635, 14793.704125917422, 13236.536721214807, 13314.400851961685, 13367.264800435973, 13731.931340343333, 14056.0, 4640.373549570799, 4648.814047438098, 4666.370040044584, 4684.414448193976, 4702.835400760991, 107947.92052079536, 108328.0, 109516.4524177374, 110523.36859788439, 112170.0, 10825.729356766144, 10889.346639201673, 10987.670369888916, 11045.39250341223, 11343.829842552368, 21590.676139857915, 21590.676139857915, 21619.243326483498, 21621.135154593954, 21663.566728665653, 7920.683172673029, 8141.14517975604, 8185.058718247358, 8196.508209507449, 8206.179382061866, 4507.954920450819, 4534.251848444969, 4681.67228294023, 4716.551927566876, 4733.195356789354, 3310.6027276726945, 3467.913302167466, 3573.392523270998, 3578.0, 3584.1577229351083, 559.6669981361084, 636.7039326713008, 644.0661041489858, 647.792706333978, 655.8819412505795, 531.875306994257, 569.4789267819898, 608.6044071353567, 647.2071712152575, 664.1863716946701, 8433.873491897626, 8664.436811607351, 8664.610092545725, 9229.307801914883, 9428.434293942386, 536.273349611279, 595.3243069116584, 629.6002038705456, 634.4480302137171, 651.9445847102954, 4249.68127390447, 4466.754328511898, 4472.955270447319, 4502.032167297746, 4634.768261586907, 159627.0, 160947.0, 164094.0, 165174.0, 170160.1491992532, 88469.0, 142142.0, 142269.0, 143087.0, 145209.3832493398, 111540.44229778962, 112869.0, 113721.43139284416, 115640.84359156301, 117781.0, 8744.825103498022, 8798.516081615573, 8811.898512685913, 8933.955330223567, 8993.460392376479, 62371.376286236555, 64246.35753642404, 64510.0, 66690.66654666695, 67999.0, 93506.53246733722, 95616.0, 96587.0, 98194.5090274544, 99859.0, 11661.825072624088, 11956.222845515014, 11961.521539138337, 12006.159568830448, 12467.314297713712, 12377.504899804268, 12455.190412623151, 12561.811572826598, 12698.682532936751, 12985.610431687064, 11816.586419475278, 11963.521459141884, 11980.0, 12302.569410070604, 12389.814152787536, 11821.645350639494, 11825.290482571074, 11889.291205793501, 12052.0, 12128.514859405524, 11752.353620550952, 11943.82084268754, 12104.09219308573, 12183.329916854658, 12477.126601137834, 9097.40866843653, 10771.299865508718, 11558.457139429649, 11693.766124677288, 12098.09264305163, 10925.672229833117, 11661.358625275352, 11814.841735286362, 11925.224860384047, 12072.504437326856], "value": 7898.310588676539, "unit": "score"}], "name": "dromaeo_css", "value": 7500.995798868112, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 111.0, 113.0, 114.0, 113.0, 113.0, 112.0, 112.0, 113.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 147.0, 118.0, 161.0, 107.0, 128.0, 115.0, 112.0, 151.0, 124.0], "value": 132.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 184.0, 183.0, 180.0, 196.0, 260.0, 182.0, 183.0], "value": 192.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 59.0, 59.0, 106.0, 57.0, 57.0, 62.0, 66.0, 85.0, 57.0], "value": 68.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 69.0, 99.0, 70.0, 80.0, 68.0, 98.0, 70.0, 67.0, 97.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 84.0, 87.0, 83.0, 89.0, 85.0, 87.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 151.0, 90.0, 150.0, 148.0, 94.0, 95.0, 95.0, 93.0, 94.0], "value": 114.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 86.0, 86.0, 87.0, 88.0, 87.0, 86.0, 87.0, 86.0], "value": 86.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 56.0, 57.0, 56.0, 56.0, 54.0, 54.0, 54.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 72.0, 78.0, 73.0, 70.0, 71.0, 70.0, 70.0, 71.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 80.0, 81.0, 83.0, 85.0, 79.0, 82.0, 79.0, 86.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 149.0, 151.0, 144.0, 134.0, 130.0, 137.0, 136.0, 174.0, 134.0], "value": 142.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [226.0, 162.0, 172.0, 157.0, 181.0, 232.0, 230.0, 161.0, 164.0, 163.0], "value": 184.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 82.0, 84.0, 88.0, 88.0, 65.0, 67.0], "value": 74.3, "unit": "score"}], "name": "kraken", "value": 1483.6, "alertThreshold": 2.0}]} 10:31:30 INFO - Return code: 0 10:31:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:31:30 INFO - # TBPL SUCCESS # 10:31:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:31:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:31:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:31:30 INFO - 2016-07-21 10:31:30.839 screenresolution[1670:9862] starting screenresolution argv=screenresolution get 10:31:30 INFO - 2016-07-21 10:31:30.841 screenresolution[1670:9862] Display 0: 1600x1200x32@60 10:31:30 INFO - 2016-07-21 10:31:30.847 screenresolution[1671:9865] starting screenresolution argv=screenresolution list 10:31:30 INFO - Available Modes on Display 0 10:31:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:31:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:31:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:31:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:31:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:31:31 INFO - Intel Iris: 10:31:31 INFO - Chipset Model: Intel Iris 10:31:31 INFO - Type: GPU 10:31:31 INFO - Bus: Built-In 10:31:31 INFO - VRAM (Dynamic, Max): 1536 MB 10:31:31 INFO - Vendor: Intel (0x8086) 10:31:31 INFO - Device ID: 0x0a2e 10:31:31 INFO - Revision ID: 0x0009 10:31:31 INFO - Displays: 10:31:31 INFO - Display: 10:31:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:31:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:31:31 INFO - Display Serial Number: 1600x1200 60 10:31:31 INFO - Main Display: Yes 10:31:31 INFO - Mirror: Off 10:31:31 INFO - Online: Yes 10:31:31 INFO - Rotation: Supported 10:31:31 INFO - Return code: 0 10:31:31 INFO - Running post-action listener: _resource_record_post_action 10:31:31 INFO - [mozharness: 2016-07-21 17:31:31.087021Z] Finished run-tests step (success) 10:31:31 INFO - Running post-run listener: _resource_record_post_run 10:31:31 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 37094400; Write bytes: 704928256; Read time: 240; Write time: 1896 10:31:31 INFO - TinderboxPrint: CPU usage
53.3% 10:31:31 INFO - TinderboxPrint: I/O read bytes / time
37,094,400 / 240 10:31:31 INFO - TinderboxPrint: I/O write bytes / time
704,928,256 / 1,896 10:31:31 INFO - TinderboxPrint: CPU idle
2,264.4 (46.7%) 10:31:31 INFO - TinderboxPrint: CPU system
966.2 (19.9%) 10:31:31 INFO - TinderboxPrint: CPU user
1,619.0 (33.4%) 10:31:31 INFO - TinderboxPrint: Swap in / out
444,485,632 / 0 10:31:31 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 408746496; Write bytes: 411108864; Read time: 31043; Write time: 823 10:31:31 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 34456064; Write bytes: 292213760; Read time: 216; Write time: 1063 10:31:31 INFO - Running post-run listener: _upload_blobber_files 10:31:31 INFO - Blob upload gear active. 10:31:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:31:31 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 10:31:31 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'] 10:31:31 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 10:31:31 INFO - (blobuploader) - INFO - Open directory for files ... 10:31:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:31:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:31:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:31:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:31:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:31:32 INFO - (blobuploader) - INFO - Done attempting. 10:31:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:31:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:31:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:31:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:31:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:31:34 INFO - (blobuploader) - INFO - Done attempting. 10:31:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:31:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:31:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:31:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:31:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:31:37 INFO - (blobuploader) - INFO - Done attempting. 10:31:37 INFO - (blobuploader) - INFO - Iteration through files over. 10:31:37 INFO - Return code: 0 10:31:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:31:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:31:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/41d45d102180b6278a28aca9f1620ba08c33193b14bdf63c53f14643a167a60fc7b8febd1300429713e4f3531249ea81e4392a4aeaf15a6bde0732dcc127a439", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b3de5e31892deadb9cecff7b768170cba0030049560c83a3727621af6b04d4abe2b9a753d381c328fc9bd6c1a8eff310c487d6c6195cbb90b1d4febe18ee9d2f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/958da3fff69160cb47ea2768a4ec4214dd12b808935c217645f6b79c3883686642dec0dedb67a825873631ba1da8166091dbbca80ef038d9ce2d9e843dd2975a"} 10:31:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:31:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:31:37 INFO - Contents: 10:31:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/41d45d102180b6278a28aca9f1620ba08c33193b14bdf63c53f14643a167a60fc7b8febd1300429713e4f3531249ea81e4392a4aeaf15a6bde0732dcc127a439", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b3de5e31892deadb9cecff7b768170cba0030049560c83a3727621af6b04d4abe2b9a753d381c328fc9bd6c1a8eff310c487d6c6195cbb90b1d4febe18ee9d2f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/958da3fff69160cb47ea2768a4ec4214dd12b808935c217645f6b79c3883686642dec0dedb67a825873631ba1da8166091dbbca80ef038d9ce2d9e843dd2975a"} 10:31:37 INFO - Running post-run listener: copy_logs_to_upload_dir 10:31:37 INFO - Copying logs to upload dir... 10:31:37 INFO - mkdir: /builds/slave/test/build/upload/logs 10:31:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.936134 ========= master_lag: 1.88 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-21 10:31:39.531091) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-07-21 10:31:39.535052) ========= 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.50psy7skgF/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.RJytpEIPLs/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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/41d45d102180b6278a28aca9f1620ba08c33193b14bdf63c53f14643a167a60fc7b8febd1300429713e4f3531249ea81e4392a4aeaf15a6bde0732dcc127a439", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b3de5e31892deadb9cecff7b768170cba0030049560c83a3727621af6b04d4abe2b9a753d381c328fc9bd6c1a8eff310c487d6c6195cbb90b1d4febe18ee9d2f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/958da3fff69160cb47ea2768a4ec4214dd12b808935c217645f6b79c3883686642dec0dedb67a825873631ba1da8166091dbbca80ef038d9ce2d9e843dd2975a"} build_url:https://queue.taskcluster.net/v1/task/VCwP7W5dRqyR2sHdIzxi2g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011007 build_url: 'https://queue.taskcluster.net/v1/task/VCwP7W5dRqyR2sHdIzxi2g/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/41d45d102180b6278a28aca9f1620ba08c33193b14bdf63c53f14643a167a60fc7b8febd1300429713e4f3531249ea81e4392a4aeaf15a6bde0732dcc127a439", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b3de5e31892deadb9cecff7b768170cba0030049560c83a3727621af6b04d4abe2b9a753d381c328fc9bd6c1a8eff310c487d6c6195cbb90b1d4febe18ee9d2f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/958da3fff69160cb47ea2768a4ec4214dd12b808935c217645f6b79c3883686642dec0dedb67a825873631ba1da8166091dbbca80ef038d9ce2d9e843dd2975a"}' ========= master_lag: 1.12 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-07-21 10:31:40.667170) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 6 secs) (at 2016-07-21 10:31:40.667537) ========= 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.50psy7skgF/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.RJytpEIPLs/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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.005241 ========= master_lag: 6.84 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 6 secs) (at 2016-07-21 10:31:47.508362) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 10:31:47.508712) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 10:31:47.512238) ========= ========= Total master_lag: 9.91 =========