-- Logs begin at Thu 2024-08-22 12:01:38 CEST, end at Thu 2024-08-22 14:52:29 CEST. -- Aug 22 14:51:13 volumio volumio[1071]: info: CoreCommandRouter::servicePushState Aug 22 14:51:13 volumio volumio[1071]: info: CoreStateMachine::pushState Aug 22 14:51:13 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 14:51:13 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState Aug 22 14:51:13 volumio vtcs[11421]: ALSA lib pcm.c:8427:(snd_pcm_recover) cannot recovery from underrun, prepare failed: Device or resource busy Aug 22 14:51:13 volumio vtcs[11421]: vtcs: pcm_meter.c:1216: snd_pcm_scope_s16_get_channel_buffer: Assertion `s16->buf_areas' failed. Aug 22 14:51:13 volumio vtcs[11421]: [2024-08-22 14:51:13.910] [tisoc] [error] [pcm_sink_alsa.cpp:363] snd_pcm_writei()/snd_pcm_recover() ERROR: Device or resource busy Aug 22 14:51:13 volumio vtcs[11421]: ALSA lib pcm.c:8427:(snd_pcm_recover) cannot recovery from underrun, prepare failed: Device or resource busy Aug 22 14:51:13 volumio vtcs[11421]: [2024-08-22 14:51:13.910] [tisoc] [error] [pcm_sink_alsa.cpp:363] snd_pcm_writei()/snd_pcm_recover() ERROR: Device or resource busy Aug 22 14:51:13 volumio vtcs[11421]: ALSA lib pcm.c:8427:(snd_pcm_recover) cannot recovery from underrun, prepare failed: Device or resource busy Aug 22 14:51:13 volumio vtcs[11421]: Aborted Aug 22 14:51:13 volumio systemd[1]: vtcs.service: Main process exited, code=exited, status=134/n/a Aug 22 14:51:13 volumio systemd[1]: vtcs.service: Failed with result 'exit-code'. Aug 22 14:51:13 volumio volumio[1071]: info: PeppySpectrum ---peppyspectrum status play Aug 22 14:51:13 volumio sudo[11549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 22 14:51:13 volumio sudo[11549]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 14:51:13 volumio systemd[1]: Started peppyspectrum Daemon. Aug 22 14:51:13 volumio sudo[11549]: pam_unix(sudo:session): session closed for user root Aug 22 14:51:13 volumio volumio[1071]: info: peppyspectrum Daemon Started Aug 22 14:51:14 volumio systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. Aug 22 14:51:14 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. Aug 22 14:51:14 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Aug 22 14:51:14 volumio systemd[1]: Started Volumio Tidal Connect Service. Aug 22 14:51:14 volumio volumio[1071]: info: Executing endpoint tc_getconfig Aug 22 14:51:14 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 22 14:51:14 volumio vtcs[11553]: BYE Aug 22 14:51:14 volumio systemd[1]: vtcs.service: Succeeded. Aug 22 14:51:14 volumio systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. Aug 22 14:51:14 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. Aug 22 14:51:14 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Aug 22 14:51:14 volumio systemd[1]: Started Volumio Tidal Connect Service. Aug 22 14:51:14 volumio volumio[1071]: info: Executing endpoint tc_getconfig Aug 22 14:51:14 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 22 14:51:14 volumio vtcs[11560]: BYE Aug 22 14:51:14 volumio systemd[1]: vtcs.service: Succeeded. Aug 22 14:51:14 volumio systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. Aug 22 14:51:14 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. Aug 22 14:51:14 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Aug 22 14:51:14 volumio systemd[1]: Started Volumio Tidal Connect Service. Aug 22 14:51:14 volumio volumio[1071]: info: Executing endpoint tc_getconfig Aug 22 14:51:14 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 22 14:51:14 volumio vtcs[11564]: BYE Aug 22 14:51:14 volumio systemd[1]: vtcs.service: Succeeded. Aug 22 14:51:14 volumio systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. Aug 22 14:51:14 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 4. Aug 22 14:51:14 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Aug 22 14:51:14 volumio systemd[1]: Started Volumio Tidal Connect Service. Aug 22 14:51:14 volumio volumio[1071]: info: Executing endpoint tc_getconfig Aug 22 14:51:14 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 22 14:51:14 volumio vtcs[11567]: BYE Aug 22 14:51:14 volumio systemd[1]: vtcs.service: Succeeded. Aug 22 14:51:15 volumio systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. Aug 22 14:51:15 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 5. Aug 22 14:51:15 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Aug 22 14:51:15 volumio systemd[1]: Started Volumio Tidal Connect Service. Aug 22 14:51:15 volumio volumio[1071]: info: Executing endpoint tc_getconfig Aug 22 14:51:15 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 22 14:51:15 volumio vtcs[11570]: BYE Aug 22 14:51:15 volumio systemd[1]: vtcs.service: Succeeded. Aug 22 14:51:15 volumio systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. Aug 22 14:51:15 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 6. Aug 22 14:51:15 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Aug 22 14:51:15 volumio systemd[1]: vtcs.service: Start request repeated too quickly. Aug 22 14:51:15 volumio systemd[1]: vtcs.service: Failed with result 'start-limit-hit'. Aug 22 14:51:15 volumio systemd[1]: Failed to start Volumio Tidal Connect Service. Aug 22 14:52:05 volumio volumio[1071]: info: CoreCommandRouter::volumioToggle Aug 22 14:52:05 volumio volumio[1071]: info: CoreStateMachine::pause Aug 22 14:52:05 volumio volumio[1071]: info: CoreStateMachine::stPlaybackTimer Aug 22 14:52:05 volumio volumio[1071]: info: CoreStateMachine::servicePause Aug 22 14:52:05 volumio volumio[1071]: info: CoreCommandRouter::servicePause Aug 22 14:52:05 volumio volumio[1071]: info: Received pause Aug 22 14:52:18 volumio volumio[1071]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 22 14:52:19 volumio volumio[1071]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::volumioGetVisibleSources Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 22 14:52:19 volumio volumio[1071]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 22 14:52:19 volumio volumio[1071]: info: Received Get System Info Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 14:52:19 volumio volumio[1071]: info: Discovery: Getting this device information Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState Aug 22 14:52:19 volumio volumio[1071]: info: Listing playlists Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 22 14:52:19 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 22 14:52:20 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState Aug 22 14:52:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 22 14:52:21 volumio volumio[1071]: info: Received Get System Info Aug 22 14:52:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 14:52:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 14:52:21 volumio volumio[1071]: info: Discovery: Getting this device information Aug 22 14:52:21 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState Aug 22 14:52:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 14:52:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 22 14:52:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 22 14:52:21 volumio volumio[1071]: info: Received Get System Info Aug 22 14:52:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 14:52:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 14:52:21 volumio volumio[1071]: info: Discovery: Getting this device information Aug 22 14:52:21 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState Aug 22 14:52:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 14:52:22 volumio volumio[1071]: info: Executing endpoint metavolumio Aug 22 14:52:22 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 22 14:52:22 volumio volumio[1071]: info: Executing endpoint metavolumio Aug 22 14:52:22 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 22 14:52:22 volumio volumio[1071]: info: Executing endpoint metavolumio Aug 22 14:52:22 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 22 14:52:25 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 22 14:52:25 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 22 14:52:29 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 22 14:52:29 volumio volumio[1071]: info: Stopping peppyspectrum service Aug 22 14:52:29 volumio volumio[1071]: info: CoreStateMachine::stop Aug 22 14:52:29 volumio volumio[1071]: info: CoreStateMachine::serviceStop Aug 22 14:52:29 volumio volumio[1071]: info: CoreCommandRouter::serviceStop Aug 22 14:52:29 volumio volumio[1071]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 22 14:52:29 volumio volumio[1071]: TypeError: Cannot read property 'then' of undefined Aug 22 14:52:29 volumio volumio[1071]: at peppyspectrum.onStop (/data/plugins/user_interface/peppyspectrum/index.js:60:43) Aug 22 14:52:29 volumio volumio[1071]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Aug 22 14:52:29 volumio volumio[1071]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Aug 22 14:52:29 volumio volumio[1071]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1537:29) Aug 22 14:52:29 volumio volumio[1071]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1387:47) Aug 22 14:52:29 volumio volumio[1071]: at Socket.emit (events.js:400:28) Aug 22 14:52:29 volumio volumio[1071]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 22 14:52:29 volumio volumio[1071]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 22 14:52:29 volumio volumio[1071]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 22 14:52:29 volumio sudo[11643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-22 14:51 Aug 22 14:52:29 volumio sudo[11643]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"