Closed Bug 1496135 Opened 6 years ago Closed 1 year ago

Intermittent Test-Verify TEST-UNEXPECTED-TIMEOUT | browser/extensions/formautofill/test/browser/browser_manageCreditCardsDialog.js | application timed out after 370 seconds with no output

Categories

(Toolkit :: Form Autofill, defect, P3)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: tiberius_oros, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [cc-autofill-reserve])

New try failure log file:
https://treeherder.mozilla.org/#/jobs?repo=try&selectedJob=203107932&revision=e8242b1f0795a4aa56ac7f54ed4396dbaa842bc7

task 2018-10-03T14:41:52.760Z] 14:41:52     INFO - TEST-START | browser/extensions/formautofill/test/browser/browser_manageCreditCardsDialog.js
[task 2018-10-03T14:41:52.798Z] 14:41:52     INFO - GECKO(1066) | ++DOCSHELL 0x7fb63cb8e000 == 17 [pid = 1066] [id = {ba940343-5dac-44eb-89bb-e8789d761cbd}]
[task 2018-10-03T14:41:52.800Z] 14:41:52     INFO - GECKO(1066) | ++DOMWINDOW == 38 (0x7fb63fd8d200) [pid = 1066] [serial = 38] [outer = (nil)]
[task 2018-10-03T14:41:52.816Z] 14:41:52     INFO - GECKO(1066) | ++DOMWINDOW == 39 (0x7fb63c8a6000) [pid = 1066] [serial = 39] [outer = 0x7fb63fd8d200]
[task 2018-10-03T14:41:52.852Z] 14:41:52     INFO - GECKO(1066) | ++DOMWINDOW == 2 (0x7feb2547a400) [pid = 1232] [serial = 2] [outer = 0x7feb3bc57c00]
[task 2018-10-03T14:41:53.111Z] 14:41:53     INFO - GECKO(1066) | ++DOMWINDOW == 40 (0x7fb63c88bc00) [pid = 1066] [serial = 40] [outer = 0x7fb63fd8d200]
[task 2018-10-03T14:41:53.430Z] 14:41:53     INFO - GECKO(1066) | ++DOCSHELL 0x7fb63cb90800 == 18 [pid = 1066] [id = {8d4eb47e-bcc2-4005-ad11-8367e9740861}]
[task 2018-10-03T14:41:53.432Z] 14:41:53     INFO - GECKO(1066) | ++DOMWINDOW == 41 (0x7fb63dbf5200) [pid = 1066] [serial = 41] [outer = (nil)]
[task 2018-10-03T14:41:53.433Z] 14:41:53     INFO - GECKO(1066) | ++DOMWINDOW == 42 (0x7fb64c7ec400) [pid = 1066] [serial = 42] [outer = 0x7fb63dbf5200]
[task 2018-10-03T14:41:53.495Z] 14:41:53     INFO - GECKO(1066) | --DOCSHELL 0x7fd7f07bf000 == 1 [pid = 1151] [id = {97fb29ca-4e11-433f-8faa-dc2c376b5976}]
[task 2018-10-03T14:41:53.553Z] 14:41:53     INFO - GECKO(1066) | [Parent 1066, Main Thread] WARNING: NS_ENSURE_TRUE(root) failed: file /builds/worker/workspace/build/src/layout/base/nsDocumentViewer.cpp, line 3539
[task 2018-10-03T14:41:53.712Z] 14:41:53     INFO - GECKO(1066) | ++DOCSHELL 0x7fb63cb93000 == 19 [pid = 1066] [id = {4f25b5ec-508a-4958-8bf1-75d3600db841}]
[task 2018-10-03T14:41:53.714Z] 14:41:53     INFO - GECKO(1066) | ++DOMWINDOW == 43 (0x7fb63e862c00) [pid = 1066] [serial = 43] [outer = (nil)]
[task 2018-10-03T14:41:53.715Z] 14:41:53     INFO - GECKO(1066) | ++DOMWINDOW == 44 (0x7fb63c8b0400) [pid = 1066] [serial = 44] [outer = 0x7fb63e862c00]
[task 2018-10-03T14:41:53.733Z] 14:41:53     INFO - GECKO(1066) | [Parent 1066, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x805D0021: file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 1127
[task 2018-10-03T14:41:53.750Z] 14:41:53     INFO - GECKO(1066) | [Parent 1066, Main Thread] WARNING: NS_ENSURE_TRUE(root) failed: file /builds/worker/workspace/build/src/layout/base/nsDocumentViewer.cpp, line 3539
[task 2018-10-03T14:41:53.890Z] 14:41:53     INFO - GECKO(1066) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp2nWaKs.mozrunner/runtests_leaks_tab_pid1250.log
[task 2018-10-03T14:41:54.128Z] 14:41:54     INFO - GECKO(1066) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2018-10-03T14:41:55.891Z] 14:41:55     INFO - GECKO(1066) | --DOMWINDOW == 43 (0x7fb644277400) [pid = 1066] [serial = 10] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:55.894Z] 14:41:55     INFO - GECKO(1066) | --DOMWINDOW == 42 (0x7fb63dbf5200) [pid = 1066] [serial = 41] [outer = (nil)] [url = chrome://formautofill/content/manageCreditCards.xhtml]
[task 2018-10-03T14:41:55.895Z] 14:41:55     INFO - GECKO(1066) | --DOMWINDOW == 41 (0x7fb63f907c00) [pid = 1066] [serial = 32] [outer = (nil)] [url = chrome://formautofill/content/manageCreditCards.xhtml]
[task 2018-10-03T14:41:55.895Z] 14:41:55     INFO - GECKO(1066) | --DOMWINDOW == 40 (0x7fb63dbf4600) [pid = 1066] [serial = 22] [outer = (nil)] [url = chrome://formautofill/content/manageCreditCards.xhtml]
[task 2018-10-03T14:41:55.896Z] 14:41:55     INFO - GECKO(1066) | --DOMWINDOW == 39 (0x7fb641b39400) [pid = 1066] [serial = 28] [outer = (nil)] [url = chrome://formautofill/content/manageCreditCards.xhtml]
[task 2018-10-03T14:41:55.898Z] 14:41:55     INFO - GECKO(1066) | --DOMWINDOW == 38 (0x7fb641b37400) [pid = 1066] [serial = 24] [outer = (nil)] [url = chrome://formautofill/content/manageCreditCards.xhtml]
[task 2018-10-03T14:41:55.899Z] 14:41:55     INFO - GECKO(1066) | --DOMWINDOW == 37 (0x7fb63cb55e00) [pid = 1066] [serial = 34] [outer = (nil)] [url = chrome://formautofill/content/manageCreditCards.xhtml]
[task 2018-10-03T14:41:55.899Z] 14:41:55     INFO - GECKO(1066) | --DOMWINDOW == 36 (0x7fb641b35400) [pid = 1066] [serial = 30] [outer = (nil)] [url = chrome://formautofill/content/manageCreditCards.xhtml]
[task 2018-10-03T14:41:55.900Z] 14:41:55     INFO - GECKO(1066) | --DOMWINDOW == 35 (0x7fb63cb52a00) [pid = 1066] [serial = 26] [outer = (nil)] [url = chrome://formautofill/content/manageCreditCards.xhtml]
[task 2018-10-03T14:41:55.900Z] 14:41:55     INFO - GECKO(1066) | --DOMWINDOW == 34 (0x7fb63e861400) [pid = 1066] [serial = 19] [outer = (nil)] [url = chrome://formautofill/content/manageCreditCards.xhtml]
[task 2018-10-03T14:41:55.902Z] 14:41:55     INFO - GECKO(1066) | --DOMWINDOW == 33 (0x7fb63fd8d200) [pid = 1066] [serial = 38] [outer = (nil)] [url = chrome://formautofill/content/manageCreditCards.xhtml]
[task 2018-10-03T14:41:55.904Z] 14:41:55     INFO - GECKO(1066) | --DOMWINDOW == 32 (0x7fb63fd8e800) [pid = 1066] [serial = 36] [outer = (nil)] [url = chrome://global/content/commonDialog.xul]
[task 2018-10-03T14:41:55.905Z] 14:41:55     INFO - GECKO(1066) | --DOCSHELL 0x7fb6442ba800 == 18 [pid = 1066] [id = {169844a0-9e70-467c-bdce-d949c2eb1d36}]
[task 2018-10-03T14:41:55.906Z] 14:41:55     INFO - GECKO(1066) | --DOCSHELL 0x7fb642738000 == 17 [pid = 1066] [id = {5d101deb-0047-4a19-aed7-90bffc57e83a}]
[task 2018-10-03T14:41:55.908Z] 14:41:55     INFO - GECKO(1066) | --DOCSHELL 0x7fb641ac7800 == 16 [pid = 1066] [id = {b8dd0c57-e007-4ca2-a485-6daa0a11c103}]
[task 2018-10-03T14:41:55.909Z] 14:41:55     INFO - GECKO(1066) | --DOCSHELL 0x7fb63de2f800 == 15 [pid = 1066] [id = {0e0eb21f-e74c-4651-bac9-ca6b6fff1e48}]
[task 2018-10-03T14:41:55.910Z] 14:41:55     INFO - GECKO(1066) | --DOCSHELL 0x7fb63cb8e000 == 14 [pid = 1066] [id = {ba940343-5dac-44eb-89bb-e8789d761cbd}]
[task 2018-10-03T14:41:55.911Z] 14:41:55     INFO - GECKO(1066) | --DOCSHELL 0x7fb642748800 == 13 [pid = 1066] [id = {01b673d6-9732-4db6-8428-9a30cafc0225}]
[task 2018-10-03T14:41:55.912Z] 14:41:55     INFO - GECKO(1066) | --DOCSHELL 0x7fb63fd3e800 == 12 [pid = 1066] [id = {4a4d5532-d978-4fb4-b75b-dbd7b3e006a3}]
[task 2018-10-03T14:41:55.913Z] 14:41:55     INFO - GECKO(1066) | --DOCSHELL 0x7fb63cb90800 == 11 [pid = 1066] [id = {8d4eb47e-bcc2-4005-ad11-8367e9740861}]
[task 2018-10-03T14:41:55.913Z] 14:41:55     INFO - GECKO(1066) | --DOCSHELL 0x7fb63de17800 == 10 [pid = 1066] [id = {027c2137-ec26-4e64-b534-14a5af96aae1}]
[task 2018-10-03T14:41:55.914Z] 14:41:55     INFO - GECKO(1066) | --DOCSHELL 0x7fb63de15000 == 9 [pid = 1066] [id = {e993d17c-5c6c-41ef-a5a2-749383e41007}]
[task 2018-10-03T14:41:55.915Z] 14:41:55     INFO - GECKO(1066) | --DOCSHELL 0x7fb64273e000 == 8 [pid = 1066] [id = {65e498f3-b092-45e0-99d4-2fb1d788434c}]
[task 2018-10-03T14:41:55.915Z] 14:41:55     INFO - GECKO(1066) | --DOCSHELL 0x7fb63fd40800 == 7 [pid = 1066] [id = {1a367858-5bd3-4348-a70c-48ae75ae33fb}]
[task 2018-10-03T14:41:57.530Z] 14:41:57     INFO - GECKO(1066) | --DOMWINDOW == 6 (0x7fd7f071cc00) [pid = 1151] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:57.531Z] 14:41:57     INFO - GECKO(1066) | --DOMWINDOW == 5 (0x7fd7f09d2800) [pid = 1151] [serial = 6] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.364Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 31 (0x7fb63c88bc00) [pid = 1066] [serial = 40] [outer = (nil)] [url = chrome://formautofill/content/manageCreditCards.xhtml]
[task 2018-10-03T14:41:59.365Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 30 (0x7fb63c8a6000) [pid = 1066] [serial = 39] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.366Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 29 (0x7fb63e8e9400) [pid = 1066] [serial = 20] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.367Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 28 (0x7fb63e8ec400) [pid = 1066] [serial = 21] [outer = (nil)] [url = chrome://formautofill/content/manageCreditCards.xhtml]
[task 2018-10-03T14:41:59.368Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 27 (0x7fb643c47c00) [pid = 1066] [serial = 14] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.369Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 26 (0x7fb643c48c00) [pid = 1066] [serial = 15] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.369Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 25 (0x7fb646210400) [pid = 1066] [serial = 11] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.370Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 24 (0x7fb665718000) [pid = 1066] [serial = 8] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.371Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 23 (0x7fb64bf4ac00) [pid = 1066] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.372Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 22 (0x7fb641aaf400) [pid = 1066] [serial = 23] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.373Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 21 (0x7fb63c888c00) [pid = 1066] [serial = 27] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.373Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 20 (0x7fb64c7ec400) [pid = 1066] [serial = 42] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.374Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 19 (0x7fb63c8a5800) [pid = 1066] [serial = 25] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.375Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 18 (0x7fb63c8a7400) [pid = 1066] [serial = 29] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.376Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 17 (0x7fb63c8a5000) [pid = 1066] [serial = 31] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.376Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 16 (0x7fb63c8a2800) [pid = 1066] [serial = 33] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.377Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 15 (0x7fb63c8a1800) [pid = 1066] [serial = 35] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.377Z] 14:41:59     INFO - GECKO(1066) | --DOMWINDOW == 14 (0x7fb63c8a8800) [pid = 1066] [serial = 37] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:41:59.493Z] 14:41:59     INFO - GECKO(1066) | --DOCSHELL 0x7f572dab9000 == 0 [pid = 1194] [id = {965d04b1-5e55-4b8e-9e20-fe860d886bf9}]
[task 2018-10-03T14:42:05.712Z] 14:42:05     INFO - GECKO(1066) | [Parent 1066, StreamTrans #28] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 419
[task 2018-10-03T14:42:05.713Z] 14:42:05     INFO - GECKO(1066) | [Parent 1066, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/modules/libjar/nsJARChannel.cpp, line 1127
[task 2018-10-03T14:42:14.636Z] 14:42:14     INFO - GECKO(1066) | --DOMWINDOW == 5 (0x7fa8388de800) [pid = 1132] [serial = 5] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:42:14.637Z] 14:42:14     INFO - GECKO(1066) | --DOMWINDOW == 4 (0x7fa838c19000) [pid = 1132] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:42:18.245Z] 14:42:18     INFO - GECKO(1066) | --DOMWINDOW == 4 (0x7fd807755400) [pid = 1151] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:42:22.261Z] 14:42:22     INFO - GECKO(1066) | --DOMWINDOW == 3 (0x7fd7f0725400) [pid = 1151] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:42:22.262Z] 14:42:22     INFO - GECKO(1066) | --DOMWINDOW == 2 (0x7fd7f071b400) [pid = 1151] [serial = 3] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:46:54.333Z] 14:46:54     INFO - GECKO(1066) | --DOMWINDOW == 1 (0x7f5744b56600) [pid = 1194] [serial = 1] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:46:58.381Z] 14:46:58     INFO - GECKO(1066) | --DOMWINDOW == 0 (0x7f572e2be400) [pid = 1194] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2018-10-03T14:53:08.379Z] 14:53:08     INFO - Buffered messages logged at 14:41:52
[task 2018-10-03T14:53:08.380Z] 14:53:08     INFO - Entering test bound test_manageCreditCardsInitialState
[task 2018-10-03T14:53:08.381Z] 14:53:08     INFO - Buffered messages logged at 14:41:53
[task 2018-10-03T14:53:08.383Z] 14:53:08     INFO - TEST-PASS | browser/extensions/formautofill/test/browser/browser_manageCreditCardsDialog.js | No credit card - 0 == 0 - 
[task 2018-10-03T14:53:08.384Z] 14:53:08     INFO - TEST-PASS | browser/extensions/formautofill/test/browser/browser_manageCreditCardsDialog.js | Remove button disabled - true == true - 
[task 2018-10-03T14:53:08.385Z] 14:53:08     INFO - TEST-PASS | browser/extensions/formautofill/test/browser/browser_manageCreditCardsDialog.js | Show Credit Cards button disabled - true == true - 
[task 2018-10-03T14:53:08.387Z] 14:53:08     INFO - TEST-PASS | browser/extensions/formautofill/test/browser/browser_manageCreditCardsDialog.js | Add button enabled - false == false - 
[task 2018-10-03T14:53:08.388Z] 14:53:08     INFO - TEST-PASS | browser/extensions/formautofill/test/browser/browser_manageCreditCardsDialog.js | Edit button disabled - true == true - 
[task 2018-10-03T14:53:08.390Z] 14:53:08     INFO - Leaving test bound test_manageCreditCardsInitialState
[task 2018-10-03T14:53:08.391Z] 14:53:08     INFO - Entering test bound test_cancelManageCreditCardsDialogWithESC
[task 2018-10-03T14:53:08.393Z] 14:53:08     INFO - must wait for load
[task 2018-10-03T14:53:08.394Z] 14:53:08     INFO - must wait for focus
[task 2018-10-03T14:53:08.395Z] 14:53:08     INFO - TEST-PASS | browser/extensions/formautofill/test/browser/browser_manageCreditCardsDialog.js | Manage credit cards dialog is closed with ESC key - 
[task 2018-10-03T14:53:08.397Z] 14:53:08     INFO - Leaving test bound test_cancelManageCreditCardsDialogWithESC
[task 2018-10-03T14:53:08.398Z] 14:53:08     INFO - Entering test bound test_removingSingleAndMultipleCreditCards
[task 2018-10-03T14:53:08.400Z] 14:53:08     INFO - expecting credit card saved
[task 2018-10-03T14:53:08.401Z] 14:53:08     INFO - Buffered messages finished
[task 2018-10-03T14:53:08.403Z] 14:53:08    ERROR - TEST-UNEXPECTED-TIMEOUT | browser/extensions/formautofill/test/browser/browser_manageCreditCardsDialog.js | application timed out after 370 seconds with no output
[task 2018-10-03T14:53:08.404Z] 14:53:08    ERROR - Force-terminating active process(es).
[task 2018-10-03T14:53:08.406Z] 14:53:08     INFO - Determining child pids from psutil...
[task 2018-10-03T14:53:08.410Z] 14:53:08     INFO - [1132, 1250, 1232, 1151, 1194]
[task 2018-10-03T14:53:08.412Z] 14:53:08     INFO - ==> process 1066 launched child process 1087
[task 2018-10-03T14:53:08.413Z] 14:53:08     INFO - ==> process 1066 launched child process 1132
[task 2018-10-03T14:53:08.415Z] 14:53:08     INFO - ==> process 1066 launched child process 1151
[task 2018-10-03T14:53:08.416Z] 14:53:08     INFO - ==> process 1066 launched child process 1194
[task 2018-10-03T14:53:08.417Z] 14:53:08     INFO - ==> process 1066 launched child process 1232
[task 2018-10-03T14:53:08.417Z] 14:53:08     INFO - ==> process 1066 launched child process 1250
[task 2018-10-03T14:53:08.417Z] 14:53:08     INFO - Found child pids: set([1250, 1194, 1132, 1232, 1151, 1087])
[task 2018-10-03T14:53:08.418Z] 14:53:08     INFO - Failed to get child procs
[task 2018-10-03T14:53:08.418Z] 14:53:08     INFO - Killing process: 1250
[task 2018-10-03T14:53:08.418Z] 14:53:08     INFO - TEST-INFO | started process screentopng
[task 2018-10-03T14:53:08.704Z] 14:53:08     INFO - TEST-INFO | screentopng: exit 0
Whiteboard: [ccautofill]
Priority: P5 → P3
Whiteboard: [ccautofill] → [cc-autofill-mvp]
Whiteboard: [cc-autofill-mvp] → [cc-autofill-reserve]
Severity: normal → S3
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.