Closed Bug 1962326 Opened 1 year ago Closed 1 year ago

Intermittent SUMMARY: ThreadSanitizer: SEGV /builds/worker/checkouts/gecko/toolkit/components/places/Database.h:141:17 in DispatchToAsyncThread

Categories

(Toolkit :: Places, defect, P5)

Product:
Toolkit
Component:
Places
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
140 Branch
Tracking Flags:
Tracking Status
firefox-esr115 --- unaffected
firefox-esr128 --- unaffected
firefox138 --- unaffected
firefox139 --- fixed
firefox140 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: daisuke)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

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

[task 2025-04-24T04:52:45.926Z] 04:52:45     INFO - TEST-START | toolkit/mozapps/update/tests/browser/browser_telemetry_updatePing_staged_ready.js
[task 2025-04-24T04:52:47.338Z] 04:52:47     INFO - GECKO(16280) | MEMORY STAT | vsize 120598757MB | residentFast 1915MB
[task 2025-04-24T04:52:47.339Z] 04:52:47     INFO - TEST-OK | toolkit/mozapps/update/tests/browser/browser_telemetry_updatePing_staged_ready.js | took 1414ms
[task 2025-04-24T04:52:47.432Z] 04:52:47     INFO - checking window state
[task 2025-04-24T04:52:51.994Z] 04:52:51     INFO - GECKO(16280) | Completed ShutdownLeaks collections in process 16397
[task 2025-04-24T04:52:52.007Z] 04:52:52     INFO - GECKO(16280) | Completed ShutdownLeaks collections in process 16431
[task 2025-04-24T04:52:52.069Z] 04:52:52     INFO - GECKO(16280) | Completed ShutdownLeaks collections in process 16506
[task 2025-04-24T04:52:52.228Z] 04:52:52     INFO - GECKO(16280) | Completed ShutdownLeaks collections in process 16354
[task 2025-04-24T04:52:54.074Z] 04:52:54     INFO - GECKO(16280) | Completed ShutdownLeaks collections in process 16280
[task 2025-04-24T04:52:54.076Z] 04:52:54     INFO - TEST-START | Shutdown
[task 2025-04-24T04:52:54.077Z] 04:52:54     INFO - Browser Chrome Test Summary
[task 2025-04-24T04:52:54.077Z] 04:52:54     INFO - Passed:  1136
[task 2025-04-24T04:52:54.078Z] 04:52:54     INFO - Failed:  0
[task 2025-04-24T04:52:54.079Z] 04:52:54     INFO - Todo:    0
[task 2025-04-24T04:52:54.079Z] 04:52:54     INFO - Mode:    e10s
[task 2025-04-24T04:52:54.080Z] 04:52:54     INFO - *** End BrowserChrome Test Results ***
[task 2025-04-24T04:52:54.266Z] 04:52:54     INFO - GECKO(16280) | 1745470374265	Marionette	TRACE	Received observer notification quit-application
[task 2025-04-24T04:52:54.267Z] 04:52:54     INFO - GECKO(16280) | 1745470374266	Marionette	INFO	Stopped listening on port 2828
[task 2025-04-24T04:52:54.279Z] 04:52:54     INFO - GECKO(16280) | 1745470374278	Marionette	DEBUG	Marionette stopped listening
[task 2025-04-24T04:52:59.886Z] 04:52:59     INFO - GECKO(16280) | ThreadSanitizer:DEADLYSIGNAL
[task 2025-04-24T04:52:59.887Z] 04:52:59     INFO - GECKO(16280) | ==16280==ERROR: ThreadSanitizer: SEGV on unknown address 0x0000000000ae (pc 0x7f12ab093a5b bp 0x724400f0adc0 sp 0x7ffecbc7dcf0 T16280)
[task 2025-04-24T04:52:59.888Z] 04:52:59     INFO - GECKO(16280) | ==16280==The signal is caused by a READ memory access.
[task 2025-04-24T04:52:59.889Z] 04:52:59     INFO - GECKO(16280) | ==16280==Hint: address points to the zero page.
[task 2025-04-24T04:52:59.919Z] 04:52:59     INFO - GECKO(16280) |     #0 DispatchToAsyncThread /builds/worker/checkouts/gecko/toolkit/components/places/Database.h:141:17 (libxul.so+0x9d94a5b) (BuildId: 1001cc0b4b47a1950475a344c397d86fca25589b)
[task 2025-04-24T04:52:59.919Z] 04:52:59     INFO - GECKO(16280) |     #1 nsFaviconService::AsyncGetFaviconForPage(nsIURI*, unsigned short) /builds/worker/checkouts/gecko/toolkit/components/places/nsFaviconService.cpp:475:7 (libxul.so+0x9d94a5b)
[task 2025-04-24T04:52:59.922Z] 04:52:59     INFO - GECKO(16280) |     #2 nsFaviconService::GetFaviconForPage(nsIURI*, unsigned short, JSContext*, mozilla::dom::Promise**) /builds/worker/checkouts/gecko/toolkit/components/places/nsFaviconService.cpp:435:7 (libxul.so+0x9d9f926) (BuildId: 1001cc0b4b47a1950475a344c397d86fca25589b)
[task 2025-04-24T04:52:59.922Z] 04:52:59     INFO - GECKO(16280) |     #3 NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:101 (libxul.so+0x3983345) (BuildId: 1001cc0b4b47a1950475a344c397d86fca25589b)
[task 2025-04-24T04:52:59.922Z] 04:52:59     INFO - GECKO(16280) |     #4 Invoke /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1620:10 (libxul.so+0x45ef0ad) (BuildId: 1001cc0b4b47a1950475a344c397d86fca25589b)
[task 2025-04-24T04:52:59.922Z] 04:52:59     INFO - GECKO(16280) |     #5 Call /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1174:19 (libxul.so+0x45ef0ad)
[task 2025-04-24T04:52:59.922Z] 04:52:59     INFO - GECKO(16280) |     #6 XPCWrappedNative::CallMethod(XPCCallContext&, XPCWrappedNative::CallMode) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNative.cpp:1120:23 (libxul.so+0x45ef0ad)
[task 2025-04-24T04:52:59.922Z] 04:52:59     INFO - GECKO(16280) |     #7 XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:966:10 (libxul.so+0x45f1472) (BuildId: 1001cc0b4b47a1950475a344c397d86fca25589b)
[task 2025-04-24T04:52:59.922Z] 04:52:59     INFO - GECKO(16280) |     #8 <null> <null> (0x7f1216dde6b4)
[task 2025-04-24T04:52:59.922Z] 04:52:59     INFO - GECKO(16280) | ==16280==Register values:
[task 2025-04-24T04:52:59.923Z] 04:52:59     INFO - GECKO(16280) | rax = 0x0000000000000000  rbx = 0x00007208003c8480  rcx = 0x00000000000000a8  rdx = 0x000000005d0cbc40
[task 2025-04-24T04:52:59.924Z] 04:52:59     INFO - GECKO(16280) | rdi = 0x00007f12bf254c00  rsi = 0x0000100000000150  rbp = 0x0000724400f0adc0  rsp = 0x00007ffecbc7dcf0
[task 2025-04-24T04:52:59.924Z] 04:52:59     INFO - GECKO(16280) |  r8 = 0x0000100000000000   r9 = 0x00007f11fec35c88  r10 = 0x00007f11fec35c88  r11 = 0x0000000005a85b36
[task 2025-04-24T04:52:59.924Z] 04:52:59     INFO - GECKO(16280) | r12 = 0x000072100028fa80  r13 = 0x0000000000000000  r14 = 0x00007228000f5301  r15 = 0x00007228000f53c0
[task 2025-04-24T04:52:59.925Z] 04:52:59     INFO - GECKO(16280) | ThreadSanitizer can not provide additional info.
[task 2025-04-24T04:52:59.925Z] 04:52:59     INFO - GECKO(16280) | SUMMARY: ThreadSanitizer: SEGV /builds/worker/checkouts/gecko/toolkit/components/places/Database.h:141:17 in DispatchToAsyncThread
[task 2025-04-24T04:52:59.925Z] 04:52:59     INFO - GECKO(16280) | ==16280==ABORTING
[task 2025-04-24T04:53:01.003Z] 04:53:01     INFO - TEST-INFO | Main app process: killed by SIGIOT
[task 2025-04-24T04:53:01.003Z] 04:53:01     INFO - Buffered messages finished
[task 2025-04-24T04:53:01.004Z] 04:53:01     INFO - runtests.py | Application ran for: 0:05:16.477810
[task 2025-04-24T04:53:01.005Z] 04:53:01     INFO - zombiecheck | Reading PID log: /tmp/tmp77mxxyc9pidlog
[task 2025-04-24T04:53:01.005Z] 04:53:01     INFO - ==> process 16280 launched child process 16354
<...>
[task 2025-04-24T04:53:01.013Z] 04:53:01     INFO - zombiecheck | Checking for orphan process with PID: 17533
[task 2025-04-24T04:53:01.013Z] 04:53:01     INFO - Stopping web server
[task 2025-04-24T04:53:01.015Z] 04:53:01     INFO - Server shut down.
[task 2025-04-24T04:53:01.018Z] 04:53:01     INFO - Web server killed.
[task 2025-04-24T04:53:01.019Z] 04:53:01     INFO - Stopping web socket server
[task 2025-04-24T04:53:01.019Z] 04:53:01     INFO - Stopping ssltunnel
[task 2025-04-24T04:53:01.019Z] 04:53:01     INFO - Stopping gst for v4l2loopback
[task 2025-04-24T04:53:01.019Z] 04:53:01     INFO - runtests.py | Running tests: end.
[task 2025-04-24T04:53:01.049Z] 04:53:01     INFO - Buffered messages finished
[task 2025-04-24T04:53:01.049Z] 04:53:01     INFO -  TEST-INFO | checking window state
[task 2025-04-24T04:53:01.050Z] 04:53:01     INFO -  Browser Chrome Test Summary
[task 2025-04-24T04:53:01.050Z] 04:53:01     INFO -  	Passed: 6819
[task 2025-04-24T04:53:01.050Z] 04:53:01     INFO -  	Failed: 0
[task 2025-04-24T04:53:01.050Z] 04:53:01     INFO -  	Todo: 3
[task 2025-04-24T04:53:01.051Z] 04:53:01     INFO -  	Mode: e10s
[task 2025-04-24T04:53:01.051Z] 04:53:01     INFO -  *** End BrowserChrome Test Results ***
[task 2025-04-24T04:53:01.051Z] 04:53:01     INFO - Buffered messages finished
[task 2025-04-24T04:53:01.051Z] 04:53:01     INFO - SUITE-END | took 2345s
[task 2025-04-24T04:53:01.185Z] 04:53:01     INFO - Return code: 1
[task 2025-04-24T04:53:01.186Z] 04:53:01     INFO - TinderboxPrint: mochitest-mochitest-browser-chrome<br/>564/0/0
[task 2025-04-24T04:53:01.186Z] 04:53:01  WARNING - setting return code to 2
[task 2025-04-24T04:53:01.186Z] 04:53:01     INFO - The mochitest suite: mochitest-browser-chrome ran with return status: FAILURE
[task 2025-04-24T04:53:01.187Z] 04:53:01     INFO - Running post-action listener: _package_coverage_data
[task 2025-04-24T04:53:01.187Z] 04:53:01     INFO - Running post-action listener: _resource_record_post_action
[task 2025-04-24T04:53:01.187Z] 04:53:01     INFO - Running post-action listener: process_java_coverage_data
[task 2025-04-24T04:53:01.187Z] 04:53:01     INFO - [mozharness: 2025-04-24 04:53:01.186961Z] Finished run-tests step (success)
[task 2025-04-24T04:53:01.187Z] 04:53:01     INFO - [mozharness: 2025-04-24 04:53:01.186999Z] Running uninstall step.
[task 2025-04-24T04:53:01.187Z] 04:53:01     INFO - Running pre-action listener: _resource_record_pre_action
[task 2025-04-24T04:53:01.187Z] 04:53:01     INFO - Running main action method: uninstall
[task 2025-04-24T04:53:01.187Z] 04:53:01     INFO - Skipping uninstall for non-MSIX test
[task 2025-04-24T04:53:01.187Z] 04:53:01     INFO - Running post-action listener: _resource_record_post_action
[task 2025-04-24T04:53:01.187Z] 04:53:01     INFO - [mozharness: 2025-04-24 04:53:01.187155Z] Finished uninstall step (success)
[task 2025-04-24T04:53:01.187Z] 04:53:01     INFO - Running post-run listener: _resource_record_post_run
[task 2025-04-24T04:53:02.155Z] 04:53:02     INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2025-04-24T04:53:02.157Z] 04:53:02     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "mochitest.mochitest-browser-chrome.29.overall", "extraOptions": ["e10s", "taskcluster-projects/887720501152/machineTypes/c2-standard-4"], "subtests": [{"name": "cpu_percent", "value": 48.319007522292594}, {"name": "io_write_bytes", "value": 2890473472}, {"name": "io.read_bytes", "value": 65536}, {"name": "io_write_time", "value": 1616604}, {"name": "io_read_time", "value": 300}]}, {"name": "mochitest.mochitest-browser-chrome.29.start-pulseaudio", "subtests": [{"name": "time", "value": 0.018173676000060368}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-browser-chrome.29.install", "subtests": [{"name": "time", "value": 19.112879092000185}, {"name": "cpu_percent", "value": 25.374078947368425}]}, {"name": "mochitest.mochitest-browser-chrome.29.stage-files", "subtests": [{"name": "time", "value": 0.0002558099999987462}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-browser-chrome.29.run-tests", "subtests": [{"name": "time", "value": 2346.7787551910005}, {"name": "cpu_percent", "value": 48.50595096076047}]}, {"name": "mochitest.mochitest-browser-chrome.29.uninstall", "subtests": [{"name": "time", "value": 9.659599982114742e-05}, {"name": "cpu_percent", "value": 0}]}]}
[task 2025-04-24T04:53:02.157Z] 04:53:02     INFO - Total resource usage - Wall time: 2367s; CPU: Can't collect data; Read bytes: 65536; Write bytes: 2890473472; Read time: 300; Write time: 1616604
[task 2025-04-24T04:53:02.157Z] 04:53:02     INFO - TinderboxPrint: I/O read bytes / time<br/>65,536 / 300
[task 2025-04-24T04:53:02.157Z] 04:53:02     INFO - TinderboxPrint: I/O write bytes / time<br/>2,890,473,472 / 1,616,604
[task 2025-04-24T04:53:02.157Z] 04:53:02     INFO - TinderboxPrint: CPU idle<br/>4,790.1 (50.8%)
[task 2025-04-24T04:53:02.157Z] 04:53:02     INFO - TinderboxPrint: CPU system<br/>341.2 (3.6%)
[task 2025-04-24T04:53:02.157Z] 04:53:02     INFO - TinderboxPrint: CPU user<br/>4,216.0 (44.7%)
[task 2025-04-24T04:53:02.157Z] 04:53:02     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2025-04-24T04:53:02.160Z] 04:53:02     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-04-24T04:53:02.164Z] 04:53:02     INFO - install - Wall time: 19s; CPU: 25%; Read bytes: 0; Write bytes: 19042304; Read time: 0; Write time: 5236
[task 2025-04-24T04:53:02.167Z] 04:53:02     INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-04-24T04:53:02.306Z] 04:53:02     INFO - run-tests - Wall time: 2347s; CPU: 49%; Read bytes: 65536; Write bytes: 2871431168; Read time: 300; Write time: 1611368
[task 2025-04-24T04:53:02.317Z] 04:53:02     INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-04-24T04:53:06.562Z] 04:53:06  WARNING - returning nonzero exit status 2
[task 2025-04-24T04:53:06.779Z] cleanup
[task 2025-04-24T04:53:06.779Z] + cleanup
[task 2025-04-24T04:53:06.779Z] + local rv=2
[task 2025-04-24T04:53:06.779Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2025-04-24T04:53:06.779Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2025-04-24T04:53:06.780Z] + false
[task 2025-04-24T04:53:06.780Z] + '[' ']'
[task 2025-04-24T04:53:06.780Z] + true
[task 2025-04-24T04:53:06.780Z] + cleanup_xvfb
[task 2025-04-24T04:53:06.780Z] ++ pidof Xvfb
[task 2025-04-24T04:53:06.790Z] + local xvfb_pid=48
[task 2025-04-24T04:53:06.790Z] + local vnc=false
[task 2025-04-24T04:53:06.790Z] + local interactive=false
[task 2025-04-24T04:53:06.791Z] + '[' -n 48 ']'
[task 2025-04-24T04:53:06.791Z] + [[ false == false ]]
[task 2025-04-24T04:53:06.791Z] + [[ false == false ]]
[task 2025-04-24T04:53:06.792Z] + kill 48
[task 2025-04-24T04:53:06.792Z] + exit 2
[taskcluster 2025-04-24 04:53:07.183Z] === Task Finished ===
[taskcluster 2025-04-24 04:53:09.348Z] Unsuccessful task run with exit code: 2 completed in 2442.804 seconds
Keywords: regression
Regressed by: 1915762
See Also: → 1962576

:daisuke, since you are the author of the regressor, bug 1915762, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(daisuke)

Set release status flags based on info from the regressing bug 1915762

status-firefox138: --- → unaffected
status-firefox139: --- → affected
status-firefox140: --- → affected
status-firefox-esr128: --- → unaffected

There was only one report before the recent fixes in Bug 1962575. Likely this was also fixed.

Status: NEW → RESOLVED
Closed: 1 year ago
Depends on: 1962575
Flags: needinfo?(daisuke)
Resolution: --- → FIXED
Assignee: nobody → daisuke
status-firefox-esr115: --- → unaffected
Target Milestone: --- → 140 Branch
You need to log in before you can comment on or make changes to this bug.