1931832 - Intermittent /push-api/push-with-applicationServerKey.https.any.window-module.html | single tracking bug

Status: RESOLVED WONTFIX

(Core :: DOM: Push Subscriptions, defect, P5)

Description

[Treeherder Bug Filer]

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

---

TEST-START | /push-api/push-with-applicationServerKey.https.any.window-module.html
[task 2024-11-18T10:26:25.412Z] 10:26:25     INFO - Closing window c9c22a2d-18ce-4783-b141-a91267faf974
[task 2024-11-18T10:26:25.420Z] 10:26:25     INFO - PID 10486 | [Parent 10486, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp:509
[task 2024-11-18T10:26:25.433Z] 10:26:25     INFO - PID 10486 | [Parent 10486, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp:125
[task 2024-11-18T10:26:25.434Z] 10:26:25     INFO - PID 10486 | [Child 12571, IPC I/O Child] WARNING: [9766F441B5CF05CC.158810F38D42AA78]: GetUserData call for port '24DC98F1E5DBD04A.50E6D3FAF96EDEBE' failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:424
[task 2024-11-18T10:26:25.473Z] 10:26:25     INFO - PID 10486 | [Parent 10486, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp:56
[task 2024-11-18T10:26:25.626Z] 10:26:25     INFO - PID 10486 | [Parent 10486, Main Thread] WARNING: NS_ENSURE_TRUE(record) failed: file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingProtection.cpp:295
[task 2024-11-18T10:26:25.628Z] 10:26:25     INFO - PID 10486 | [Parent 10486, Main Thread] WARNING: Running RecordStatefulBounces after a timeout failed.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingState.cpp:722
[task 2024-11-18T10:26:25.792Z] 10:26:25     INFO - PID 10486 | [Parent 10486, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp:509
[task 2024-11-18T10:26:25.792Z] 10:26:25     INFO - PID 10486 | [Parent 10486, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp:125
[task 2024-11-18T10:26:25.814Z] 10:26:25     INFO - PID 10486 | [Parent 10486, IPC I/O Parent] WARNING: [1.1]: GetUserData call for port 'DB66FD1838ECCB5E.31BEFD2809A4E686' failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:424
[task 2024-11-18T10:26:25.981Z] 10:26:25     INFO - PID 10486 | [Child 12837, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_ERROR_INVALID_ARG) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/intl/l10n/L10nRegistry.cpp:385
[task 2024-11-18T10:26:26.553Z] 10:26:26     INFO - PID 10486 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp84yvzyao/runtests_leaks_835_tab_pid12911.log
[task 2024-11-18T10:26:26.563Z] 10:26:26     INFO - PID 10486 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp84yvzyao/runtests_leaks_835_tab_pid12917.log
[task 2024-11-18T10:26:26.567Z] 10:26:26     INFO - PID 10486 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp84yvzyao/runtests_leaks_835_tab_pid12924.log
[task 2024-11-18T10:26:26.584Z] 10:26:26     INFO - PID 10486 | [Child 12911, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:757
[task 2024-11-18T10:26:26.595Z] 10:26:26     INFO - PID 10486 | [Child 12924, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:757
[task 2024-11-18T10:26:26.605Z] 10:26:26     INFO - PID 10486 | [Child 12917, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:757
[task 2024-11-18T10:26:29.702Z] 10:26:29     INFO - PID 10486 | [Parent 10486, Main Thread] WARNING: NS_ENSURE_TRUE(record) failed: file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingProtection.cpp:295
[task 2024-11-18T10:26:29.703Z] 10:26:29     INFO - PID 10486 | [Parent 10486, Main Thread] WARNING: Running RecordStatefulBounces after a timeout failed.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingState.cpp:722
[task 2024-11-18T10:26:31.744Z] 10:26:31     INFO - PID 10486 | [Parent 10486, Main Thread] WARNING: NS_ENSURE_TRUE(record) failed: file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingProtection.cpp:295
[task 2024-11-18T10:26:31.745Z] 10:26:31     INFO - PID 10486 | [Parent 10486, Main Thread] WARNING: Running RecordStatefulBounces after a timeout failed.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingState.cpp:722
[task 2024-11-18T10:26:35.190Z] 10:26:35     INFO - PID 10486 | [Parent 10486, Main Thread] WARNING: NS_ENSURE_TRUE(record) failed: file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingProtection.cpp:295
[task 2024-11-18T10:26:35.191Z] 10:26:35     INFO - PID 10486 | [Parent 10486, Main Thread] WARNING: Running RecordStatefulBounces after a timeout failed.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingState.cpp:722
[task 2024-11-18T10:26:56.253Z] 10:26:56     INFO - 
[task 2024-11-18T10:26:56.253Z] 10:26:56     INFO - TEST-UNEXPECTED-TIMEOUT | /push-api/push-with-applicationServerKey.https.any.window-module.html | Posting to the push endpoint should fire push event on the service worker - Test timed out
[task 2024-11-18T10:26:56.254Z] 10:26:56     INFO - 
[task 2024-11-18T10:26:56.254Z] 10:26:56     INFO - TEST-UNEXPECTED-NOTRUN | /push-api/push-with-applicationServerKey.https.any.window-module.html | Posting to the push endpoint with encrypted data should fire push event on the service worker - expected PASS
[task 2024-11-18T10:26:56.255Z] 10:26:56     INFO - TEST-UNEXPECTED-TIMEOUT | /push-api/push-with-applicationServerKey.https.any.window-module.html | expected OK
[task 2024-11-18T10:26:56.255Z] 10:26:56     INFO - TEST-INFO took 30857ms
[task 2024-11-18T10:26:56.259Z] 10:26:56     INFO - PID 10486 | 1731925616259	Marionette	INFO	Stopped listening on port 49899
[task 2024-11-18T10:26:56.770Z] 10:26:56     INFO - PID 10486 | [Parent 10486, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp:56
[task 2024-11-18T10:26:56.806Z] 10:26:56     INFO - PID 10486 | [Parent 10486, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp:509
[task 2024-11-18T10:26:56.806Z] 10:26:56     INFO - PID 10486 | [Parent 10486, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp:125
[task 2024-11-18T10:26:56.841Z] 10:26:56     INFO - PID 10486 | [Child 11349, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4155
[task 2024-11-18T10:26:56.905Z] 10:26:56     INFO - PID 10486 | [Parent 10486, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:240
[task 2024-11-18T10:26:56.906Z] 10:26:56     INFO - PID 10486 | [Parent 10486, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:240
[task 2024-11-18T10:26:56.906Z] 10:26:56     INFO - PID 10486 | [Parent 10486, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:240
[task 2024-11-18T10:26:56.906Z] 10:26:56     INFO - PID 10486 | [Parent 10486, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:240
[task 2024-11-18T10:26:56.906Z] 10:26:56     INFO - PID 10486 | [Parent 10486, IPC I/O Parent] WARNING: [1.1]: GetUserData call for port 'C123433831C0F14A.9A3E3C9C542BC6BD' failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:424
[task 2024-11-18T10:26:57.056Z] 10:26:57     INFO - Browser exited with return code -15
[task 2024-11-18T10:26:57.057Z] 10:26:57  WARNING - Firefox didn't exit cleanly, not processing leak logs
[task 2024-11-18T10:26:57.058Z] 10:26:57     INFO - Closing logging queue
[task 2024-11-18T10:26:57.058Z] 10:26:57     INFO - queue closed
[task 2024-11-18T10:26:57.075Z] 10:26:57     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpx60bje2f
[task 2024-11-18T10:26:57.089Z] 10:26:57     INFO - PID 12105 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp1n0fn5_c/runtests_leaks_835.log
[task 2024-11-18T10:26:57.090Z] 10:26:57     INFO - PID 12105 | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2024-11-18T10:26:57.100Z] 10:26:57     INFO - PID 12105 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-11-18T10:26:57.100Z] 10:26:57     INFO - PID 12105 | [Parent 12105, Renderer] WARNING: Failed to create EGLContext with khr_rbab_attribs: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:733
[task 2024-11-18T10:26:57.100Z] 10:26:57     INFO - PID 12105 | [Parent 12105, Renderer] WARNING: Failed to create EGLContext with khr_robustness_attribs: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:745
[task 2024-11-18T10:26:57.100Z] 10:26:57     INFO - PID 12105 | Initializing context 7fdc2a621580 surface 0 on display 7fdc2a53d200
[task 2024-11-18T10:26:57.100Z] 10:26:57     INFO - PID 12105 | GL_VENDOR: VMware, Inc.

Comment 1

[Intermittent Failures Robot]

21 failures in 3002 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 19
• try: 1
• autoland: 1

Platform and build breakdown:

• linux1804-64-shippable-qr: 18
  • opt: 18
• linux1804-64-qr: 2
  • debug: 1
  • opt: 1
• linux1804-32-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1931832&startday=2024-11-18&endday=2024-11-24&tree=all

Comment 2

[Intermittent Failures Robot]

19 failures in 3953 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 19

Platform and build breakdown:

• linux1804-64-shippable-qr: 19
  • opt: 19

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1931832&startday=2024-11-25&endday=2024-12-01&tree=all

Comment 3

[Sandor Molnar[:smolnar]]

Attachment: Bug 1931832 - Mark 'push-with-applicationServerKey.https.any.window-module.html' as intermittent on linux. r=jgraham,sasha

Comment 4

[Intermittent Failures Robot]

20 failures in 4722 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 19
• autoland: 1

Platform and build breakdown:

• linux1804-64-shippable-qr: 19
  • opt: 19
• linux1804-64-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1931832&startday=2024-12-02&endday=2024-12-08&tree=all

Comment 5

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/8c91d8c0369c
Mark 'push-with-applicationServerKey.https.any.window-module.html' as intermittent on linux. r=Sasha DONTBUILD

Comment 6

[amarc]

https://hg.mozilla.org/mozilla-central/rev/8c91d8c0369c

Comment 7

[Serban Stanca [:SerbanS]]

This is still happening, as it can be seen here.

Comment 8

[Intermittent Failures Robot]

Summary

8 failures in 4615 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1
• autoland: 1
• mozilla-central: 6

Platform and build breakdown:

• linux1804-64-devedition-qr: 1
  • opt: 1
• linux1804-64-qr: 1
  • opt: 1
• linux1804-64-shippable-qr: 6
  • opt: 6

Table

	1proc	1proc-s	a11y-checks	a11y-checks-s	a11y-checks-swr	a11y-checks-swr-s	condprof	condprof-s	fis	fis-hv	fis-hv-s	fis-s	headless	headless-s	http2	http3	msix	msix-s	no_variant	nofis	nofis-aab	nofis-s	nofis-ship	nofis-ship-s	nofis-spi	nofis-swr	nofis-swr-s	nogpu	nogpu-s	s	spi	spi-nw	spi-nw-1proc	spi-nw-nofis	spi-nw-nofis-s	spi-nw-s	spi-s	swr	swr-s	wr-dc1-p	xorig	xorig-s
linux1804-64-devedition-qr/opt																			1
linux1804-64-qr/opt																			1
linux1804-64-shippable-qr/opt																						6

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1931832&startday=2024-12-09&endday=2024-12-15&tree=all

Comment 9

[Joel Maher ( :jmaher ) (UTC -8)]

I cannot find this test in mozilla-central, if this is in the repo, please update the summary with the proper path