Closed
Bug 1753104
Opened 3 years ago
Closed 3 years ago
Intermittent remote/cdp/test/browser/dom/browser_resolveNode.js | Test timed out -
Categories
(Remote Protocol :: CDP, defect, P5)
Remote Protocol
CDP
Tracking
(Not tracked)
RESOLVED
DUPLICATE
of bug 1735283
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=366351544&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Ja3jcjfgSDqNZabZns4BLQ/runs/0/artifacts/public/logs/live_backing.log
[task 2022-02-01T19:09:42.318Z] 19:09:42 INFO - checking window state
[task 2022-02-01T19:09:42.318Z] 19:09:42 INFO - TEST-START | remote/cdp/test/browser/dom/browser_resolveNode.js
[task 2022-02-01T19:09:42.386Z] 19:09:42 INFO - GECKO(2291) | 1643742582382 CDP TRACE CDPConnection 3594582e-28d8-4f97-842b-f6fd2dd50e68 <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:09:42.388Z] 19:09:42 INFO - GECKO(2291) | 1643742582382 CDP TRACE CDPConnection 503be76c-0f5f-48c8-a17f-b03aadc9522d <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:09:42.390Z] 19:09:42 INFO - GECKO(2291) | 1643742582382 CDP TRACE CDPConnection d1169043-da0f-420c-82dd-f38aed94fa9d <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:09:42.399Z] 19:09:42 INFO - GECKO(2291) | 1643742582383 CDP TRACE CDPConnection 57714168-24ce-4024-8d07-e78f0d04d2ef <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:09:42.401Z] 19:09:42 INFO - GECKO(2291) | 1643742582383 CDP TRACE CDPConnection 3154e6a3-6a39-4416-a585-c662531146fb <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:09:42.401Z] 19:09:42 INFO - GECKO(2291) | 1643742582383 CDP TRACE CDPConnection b57fa8c0-a077-4bf7-9681-e7abc6c3a1c7 <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:09:42.402Z] 19:09:42 INFO - GECKO(2291) | 1643742582383 CDP TRACE CDPConnection 64605e56-9b4f-4bac-b8fd-4f333e2c1092 <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:09:42.403Z] 19:09:42 INFO - GECKO(2291) | 1643742582383 CDP TRACE CDPConnection e3ed388f-7a6a-40d2-ba14-9ba0fe3c58ed <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:10:27.322Z] 19:10:27 INFO - TEST-INFO | started process screentopng
[task 2022-02-01T19:10:27.528Z] 19:10:27 INFO - TEST-INFO | screentopng: exit 0
[task 2022-02-01T19:10:27.528Z] 19:10:27 INFO - Buffered messages logged at 19:09:42
[task 2022-02-01T19:10:27.530Z] 19:10:27 INFO - Entering test bound backendNodeIdInvalidTypes
[task 2022-02-01T19:10:27.530Z] 19:10:27 INFO - Buffered messages finished
[task 2022-02-01T19:10:27.530Z] 19:10:27 INFO - TEST-UNEXPECTED-FAIL | remote/cdp/test/browser/dom/browser_resolveNode.js | Test timed out -
[task 2022-02-01T19:10:27.531Z] 19:10:27 INFO - GECKO(2291) | MEMORY STAT | vsize 3581MB | residentFast 714MB | heapAllocated 210MB
[task 2022-02-01T19:10:27.531Z] 19:10:27 INFO - TEST-OK | remote/cdp/test/browser/dom/browser_resolveNode.js | took 45014ms
[task 2022-02-01T19:10:27.531Z] 19:10:27 INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-02-01T19:10:27.531Z] 19:10:27 INFO - TEST-UNEXPECTED-FAIL | remote/cdp/test/browser/dom/browser_resolveNode.js | Found a tab after previous test timed out: about:blank -
[task 2022-02-01T19:10:27.532Z] 19:10:27 INFO - GECKO(2291) | 1643742627342 CDP TRACE CDPConnection 3594582e-28d8-4f97-842b-f6fd2dd50e68 <- {"method":"Target.targetDestroyed","params":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0"}}
[task 2022-02-01T19:10:27.532Z] 19:10:27 INFO - GECKO(2291) | 1643742627342 CDP TRACE CDPConnection 503be76c-0f5f-48c8-a17f-b03aadc9522d <- {"method":"Target.targetDestroyed","params":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0"}}
[task 2022-02-01T19:10:27.532Z] 19:10:27 INFO - GECKO(2291) | 1643742627342 CDP TRACE CDPConnection d1169043-da0f-420c-82dd-f38aed94fa9d <- {"method":"Target.targetDestroyed","params":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0"}}
[task 2022-02-01T19:10:27.533Z] 19:10:27 INFO - GECKO(2291) | 1643742627343 CDP TRACE CDPConnection 57714168-24ce-4024-8d07-e78f0d04d2ef <- {"method":"Target.targetDestroyed","params":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0"}}
[task 2022-02-01T19:10:27.533Z] 19:10:27 INFO - GECKO(2291) | 1643742627343 CDP TRACE CDPConnection 3154e6a3-6a39-4416-a585-c662531146fb <- {"method":"Target.targetDestroyed","params":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0"}}
[task 2022-02-01T19:10:27.533Z] 19:10:27 INFO - GECKO(2291) | 1643742627343 CDP TRACE CDPConnection b57fa8c0-a077-4bf7-9681-e7abc6c3a1c7 <- {"method":"Target.targetDestroyed","params":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0"}}
[task 2022-02-01T19:10:27.534Z] 19:10:27 INFO - GECKO(2291) | 1643742627343 CDP TRACE CDPConnection 64605e56-9b4f-4bac-b8fd-4f333e2c1092 <- {"method":"Target.targetDestroyed","params":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0"}}
[task 2022-02-01T19:10:27.534Z] 19:10:27 INFO - GECKO(2291) | 1643742627344 CDP TRACE CDPConnection e3ed388f-7a6a-40d2-ba14-9ba0fe3c58ed <- {"method":"Target.targetDestroyed","params":{"targetId":"bbce24b0-7ea7-46ad-bfbf-a3a1a174a4e0"}}
[task 2022-02-01T19:10:27.535Z] 19:10:27 INFO - GECKO(2291) | 1643742627378 CDP TRACE CDPConnection 3594582e-28d8-4f97-842b-f6fd2dd50e68 <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"8d15b5fe-ffd0-4bd7-9073-e7816fb667c2","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:10:27.535Z] 19:10:27 INFO - GECKO(2291) | 1643742627379 CDP TRACE CDPConnection 503be76c-0f5f-48c8-a17f-b03aadc9522d <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"8d15b5fe-ffd0-4bd7-9073-e7816fb667c2","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:10:27.535Z] 19:10:27 INFO - GECKO(2291) | 1643742627380 CDP TRACE CDPConnection d1169043-da0f-420c-82dd-f38aed94fa9d <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"8d15b5fe-ffd0-4bd7-9073-e7816fb667c2","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:10:27.536Z] 19:10:27 INFO - GECKO(2291) | 1643742627380 CDP TRACE CDPConnection 57714168-24ce-4024-8d07-e78f0d04d2ef <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"8d15b5fe-ffd0-4bd7-9073-e7816fb667c2","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:10:27.536Z] 19:10:27 INFO - GECKO(2291) | 1643742627381 CDP TRACE CDPConnection 3154e6a3-6a39-4416-a585-c662531146fb <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"8d15b5fe-ffd0-4bd7-9073-e7816fb667c2","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:10:27.537Z] 19:10:27 INFO - GECKO(2291) | 1643742627381 CDP TRACE CDPConnection b57fa8c0-a077-4bf7-9681-e7abc6c3a1c7 <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"8d15b5fe-ffd0-4bd7-9073-e7816fb667c2","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:10:27.537Z] 19:10:27 INFO - GECKO(2291) | 1643742627382 CDP TRACE CDPConnection 64605e56-9b4f-4bac-b8fd-4f333e2c1092 <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"8d15b5fe-ffd0-4bd7-9073-e7816fb667c2","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:10:27.538Z] 19:10:27 INFO - GECKO(2291) | 1643742627382 CDP TRACE CDPConnection e3ed388f-7a6a-40d2-ba14-9ba0fe3c58ed <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"8d15b5fe-ffd0-4bd7-9073-e7816fb667c2","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2022-02-01T19:10:27.538Z] 19:10:27 INFO - GECKO(2291) | 1643742627397 CDP TRACE CDPConnection 3594582e-28d8-4f97-842b-f6fd2dd50e68 <- {"method":"Target.targetDestroyed","params":{"targetId":"1afd8a0f-37b7-4ccd-8b3d-b8f11acf6639"}}
[task 2022-02-01T19:10:27.539Z] 19:10:27 INFO - GECKO(2291) | 1643742627397 CDP TRACE CDPConnection 503be76c-0f5f-48c8-a17f-b03aadc9522d <- {"method":"Target.targetDestroyed","params":{"targetId":"1afd8a0f-37b7-4ccd-8b3d-b8f11acf6639"}}
[task 2022-02-01T19:10:27.539Z] 19:10:27 INFO - GECKO(2291) | 1643742627398 CDP TRACE CDPConnection d1169043-da0f-420c-82dd-f38aed94fa9d <- {"method":"Target.targetDestroyed","params":{"targetId":"1afd8a0f-37b7-4ccd-8b3d-b8f11acf6639"}}
[task 2022-02-01T19:10:27.539Z] 19:10:27 INFO - GECKO(2291) | 1643742627398 CDP TRACE CDPConnection 57714168-24ce-4024-8d07-e78f0d04d2ef <- {"method":"Target.targetDestroyed","params":{"targetId":"1afd8a0f-37b7-4ccd-8b3d-b8f11acf6639"}}
[task 2022-02-01T19:10:27.540Z] 19:10:27 INFO - GECKO(2291) | 1643742627398 CDP TRACE CDPConnection 3154e6a3-6a39-4416-a585-c662531146fb <- {"method":"Target.targetDestroyed","params":{"targetId":"1afd8a0f-37b7-4ccd-8b3d-b8f11acf6639"}}
[task 2022-02-01T19:10:27.540Z] 19:10:27 INFO - GECKO(2291) | 1643742627399 CDP TRACE CDPConnection b57fa8c0-a077-4bf7-9681-e7abc6c3a1c7 <- {"method":"Target.targetDestroyed","params":{"targetId":"1afd8a0f-37b7-4ccd-8b3d-b8f11acf6639"}}
[task 2022-02-01T19:10:27.541Z] 19:10:27 INFO - GECKO(2291) | 1643742627399 CDP TRACE CDPConnection 64605e56-9b4f-4bac-b8fd-4f333e2c1092 <- {"method":"Target.targetDestroyed","params":{"targetId":"1afd8a0f-37b7-4ccd-8b3d-b8f11acf6639"}}
[task 2022-02-01T19:10:27.541Z] 19:10:27 INFO - GECKO(2291) | 1643742627399 CDP TRACE CDPConnection e3ed388f-7a6a-40d2-ba14-9ba0fe3c58ed <- {"method":"Target.targetDestroyed","params":{"targetId":"1afd8a0f-37b7-4ccd-8b3d-b8f11acf6639"}}
[task 2022-02-01T19:10:27.542Z] 19:10:27 INFO - checking window state
[task 2022-02-01T19:10:37.460Z] 19:10:37 INFO - GECKO(2291) | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ShutdownLeaks: Wait for tabs to finish closing","state":"(none)","filename":"chrome://mochikit/content/browser-test.js","lineNumber":939,"stack":["chrome://mochikit/content/browser-test.js:nextTest/<:939","chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1041"]}] Barrier: ShutdownLeaks: Wait for cleanup to be finished before checking for leaks
[task 2022-02-01T19:11:28.460Z] 19:11:28 INFO - GECKO(2291) | FATAL ERROR: AsyncShutdown timeout in ShutdownLeaks: Wait for cleanup to be finished before checking for leaks Conditions: [{"name":"ShutdownLeaks: Wait for tabs to finish closing","state":"(none)","filename":"chrome://mochikit/content/browser-test.js","lineNumber":939,"stack":["chrome://mochikit/content/browser-test.js:nextTest/<:939","chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1041"]}] At least one completion condition failed to complete within a reasonable amount of time. Causing a crash to ensure that we do not leave the user with an unresponsive process draining resources.
[task 2022-02-01T19:11:28.461Z] 19:11:28 INFO - GECKO(2291) | [Parent 2291, Main Thread] ###!!! ABORT: file chrome://mochikit/content/browser-test.js:939
[task 2022-02-01T19:11:28.465Z] 19:11:28 INFO - GECKO(2291) | ExceptionHandler::GenerateDump cloned child ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2022-02-01T19:11:28.466Z] 19:11:28 INFO - GECKO(2291) | 2522
[task 2022-02-01T19:11:28.467Z] 19:11:28 INFO - GECKO(2291) | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2022-02-01T19:11:28.586Z] 19:11:28 INFO - GECKO(2291) | Exiting due to channel error.
[task 2022-02-01T19:11:28.586Z] 19:11:28 INFO - GECKO(2291) | Exiting due to channel error.
[task 2022-02-01T19:11:28.586Z] 19:11:28 INFO - GECKO(2291) | Exiting due to channel error.
[task 2022-02-01T19:11:28.586Z] 19:11:28 INFO - GECKO(2291) | Exiting due to channel error.
[task 2022-02-01T19:17:38.582Z] 19:17:38 INFO - Buffered messages finished
[task 2022-02-01T19:17:38.583Z] 19:17:38 ERROR - TEST-UNEXPECTED-TIMEOUT | Last test finished | application timed out after 370 seconds with no output
[task 2022-02-01T19:17:38.583Z] 19:17:38 ERROR - Force-terminating active process(es).
[task 2022-02-01T19:17:38.584Z] 19:17:38 INFO - Determining child pids from psutil...
[task 2022-02-01T19:17:38.586Z] 19:17:38 INFO - []
[task 2022-02-01T19:17:38.591Z] 19:17:38 INFO - ==> process 2291 launched child process 2314
[task 2022-02-01T19:17:38.591Z] 19:17:38 INFO - ==> process 2291 launched child process 2370
[task 2022-02-01T19:17:38.591Z] 19:17:38 INFO - ==> process 2291 launched child process 2373
[task 2022-02-01T19:17:38.591Z] 19:17:38 INFO - ==> process 2291 launched child process 2446
[task 2022-02-01T19:17:38.591Z] 19:17:38 INFO - ==> process 2291 launched child process 2494
[task 2022-02-01T19:17:38.591Z] 19:17:38 INFO - ==> process 2291 launched child process 2513
[task 2022-02-01T19:17:38.591Z] 19:17:38 INFO - ==> process 2291 launched child process 2516
[task 2022-02-01T19:17:38.591Z] 19:17:38 INFO - Found child pids: {2370, 2373, 2314, 2446, 2513, 2516, 2494}
[task 2022-02-01T19:17:38.591Z] 19:17:38 INFO - Failed to get child procs
[task 2022-02-01T19:17:38.591Z] 19:17:38 INFO - Killing process: 2370
|
||
Comment 1•3 years ago
|
||
Shutdown hang of Firefox while waiting for tabs to close.
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → DUPLICATE
You need to log in
before you can comment on or make changes to this bug.
Description
•