Closed Bug 1743866 Opened 4 years ago Closed 3 years ago

Intermittent remote/cdp/test/browser/log/browser_entryAdded.js | Test timed out -

Tracking

(Not tracked)

Status:

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Treeherder Bug Filer

Reporter

Description

•

4 years ago

treeherder

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=359855782&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/HDiBXYfdREqIigy6zvFyjw/runs/0/artifacts/public/logs/live_backing.log

[task 2021-12-01T17:06:05.958Z] 17:06:05     INFO - TEST-PASS | remote/cdp/test/browser/log/browser_entryAdded.js | Got expected line number - 
[task 2021-12-01T17:06:05.958Z] 17:06:05     INFO - CDP client closed
[task 2021-12-01T17:06:05.959Z] 17:06:05     INFO - Leaving test bound eventsForScriptErrorWithoutException
[task 2021-12-01T17:06:05.959Z] 17:06:05     INFO - Entering test bound eventsForScriptErrorLevels
[task 2021-12-01T17:06:05.959Z] 17:06:05     INFO - Buffered messages finished
[task 2021-12-01T17:06:05.960Z] 17:06:05     INFO - TEST-UNEXPECTED-FAIL | remote/cdp/test/browser/log/browser_entryAdded.js | Test timed out - 
[task 2021-12-01T17:06:05.960Z] 17:06:05     INFO - GECKO(3865) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2021-12-01T17:06:05.960Z] 17:06:05     INFO - GECKO(3865) | MEMORY STAT | vsize 3576MB | residentFast 705MB | heapAllocated 212MB
[task 2021-12-01T17:06:05.961Z] 17:06:05     INFO - TEST-OK | remote/cdp/test/browser/log/browser_entryAdded.js | took 45066ms
[task 2021-12-01T17:06:05.961Z] 17:06:05     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-12-01T17:06:05.961Z] 17:06:05     INFO - TEST-UNEXPECTED-FAIL | remote/cdp/test/browser/log/browser_entryAdded.js | Found a tab after previous test timed out: about:blank - 
[task 2021-12-01T17:06:05.962Z] 17:06:05     INFO - GECKO(3865) | 1638378365767	CDP	TRACE	CDPConnection ec3a78a9-b774-4558-a8c1-bac157d5e6e7 <- {"method":"Target.targetDestroyed","params":{"targetId":"4c04950d-3caf-4c96-abe4-dd72720d4561"}}
[task 2021-12-01T17:06:05.962Z] 17:06:05     INFO - GECKO(3865) | 1638378365768	CDP	TRACE	CDPConnection d97f466a-0de2-4133-8c4e-ab8c70197a00 <- {"method":"Target.targetDestroyed","params":{"targetId":"4c04950d-3caf-4c96-abe4-dd72720d4561"}}
[task 2021-12-01T17:06:05.963Z] 17:06:05     INFO - GECKO(3865) | 1638378365768	CDP	TRACE	CDPConnection ca460a07-5f20-498b-8706-2a84e910784b <- {"method":"Target.targetDestroyed","params":{"targetId":"4c04950d-3caf-4c96-abe4-dd72720d4561"}}
[task 2021-12-01T17:06:05.963Z] 17:06:05     INFO - GECKO(3865) | 1638378365768	CDP	TRACE	CDPConnection 4429b009-86b2-40fd-9e90-da05be6e4f29 <- {"method":"Target.targetDestroyed","params":{"targetId":"4c04950d-3caf-4c96-abe4-dd72720d4561"}}
[task 2021-12-01T17:06:05.964Z] 17:06:05     INFO - GECKO(3865) | 1638378365787	CDP	TRACE	CDPConnection ec3a78a9-b774-4558-a8c1-bac157d5e6e7 <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"d71b26c7-b858-44be-bc3c-3000f4c3deeb","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2021-12-01T17:06:05.964Z] 17:06:05     INFO - GECKO(3865) | 1638378365788	CDP	TRACE	CDPConnection d97f466a-0de2-4133-8c4e-ab8c70197a00 <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"d71b26c7-b858-44be-bc3c-3000f4c3deeb","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2021-12-01T17:06:05.965Z] 17:06:05     INFO - GECKO(3865) | 1638378365788	CDP	TRACE	CDPConnection ca460a07-5f20-498b-8706-2a84e910784b <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"d71b26c7-b858-44be-bc3c-3000f4c3deeb","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2021-12-01T17:06:05.965Z] 17:06:05     INFO - GECKO(3865) | 1638378365788	CDP	TRACE	CDPConnection 4429b009-86b2-40fd-9e90-da05be6e4f29 <- {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"d71b26c7-b858-44be-bc3c-3000f4c3deeb","type":"page","title":"","url":"about:blank","attached":false,"browserContextId":null}}}
[task 2021-12-01T17:06:05.966Z] 17:06:05     INFO - GECKO(3865) | 1638378365812	CDP	TRACE	CDPConnection ec3a78a9-b774-4558-a8c1-bac157d5e6e7 <- {"method":"Target.targetDestroyed","params":{"targetId":"d3d6ff77-4f6f-4934-8b72-56a3b403592d"}}
[task 2021-12-01T17:06:05.968Z] 17:06:05     INFO - GECKO(3865) | 1638378365812	CDP	TRACE	CDPConnection d97f466a-0de2-4133-8c4e-ab8c70197a00 <- {"method":"Target.targetDestroyed","params":{"targetId":"d3d6ff77-4f6f-4934-8b72-56a3b403592d"}}
[task 2021-12-01T17:06:05.969Z] 17:06:05     INFO - GECKO(3865) | 1638378365813	CDP	TRACE	CDPConnection ca460a07-5f20-498b-8706-2a84e910784b <- {"method":"Target.targetDestroyed","params":{"targetId":"d3d6ff77-4f6f-4934-8b72-56a3b403592d"}}
[task 2021-12-01T17:06:05.969Z] 17:06:05     INFO - GECKO(3865) | 1638378365813	CDP	TRACE	CDPConnection 4429b009-86b2-40fd-9e90-da05be6e4f29 <- {"method":"Target.targetDestroyed","params":{"targetId":"d3d6ff77-4f6f-4934-8b72-56a3b403592d"}}
[task 2021-12-01T17:06:05.970Z] 17:06:05     INFO - checking window state
[task 2021-12-01T17:06:15.890Z] 17:06:15     INFO - GECKO(3865) | 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":900,"stack":["chrome://mochikit/content/browser-test.js:nextTest/<:900","chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1041"]}] Barrier: ShutdownLeaks: Wait for cleanup to be finished before checking for leaks
[task 2021-12-01T17:07:06.891Z] 17:07:06     INFO - GECKO(3865) | 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":900,"stack":["chrome://mochikit/content/browser-test.js:nextTest/<:900","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 2021-12-01T17:07:06.891Z] 17:07:06     INFO - GECKO(3865) | [Parent 3865, Main Thread] ###!!! ABORT: file chrome://mochikit/content/browser-test.js:900
[task 2021-12-01T17:07:06.896Z] 17:07:06     INFO - GECKO(3865) | ExceptionHandler::GenerateDump cloned child 4095
[task 2021-12-01T17:07:06.896Z] 17:07:06     INFO - GECKO(3865) | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2021-12-01T17:07:06.897Z] 17:07:06     INFO - GECKO(3865) | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2021-12-01T17:07:07.005Z] 17:07:07     INFO - GECKO(3865) | Exiting due to channel error.
[task 2021-12-01T17:07:07.006Z] 17:07:07     INFO - GECKO(3865) | Exiting due to channel error.
[task 2021-12-01T17:07:07.014Z] 17:07:07     INFO - GECKO(3865) | Exiting due to channel error.
[task 2021-12-01T17:07:07.014Z] 17:07:07     INFO - GECKO(3865) | Exiting due to channel error.
[task 2021-12-01T17:13:17.018Z] 17:13:17     INFO - Buffered messages finished
[task 2021-12-01T17:13:17.019Z] 17:13:17    ERROR - TEST-UNEXPECTED-TIMEOUT | Last test finished | application timed out after 370 seconds with no output
[task 2021-12-01T17:13:17.019Z] 17:13:17    ERROR - Force-terminating active process(es).
[task 2021-12-01T17:13:17.019Z] 17:13:17     INFO - Determining child pids from psutil...

Henrik Skupin [:whimboo][⌚️UTC+2]

Comment 1

•

4 years ago

Shutdown hang of Firefox while waiting for tabs to close:

[task 2021-12-01T17:06:15.890Z] 17:06:15     INFO - GECKO(3865) | 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":900,"stack":["chrome://mochikit/content/browser-test.js:nextTest/<:900","chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1041"]}] Barrier: ShutdownLeaks: Wait for cleanup to be finished before checking for leaks
[task 2021-12-01T17:07:06.891Z] 17:07:06     INFO - GECKO(3865) | 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":900,"stack":["chrome://mochikit/content/browser-test.js:nextTest/<:900","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 2021-12-01T17:07:06.891Z] 17:07:06     INFO - GECKO(3865) | [Parent 3865, Main Thread] ###!!! ABORT: file chrome://mochikit/content/browser-test.js:900

Comment hidden (Intermittent Failures Robot)

BugBot [:suhaib / :marco/ :calixte]

Comment 3

•

3 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: NEW → RESOLVED

Closed: 3 years ago

Resolution: --- → INCOMPLETE

You need to log in before you can comment on or make changes to this bug.

Bugzilla

Intermittent remote/cdp/test/browser/log/browser_entryAdded.js | Test timed out -

Categories

(Remote Protocol :: CDP, defect, P5)

Tracking

(Not tracked)

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Crash Data

Security

(public)

User Story

Description

Comment 1

Comment 2

Comment 3