1804494 - Intermittent netwerk/test/browser/browser_103_no_cancel_on_error.js | single tracking bug

Status: RESOLVED DUPLICATE of bug 1803884

(Core :: Networking, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-12-07T16:53:51.130Z] 16:53:51     INFO - TEST-PASS | netwerk/test/browser/browser_103_no_cancel_on_error.js | test_103_error_403: unexpected amount of normal request made expected 0 ({"hinted":1,"normal":0}), got 0 ({"hinted":1,"normal":0}) - true == true - 
[task 2022-12-07T16:53:51.131Z] 16:53:51     INFO - Leaving test bound test_complete_103_on_403
[task 2022-12-07T16:53:51.132Z] 16:53:51     INFO - Entering test bound test_complete_103_on_500
[task 2022-12-07T16:53:51.133Z] 16:53:51     INFO - Buffered messages finished
[task 2022-12-07T16:53:51.135Z] 16:53:51     INFO - TEST-UNEXPECTED-FAIL | netwerk/test/browser/browser_103_no_cancel_on_error.js | test_103_error_500: unexpected amount of hinted request made expected 1 ({"hinted":1,"normal":0}), got 0 ({"hinted":0,"normal":1}) - false == true - JS frame :: resource://testing-common/early_hint_preload_test_helper.jsm :: request_count_checking :: line 27
[task 2022-12-07T16:53:51.135Z] 16:53:51     INFO - Stack trace:
[task 2022-12-07T16:53:51.136Z] 16:53:51     INFO - resource://testing-common/early_hint_preload_test_helper.jsm:request_count_checking:27
[task 2022-12-07T16:53:51.137Z] 16:53:51     INFO - chrome://mochitests/content/browser/netwerk/test/browser/browser_103_no_cancel_on_error.js:test_hint_completion_on_error:40
[task 2022-12-07T16:53:51.138Z] 16:53:51     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-12-07T16:53:51.139Z] 16:53:51     INFO - TEST-UNEXPECTED-FAIL | netwerk/test/browser/browser_103_no_cancel_on_error.js | test_103_error_500: unexpected amount of normal request made expected 0 ({"hinted":1,"normal":0}), got 1 ({"hinted":0,"normal":1}) - false == true - JS frame :: resource://testing-common/early_hint_preload_test_helper.jsm :: request_count_checking :: line 34
[task 2022-12-07T16:53:51.140Z] 16:53:51     INFO - Stack trace:
[task 2022-12-07T16:53:51.141Z] 16:53:51     INFO - resource://testing-common/early_hint_preload_test_helper.jsm:request_count_checking:34
[task 2022-12-07T16:53:51.141Z] 16:53:51     INFO - chrome://mochitests/content/browser/netwerk/test/browser/browser_103_no_cancel_on_error.js:test_hint_completion_on_error:40
[task 2022-12-07T16:53:51.142Z] 16:53:51     INFO - Leaving test bound test_complete_103_on_500
[task 2022-12-07T16:53:51.143Z] 16:53:51     INFO - Entering test bound test_complete_103_on_501
[task 2022-12-07T16:53:51.145Z] 16:53:51     INFO - GECKO(17455) | [Child 18290: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f13c6031800 == 1 [pid = 18290] [id = 0]
[task 2022-12-07T16:53:51.146Z] 16:53:51     INFO - GECKO(17455) | [Child 18290: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f13e18919f0) [pid = 18290] [serial = 1] [outer = 0]
[task 2022-12-07T16:53:51.147Z] 16:53:51     INFO - GECKO(17455) | [Child 18290: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f13c6032000) [pid = 18290] [serial = 2] [outer = 7f13e18919f0]
[task 2022-12-07T16:53:51.148Z] 16:53:51     INFO - GECKO(17455) | [Child 18289: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f71a6d919f0) [pid = 18289] [serial = 1] [outer = 0] [url = https://example.com/browser/netwerk/test/browser/early_hint_asset_html.sjs?as=image&code=500]
[task 2022-12-07T16:53:51.149Z] 16:53:51     INFO - GECKO(17455) | [Child 18289: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f718b534c00) [pid = 18289] [serial = 3] [outer = 0] [url = https://example.com/browser/netwerk/test/browser/early_hint_asset_html.sjs?as=image&code=500]
[task 2022-12-07T16:53:51.151Z] 16:53:51     INFO - GECKO(17455) | [Child 18289: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f718b532000) [pid = 18289] [serial = 2] [outer = 0] [url = about:blank]
[task 2022-12-07T16:53:51.152Z] 16:53:51     INFO - GECKO(17455) | [Child 18289, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3387
[task 2022-12-07T16:53:51.153Z] 16:53:51     INFO - GECKO(17455) | [Child 18289, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-12-07T16:53:51.162Z] 16:53:51     INFO - GECKO(17455) | [Child 18289, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4659
[task 2022-12-07T16:53:51.163Z] 16:53:51     INFO - GECKO(17455) | [Child 18290: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f13c6034c00) [pid = 18290] [serial = 3] [outer = 7f13e18919f0]
[task 2022-12-07T16:53:51.182Z] 16:53:51     INFO - GECKO(17455) | [Child 18290, Main Thread] WARNING: Forced to copy ObserverTable due to nested notifications: file /builds/worker/checkouts/gecko/image/ProgressTracker.h:85
[task 2022-12-07T16:53:51.317Z] 16:53:51     INFO - GECKO(17455) | [Parent 17455, IPDL Background] WARNING: IPC Connection Error: [Parent][PBackgroundParent] RunMessage(msgname=PRemoteWorkerService::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1883
[task 2022-12-07T16:53:51.322Z] 16:53:51     INFO - GECKO(17455) | [Parent 17455, Compositor] WARNING: IPC Connection Error: [Parent][PCompositorManagerParent] RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1883
[task 2022-12-07T16:53:51.352Z] 16:53:51     INFO - GECKO(17455) | [Child 18290: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f13c6031800 == 0 [pid = 18290] [id = 0] [url = https://example.com/browser/netwerk/test/browser/early_hint_asset_html.sjs?as=image&code=501]
[task 2022-12-07T16:53:51.356Z] 16:53:51     INFO - TEST-PASS | netwerk/test/browser/browser_103_no_cancel_on_error.js | test_103_error_501: unexpected amount of hinted request made expected 1 ({"hinted":1,"normal":0}), got 1 ({"hinted":1,"normal":0}) - true == true - 
[task 2022-12-07T16:53:51.358Z] 16:53:51     INFO - TEST-PASS | netwerk/test/browser/browser_103_no_cancel_on_error.js | test_103_error_501: unexpected amount of normal request made expected 0 ({"hinted":1,"normal":0}), got 0 ({"hinted":1,"normal":0}) - true == true - 
[task 2022-12-07T16:53:51.359Z] 16:53:51     INFO - Leaving test bound test_complete_103_on_501

Comment 1

[Intermittent Failures Robot]

1 failures in 3510 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1804494&startday=2022-12-05&endday=2022-12-11&tree=all

Comment 2

[Intermittent Failures Robot]

3 failures in 2999 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

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

Platform and build breakdown:

• linux1804-64-qr: 3
  • debug: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1804494&startday=2022-12-19&endday=2022-12-25&tree=all

Comment 3

[Intermittent Failures Robot]

2 failures in 2607 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 2

Platform and build breakdown:

• linux1804-64-qr: 2
  • debug: 2

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