vircadia / vircadia-native-core

Vircadia open source agent-based metaverse ecosystem.
https://vircadia.com/
Other
534 stars 176 forks source link

Application Crashed Listening to Audio. #1234

Open Penguin-Guru opened 3 years ago

Penguin-Guru commented 3 years ago

I was listening to someone talk, not moving or looking around. My mic was muted and probably wouldn't have worked anyway. The application suddenly crashed, but restarting without clearing settings worked fine. O.S. is Linux. Vircadia version is "Vircadia-2021.1.1-Eos.AppImage". Console output as follows:

NEW MAX ELAPSED AVERAGE samples: 15466 [05/29 14:01:27] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 81995 elapsedMovingAverage: 5633 maxElapsed: 3519736 PREVIOUS maxElapsedAverage: 5632 NEW maxElapsedAverage: 5633 NEW MAX ELAPSED AVERAGE samples: 15476 [05/29 14:01:27] [DEBUG] [hifi.audioclient] getAvailableDevices Default device not found in list: "" Setting Default to: "default" [Previous message was repeated 1 times] [05/29 14:01:28] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 83224 elapsedMovingAverage: 5637 maxElapsed: 3519736 PREVIOUS maxElapsedAverage: 5633 NEW maxElapsedAverage: 5637 NEW MAX ELAPSED AVERAGE samples: 15486 [05/29 14:01:28] [DEBUG] [hifi.scriptengine] stopTimer -- not in _timerFunctionMap QObject(0x0) [05/29 14:01:28] [WARNING] [default] "QColor::setAlphaF": invalid value -0.1 [05/29 14:01:29] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 82612 elapsedMovingAverage: 5639 maxElapsed: 3519736 PREVIOUS maxElapsedAverage: 5637 NEW maxElapsedAverage: 5639 NEW MAX ELAPSED AVERAGE samples: 15496 [05/29 14:01:29] [DEBUG] [hifi.audioclient] getAvailableDevices Default device not found in list: "" Setting Default to: "default" [Previous message was repeated 1 times] [05/29 14:01:30] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 79785 elapsedMovingAverage: 5640 maxElapsed: 3519736 PREVIOUS maxElapsedAverage: 5639 NEW maxElapsedAverage: 5640 NEW MAX ELAPSED AVERAGE samples: 15506 [05/29 14:01:31] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 85406 elapsedMovingAverage: 5642 maxElapsed: 3519736 PREVIOUS maxElapsedAverage: 5640 NEW maxElapsedAverage: 5642 NEW MAX ELAPSED AVERAGE samples: 15516 [05/29 14:01:32] [DEBUG] [hifi.audioclient] getAvailableDevices Default device not found in list: "" Setting Default to: "default" [Previous message was repeated 1 times] [05/29 14:01:32] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 98438 elapsedMovingAverage: 5645 maxElapsed: 3519736 PREVIOUS maxElapsedAverage: 5642 NEW maxElapsedAverage: 5645 NEW MAX ELAPSED AVERAGE ** samples: 15526 ALSA lib pcm.c:8306:(snd_pcm_recover) underrun occurred Vircadia-2021.1.1-Eos.AppImage: pcm.c:1136: snd_pcm_prepare: Assertion `pcm' failed.

JulianGro commented 3 years ago

I have seen this error before. Might take a while to try and dig it up.

JulianGro commented 3 years ago

Yeah I have definitely seen the issue before, but cannot find any info on it.

What distribution are you running?

Penguin-Guru commented 3 years ago

Gentoo. Kernel is 5.10.14. My sound configuration is pretty basic (see below). I think all of the kernel parameters I have enabled are set to their default values. I do have some alsa interfaces configured for my user account but the output I selected in Vircadia was one of the automatically generated ones (for my desktop's front panel headphone jack).

CONFIG_SND=Y CONFIG_SND_HRTIMER=Y CONFIG_SND_PROC_FS=Y CONFIG_SND_VERBOSE_PROCFS=Y CONFIG_SND_SEQUENCER=Y CONFIG_SND_SEQ_DUMMY=Y CONFIG_SND_SEQ_HRTIMER_DEFAULT=Y

CONFIG_SND_HDA_INTEL=Y CONFIG_SND_HDA_CODEC_REALTEK=Y CONFIG_SND_HDA_CODEC_ANALOG=Y CONFIG_SND_HDA_GENERIC=Y CONFIG_SND_HDA_POWER_SAVE_DEFAULT=0

CONFIG_SND_INTEL8X0=M CONFIG_SND_INTEL8X0M=M

CONFIG_SND_HDA_PREALLOC_SIZE=64

JulianGro commented 3 years ago

So I asked our (probably only) Gentoo user, and they never had that problem, which means that I am out of clues to where I have seen that issue before. I would think that this is most likely a problem outside of Vircadia especially since we use Qt Audio (or whatever it's called). One thing you could try would be the newest release candidate, since that includes the newest version of Qt. https://appimage.moto9000.moe/release-candidate/Vircadia-x86_64_v2021.1.2-rc1.AppImage It should also be released pretty soon.

Penguin-Guru commented 3 years ago

Ok. I've only run into the problem once so far but I wanted to post it here since this is the application that crashed. I haven't had any audio issues with other programs but I'll try to keep up with new versions and let you know if I run into it again. Feel free to close this issue if you want. I'll be updating system libraries too, although I'm not sure if that matters with the AppImage.

Penguin-Guru commented 2 years ago

I clicked the bounty board and then answered "yes" that I was sure I wanted to open it. The program immediately crashed. This occurred 3/3 times for me testing with both Vircadia-2021.1.1-Eos.AppImage and Vircadia-x86_64_v2021.1.3-Eos.AppImage. Clicking "No" to the prompt did not cause the crash 1/1 times.

JulianGro commented 2 years ago

@Penguin-Guru can you turn crash reporting on (then restart Vircadia) and then try the crash again? That might give us a more useful crash report.

Penguin-Guru commented 2 years ago

I just tried enabling that setting and using the board again. It worked 3/3 times. I quit and restarted the application and tried two more times-- no crash. I disabled the setting and tried the board again-- no crash. I have not restarted this computer or updated any software on it since my previous post, just put it to sleep for a while at one point. Haven't changed any audio settings or attached devices either. I tested in windowed mode and full-screen with crash report setting on and off.

Penguin-Guru commented 2 years ago

I tried to play a Youtube video on the web entity in one of the rooms in The Hub. This happened:

NEW MAX ELAPSED AVERAGE samples: 7377 [11/14 18:14:33] [DEBUG] [hifi.ui] setKeyboardRaised: QQuickRootItem(0x55c1ae073550) , raised: false , numeric: false , password: false [11/14 18:14:33] [DEBUG] [hifi.ui] setKeyboardRaised: Desktop_QMLTYPE_230(0x55c1bec10270, name = "desktop") , raised: false , numeric: false , password: false [11/14 18:14:33] [DEBUG] [hifi.ui] setKeyboardRaised: QQuickRootItem(0x55c1ae073550) , raised: false , numeric: false , password: false [11/14 18:14:33] [DEBUG] [hifi.ui] setKeyboardRaised: Desktop_QMLTYPE_230(0x55c1bec10270, name = "desktop") , raised: false , numeric: false , password: false [11/14 18:14:34] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 56735 elapsedMovingAverage: 4007 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4004 NEW maxElapsedAverage: 4007 NEW MAX ELAPSED AVERAGE samples: 7397 [11/14 18:14:35] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 29309 elapsedMovingAverage: 4012 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4007 NEW maxElapsedAverage: 4012 NEW MAX ELAPSED AVERAGE samples: 7407 [11/14 18:14:36] [DEBUG] [hifi.entities] kinematic timestep = 3.8339 truncated to 1 [11/14 18:14:36] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 57694 elapsedMovingAverage: 4015 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4012 NEW maxElapsedAverage: 4015 NEW MAX ELAPSED AVERAGE samples: 7417 [11/14 18:14:37] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 44018 elapsedMovingAverage: 4021 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4015 NEW maxElapsedAverage: 4021 NEW MAX ELAPSED AVERAGE samples: 7425 [11/14 18:14:38] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 57733 elapsedMovingAverage: 4026 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4021 NEW maxElapsedAverage: 4026 NEW MAX ELAPSED AVERAGE samples: 7435 [11/14 18:14:39] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 53123 elapsedMovingAverage: 4031 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4026 NEW maxElapsedAverage: 4031 NEW MAX ELAPSED AVERAGE samples: 7445 [11/14 18:14:40] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 59110 elapsedMovingAverage: 4037 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4031 NEW maxElapsedAverage: 4037 NEW MAX ELAPSED AVERAGE samples: 7453 [11/14 18:14:40] [WARNING] [hifi.modelformat] Indices out of range for model QUrl("https://cloud.transition-space.org/s/pHf8XMiWNB55nQw/download/Untitled-Scan-19-37-04.glb") [11/14 18:14:40] [WARNING] [hifi.modelformat] No valid indices for model QUrl("https://cloud.transition-space.org/s/pHf8XMiWNB55nQw/download/Untitled-Scan-19-37-04.glb") [11/14 18:14:40] [DEBUG] [hifi.model_baker] BuildGraphicsMeshTask failed -- no indices [11/14 18:14:41] [WARNING] [hifi.image] Failed to load: (Invalid Format) [11/14 18:14:41] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 59381 elapsedMovingAverage: 4042 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4037 NEW maxElapsedAverage: 4042 NEW MAX ELAPSED AVERAGE samples: 7463 [11/14 18:14:42] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 59701 elapsedMovingAverage: 4046 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4042 NEW maxElapsedAverage: 4046 NEW MAX ELAPSED AVERAGE samples: 7473 [11/14 18:14:43] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 47226 elapsedMovingAverage: 4051 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4046 NEW maxElapsedAverage: 4051 NEW MAX ELAPSED AVERAGE samples: 7483 [11/14 18:14:44] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 46213 elapsedMovingAverage: 4056 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4051 NEW maxElapsedAverage: 4056 NEW MAX ELAPSED AVERAGE samples: 7493 [11/14 18:14:45] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 49078 elapsedMovingAverage: 4061 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4056 NEW maxElapsedAverage: 4061 NEW MAX ELAPSED AVERAGE samples: 7503 [11/14 18:14:46] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 45365 elapsedMovingAverage: 4066 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4061 NEW maxElapsedAverage: 4066 NEW MAX ELAPSED AVERAGE samples: 7513 [11/14 18:14:47] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 42424 elapsedMovingAverage: 4068 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4066 NEW maxElapsedAverage: 4068 NEW MAX ELAPSED AVERAGE samples: 7523 [11/14 18:14:48] [DEBUG] [hifi.ui] setKeyboardRaised: QQuickRootItem(0x55c1ae073550) , raised: false , numeric: false , password: false [11/14 18:14:48] [DEBUG] [hifi.ui] setKeyboardRaised: Desktop_QMLTYPE_230(0x55c1bec10270, name = "desktop") , raised: false , numeric: false , password: false [11/14 18:14:48] [DEBUG] [hifi.ui] setKeyboardRaised: QQuickRootItem(0x55c1ae073550) , raised: false , numeric: false , password: false [11/14 18:14:48] [DEBUG] [hifi.ui] setKeyboardRaised: Desktop_QMLTYPE_230(0x55c1bec10270, name = "desktop") , raised: false , numeric: false , password: false [11/14 18:14:48] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 53239 elapsedMovingAverage: 4072 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4068 NEW maxElapsedAverage: 4072 NEW MAX ELAPSED AVERAGE samples: 7533 [11/14 18:14:49] [DEBUG] [hifi.interface.deadlock] DEADLOCK WATCHDOG WARNING: lastHeartbeatAge: 47559 elapsedMovingAverage: 4077 maxElapsed: 1801551 PREVIOUS maxElapsedAverage: 4072 NEW maxElapsedAverage: 4077 NEW MAX ELAPSED AVERAGE ** samples: 7543 ALSA lib /var/tmp/portage/media-libs/alsa-lib-1.2.4/work/alsa-lib-1.2.4/src/pcm/pcm.c:8545:(snd_pcm_recover) underrun occurred [3710:3727:1114/181450.568550:ERROR:broker_posix.cc(40)] Recvmsg error: Connection reset by peer (104) Segmentation fault

Penguin-Guru commented 2 years ago

The same thing happened again. This time I had crash reporting turned on like @JulianGro wanted previously.

JulianGro commented 2 years ago

Nothing arrived on the crash reporting server :woozy_face:

Penguin-Guru commented 2 years ago

Do I need to restart the application after enabling that setting?

JulianGro commented 2 years ago

I think so actually, yes.

Penguin-Guru commented 2 years ago

Ok. That seems like a bug but I'll give it a try next time I run into this.

stale[bot] commented 2 years ago

Hello! Is this still an issue?