Intermittent toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Test timed out.
Categories
(Toolkit :: Password Manager, defect, P5)
Tracking
()
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: opoprus [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=289893759&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/YKQg4J8nR7maOZPj0VIWNw/runs/0/artifacts/public/logs/live_backing.log
[task 2020-02-21T13:39:15.893Z] 13:39:15 INFO - TEST-START | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html
[task 2020-02-21T13:44:44.164Z] 13:44:44 INFO - TEST-INFO | started process screenshot
[task 2020-02-21T13:44:44.235Z] 13:44:44 INFO - TEST-INFO | screenshot: exit 0
[task 2020-02-21T13:44:44.235Z] 13:44:44 INFO - Buffered messages logged at 13:39:15
[task 2020-02-21T13:44:44.236Z] 13:44:44 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Access LoginManager - true == true
[task 2020-02-21T13:44:44.236Z] 13:44:44 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Not expecting logins to be present - 0 == 0
[task 2020-02-21T13:44:44.237Z] 13:44:44 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking for successful init login - 0 == 0
[task 2020-02-21T13:44:44.237Z] 13:44:44 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking for no disabled hosts - 0 == 0
[task 2020-02-21T13:44:44.238Z] 13:44:44 INFO - add_task | Entering test setup
[task 2020-02-21T13:44:44.238Z] 13:44:44 INFO - add_task | Leaving test setup
[task 2020-02-21T13:44:44.239Z] 13:44:44 INFO - add_task | Entering test test_simpleNoDupesNoAction
[task 2020-02-21T13:44:44.239Z] 13:44:44 INFO - 1 form(s) processed
[task 2020-02-21T13:44:44.239Z] 13:44:44 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic username is: name2 - "name2" == "name2"
[task 2020-02-21T13:44:44.240Z] 13:44:44 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic password is: pass2 - "pass2" == "pass2"
[task 2020-02-21T13:44:44.240Z] 13:44:44 INFO - add_task | Leaving test test_simpleNoDupesNoAction
[task 2020-02-21T13:44:44.241Z] 13:44:44 INFO - add_task | Entering test test_simpleNoDupesUpgradeOriginAndAction
[task 2020-02-21T13:44:44.241Z] 13:44:44 INFO - Buffered messages logged at 13:39:16
[task 2020-02-21T13:44:44.241Z] 13:44:44 INFO - 1 form(s) processed
[task 2020-02-21T13:44:44.242Z] 13:44:44 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic username is: name2 - "name2" == "name2"
[task 2020-02-21T13:44:44.242Z] 13:44:44 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic password is: pass2 - "pass2" == "pass2"
[task 2020-02-21T13:44:44.242Z] 13:44:44 INFO - add_task | Leaving test test_simpleNoDupesUpgradeOriginAndAction
[task 2020-02-21T13:44:44.243Z] 13:44:44 INFO - add_task | Entering test test_simpleNoDupesUpgradeOriginOnly
[task 2020-02-21T13:44:44.243Z] 13:44:44 INFO - 1 form(s) processed
[task 2020-02-21T13:44:44.243Z] 13:44:44 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic username is: name2 - "name2" == "name2"
[task 2020-02-21T13:44:44.243Z] 13:44:44 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic password is: pass2 - "pass2" == "pass2"
[task 2020-02-21T13:44:44.243Z] 13:44:44 INFO - add_task | Leaving test test_simpleNoDupesUpgradeOriginOnly
[task 2020-02-21T13:44:44.243Z] 13:44:44 INFO - add_task | Entering test test_simpleNoDupesUpgradeActionOnly
[task 2020-02-21T13:44:44.243Z] 13:44:44 INFO - 1 form(s) processed
[task 2020-02-21T13:44:44.244Z] 13:44:44 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic username is: name2 - "name2" == "name2"
[task 2020-02-21T13:44:44.244Z] 13:44:44 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic password is: pass2 - "pass2" == "pass2"
[task 2020-02-21T13:44:44.244Z] 13:44:44 INFO - add_task | Leaving test test_simpleNoDupesUpgradeActionOnly
[task 2020-02-21T13:44:44.244Z] 13:44:44 INFO - add_task | Entering test test_dedupe
[task 2020-02-21T13:44:44.244Z] 13:44:44 INFO - 1 form(s) processed
[task 2020-02-21T13:44:44.244Z] 13:44:44 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic username is: name1 - "name1" == "name1"
[task 2020-02-21T13:44:44.244Z] 13:44:44 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic password is: passHTTPStoHTTPS - "passHTTPStoHTTPS" == "passHTTPStoHTTPS"
[task 2020-02-21T13:44:44.244Z] 13:44:44 INFO - add_task | Leaving test test_dedupe
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - add_task | Entering test test_dedupe_subdomain
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - Buffered messages finished
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Test timed out.
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - reportError@SimpleTest/TestRunner.js:128:22
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:150:18
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:44:44.245Z] 13:44:44 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-02-21T13:44:44.246Z] 13:44:44 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-02-21T13:44:44.246Z] 13:44:44 INFO - RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-02-21T13:44:44.246Z] 13:44:44 INFO - RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-02-21T13:44:44.246Z] 13:44:44 INFO - hookupTests@SimpleTest/setup.js:294:12
[task 2020-02-21T13:44:44.246Z] 13:44:44 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2020-02-21T13:44:44.246Z] 13:44:44 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2020-02-21T13:44:44.246Z] 13:44:44 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2020-02-21T13:44:44.246Z] 13:44:44 INFO - hookup@SimpleTest/setup.js:270:20
[task 2020-02-21T13:44:44.246Z] 13:44:44 INFO - EventHandlerNonNull@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1582288175%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2020-02-21T13:44:45.179Z] 13:44:45 INFO - GECKO(8772) | MEMORY STAT | vsize 2103784MB | vsizeMaxContiguous 73207125MB | residentFast 106MB | heapAllocated 14MB
[task 2020-02-21T13:45:14.174Z] 13:45:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-21T13:45:14.174Z] 13:45:14 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Test timed out.
[task 2020-02-21T13:45:14.174Z] 13:45:14 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-02-21T13:45:14.174Z] 13:45:14 INFO - reportError@SimpleTest/TestRunner.js:128:22
[task 2020-02-21T13:45:14.174Z] 13:45:14 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:150:18
[task 2020-02-21T13:45:14.174Z] 13:45:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:14.174Z] 13:45:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:14.174Z] 13:45:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:14.174Z] 13:45:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:14.174Z] 13:45:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:14.174Z] 13:45:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - hookupTests@SimpleTest/setup.js:294:12
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - hookup@SimpleTest/setup.js:270:20
[task 2020-02-21T13:45:14.175Z] 13:45:14 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1582288175%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2020-02-21T13:45:15.166Z] 13:45:15 ERROR - [SimpleTest.finish()] this test already called finish!
[task 2020-02-21T13:45:15.166Z] 13:45:15 INFO - TEST-UNEXPECTED-ERROR | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | called finish() multiple times
[task 2020-02-21T13:45:15.166Z] 13:45:15 INFO - TEST-INFO took 359270ms
[task 2020-02-21T13:45:44.166Z] 13:45:44 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Test timed out.
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - reportError@SimpleTest/TestRunner.js:128:22
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:150:18
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-02-21T13:45:44.168Z] 13:45:44 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-02-21T13:45:44.169Z] 13:45:44 INFO - RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-02-21T13:45:44.169Z] 13:45:44 INFO - RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-02-21T13:45:44.169Z] 13:45:44 INFO - hookupTests@SimpleTest/setup.js:294:12
[task 2020-02-21T13:45:44.169Z] 13:45:44 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2020-02-21T13:45:44.169Z] 13:45:44 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2020-02-21T13:45:44.169Z] 13:45:44 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2020-02-21T13:45:44.169Z] 13:45:44 INFO - hookup@SimpleTest/setup.js:270:20
[task 2020-02-21T13:45:44.169Z] 13:45:44 INFO - EventHandlerNonNull@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1582288175%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2020-02-21T13:45:45.165Z] 13:45:45 ERROR - [SimpleTest.finish()] this test already called finish!
[task 2020-02-21T13:45:45.167Z] 13:45:45 INFO - TEST-UNEXPECTED-ERROR | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | called finish() multiple times
[task 2020-02-21T13:45:45.167Z] 13:45:45 INFO - TEST-INFO
[task 2020-02-21T13:46:14.172Z] 13:46:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-21T13:46:14.172Z] 13:46:14 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Test timed out.
[task 2020-02-21T13:46:14.172Z] 13:46:14 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-02-21T13:46:14.172Z] 13:46:14 INFO - reportError@SimpleTest/TestRunner.js:128:22
[task 2020-02-21T13:46:14.172Z] 13:46:14 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:150:18
[task 2020-02-21T13:46:14.172Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.172Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.172Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.172Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.172Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.172Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.172Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.172Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.172Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - hookupTests@SimpleTest/setup.js:294:12
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - hookup@SimpleTest/setup.js:270:20
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1582288175%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up.
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - reportError@SimpleTest/TestRunner.js:128:22
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:158:20
[task 2020-02-21T13:46:14.173Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - hookupTests@SimpleTest/setup.js:294:12
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2020-02-21T13:46:14.174Z] 13:46:14 INFO - hookup@SimpleTest/setup.js:270:20
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1582288175%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 39 remaining tests.
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:299:16
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - reportError@SimpleTest/TestRunner.js:128:22
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:20
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.175Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.176Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.176Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.176Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.176Z] 13:46:14 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:184:15
[task 2020-02-21T13:46:14.176Z] 13:46:14 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:420:16
[task 2020-02-21T13:46:14.176Z] 13:46:14 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:407:48
[task 2020-02-21T13:46:14.176Z] 13:46:14 INFO - RunSet.runtests@SimpleTest/setup.js:218:14
[task 2020-02-21T13:46:14.176Z] 13:46:14 INFO - RunSet.runall@SimpleTest/setup.js:197:12
[task 2020-02-21T13:46:14.176Z] 13:46:14 INFO - hookupTests@SimpleTest/setup.js:294:12
[task 2020-02-21T13:46:14.176Z] 13:46:14 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2020-02-21T13:46:14.176Z] 13:46:14 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2020-02-21T13:46:14.176Z] 13:46:14 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2020-02-21T13:46:14.176Z] 13:46:14 INFO - hookup@SimpleTest/setup.js:270:20
[task 2020-02-21T13:46:14.176Z] 13:46:14 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ctask_1582288175%5Cappdata%5Clocal%5Ctemp&cleanupCrashes=true:11:1
[task 2020-02-21T13:46:15.169Z] 13:46:15 ERROR - [SimpleTest.finish()] this test already called finish!
[task 2020-02-21T13:46:15.171Z] 13:46:15 INFO - TEST-UNEXPECTED-ERROR | (SimpleTest/TestRunner.js) | called finish() multiple times
[task 2020-02-21T13:46:15.171Z] 13:46:15 INFO - TEST-INFO
[task 2020-02-21T13:52:25.188Z] 13:52:25 INFO - Buffered messages finished
[task 2020-02-21T13:52:25.188Z] 13:52:25 ERROR - TEST-UNEXPECTED-TIMEOUT | (SimpleTest/TestRunner.js) (finished) | application timed out after 370 seconds with no output
[task 2020-02-21T13:52:25.188Z] 13:52:25 ERROR - Force-terminating active process(es).
[task 2020-02-21T13:52:25.188Z] 13:52:25 INFO - Determining child pids from psutil...
[task 2020-02-21T13:52:25.193Z] 13:52:25 INFO - [10608]
[task 2020-02-21T13:52:25.193Z] 13:52:25 INFO - ==> process 10608 launched child process 7472 ("Z:\task_1582288175\build\application\firefox\firefox.exe" -contentproc --channel="10608.0.781547399\1474243468" -parentBuildID 20200221110631 -prefsHandle 2012 -prefMapHandle 1984 -prefsLen 1 -prefMapSize 227226 -appdir "Z:\task_1582288175\build\application\firefox\browser" - 10608 "\.\pipe\gecko-crash-server-pipe.10608" 2092 gpu)
[task 2020-02-21T13:52:25.193Z] 13:52:25 INFO - ==> process 10608 launched child process 8184 ("Z:\task_1582288175\build\application\firefox\firefox.exe" -contentproc --channel="10608.6.1961811683\1723975435" -childID 1 -isForBrowser -prefsHandle 2196 -prefMapHandle 2652 -prefsLen 1574 -prefMapSize 227226 -parentBuildID 20200221110631 -appdir "Z:\task_1582288175\build\application\firefox\browser" - 10608 "\.\pipe\gecko-crash-server-pipe.10608" 2680 tab)
[task 2020-02-21T13:52:25.193Z] 13:52:25 INFO - ==> process 10608 launched child process 940 ("Z:\task_1582288175\build\application\firefox\firefox.exe" -contentproc --channel="10608.13.658803841\166152080" -childID 2 -isForBrowser -prefsHandle 2824 -prefMapHandle 2656 -prefsLen 2040 -prefMapSize 227226 -parentBuildID 20200221110631 -appdir "Z:\task_1582288175\build\application\firefox\browser" - 10608 "\.\pipe\gecko-crash-server-pipe.10608" 2900 tab)
[task 2020-02-21T13:52:25.193Z] 13:52:25 INFO - ==> process 10608 launched child process 2896 ("Z:\task_1582288175\build\application\firefox\firefox.exe" -contentproc --channel="10608.20.1342682864\1847275589" -childID 3 -isForBrowser -prefsHandle 5748 -prefMapHandle 5744 -prefsLen 2108 -prefMapSize 227226 -parentBuildID 20200221110631 -appdir "Z:\task_1582288175\build\application\firefox\browser" - 10608 "\.\pipe\gecko-crash-server-pipe.10608" 5752 tab)
[task 2020-02-21T13:52:25.193Z] 13:52:25 INFO - Found child pids: set([10608, 7472, 2896, 940, 8184])
[task 2020-02-21T13:52:25.193Z] 13:52:25 INFO - Killing process: 10608
[task 2020-02-21T13:52:25.193Z] 13:52:25 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-21T13:52:25.256Z] 13:52:25 INFO - Killing process: 7472
[task 2020-02-21T13:52:25.256Z] 13:52:25 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-21T13:52:25.257Z] 13:52:25 WARNING - mozcrash kill_pid(): unable to get handle for pid 7472: 87
[task 2020-02-21T13:52:25.257Z] 13:52:25 INFO - Killing process: 2896
[task 2020-02-21T13:52:25.258Z] 13:52:25 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-21T13:52:25.258Z] 13:52:25 WARNING - mozcrash kill_pid(): unable to get handle for pid 2896: 87
[task 2020-02-21T13:52:25.258Z] 13:52:25 INFO - Killing process: 940
[task 2020-02-21T13:52:25.258Z] 13:52:25 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-21T13:52:25.258Z] 13:52:25 WARNING - mozcrash kill_pid(): unable to terminate pid 940: 5
[task 2020-02-21T13:52:25.258Z] 13:52:25 INFO - Killing process: 8184
[task 2020-02-21T13:52:25.258Z] 13:52:25 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-21T13:52:25.258Z] 13:52:25 WARNING - mozcrash kill_pid(): unable to get handle for pid 8184: 87
[task 2020-02-21T13:52:25.259Z] 13:52:25 INFO - psutil found pid 2896 dead
[task 2020-02-21T13:52:25.259Z] 13:52:25 INFO - psutil found pid 10608 dead
[task 2020-02-21T13:52:25.259Z] 13:52:25 INFO - psutil found pid 8184 dead
[task 2020-02-21T13:52:25.259Z] 13:52:25 INFO - psutil found pid 7472 dead
[task 2020-02-21T13:52:25.259Z] 13:52:25 INFO - psutil found pid 940 dead
[task 2020-02-21T13:52:25.259Z] 13:52:25 INFO - Killing process: 8772
[task 2020-02-21T13:52:25.259Z] 13:52:25 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-21T13:52:25.259Z] 13:52:25 WARNING - mozcrash kill_pid(): unable to terminate pid 8772: 5
[task 2020-02-21T13:52:25.260Z] 13:52:25 INFO - psutil found pid 8772 dead
[task 2020-02-21T13:52:25.278Z] 13:52:25 INFO - TEST-INFO | Main app process: exit 1
[task 2020-02-21T13:52:25.278Z] 13:52:25 INFO - Buffered messages finished
[task 2020-02-21T13:52:25.278Z] 13:52:25 ERROR - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) (finished) | application terminated with exit code 1
[task 2020-02-21T13:52:25.278Z] 13:52:25 INFO - runtests.py | Application ran for: 0:13:14.493000
[task 2020-02-21T13:52:25.278Z] 13:52:25 INFO - zombiecheck | Reading PID log: c:\users\task_1582288175\appdata\local\temp\tmps0_p_hpidlog
[task 2020-02-21T13:52:25.278Z] 13:52:25 INFO - ==> process 10608 launched child process 7472 ("Z:\task_1582288175\build\application\firefox\firefox.exe" -contentproc --channel="10608.0.781547399\1474243468" -parentBuildID 20200221110631 -prefsHandle 2012 -prefMapHandle 1984 -prefsLen 1 -prefMapSize 227226 -appdir "Z:\task_1582288175\build\application\firefox\browser" - 10608 "\.\pipe\gecko-crash-server-pipe.10608" 2092 gpu)
[task 2020-02-21T13:52:25.278Z] 13:52:25 INFO - ==> process 10608 launched child process 8184 ("Z:\task_1582288175\build\application\firefox\firefox.exe" -contentproc --channel="10608.6.1961811683\1723975435" -childID 1 -isForBrowser -prefsHandle 2196 -prefMapHandle 2652 -prefsLen 1574 -prefMapSize 227226 -parentBuildID 20200221110631 -appdir "Z:\task_1582288175\build\application\firefox\browser" - 10608 "\.\pipe\gecko-crash-server-pipe.10608" 2680 tab)
[task 2020-02-21T13:52:25.278Z] 13:52:25 INFO - ==> process 10608 launched child process 940 ("Z:\task_1582288175\build\application\firefox\firefox.exe" -contentproc --channel="10608.13.658803841\166152080" -childID 2 -isForBrowser -prefsHandle 2824 -prefMapHandle 2656 -prefsLen 2040 -prefMapSize 227226 -parentBuildID 20200221110631 -appdir "Z:\task_1582288175\build\application\firefox\browser" - 10608 "\.\pipe\gecko-crash-server-pipe.10608" 2900 tab)
[task 2020-02-21T13:52:25.279Z] 13:52:25 INFO - ==> process 10608 launched child process 2896 ("Z:\task_1582288175\build\application\firefox\firefox.exe" -contentproc --channel="10608.20.1342682864\1847275589" -childID 3 -isForBrowser -prefsHandle 5748 -prefMapHandle 5744 -prefsLen 2108 -prefMapSize 227226 -parentBuildID 20200221110631 -appdir "Z:\task_1582288175\build\application\firefox\browser" - 10608 "\.\pipe\gecko-crash-server-pipe.10608" 5752 tab)
[task 2020-02-21T13:52:25.279Z] 13:52:25 INFO - zombiecheck | Checking for orphan process with PID: 7472
[task 2020-02-21T13:52:25.279Z] 13:52:25 INFO - zombiecheck | Checking for orphan process with PID: 8184
[task 2020-02-21T13:52:25.279Z] 13:52:25 INFO - zombiecheck | Checking for orphan process with PID: 2896
[task 2020-02-21T13:52:25.279Z] 13:52:25 INFO - zombiecheck | Checking for orphan process with PID: 940
[task 2020-02-21T13:52:25.279Z] 13:52:25 INFO - runtests.py | Running with scheme: https
[task 2020-02-21T13:52:25.279Z] 13:52:25 INFO - runtests.py | Running with e10s: True
[task 2020-02-21T13:52:25.279Z] 13:52:25 INFO - runtests.py | Running with fission: False
[task 2020-02-21T13:52:25.279Z] 13:52:25 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2020-02-21T13:52:25.279Z] 13:52:25 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2020-02-21T13:52:25.280Z] 13:52:25 INFO - runtests.py | Running tests: start.
[task 2020-02-21T13:52:25.280Z] 13:52:25 INFO -
[task 2020-02-21T13:52:25.359Z] 13:52:25 INFO - Application command: Z:\task_1582288175\build\application\firefox\firefox.exe -marionette --wait-for-browser -foreground -profile c:\users\task_1582288175\appdata\local\temp\tmpq3cqrb.mozrunner
[task 2020-02-21T13:52:25.359Z] 13:52:25 INFO - runtests.py | Application pid: 7728
[task 2020-02-21T13:52:25.359Z] 13:52:25 INFO - TEST-INFO | started process GECKO(7728)
[task 2020-02-21T13:52:25.601Z] 13:52:25 INFO - GECKO(7728) | 1582293145600 Marionette TRACE Received observer notification profile-after-change
[task 2020-02-21T13:52:25.642Z] 13:52:25 INFO - GECKO(7728) | 1582293145641 Marionette TRACE Received observer notification command-line-startup
[task 2020-02-21T13:52:25.721Z] 13:52:25 INFO - GECKO(7728) | 1582293145710 Marionette TRACE Received observer notification toplevel-window-ready
[task 2020-02-21T13:52:26.875Z] 13:52:26 INFO - SimpleTest START
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 17•5 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Comment 18•5 years ago
|
||
Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=313034726&repo=mozilla-beta&lineNumber=7683
[task 2020-08-14T16:35:18.687Z] 16:35:18 INFO - TEST-START | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html
[task 2020-08-14T16:40:41.205Z] 16:40:41 INFO - TEST-INFO | started process screenshot
[task 2020-08-14T16:40:41.267Z] 16:40:41 INFO - TEST-INFO | screenshot: exit 0
[task 2020-08-14T16:40:41.267Z] 16:40:41 INFO - Buffered messages logged at 16:35:18
[task 2020-08-14T16:40:41.268Z] 16:40:41 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Access LoginManager - true == true
[task 2020-08-14T16:40:41.268Z] 16:40:41 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Not expecting logins to be present - 0 == 0
[task 2020-08-14T16:40:41.268Z] 16:40:41 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking for successful init login - 0 == 0
[task 2020-08-14T16:40:41.269Z] 16:40:41 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking for no disabled hosts - 0 == 0
[task 2020-08-14T16:40:41.269Z] 16:40:41 INFO - add_task | Entering test setup
[task 2020-08-14T16:40:41.270Z] 16:40:41 INFO - add_task | Leaving test setup
[task 2020-08-14T16:40:41.270Z] 16:40:41 INFO - add_task | Entering test test_simpleNoDupesNoAction
[task 2020-08-14T16:40:41.271Z] 16:40:41 INFO - 1 form(s) processed
[task 2020-08-14T16:40:41.271Z] 16:40:41 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic username is: name2 - "name2" == "name2"
[task 2020-08-14T16:40:41.271Z] 16:40:41 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic password is: pass2 - "pass2" == "pass2"
[task 2020-08-14T16:40:41.272Z] 16:40:41 INFO - add_task | Leaving test test_simpleNoDupesNoAction
[task 2020-08-14T16:40:41.272Z] 16:40:41 INFO - add_task | Entering test test_simpleNoDupesUpgradeOriginAndAction
[task 2020-08-14T16:40:41.273Z] 16:40:41 INFO - 1 form(s) processed
[task 2020-08-14T16:40:41.273Z] 16:40:41 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic username is: name2 - "name2" == "name2"
[task 2020-08-14T16:40:41.274Z] 16:40:41 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic password is: pass2 - "pass2" == "pass2"
[task 2020-08-14T16:40:41.274Z] 16:40:41 INFO - add_task | Leaving test test_simpleNoDupesUpgradeOriginAndAction
[task 2020-08-14T16:40:41.274Z] 16:40:41 INFO - add_task | Entering test test_simpleNoDupesUpgradeOriginOnly
[task 2020-08-14T16:40:41.275Z] 16:40:41 INFO - 1 form(s) processed
[task 2020-08-14T16:40:41.275Z] 16:40:41 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic username is: name2 - "name2" == "name2"
[task 2020-08-14T16:40:41.275Z] 16:40:41 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic password is: pass2 - "pass2" == "pass2"
[task 2020-08-14T16:40:41.275Z] 16:40:41 INFO - add_task | Leaving test test_simpleNoDupesUpgradeOriginOnly
[task 2020-08-14T16:40:41.275Z] 16:40:41 INFO - add_task | Entering test test_simpleNoDupesUpgradeActionOnly
[task 2020-08-14T16:40:41.275Z] 16:40:41 INFO - 1 form(s) processed
[task 2020-08-14T16:40:41.275Z] 16:40:41 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic username is: name2 - "name2" == "name2"
[task 2020-08-14T16:40:41.275Z] 16:40:41 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic password is: pass2 - "pass2" == "pass2"
[task 2020-08-14T16:40:41.275Z] 16:40:41 INFO - add_task | Leaving test test_simpleNoDupesUpgradeActionOnly
[task 2020-08-14T16:40:41.275Z] 16:40:41 INFO - add_task | Entering test test_dedupe
[task 2020-08-14T16:40:41.276Z] 16:40:41 INFO - 1 form(s) processed
[task 2020-08-14T16:40:41.276Z] 16:40:41 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic username is: name1 - "name1" == "name1"
[task 2020-08-14T16:40:41.276Z] 16:40:41 INFO - TEST-PASS | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Checking form-basic password is: passHTTPStoHTTPS - "passHTTPStoHTTPS" == "passHTTPStoHTTPS"
[task 2020-08-14T16:40:41.276Z] 16:40:41 INFO - add_task | Leaving test test_dedupe
[task 2020-08-14T16:40:41.276Z] 16:40:41 INFO - add_task | Entering test test_dedupe_subdomain
[task 2020-08-14T16:40:41.276Z] 16:40:41 INFO - Buffered messages finished
[task 2020-08-14T16:40:41.277Z] 16:40:41 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Test timed out.
[task 2020-08-14T16:40:41.277Z] 16:40:41 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:412:16
[task 2020-08-14T16:40:41.277Z] 16:40:41 INFO - reportError@SimpleTest/TestRunner.js:138:22
[task 2020-08-14T16:40:41.277Z] 16:40:41 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:160:18
[task 2020-08-14T16:40:42.252Z] 16:40:42 INFO - GECKO(7212) | MEMORY STAT | vsize 2103755MB | vsizeMaxContiguous 71883295MB | residentFast 85MB | heapAllocated 9MB
[task 2020-08-14T16:41:11.205Z] 16:41:11 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T16:41:11.205Z] 16:41:11 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Test timed out.
[task 2020-08-14T16:41:11.205Z] 16:41:11 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:412:16
[task 2020-08-14T16:41:11.205Z] 16:41:11 INFO - reportError@SimpleTest/TestRunner.js:138:22
[task 2020-08-14T16:41:11.205Z] 16:41:11 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:160:18
[task 2020-08-14T16:41:12.204Z] 16:41:12 ERROR - [SimpleTest.finish()] this test already called finish!
[task 2020-08-14T16:41:12.204Z] 16:41:12 INFO - TEST-UNEXPECTED-ERROR | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | called finish() multiple times
[task 2020-08-14T16:41:12.204Z] 16:41:12 INFO - TEST-INFO took 353527ms
[task 2020-08-14T16:41:41.214Z] 16:41:41 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T16:41:41.214Z] 16:41:41 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Test timed out.
[task 2020-08-14T16:41:41.214Z] 16:41:41 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:412:16
[task 2020-08-14T16:41:41.214Z] 16:41:41 INFO - reportError@SimpleTest/TestRunner.js:138:22
[task 2020-08-14T16:41:41.214Z] 16:41:41 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:160:18
[task 2020-08-14T16:41:42.207Z] 16:41:42 ERROR - [SimpleTest.finish()] this test already called finish!
[task 2020-08-14T16:41:42.207Z] 16:41:42 INFO - TEST-UNEXPECTED-ERROR | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | called finish() multiple times
[task 2020-08-14T16:41:42.207Z] 16:41:42 INFO - TEST-INFO
[task 2020-08-14T16:42:11.209Z] 16:42:11 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T16:42:11.209Z] 16:42:11 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/mochitest/test_autofill_https_upgrade.html | Test timed out.
[task 2020-08-14T16:42:11.209Z] 16:42:11 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:412:16
[task 2020-08-14T16:42:11.209Z] 16:42:11 INFO - reportError@SimpleTest/TestRunner.js:138:22
[task 2020-08-14T16:42:11.209Z] 16:42:11 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:160:18
[task 2020-08-14T16:42:11.209Z] 16:42:11 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T16:42:11.209Z] 16:42:11 INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up.
[task 2020-08-14T16:42:11.209Z] 16:42:11 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:412:16
[task 2020-08-14T16:42:11.209Z] 16:42:11 INFO - reportError@SimpleTest/TestRunner.js:138:22
[task 2020-08-14T16:42:11.209Z] 16:42:11 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:168:20
[task 2020-08-14T16:42:11.210Z] 16:42:11 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T16:42:11.210Z] 16:42:11 INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 38 remaining tests.
[task 2020-08-14T16:42:11.210Z] 16:42:11 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:412:16
[task 2020-08-14T16:42:11.210Z] 16:42:11 INFO - reportError@SimpleTest/TestRunner.js:138:22
[task 2020-08-14T16:42:11.210Z] 16:42:11 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:173:20
[task 2020-08-14T16:42:12.209Z] 16:42:12 ERROR - [SimpleTest.finish()] this test already called finish!
[task 2020-08-14T16:42:12.211Z] 16:42:12 INFO - TEST-UNEXPECTED-ERROR | (SimpleTest/TestRunner.js) | called finish() multiple times
[task 2020-08-14T16:42:12.211Z] 16:42:12 INFO - TEST-INFO
[task 2020-08-14T16:48:22.217Z] 16:48:22 INFO - Buffered messages finished
[task 2020-08-14T16:48:22.217Z] 16:48:22 ERROR - TEST-UNEXPECTED-TIMEOUT | (SimpleTest/TestRunner.js) (finished) | application timed out after 370 seconds with no output
[task 2020-08-14T16:48:22.217Z] 16:48:22 ERROR - Force-terminating active process(es).
[task 2020-08-14T16:48:22.217Z] 16:48:22 INFO - Determining child pids from psutil...
[task 2020-08-14T16:48:22.222Z] 16:48:22 INFO - [9000]
[task 2020-08-14T16:48:22.222Z] 16:48:22 INFO - ==> process 9000 launched child process 5876 ("Z:\task_1597421675\build\application\firefox\firefox.exe" -contentproc --channel="9000.0.726328823\870431645" -parentBuildID 20200814141910 -prefsHandle 1984 -prefMapHandle 1916 -prefsLen 1 -prefMapSize 239823 -appdir "Z:\task_1597421675\build\application\firefox\browser" - 9000 "\.\pipe\gecko-crash-server-pipe.9000" 2184 gpu)
[task 2020-08-14T16:48:22.222Z] 16:48:22 INFO - ==> process 9000 launched child process 8592 ("Z:\task_1597421675\build\application\firefox\firefox.exe" -contentproc --channel="9000.6.1130041851\42112436" -childID 1 -isForBrowser -prefsHandle 1912 -prefMapHandle 2796 -prefsLen 1721 -prefMapSize 239823 -parentBuildID 20200814141910 -appdir "Z:\task_1597421675\build\application\firefox\browser" - 9000 "\.\pipe\gecko-crash-server-pipe.9000" 1892 tab)
[task 2020-08-14T16:48:22.222Z] 16:48:22 INFO - ==> process 9000 launched child process 7468 ("Z:\task_1597421675\build\application\firefox\firefox.exe" -contentproc --channel="9000.13.575279677\51208955" -childID 2 -isForBrowser -prefsHandle 5976 -prefMapHandle 5972 -prefsLen 2134 -prefMapSize 239823 -noScache -parentBuildID 20200814141910 -appdir "Z:\task_1597421675\build\application\firefox\browser" - 9000 "\.\pipe\gecko-crash-server-pipe.9000" 5980 tab)
[task 2020-08-14T16:48:22.222Z] 16:48:22 INFO - ==> process 9000 launched child process 10772 ("Z:\task_1597421675\build\application\firefox\firefox.exe" -contentproc --channel="9000.20.1411314041\173905848" -childID 3 -isForBrowser -prefsHandle 6100 -prefMapHandle 5972 -prefsLen 2175 -prefMapSize 239823 -parentBuildID 20200814141910 -appdir "Z:\task_1597421675\build\application\firefox\browser" - 9000 "\.\pipe\gecko-crash-server-pipe.9000" 6152 tab)
[task 2020-08-14T16:48:22.222Z] 16:48:22 INFO - ==> process 9000 launched child process 2128 ("Z:\task_1597421675\build\application\firefox\firefox.exe" -contentproc --channel="9000.27.1201040438\762010797" -childID 4 -isForBrowser -prefsHandle 5712 -prefMapHandle 5708 -prefsLen 11007 -prefMapSize 239823 -noScache -parentBuildID 20200814141910 -appdir "Z:\task_1597421675\build\application\firefox\browser" - 9000 "\.\pipe\gecko-crash-server-pipe.9000" 4308 tab)
[task 2020-08-14T16:48:22.223Z] 16:48:22 INFO - ==> process 9000 launched child process 9664 ("Z:\task_1597421675\build\application\firefox\firefox.exe" -contentproc --channel="9000.34.1761129427\1811634670" -childID 5 -isForBrowser -prefsHandle 4836 -prefMapHandle 4800 -prefsLen 11071 -prefMapSize 239823 -noScache -parentBuildID 20200814141910 -appdir "Z:\task_1597421675\build\application\firefox\browser" - 9000 "\.\pipe\gecko-crash-server-pipe.9000" 4608 tab)
[task 2020-08-14T16:48:22.223Z] 16:48:22 INFO - Found child pids: set([9664, 2128, 9000, 7468, 8592, 5876, 10772])
[task 2020-08-14T16:48:22.223Z] 16:48:22 INFO - Killing process: 9664
[task 2020-08-14T16:48:22.223Z] 16:48:22 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T16:48:22.223Z] 16:48:22 INFO - mozcrash Writing a dump to c:\users\task_1597421675\appdata\local\temp\tmpx9j4vs.mozrunner\minidumps\3976932b-fe5a-4e46-9f1f-30aa97fc55c1.dmp for [9664]
[task 2020-08-14T16:48:22.283Z] 16:48:22 INFO - Killing process: 2128
[task 2020-08-14T16:48:22.283Z] 16:48:22 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T16:48:22.283Z] 16:48:22 INFO - mozcrash Writing a dump to c:\users\task_1597421675\appdata\local\temp\tmpx9j4vs.mozrunner\minidumps\67ec2972-6ef3-409f-af05-147a378fee4d.dmp for [2128]
[task 2020-08-14T16:48:22.341Z] 16:48:22 INFO - Killing process: 9000
[task 2020-08-14T16:48:22.341Z] 16:48:22 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T16:48:22.341Z] 16:48:22 INFO - mozcrash Writing a dump to c:\users\task_1597421675\appdata\local\temp\tmpx9j4vs.mozrunner\minidumps\a446e978-b688-4f27-9c9b-45c99c214567.dmp for [9000]
[task 2020-08-14T16:48:22.563Z] 16:48:22 INFO - Killing process: 7468
[task 2020-08-14T16:48:22.563Z] 16:48:22 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T16:48:22.564Z] 16:48:22 INFO - mozcrash Writing a dump to c:\users\task_1597421675\appdata\local\temp\tmpx9j4vs.mozrunner\minidumps\13b3c99d-5e71-4fb7-bdac-880014ec6969.dmp for [7468]
[task 2020-08-14T16:48:22.564Z] 16:48:22 WARNING - mozcrash unable to get handle for pid 7468: 87
[task 2020-08-14T16:48:22.565Z] 16:48:22 WARNING - mozcrash kill_pid(): unable to get handle for pid 7468: 87
[task 2020-08-14T16:48:22.565Z] 16:48:22 INFO - Killing process: 8592
[task 2020-08-14T16:48:22.566Z] 16:48:22 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T16:48:22.566Z] 16:48:22 INFO - mozcrash Writing a dump to c:\users\task_1597421675\appdata\local\temp\tmpx9j4vs.mozrunner\minidumps\fe291190-4b8e-4a37-a9be-66f3d1d1c7df.dmp for [8592]
[task 2020-08-14T16:48:22.566Z] 16:48:22 WARNING - mozcrash unable to get handle for pid 8592: 87
[task 2020-08-14T16:48:22.567Z] 16:48:22 WARNING - mozcrash kill_pid(): unable to get handle for pid 8592: 87
[task 2020-08-14T16:48:22.567Z] 16:48:22 INFO - Killing process: 5876
[task 2020-08-14T16:48:22.568Z] 16:48:22 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T16:48:22.568Z] 16:48:22 INFO - mozcrash Writing a dump to c:\users\task_1597421675\appdata\local\temp\tmpx9j4vs.mozrunner\minidumps\cea0442f-4985-4fd6-8ef3-19e79a7af003.dmp for [5876]
[task 2020-08-14T16:48:22.568Z] 16:48:22 WARNING - mozcrash unable to get handle for pid 5876: 87
[task 2020-08-14T16:48:22.568Z] 16:48:22 WARNING - mozcrash kill_pid(): unable to get handle for pid 5876: 87
[task 2020-08-14T16:48:22.568Z] 16:48:22 INFO - Killing process: 10772
[task 2020-08-14T16:48:22.568Z] 16:48:22 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T16:48:22.568Z] 16:48:22 INFO - mozcrash Writing a dump to c:\users\task_1597421675\appdata\local\temp\tmpx9j4vs.mozrunner\minidumps\1274e9d7-2429-4071-a85a-fd6dacff1f9e.dmp for [10772]
[task 2020-08-14T16:48:22.569Z] 16:48:22 WARNING - mozcrash unable to get handle for pid 10772: 87
[task 2020-08-14T16:48:22.569Z] 16:48:22 WARNING - mozcrash kill_pid(): unable to get handle for pid 10772: 87
[task 2020-08-14T16:48:22.569Z] 16:48:22 INFO - psutil found pid 2128 dead
[task 2020-08-14T16:48:22.569Z] 16:48:22 INFO - psutil found pid 8592 dead
[task 2020-08-14T16:48:22.569Z] 16:48:22 INFO - psutil found pid 10772 dead
[task 2020-08-14T16:48:22.569Z] 16:48:22 INFO - psutil found pid 5876 dead
[task 2020-08-14T16:48:22.569Z] 16:48:22 INFO - psutil found pid 9000 dead
[task 2020-08-14T16:48:22.569Z] 16:48:22 INFO - psutil found pid 7468 dead
[task 2020-08-14T16:48:22.569Z] 16:48:22 INFO - psutil found pid 9664 dead
[task 2020-08-14T16:48:22.569Z] 16:48:22 INFO - Killing process: 7212
[task 2020-08-14T16:48:22.569Z] 16:48:22 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T16:48:22.570Z] 16:48:22 INFO - mozcrash Writing a dump to c:\users\task_1597421675\appdata\local\temp\tmpx9j4vs.mozrunner\minidumps\24a8fca8-cfc8-464e-9732-420151e6a148.dmp for [7212]
[task 2020-08-14T16:48:22.570Z] 16:48:22 WARNING - mozcrash unable to dump minidump file for pid 7212: -2147024597
[task 2020-08-14T16:48:22.570Z] 16:48:22 WARNING - mozcrash kill_pid(): unable to terminate pid 7212: 5
[task 2020-08-14T16:48:22.570Z] 16:48:22 INFO - psutil found pid 7212 dead
[task 2020-08-14T16:48:22.600Z] 16:48:22 INFO - TEST-INFO | Main app process: exit 1
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 26•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Comment 27•4 years ago
|
||
recent failure: https://treeherder.mozilla.org/logviewer?job_id=328884420&repo=mozilla-esr78&lineNumber=6724
Comment hidden (Intermittent Failures Robot) |
Comment 29•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Comment hidden (Intermittent Failures Robot) |
Description
•