Open
Bug 1930675
Opened 23 days ago
Updated 17 days ago
Intermittent None [@ cubeb_stream_start] | gtest
Categories
(Core :: Audio/Video: cubeb, defect, P5)
Core
Audio/Video: cubeb
Tracking
()
NEW
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: crash, intermittent-failure)
Crash Data
Filed by: amarc [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=482278122&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/GFC6RoYRSYeauQhllhE78A/runs/0/artifacts/public/logs/live_backing.log
[task 2024-11-12T14:47:12.770Z] 14:47:12 INFO - TEST-PASS | cubeb.test_output_callback | test completed (time: 895ms)
[task 2024-11-12T14:47:12.770Z] 14:47:12 INFO - TEST-START | cubeb.test_duplex_callback
[task 2024-11-12T14:47:12.958Z] 14:47:12 WARNING - TEST-UNEXPECTED-FAIL | cubeb.test_duplex_callback | Expected equality of these values:
[task 2024-11-12T14:47:12.958Z] 14:47:12 INFO - r
[task 2024-11-12T14:47:12.958Z] 14:47:12 INFO - Which is: -1
[task 2024-11-12T14:47:12.959Z] 14:47:12 INFO - CUBEB_OK
[task 2024-11-12T14:47:12.959Z] 14:47:12 INFO - Which is: 0
[task 2024-11-12T14:47:12.959Z] 14:47:12 INFO - Error initializing cubeb stream
[task 2024-11-12T14:47:12.959Z] 14:47:12 INFO - @ /builds/worker/checkouts/gecko/media/libcubeb/test/test_callback_ret.cpp:208
[task 2024-11-12T14:47:13.216Z] 14:47:13 INFO - gtest INFO | gtest | process wait complete, returncode=1
[task 2024-11-12T14:47:13.216Z] 14:47:13 INFO - mozcrash checking /opt/worker/tasks/task_173142254005317/build/tests/gtest for minidumps...
[task 2024-11-12T14:47:13.218Z] 14:47:13 INFO - mozcrash INFO | Copy/paste: /opt/worker/tasks/task_173142254005317/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --cyborg=/var/folders/69/xg_3kph93lq740mxrc93w9km000014/T/tmpu742gdhx/4099C53A-237D-444B-91F6-D344106FFCE9.trace /opt/worker/tasks/task_173142254005317/build/tests/gtest/4099C53A-237D-444B-91F6-D344106FFCE9.dmp /opt/worker/tasks/task_173142254005317/build/symbols
[task 2024-11-12T14:47:23.112Z] 14:47:23 INFO - mozcrash INFO | Saved minidump as /opt/worker/tasks/task_173142254005317/build/blobber_upload_dir/4099C53A-237D-444B-91F6-D344106FFCE9.dmp
[task 2024-11-12T14:47:23.113Z] 14:47:23 INFO - mozcrash INFO | Saved app info as /opt/worker/tasks/task_173142254005317/build/blobber_upload_dir/4099C53A-237D-444B-91F6-D344106FFCE9.extra
[task 2024-11-12T14:47:23.113Z] 14:47:23 WARNING - PROCESS-CRASH | None [@ cubeb_stream_start] | gtest
[task 2024-11-12T14:47:23.113Z] 14:47:23 INFO - Crash dump filename: /opt/worker/tasks/task_173142254005317/build/tests/gtest/4099C53A-237D-444B-91F6-D344106FFCE9.dmp
[task 2024-11-12T14:47:23.113Z] 14:47:23 INFO - Process type: main
[task 2024-11-12T14:47:23.114Z] 14:47:23 INFO - Process pid: 868
[task 2024-11-12T14:47:23.114Z] 14:47:23 INFO - Operating system: Mac OS X
[task 2024-11-12T14:47:23.114Z] 14:47:23 INFO - 10.15.7 19H2026
[task 2024-11-12T14:47:23.114Z] 14:47:23 INFO - CPU: amd64
[task 2024-11-12T14:47:23.114Z] 14:47:23 INFO - family 6 model 158 stepping 10
[task 2024-11-12T14:47:23.114Z] 14:47:23 INFO - 12 CPUs
[task 2024-11-12T14:47:23.115Z] 14:47:23 INFO - Crash reason: EXC_BAD_ACCESS / EXC_I386_GPFLT
[task 2024-11-12T14:47:23.115Z] 14:47:23 INFO - Crash address: 0x0000000000000000 **
[task 2024-11-12T14:47:23.115Z] 14:47:23 INFO - ** Non-canonical address detected: 0xaaaaaaaaaaaaaaaa
[task 2024-11-12T14:47:23.115Z] 14:47:23 INFO - Crashing instruction: `mov rax, qword [rdi]`
[task 2024-11-12T14:47:23.115Z] 14:47:23 INFO - Memory accessed by instruction:
[task 2024-11-12T14:47:23.115Z] 14:47:23 INFO - 0. Address: 0xaaaaaaaaaaaaaaaa
[task 2024-11-12T14:47:23.115Z] 14:47:23 INFO - Size: 8
[task 2024-11-12T14:47:23.116Z] 14:47:23 INFO - Mac Crash Info:
[task 2024-11-12T14:47:23.116Z] 14:47:23 INFO - Mac Boot Args: chunklist-security-epoch=0 -chunklist-no-rev2-dev
[task 2024-11-12T14:47:23.116Z] 14:47:23 INFO - Process uptime: 31 seconds
[task 2024-11-12T14:47:23.116Z] 14:47:23 INFO - Thread 0 MainThread (crashed)
[task 2024-11-12T14:47:23.116Z] 14:47:23 INFO - 0 XUL!cubeb_stream_start [cubeb.c:1d19012cddeeab98706325fa4643fcecc2551aea : 424 + 0x0]
[task 2024-11-12T14:47:23.117Z] 14:47:23 INFO - rax = 0x5eced250da1c00a8 rdx = 0x0000000000000307
[task 2024-11-12T14:47:23.117Z] 14:47:23 INFO - rcx = 0x0000000000000000 rbx = 0x000000012be946a0
[task 2024-11-12T14:47:23.117Z] 14:47:23 INFO - rsi = 0x0000000000000307 rdi = 0xaaaaaaaaaaaaaaaa
[task 2024-11-12T14:47:23.117Z] 14:47:23 INFO - rbp = 0x00007ffeee130670 rsp = 0x00007ffeee130670
[task 2024-11-12T14:47:23.117Z] 14:47:23 INFO - r8 = 0x0000000000000080 r9 = 0x0000000000000000
[task 2024-11-12T14:47:23.117Z] 14:47:23 INFO - r10 = 0x0000000105401448 r11 = 0x0000000105600171
[task 2024-11-12T14:47:23.118Z] 14:47:23 INFO - r12 = 0xaaaaaaaaaaaaaaaa r13 = 0x00007ffeee1306d4
[task 2024-11-12T14:47:23.118Z] 14:47:23 INFO - r14 = 0x0000000105485d20 r15 = 0x000000010654d840
[task 2024-11-12T14:47:23.118Z] 14:47:23 INFO - rip = 0x000000010657c479
[task 2024-11-12T14:47:23.118Z] 14:47:23 INFO - Found by: given as instruction pointer in context
[task 2024-11-12T14:47:23.119Z] 14:47:23 INFO - 1 XUL!run_test_callback(test_direction, long (*)(cubeb_stream*, void*, void const*, void*, long), std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> > const&) [test_callback_ret.cpp:1d19012cddeeab98706325fa4643fcecc2551aea : 213 + 0x7]
[task 2024-11-12T14:47:23.119Z] 14:47:23 INFO - rbx = 0x000000012be946a0 rbp = 0x00007ffeee130760
[task 2024-11-12T14:47:23.119Z] 14:47:23 INFO - rsp = 0x00007ffeee130680 r12 = 0xaaaaaaaaaaaaaaaa
[task 2024-11-12T14:47:23.119Z] 14:47:23 INFO - r13 = 0x00007ffeee1306d4 r14 = 0x0000000105485d20
[task 2024-11-12T14:47:23.119Z] 14:47:23 INFO - r15 = 0x000000010654d840 rip = 0x000000010654e465
[task 2024-11-12T14:47:23.120Z] 14:47:23 INFO - Found by: call frame info
[task 2024-11-12T14:47:23.120Z] 14:47:23 INFO - 2 XUL!cubeb_test_duplex_callback_Test::TestBody() [test_callback_ret.cpp:1d19012cddeeab98706325fa4643fcecc2551aea : 249 + 0x14]
[task 2024-11-12T14:47:23.120Z] 14:47:23 INFO - rbx = 0x00000001054181d0 rbp = 0x00007ffeee130790
[task 2024-11-12T14:47:23.120Z] 14:47:23 INFO - rsp = 0x00007ffeee130770 r12 = 0x00000001054181d0
[task 2024-11-12T14:47:23.120Z] 14:47:23 INFO - r13 = 0x0000000000000000 r14 = 0x0000000101db7bb0
[task 2024-11-12T14:47:23.121Z] 14:47:23 INFO - r15 = 0x0000000101d73700 rip = 0x000000010654f03e
[task 2024-11-12T14:47:23.121Z] 14:47:23 INFO - Found by: call frame info
<...>
[task 2024-11-12T14:47:23.540Z] 14:47:23 INFO - 0x7fff68af4000 - 0x7fff68af8fff libsystem_sandbox.dylib ???
[task 2024-11-12T14:47:23.540Z] 14:47:23 INFO - 0x7fff68af9000 - 0x7fff68afbfff libsystem_secinit.dylib ???
[task 2024-11-12T14:47:23.540Z] 14:47:23 INFO - 0x7fff68afc000 - 0x7fff68b03fff libsystem_symptoms.dylib ???
[task 2024-11-12T14:47:23.540Z] 14:47:23 INFO - 0x7fff68b04000 - 0x7fff68b1afff libsystem_trace.dylib ???
[task 2024-11-12T14:47:23.540Z] 14:47:23 INFO - 0x7fff68b1c000 - 0x7fff68b21fff libunwind.dylib ???
[task 2024-11-12T14:47:23.540Z] 14:47:23 INFO - 0x7fff68b22000 - 0x7fff68b57fff libxpc.dylib ???
[task 2024-11-12T14:47:23.540Z] 14:47:23 INFO - Unloaded modules:
[task 2024-11-12T14:47:23.541Z] 14:47:23 INFO - gtest INFO | rungtests.py exits with code 1
[task 2024-11-12T14:47:23.541Z] 14:47:23 INFO - Return code: 1
[task 2024-11-12T14:47:23.541Z] 14:47:23 ERROR - No tests run or test summary not found
[task 2024-11-12T14:47:23.541Z] 14:47:23 INFO - TinderboxPrint: gtest-gtest<br/><em class="testfail">T-FAIL</em>
[task 2024-11-12T14:47:23.541Z] 14:47:23 WARNING - setting return code to 2
[task 2024-11-12T14:47:23.541Z] 14:47:23 INFO - The gtest suite: gtest ran with return status: FAILURE
[task 2024-11-12T14:47:23.541Z] 14:47:23 INFO - Running post-action listener: _package_coverage_data
[task 2024-11-12T14:47:23.541Z] 14:47:23 INFO - Running post-action listener: _resource_record_post_action
[task 2024-11-12T14:47:23.541Z] 14:47:23 INFO - Running post-action listener: process_java_coverage_data
[task 2024-11-12T14:47:23.542Z] 14:47:23 INFO - [mozharness: 2024-11-12 14:47:23.541984Z] Finished run-tests step (success)
[task 2024-11-12T14:47:23.542Z] 14:47:23 INFO - [mozharness: 2024-11-12 14:47:23.542026Z] Running uninstall step.
[task 2024-11-12T14:47:23.542Z] 14:47:23 INFO - Running pre-action listener: _resource_record_pre_action
[task 2024-11-12T14:47:23.542Z] 14:47:23 INFO - Running main action method: uninstall
[task 2024-11-12T14:47:23.542Z] 14:47:23 INFO - Skipping uninstall for non-MSIX test
[task 2024-11-12T14:47:23.542Z] 14:47:23 INFO - Running post-action listener: _resource_record_post_action
[task 2024-11-12T14:47:23.542Z] 14:47:23 INFO - [mozharness: 2024-11-12 14:47:23.542253Z] Finished uninstall step (success)
[task 2024-11-12T14:47:23.542Z] 14:47:23 INFO - Running post-run listener: _resource_record_post_run
[task 2024-11-12T14:47:23.630Z] 14:47:23 INFO - instance_metadata.json not found; unable to determine instance type
[task 2024-11-12T14:47:23.631Z] 14:47:23 INFO - Validating Perfherder data against /opt/worker/tasks/task_173142254005317/mozharness/external_tools/performance-artifact-schema.json
[task 2024-11-12T14:47:23.633Z] 14:47:23 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "gtest.gtest.overall", "extraOptions": ["buildbot-unknown"], "subtests": [{"name": "cpu_percent", "value": 9.64615982587065}, {"name": "io_write_bytes", "value": 695164928}, {"name": "io.read_bytes", "value": 184889344}, {"name": "io_write_time", "value": 1297}, {"name": "io_read_time", "value": 1591}]}, {"name": "gtest.gtest.start-pulseaudio", "subtests": [{"name": "time", "value": 0.00023588799996332455}, {"name": "cpu_percent", "value": 0}]}, {"name": "gtest.gtest.install", "subtests": [{"name": "time", "value": 29.147642492000045}, {"name": "cpu_percent", "value": 14.154459635416666}]}, {"name": "gtest.gtest.stage-files", "subtests": [{"name": "time", "value": 0.0903179460000274}, {"name": "cpu_percent", "value": 0}]}, {"name": "gtest.gtest.run-tests", "subtests": [{"name": "time", "value": 42.176877846000025}, {"name": "cpu_percent", "value": 5.513709677419355}]}, {"name": "gtest.gtest.uninstall", "subtests": [{"name": "time", "value": 0.00012131300002238277}, {"name": "cpu_percent", "value": 0}]}]}
[task 2024-11-12T14:47:23.633Z] 14:47:23 INFO - Total resource usage - Wall time: 71s; CPU: Can't collect data; Read bytes: 184889344; Write bytes: 695164928; Read time: 1591; Write time: 1297
[task 2024-11-12T14:47:23.634Z] 14:47:23 INFO - TinderboxPrint: I/O read bytes / time<br/>184,889,344 / 1,591
[task 2024-11-12T14:47:23.634Z] 14:47:23 INFO - TinderboxPrint: I/O write bytes / time<br/>695,164,928 / 1,297
[task 2024-11-12T14:47:23.634Z] 14:47:23 INFO - TinderboxPrint: CPU idle<br/>778.1 (91.0%)
[task 2024-11-12T14:47:23.634Z] 14:47:23 INFO - TinderboxPrint: CPU system<br/>14.4 (1.7%)
[task 2024-11-12T14:47:23.634Z] 14:47:23 INFO - TinderboxPrint: CPU user<br/>62.1 (7.3%)
[task 2024-11-12T14:47:23.634Z] 14:47:23 INFO - TinderboxPrint: Swap in / out<br/>538,988,544 / 0
[task 2024-11-12T14:47:23.634Z] 14:47:23 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-11-12T14:47:23.636Z] 14:47:23 INFO - install - Wall time: 29s; CPU: 14%; Read bytes: 34938880; Write bytes: 388493312; Read time: 337; Write time: 706
[task 2024-11-12T14:47:23.636Z] 14:47:23 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-11-12T14:47:23.638Z] 14:47:23 INFO - run-tests - Wall time: 42s; CPU: 6%; Read bytes: 149659648; Write bytes: 60383232; Read time: 1248; Write time: 152
[task 2024-11-12T14:47:23.638Z] 14:47:23 INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-11-12T14:47:23.762Z] 14:47:23 WARNING - returning nonzero exit status 2
[taskcluster 2024-11-12T14:47:23.807Z] Exit Code: 2
[taskcluster 2024-11-12T14:47:23.807Z] User Time: 1m16.029381s
[taskcluster 2024-11-12T14:47:23.807Z] Kernel Time: 13.99795s
[taskcluster 2024-11-12T14:47:23.807Z] Wall Time: 4m8.234084s
[taskcluster 2024-11-12T14:47:23.807Z] Result: FAILED
[taskcluster 2024-11-12T14:47:23.807Z] === Task Finished ===
[taskcluster 2024-11-12T14:47:23.808Z] Task Duration: 4m8.237997s
[taskcluster 2024-11-12T14:47:23.995Z] Uploading artifact public/logs/localconfig.json from file /opt/worker/tasks/task_173142254005317/logs/localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2025-11-12T14:19:44.408Z
[taskcluster 2024-11-12T14:47:24.286Z] Uploading artifact public/test_info/4099C53A-237D-444B-91F6-D344106FFCE9.dmp from file /opt/worker/tasks/task_173142254005317/build/blobber_upload_dir/4099C53A-237D-444B-91F6-D344106FFCE9.dmp with content encoding "gzip", mime type "application/vnd.tcpdump.pcap" and expiry 2025-11-12T14:19:44.408Z
[taskcluster 2024-11-12T14:47:24.580Z] Uploading artifact public/test_info/4099C53A-237D-444B-91F6-D344106FFCE9.extra from file /opt/worker/tasks/task_173142254005317/build/blobber_upload_dir/4099C53A-237D-444B-91F6-D344106FFCE9.extra with content encoding "gzip", mime type "application/octet-stream" and expiry 2025-11-12T14:19:44.408Z
[taskcluster 2024-11-12T14:47:24.844Z] Uploading artifact public/test_info/profile_resource-usage.json from file /opt/worker/tasks/task_173142254005317/build/blobber_upload_dir/profile_resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-11-12T14:19:44.408Z
[taskcluster 2024-11-12T14:47:25.137Z] Uploading artifact public/test_info/resource-usage.json from file /opt/worker/tasks/task_173142254005317/build/blobber_upload_dir/resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-11-12T14:19:44.408Z
[taskcluster 2024-11-12T14:47:25.442Z] Uploading artifact public/test_info/system-info.log from file /opt/worker/tasks/task_173142254005317/build/blobber_upload_dir/system-info.log with content encoding "gzip", mime type "text/plain" and expiry 2025-11-12T14:19:44.408Z
[taskcluster 2024-11-12T14:47:25.695Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2025-11-12T14:19:44.408Z
[taskcluster:error] exit status 2
|
||
Updated•20 days ago
|
Severity: -- → S4
Component: Audio/Video → Audio/Video: cubeb
Priority: -- → P5
| Comment hidden (Intermittent Failures Robot) |
You need to log in
before you can comment on or make changes to this bug.
Description
•