Closed Bug 1805067 Opened 1 year ago Closed 1 year ago

Intermittent netwerk/test/browser/browser_103_error.js | single tracking bug

Categories

(Core :: Networking, defect, P5)

Product:
Core
Component:
Networking
Type:
defect

Tracking

()

Status:
RESOLVED DUPLICATE of bug 1803884

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure, intermittent-testcase, Whiteboard: [retriggered])

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

[task 2022-12-11T01:27:20.771Z] 01:27:20     INFO - TEST-PASS | netwerk/test/browser/browser_103_error.js | test_103_error_502: unexpected amount of normal request made expected 0 ({"hinted":1,"normal":0}), got 0 ({"hinted":1,"normal":0}) - true == true - 
[task 2022-12-11T01:27:20.772Z] 01:27:20     INFO - Leaving test bound test_103_error_502
[task 2022-12-11T01:27:20.778Z] 01:27:20     INFO - Entering test bound test_103_error_503
[task 2022-12-11T01:27:20.779Z] 01:27:20     INFO - Buffered messages finished
[task 2022-12-11T01:27:20.780Z] 01:27:20     INFO - TEST-UNEXPECTED-FAIL | netwerk/test/browser/browser_103_error.js | test_103_error_503: 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-11T01:27:20.781Z] 01:27:20     INFO - Stack trace:
[task 2022-12-11T01:27:20.782Z] 01:27:20     INFO - resource://testing-common/early_hint_preload_test_helper.jsm:request_count_checking:27
[task 2022-12-11T01:27:20.782Z] 01:27:20     INFO - resource://testing-common/early_hint_preload_test_helper.jsm:test_hint_preload_internal:97
[task 2022-12-11T01:27:20.784Z] 01:27:20     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-12-11T01:27:20.786Z] 01:27:20     INFO - TEST-UNEXPECTED-FAIL | netwerk/test/browser/browser_103_error.js | test_103_error_503: 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-11T01:27:20.786Z] 01:27:20     INFO - Stack trace:
[task 2022-12-11T01:27:20.787Z] 01:27:20     INFO - resource://testing-common/early_hint_preload_test_helper.jsm:request_count_checking:34
[task 2022-12-11T01:27:20.787Z] 01:27:20     INFO - resource://testing-common/early_hint_preload_test_helper.jsm:test_hint_preload_internal:97
[task 2022-12-11T01:27:20.788Z] 01:27:20     INFO - Leaving test bound test_103_error_503
[task 2022-12-11T01:27:20.789Z] 01:27:20     INFO - Entering test bound test_103_error_504
[task 2022-12-11T01:27:20.791Z] 01:27:20     INFO - GECKO(3628) | [Child 4263: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fef2ce1d800 == 0 [pid = 4263] [id = 0] [url = https://example.com/browser/netwerk/test/browser/early_hint_main_html.sjs?https%3A%2F%2Fexample.com%2Fbrowser%2Fnetwerk%2Ftest%2Fbrowser%2Fearly_hint_error.sjs%3F503=%7B74ecec17-9a05-4b79-ab15-e9e614aa2b5c%7D]
[task 2022-12-11T01:27:20.792Z] 01:27:20     INFO - GECKO(3628) | [Child 4325: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f4d1f226800 == 1 [pid = 4325] [id = 0]
[task 2022-12-11T01:27:20.794Z] 01:27:20     INFO - GECKO(3628) | [Child 4325: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f4d3aa919f0) [pid = 4325] [serial = 1] [outer = 0]
[task 2022-12-11T01:27:20.795Z] 01:27:20     INFO - GECKO(3628) | [Child 4325: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f4d1f227000) [pid = 4325] [serial = 2] [outer = 7f4d3aa919f0]
[task 2022-12-11T01:27:20.796Z] 01:27:20     INFO - GECKO(3628) | [Child 4263: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7fef486919f0) [pid = 4263] [serial = 1] [outer = 0] [url = https://example.com/browser/netwerk/test/browser/early_hint_main_html.sjs?https%3A%2F%2Fexample.com%2Fbrowser%2Fnetwerk%2Ftest%2Fbrowser%2Fearly_hint_error.sjs%3F503=%7B74ecec17-9a05-4b79-ab15-e9e614aa2b5c%7D]
[task 2022-12-11T01:27:20.797Z] 01:27:20     INFO - GECKO(3628) | [Child 4263: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7fef2ce20c00) [pid = 4263] [serial = 3] [outer = 0] [url = https://example.com/browser/netwerk/test/browser/early_hint_main_html.sjs?https%3A%2F%2Fexample.com%2Fbrowser%2Fnetwerk%2Ftest%2Fbrowser%2Fearly_hint_error.sjs%3F503=%7B74ecec17-9a05-4b79-ab15-e9e614aa2b5c%7D]
[task 2022-12-11T01:27:20.798Z] 01:27:20     INFO - GECKO(3628) | [Child 4263: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7fef2ce1e000) [pid = 4263] [serial = 2] [outer = 0] [url = about:blank]
[task 2022-12-11T01:27:20.800Z] 01:27:20     INFO - GECKO(3628) | [Child 4263, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3387
[task 2022-12-11T01:27:20.803Z] 01:27:20     INFO - GECKO(3628) | [Child 4263, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-12-11T01:27:20.807Z] 01:27:20     INFO - GECKO(3628) | [Child 4263, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4659
[task 2022-12-11T01:27:20.808Z] 01:27:20     INFO - GECKO(3628) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpwwgat3aa.mozrunner/runtests_leaks_tab_pid4362.log
[task 2022-12-11T01:27:20.811Z] 01:27:20     INFO - GECKO(3628) | [4362, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-12-11T01:27:20.811Z] 01:27:20     INFO - GECKO(3628) | [Child 4362, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:714
[task 2022-12-11T01:27:20.812Z] 01:27:20     INFO - GECKO(3628) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2022-12-11T01:27:20.812Z] 01:27:20     INFO - GECKO(3628) | [Child 4362, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:429
[task 2022-12-11T01:27:20.927Z] 01:27:20     INFO - GECKO(3628) | [Child 4325: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f4d1f229c00) [pid = 4325] [serial = 3] [outer = 7f4d3aa919f0]
[task 2022-12-11T01:27:21.005Z] 01:27:21     INFO - GECKO(3628) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpwwgat3aa.mozrunner/runtests_leaks_tab_pid4379.log
[task 2022-12-11T01:27:21.009Z] 01:27:21     INFO - GECKO(3628) | [4379, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-12-11T01:27:21.118Z] 01:27:21     INFO - GECKO(3628) | [Child 4325, Main Thread] WARNING: Forced to copy ObserverTable due to nested notifications: file /builds/worker/checkouts/gecko/image/ProgressTracker.h:85
[task 2022-12-11T01:27:21.139Z] 01:27:21     INFO - GECKO(3628) | [Child 4379, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:714
[task 2022-12-11T01:27:21.347Z] 01:27:21     INFO - GECKO(3628) | [Parent 3628, 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-11T01:27:21.350Z] 01:27:21     INFO - GECKO(3628) | [Parent 3628, 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-11T01:27:21.351Z] 01:27:21     INFO - GECKO(3628) | [Parent 3628, Compositor] WARNING: IPC Connection Error: [Parent][PImageBridgeParent] RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1883
[task 2022-12-11T01:27:21.357Z] 01:27:21     INFO - TEST-PASS | netwerk/test/browser/browser_103_error.js | test_103_error_504: unexpected amount of hinted request made expected 1 ({"hinted":1,"normal":0}), got 1 ({"hinted":1,"normal":0}) - true == true -
Blocks: earlyhints
Status: NEW → RESOLVED
Closed: 1 year ago
Duplicate of bug: 1803884
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.