Open Bug 1610444 Opened 4 years ago Updated 2 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /html/cross-origin-opener-policy/popup-same-origin-with-same-origin.https.html | same-origin document opening popup to https://web-platform.test:8443 with COOP: "same-origin-allow-popups" - Test timed out

Categories

(Core :: DOM: Core & HTML, defect, P5)

defect

Tracking

()

REOPENED

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure)

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


[task 2020-01-21T05:07:11.267Z] 05:07:11 INFO - TEST-START | /html/cross-origin-opener-policy/popup-same-origin-with-same-origin.https.html
[task 2020-01-21T05:07:11.286Z] 05:07:11 INFO - Closing window 181
[task 2020-01-21T05:07:11.302Z] 05:07:11 INFO - PID 20356 | [Child 21971, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 321
[task 2020-01-21T05:07:11.306Z] 05:07:11 INFO - PID 20356 | [Child 21954, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 321
[task 2020-01-21T05:07:11.306Z] 05:07:11 INFO - PID 20356 | [Child 21954, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 321
[task 2020-01-21T05:07:11.363Z] 05:07:11 INFO - PID 20356 | [Child 21954, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 321
[task 2020-01-21T05:07:11.364Z] 05:07:11 INFO - PID 20356 | [Child 21954, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 321
[task 2020-01-21T05:07:11.364Z] 05:07:11 INFO - PID 20356 | [Child 21971, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-01-21T05:07:11.424Z] 05:07:11 INFO - PID 20356 | [Child 21954, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-01-21T05:07:11.467Z] 05:07:11 INFO - PID 20356 | nsStringStats
[task 2020-01-21T05:07:11.468Z] 05:07:11 INFO - PID 20356 | => mAllocCount: 9028
[task 2020-01-21T05:07:11.469Z] 05:07:11 INFO - PID 20356 | => mReallocCount: 0
[task 2020-01-21T05:07:11.471Z] 05:07:11 INFO - PID 20356 | => mFreeCount: 9028
[task 2020-01-21T05:07:11.471Z] 05:07:11 INFO - PID 20356 | => mShareCount: 7342
[task 2020-01-21T05:07:11.472Z] 05:07:11 INFO - PID 20356 | => mAdoptCount: 398
[task 2020-01-21T05:07:11.473Z] 05:07:11 INFO - PID 20356 | => mAdoptFreeCount: 408
[task 2020-01-21T05:07:11.473Z] 05:07:11 INFO - PID 20356 | => Process ID: 21971, Thread ID: 140263788971904
[task 2020-01-21T05:07:11.563Z] 05:07:11 INFO - PID 20356 | nsStringStats
[task 2020-01-21T05:07:11.563Z] 05:07:11 INFO - PID 20356 | => mAllocCount: 10714
[task 2020-01-21T05:07:11.563Z] 05:07:11 INFO - PID 20356 | => mReallocCount: 0
[task 2020-01-21T05:07:11.564Z] 05:07:11 INFO - PID 20356 | => mFreeCount: 10714
[task 2020-01-21T05:07:11.564Z] 05:07:11 INFO - PID 20356 | => mShareCount: 10433
[task 2020-01-21T05:07:11.565Z] 05:07:11 INFO - PID 20356 | => mAdoptCount: 416
[task 2020-01-21T05:07:11.565Z] 05:07:11 INFO - PID 20356 | => mAdoptFreeCount: 436
[task 2020-01-21T05:07:11.566Z] 05:07:11 INFO - PID 20356 | => Process ID: 21954, Thread ID: 139821453608832
[task 2020-01-21T05:07:11.588Z] 05:07:11 INFO - PID 20356 | [Child 22022, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 321
[task 2020-01-21T05:07:11.652Z] 05:07:11 INFO - PID 20356 | [Child 22054, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 845
[task 2020-01-21T05:07:11.652Z] 05:07:11 INFO - PID 20356 | [Child 22022, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 321
[task 2020-01-21T05:07:11.653Z] 05:07:11 INFO - PID 20356 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties

[task 2020-01-21T05:07:43.603Z] 05:07:43 INFO - TEST-PASS | /html/cross-origin-opener-policy/popup-same-origin-with-same-origin.https.html | same-origin document opening popup to https://web-platform.test:8443 with COOP: "same-site unsafe-allow-outgoing"
[task 2020-01-21T05:07:43.603Z] 05:07:43 INFO - TEST-PASS | /html/cross-origin-opener-policy/popup-same-origin-with-same-origin.https.html | same-origin document opening popup to https://web-platform.test:8443 with COOP: "same-origin unsafe-allow-outgoing"
[task 2020-01-21T05:07:43.603Z] 05:07:43 INFO - TEST-PASS | /html/cross-origin-opener-policy/popup-same-origin-with-same-origin.https.html | same-origin document opening popup to https://web-platform.test:8443 with COOP: "same-origin"
[task 2020-01-21T05:07:43.603Z] 05:07:43 INFO - TEST-UNEXPECTED-TIMEOUT | /html/cross-origin-opener-policy/popup-same-origin-with-same-origin.https.html | same-origin document opening popup to https://web-platform.test:8443 with COOP: "same-origin-allow-popups" - Test timed out
[task 2020-01-21T05:07:43.603Z] 05:07:43 INFO - TEST-UNEXPECTED-TIMEOUT | /html/cross-origin-opener-policy/popup-same-origin-with-same-origin.https.html | expected OK
[task 2020-01-21T05:07:43.603Z] 05:07:43 INFO - TEST-INFO took 32334ms
[task 2020-01-21T05:07:43.628Z] 05:07:43 INFO - PID 20356 | 1579583263625 Marionette INFO Stopped listening on port 38322
[task 2020-01-21T05:07:43.649Z] 05:07:43 INFO - PID 20356 | JavaScript error: chrome://browser/content/browser.js, line 8107: TypeError: can't access property "processMessageManager", browser.messageManager is null
[task 2020-01-21T05:07:43.876Z] 05:07:43 INFO - PID 20356 | WARNING: A blocker encountered an error while we were waiting.
[task 2020-01-21T05:07:43.876Z] 05:07:43 INFO - PID 20356 | Blocker: SessionStore: flushing all windows
[task 2020-01-21T05:07:43.877Z] 05:07:43 INFO - PID 20356 | Phase: quit-application-granted
[task 2020-01-21T05:07:43.877Z] 05:07:43 INFO - PID 20356 | State: {"total":-1,"current":-1}
[task 2020-01-21T05:07:43.877Z] 05:07:43 INFO - PID 20356 | WARNING: TypeError: can't access property "sendAsyncMessage", mm is null
[task 2020-01-21T05:07:43.877Z] 05:07:43 INFO - PID 20356 | WARNING: flush@resource:///modules/sessionstore/TabStateFlusher.jsm:97:5
[task 2020-01-21T05:07:43.877Z] 05:07:43 INFO - PID 20356 | flushWindow@resource:///modules/sessionstore/TabStateFlusher.jsm:127:28
[task 2020-01-21T05:07:43.880Z] 05:07:43 INFO - PID 20356 | flushWindow@resource:///modules/sessionstore/TabStateFlusher.jsm:31:36
[task 2020-01-21T05:07:43.880Z] 05:07:43 INFO - PID 20356 | flushAllWindowsAsync@resource:///modules/sessionstore/SessionStore.jsm:1906:50
[task 2020-01-21T05:07:43.881Z] 05:07:43 INFO - PID 20356 | ssi_onQuitApplicationGranted/<@resource:///modules/sessionstore/SessionStore.jsm:1830:32
[task 2020-01-21T05:07:43.881Z] 05:07:43 INFO - PID 20356 | trigger@resource://gre/modules/AsyncShutdown.jsm:725:23
[task 2020-01-21T05:07:43.890Z] 05:07:43 INFO - PID 20356 | _wait@resource://gre/modules/AsyncShutdown.jsm:877:15
[task 2020-01-21T05:07:43.890Z] 05:07:43 INFO - PID 20356 | wait@resource://gre/modules/AsyncShutdown.jsm:858:34
[task 2020-01-21T05:07:43.890Z] 05:07:43 INFO - PID 20356 | observe@resource://gre/modules/AsyncShutdown.jsm:533:10
[task 2020-01-21T05:07:43.890Z] 05:07:43 INFO - PID 20356 | GeckoDriver.prototype.quit@chrome://marionette/content/driver.js:3502:20
[task 2020-01-21T05:07:43.890Z] 05:07:43 INFO - PID 20356 | despatch@chrome://marionette/content/server.js:305:40
[task 2020-01-21T05:07:43.890Z] 05:07:43 INFO - PID 20356 | execute@chrome://marionette/content/server.js:275:16
[task 2020-01-21T05:07:43.895Z] 05:07:43 INFO - PID 20356 | onPacket/<@chrome://marionette/content/server.js:248:20
[task 2020-01-21T05:07:43.895Z] 05:07:43 INFO - PID 20356 | onPacket@chrome://marionette/content/server.js:249:9
[task 2020-01-21T05:07:43.895Z] 05:07:43 INFO - PID 20356 | _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2020-01-21T05:07:43.895Z] 05:07:43 INFO - PID 20356 | JavaScript error: resource:///modules/sessionstore/SessionStore.jsm, line 5686: TypeError: can't access property "sendAsyncMessage", browser.messageManager is null

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

Nothing in the last month.

Status: REOPENED → RESOLVED
Closed: 4 years ago4 years ago
Resolution: --- → INCOMPLETE

Recent log:
https://treeherder.mozilla.org/logviewer?job_id=341426470&repo=autoland&lineNumber=2094

INFO - TEST-START | /html/cross-origin-opener-policy/popup-same-origin-with-same-origin.https.html
[task 2021-06-01T15:21:26.470Z] 15:21:26     INFO - Closing window 006c7218-ca5d-49ea-9931-d5c22a8e34cb
[task 2021-06-01T15:24:36.439Z] 15:24:36     INFO - Browser not responding, setting status to CRASH
[task 2021-06-01T15:24:36.543Z] 15:24:36     INFO - TEST-UNEXPECTED-CRASH | /html/cross-origin-opener-policy/popup-same-origin-with-same-origin.https.html | expected OK
[task 2021-06-01T15:24:36.543Z] 15:24:36     INFO - TEST-INFO took 190119ms
[task 2021-06-01T15:24:36.650Z] 15:24:36 CRITICAL - Test harness output was not a valid structured log message: 
[task 2021-06-01T15:24:36.650Z] 15:24:36 CRITICAL - Exception ignored in: <bound method Marionette.__del__ of <marionette_driver.marionette.Marionette object at 0x7f181b693cf8>>
[task 2021-06-01T15:24:36.650Z] 15:24:36 CRITICAL - Traceback (most recent call last):
[task 2021-06-01T15:24:36.651Z] 15:24:36 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 536, in __del__
[task 2021-06-01T15:24:36.651Z] 15:24:36 CRITICAL -     self.cleanup()
[task 2021-06-01T15:24:36.651Z] 15:24:36 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 521, in cleanup
[task 2021-06-01T15:24:36.652Z] 15:24:36 CRITICAL -     self.delete_session()
[task 2021-06-01T15:24:36.652Z] 15:24:36 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1228, in delete_session
[task 2021-06-01T15:24:36.652Z] 15:24:36 CRITICAL -     self._send_message("WebDriver:DeleteSession")
[task 2021-06-01T15:24:36.653Z] 15:24:36 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-06-01T15:24:36.653Z] 15:24:36 CRITICAL -     return func(*args, **kwargs)
[task 2021-06-01T15:24:36.654Z] 15:24:36 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 621, in _send_message
[task 2021-06-01T15:24:36.654Z] 15:24:36 CRITICAL -     msg = self.client.request(name, params)
[task 2021-06-01T15:24:36.654Z] 15:24:36 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 292, in request
[task 2021-06-01T15:24:36.655Z] 15:24:36 CRITICAL -     return self.receive()
[task 2021-06-01T15:24:36.655Z] 15:24:36 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 178, in receive
[task 2021-06-01T15:24:36.655Z] 15:24:36 CRITICAL -     if len(remaining) == int(length):
[task 2021-06-01T15:24:36.655Z] 15:24:36 CRITICAL - ValueError: invalid literal for int() with base 10: b'ue"'
[task 2021-06-01T15:24:36.855Z] 15:24:36     INFO - STDOUT: cleanup aborted: Unable to remount device
[task 2021-06-01T15:24:36.973Z] 15:24:36     INFO - STDOUT: cleanup aborted: Unable to remount device
[task 2021-06-01T15:24:36.976Z] 15:24:36     INFO - Closing logging queue
[task 2021-06-01T15:24:36.976Z] 15:24:36     INFO - queue closed
[task 2021-06-01T15:24:36.999Z] 15:24:36     INFO - Setting up ssl
[task 2021-06-01T15:24:37.021Z] 15:24:37     INFO - certutil | b''
[task 2021-06-01T15:24:37.039Z] 15:24:37     INFO - certutil | b''
[task 2021-06-01T15:24:37.058Z] 15:24:37     INFO - certutil | b'\nCertificate Nickname                                         Trust Attributes\n                                                             SSL,S/MIME,JAR/XPI\n\nweb-platform-tests                                           CT,, \n'
[task 2021-06-01T15:24:37.880Z] 15:24:37     INFO - adb Granting important runtime permissions to org.mozilla.geckoview.test
[task 2021-06-01T15:24:39.204Z] 15:24:39     INFO - adb launch_application: am start -W -n org.mozilla.geckoview.test/org.mozilla.geckoview.test.TestRunnerActivity -a android.intent.action.MAIN --es env0 MOZ_CRASHREPORTER=1 --es env1 MOZ_CRASHREPORTER_NO_REPORT=1 --es env2 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env3 MOZ_HIDE_RESULTS_TABLE=1 --es env4 MOZ_IN_AUTOMATION=1 --es env5 MOZ_LOG=signaling:3,mtransport:4,DataChannel:4,jsep:4 --es env6 R_LOG_LEVEL=6 --es env7 R_LOG_DESTINATION=stderr --es env8 R_LOG_VERBOSE=1 --es env9 MOZ_PROCESS_LOG=/tmp/tmprh8dfwg8pidlog --es env10 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env11 STYLO_THREADS=1 --es env12 MOZ_WEBRENDER=1 --es arg0 -no-remote --es arg1 -profile --es arg2 /data/local/tmp/test_root/profile --es arg3 --marionette --es arg4 about:blank --ez use_multiprocess True
[task 2021-06-01T15:24:40.054Z] 15:24:40     INFO - Starting runner
[task 2021-06-01T15:24:40.752Z] 15:24:40     INFO - TEST-START | /html/cross-origin-opener-policy/popup-same-origin-with-same-site.https.html
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Depends on: 1741145
Depends on: 1741149
Depends on: 1741152
No longer depends on: 1741149
See Also: → 1741149
No longer depends on: 1741145
See Also: → 1741145
Severity: normal → S3
You need to log in before you can comment on or make changes to this bug.