Apr 22 21:38:41 rpi4-0058 bash[2034]: Tue Apr 22 21:38:41 CEST 2025 Apr 22 21:38:41 rpi4-0058 sudo[1775]: pam_unix(sudo:session): session closed for user root Apr 22 21:38:41 rpi4-0058 systemd[1]: setdatetime.service: Deactivated successfully. Apr 22 21:38:41 rpi4-0058 sudo[1771]: pam_unix(sudo:session): session closed for user root Apr 22 21:38:41 rpi4-0058 systemd[1]: Finished setdatetime.service - Set local time using time server. Apr 22 21:38:41 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:41 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:41 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:41 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:41 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:41 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:42 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:42 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:42 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:42 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:42 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:42 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:43 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:43 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:43 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:43 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:43 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:43 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:44 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:44 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:44 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:44 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:44 rpi4-0058 upmpdcli[2036]: writing RSA key Apr 22 21:38:44 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:45 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:45 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:45 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:45 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:45 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:46 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:46 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:46 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:46 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:46 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:46 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:47 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:47 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:47 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:47 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:47 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:47 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:48 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:48 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:48 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:48 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:48 rpi4-0058 volumio[1358]: info: Executing endpoint metavolumio Apr 22 21:38:48 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 21:38:48 rpi4-0058 volumio[1358]: info: Executing endpoint metavolumio Apr 22 21:38:48 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 21:38:49 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:49 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:49 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:49 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:49 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:49 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:50 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:50 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:50 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:50 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:50 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:50 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:51 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:51 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:51 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 22 21:38:51 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 22 21:38:51 rpi4-0058 volumio[1358]: info: Discovery: Getting this device information Apr 22 21:38:51 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:51 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 22 21:38:51 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:51 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:51 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:51 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:52 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:52 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:52 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:52 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:53 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:53 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:53 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:53 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:53 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:53 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:54 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:54 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:54 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:54 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:54 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:54 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:55 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:55 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:55 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:55 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:55 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:55 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:56 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:56 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:56 rpi4-0058 go-librespot[1596]: time="2025-04-22T21:38:56+02:00" level=trace msg="sent dealer ping" Apr 22 21:38:56 rpi4-0058 go-librespot[1596]: time="2025-04-22T21:38:56+02:00" level=trace msg="received dealer pong" Apr 22 21:38:56 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:56 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:56 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:56 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:57 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:57 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:57 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:57 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:57 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:58 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:58 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:58 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:58 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Apr 22 21:38:58 rpi4-0058 volumio[1358]: info: [1745350738434] [80s80s] handleBrowseUri curUri: 80s80s Apr 22 21:38:58 rpi4-0058 volumio[1358]: info: Preload queue cleared Apr 22 21:38:58 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:58 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: Preload queue cleared Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CoreStateMachine::ClearQueue Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CoreStateMachine::stop Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CoreStateMachine::serviceStop Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: Received STOP, but no service to execute it Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CorePlayQueue::clearPlayQueue Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CorePlayQueue::saveQueue Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPushQueue Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CoreStateMachine::addQueueItems Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CorePlayQueue::addQueueItems Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: Preload queue cleared Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: Adding Item to queue: 80s80s/eighties Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: Exploding uri 80s80s/eighties in service 80s80s Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: [1745350739995] [80s80s] explodeUri: 80s80s/eighties Apr 22 21:38:59 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPushQueue Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CorePlayQueue::saveQueue Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreStateMachine::updateTrackBlock Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrackBlock Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPlay Apr 22 21:39:00 rpi4-0058 volumio[1358]: verbose: UNSET VOLATILE: Service: undefined Apr 22 21:39:00 rpi4-0058 volumio[1358]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Apr 22 21:39:00 rpi4-0058 volumio[1358]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Apr 22 21:39:00 rpi4-0058 volumio[1358]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreStateMachine::play index 0 Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 21:39:00 rpi4-0058 volumio[1358]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreStateMachine::stop Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreStateMachine::resetVolumioState Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreStateMachine::getcurrentVolume Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioRetrievevolume Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreStateMachine::play index undefined Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreStateMachine::pushState Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPushState Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output update for this device Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioStop Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreStateMachine::stop Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:00 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:01 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:01 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:01 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:01 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:01 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:01 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:01 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:01 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:01 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:01 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:01 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:01 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:02 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:02 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:02 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:02 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:02 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:02 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:02 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:02 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:02 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:02 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:02 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:02 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:03 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:03 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:03 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:03 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:03 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:03 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:03 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:03 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:04 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:04 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:04 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:04 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:04 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:04 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:04 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:04 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:04 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:04 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:04 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:04 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: [1745350745499] [80s80s] handleBrowseUri curUri: 80s80s/eighties Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: [1745350745500] [80s80s] getRadioContent url: eighties Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: Preload queue cleared Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:05 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: Preload queue cleared Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::ClearQueue Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::stop Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::clearPlayQueue Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::saveQueue Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPushQueue Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::addQueueItems Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::addQueueItems Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: Preload queue cleared Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: Adding Item to queue: webeighties/0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: Exploding uri webeighties/0 in service 80s80s Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: [1745350746549] [80s80s] explodeUri: webeighties/0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPushQueue Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::saveQueue Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::updateTrackBlock Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrackBlock Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPlay Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::play index 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::stop Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::play index undefined Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::startPlaybackTimer Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetVisibleSources Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: [1745350746588] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: [1745350746589] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: [1745350746800] [80s80s] received new event containing 2 songs. Apr 22 21:39:06 rpi4-0058 volumio[1358]: verbose: ControllerMpd::sendMpdCommand stop Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: sendMpdCommand stop took 1 milliseconds Apr 22 21:39:06 rpi4-0058 volumio[1358]: verbose: ControllerMpd::sendMpdCommand clear Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: Apr 22 21:39:06 rpi4-0058 volumio[1358]: ---------------------------- MPD announces system playlist update Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: Ignoring MPD Status Update Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: sendMpdCommand clear took 1 milliseconds Apr 22 21:39:06 rpi4-0058 volumio[1358]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 22 21:39:06 rpi4-0058 volumio[1358]: error: updateQueue error: null Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: Apr 22 21:39:06 rpi4-0058 volumio[1358]: ---------------------------- MPD announces state update: options Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: ------------------------------ 2ms Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: sendMpdCommand consume 1 took 2 milliseconds Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: ControllerMpd::getState Apr 22 21:39:06 rpi4-0058 volumio[1358]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: [1745350746815] [80s80s] adding url: http://streams.80s80s.de/web/mp3-192/volumio Apr 22 21:39:06 rpi4-0058 volumio[1358]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: Apr 22 21:39:06 rpi4-0058 volumio[1358]: ---------------------------- MPD announces system playlist update Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: Ignoring MPD Status Update Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: sendMpdCommand status took 2 milliseconds Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" took 2 milliseconds Apr 22 21:39:06 rpi4-0058 volumio[1358]: verbose: ControllerMpd::parseState Apr 22 21:39:06 rpi4-0058 volumio[1358]: verbose: ControllerMpd::sendMpdCommand play Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: ControllerMpd::pushState Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::servicePushState Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::pushState Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPushState Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output update for this device Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: ------------------------------ 116ms Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: ------------------------------ 116ms Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: sendMpdCommand play took 120 milliseconds Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: [1745350746974] [80s80s] Pushing the next song state: Grace Jones - Slave to the Rhythm Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreCommandRouter::servicePushState Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music124/v4/69/aa/bd/69aabdb6-cf1e-8f94-4e53-450d07f62f54/source/600x600bb.jpg","name":"Grace Jones - Slave to the Rhythm","title":"Slave to the Rhythm","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"256","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 22 21:39:06 rpi4-0058 volumio[1358]: verbose: CURRENT POSITION 0 Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::syncState stateService play Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: CoreStateMachine::syncState currentStatus stop Apr 22 21:39:06 rpi4-0058 volumio[1358]: info: [1745350746976] [80s80s] PlayNextTrack API delay: 30 Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: [1745350747016] [80s80s] Setting timer to: 256000 milliseconds. Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 1 Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:07 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: Apr 22 21:39:08 rpi4-0058 volumio[1358]: ---------------------------- MPD announces state update: player Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: ControllerMpd::getState Apr 22 21:39:08 rpi4-0058 volumio[1358]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: Apr 22 21:39:08 rpi4-0058 volumio[1358]: ---------------------------- MPD announces state update: player Apr 22 21:39:08 rpi4-0058 volumio[1358]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: sendMpdCommand status took 9 milliseconds Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: ControllerMpd::getState Apr 22 21:39:08 rpi4-0058 volumio[1358]: verbose: ControllerMpd::sendMpdCommand status Apr 22 21:39:08 rpi4-0058 volumio[1358]: verbose: ControllerMpd::parseState Apr 22 21:39:08 rpi4-0058 volumio[1358]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: sendMpdCommand clearerror took 2 milliseconds Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: sendMpdCommand status took 2 milliseconds Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 22 21:39:08 rpi4-0058 volumio[1358]: verbose: ControllerMpd::parseState Apr 22 21:39:08 rpi4-0058 volumio[1358]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 21:39:08 rpi4-0058 volumio[1358]: verbose: ControllerMpd::parseTrackInfo Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: ControllerMpd::pushState Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CoreCommandRouter::servicePushState Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: ------------------------------ 15ms Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 22 21:39:08 rpi4-0058 volumio[1358]: verbose: ControllerMpd::parseTrackInfo Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: ControllerMpd::pushState Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CoreCommandRouter::servicePushState Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: ------------------------------ 36ms Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:08 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:09 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:09 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:09 rpi4-0058 mpd_oled_plugin.sh[2093]: mpd_oled_cava: error while loading shared libraries: libfftw3.so.3: cannot open shared object file: No such file or directory Apr 22 21:39:09 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:09 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:09 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:09 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:09 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:09 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:09 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:09 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:10 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:10 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:10 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:10 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:10 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:10 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:10 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:10 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:10 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:10 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:11 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:11 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:11 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:11 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:11 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:11 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:11 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:11 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:11 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:11 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:11 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:11 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:12 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:12 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:12 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:12 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:12 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:12 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:12 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:12 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:12 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:12 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:12 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:12 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:13 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:13 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:13 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:13 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:13 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:13 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:13 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:13 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:13 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:13 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:13 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:13 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:14 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:14 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:14 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:14 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:14 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:14 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:14 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:14 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:14 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:14 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:14 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:14 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:15 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:15 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:15 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:15 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:15 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:15 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:15 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:15 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:15 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:15 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:16 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:16 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:16 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:16 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:16 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:16 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:16 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:16 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:16 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:16 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:16 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:16 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: Executing endpoint metavolumio Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: Executing endpoint metavolumio Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:17 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:18 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:18 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:18 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:18 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:18 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:18 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:18 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:18 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:18 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:18 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:18 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:18 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: Preparing to save Alsa Options, stopping services first Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPause Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreStateMachine::pause Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreStateMachine::stPlaybackTimer Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreStateMachine::servicePause Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::servicePause Apr 22 21:39:19 rpi4-0058 volumio[1358]: verbose: ControllerMpd::sendMpdCommand pause Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"E30 II"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: Setting mixer E30 II for card E30 II Apr 22 21:39:19 rpi4-0058 vtcs[1976]: [2025-04-22 21:39:19.468] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 22 21:39:19 rpi4-0058 vtcs[1976]: [2025-04-22 21:39:19.488] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 22 21:39:19 rpi4-0058 sudo[2127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 22 21:39:19 rpi4-0058 sudo[2127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: Updating Volume Controller Parameters: Device: 5 Name: E30 II Mixer: E30 II Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: Disabling external Volume Control Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 21:39:19 rpi4-0058 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: Preparing to generate the ALSA configuration file Apr 22 21:39:19 rpi4-0058 systemd[1]: vtcs.service: Deactivated successfully. Apr 22 21:39:19 rpi4-0058 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 22 21:39:19 rpi4-0058 sudo[2127]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: sendMpdCommand pause took 378 milliseconds Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::servicePushState Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:19 rpi4-0058 volumio[1358]: verbose: STATE SERVICE {"status":"pause","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music124/v4/69/aa/bd/69aabdb6-cf1e-8f94-4e53-450d07f62f54/source/600x600bb.jpg","name":"Grace Jones - Slave to the Rhythm","title":"Slave to the Rhythm","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"256","seek":12713,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 22 21:39:19 rpi4-0058 volumio[1358]: verbose: CURRENT POSITION 0 Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreStateMachine::syncState stateService pause Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreStateMachine::syncState currentStatus pause Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreStateMachine::pushState Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPushState Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output update for this device Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreStateMachine::stPlaybackTimer Apr 22 21:39:19 rpi4-0058 sudo[2135]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 22 21:39:19 rpi4-0058 sudo[2135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: Reading ALSA contributions from plugins. Apr 22 21:39:19 rpi4-0058 sudo[2135]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: VolumeController:: Volume=100 Mute =false Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreStateMachine::pushState Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPushState Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output update for this device Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:19 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 22 21:39:20 rpi4-0058 volumio[1358]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: Asound.conf file unchanged, so no further update is needed Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: Output device has changed, restarting MPD Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: Output device has changed, restarting Shairport Sync Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 21:39:20 rpi4-0058 sudo[2146]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 21:39:20 rpi4-0058 sudo[2146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:20 rpi4-0058 sudo[2146]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:20 rpi4-0058 sudo[2149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 21:39:20 rpi4-0058 sudo[2149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:20 rpi4-0058 systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Apr 22 21:39:20 rpi4-0058 systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Apr 22 21:39:20 rpi4-0058 systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Apr 22 21:39:20 rpi4-0058 systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 22 21:39:20 rpi4-0058 volumio[1358]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Apr 22 21:39:20 rpi4-0058 sudo[2158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: MPD Permissions set Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:20 rpi4-0058 sudo[2158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:20 rpi4-0058 sudo[2160]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 22 21:39:20 rpi4-0058 sudo[2160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:20 rpi4-0058 systemd[1]: mpd.service: Deactivated successfully. Apr 22 21:39:20 rpi4-0058 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 22 21:39:20 rpi4-0058 systemd[1]: mpd.service: Consumed 1.525s CPU time. Apr 22 21:39:20 rpi4-0058 systemd[1]: mpd.socket: Deactivated successfully. Apr 22 21:39:20 rpi4-0058 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 22 21:39:20 rpi4-0058 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:20 rpi4-0058 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 22 21:39:20 rpi4-0058 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: Starting Shairport Sync Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: Preparing to generate the ALSA configuration file Apr 22 21:39:20 rpi4-0058 sudo[2160]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:20 rpi4-0058 sudo[2158]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:20 rpi4-0058 sudo[2171]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 21:39:20 rpi4-0058 sudo[2171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: Reading ALSA contributions from plugins. Apr 22 21:39:20 rpi4-0058 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 22 21:39:20 rpi4-0058 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 22 21:39:20 rpi4-0058 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 22 21:39:20 rpi4-0058 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 22 21:39:20 rpi4-0058 sudo[2171]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: Shairport-Sync Started Apr 22 21:39:20 rpi4-0058 sudo[2169]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 21:39:20 rpi4-0058 sudo[2169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 22 21:39:20 rpi4-0058 sudo[2169]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:20 rpi4-0058 volumio[1358]: info: Asound.conf file written Apr 22 21:39:21 rpi4-0058 sudo[2194]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 22 21:39:21 rpi4-0058 sudo[2194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:21 rpi4-0058 sudo[2194]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:21 rpi4-0058 volumio[1358]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 22 21:39:21 rpi4-0058 volumio[1358]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: Output device has changed, restarting MPD Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: Output device has changed, restarting Shairport Sync Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 21:39:21 rpi4-0058 sudo[2202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 21:39:21 rpi4-0058 sudo[2202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:21 rpi4-0058 sudo[2202]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:21 rpi4-0058 sudo[2201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 21:39:21 rpi4-0058 sudo[2201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:21 rpi4-0058 volumio[1358]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: MPD Permissions set Apr 22 21:39:21 rpi4-0058 systemd[1]: mpd.service: Deactivated successfully. Apr 22 21:39:21 rpi4-0058 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 22 21:39:21 rpi4-0058 systemd[1]: mpd.socket: Deactivated successfully. Apr 22 21:39:21 rpi4-0058 sudo[2212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 22 21:39:21 rpi4-0058 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 22 21:39:21 rpi4-0058 sudo[2212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:21 rpi4-0058 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: Discovery: Getting this device information Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioGetState Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:21 rpi4-0058 sudo[2214]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 22 21:39:21 rpi4-0058 sudo[2214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:21 rpi4-0058 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 22 21:39:21 rpi4-0058 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:21 rpi4-0058 sudo[2212]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:21 rpi4-0058 sudo[2214]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: Starting Shairport Sync Apr 22 21:39:21 rpi4-0058 sudo[2227]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 21:39:21 rpi4-0058 sudo[2227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:21 rpi4-0058 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 22 21:39:21 rpi4-0058 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 22 21:39:21 rpi4-0058 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 22 21:39:21 rpi4-0058 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 22 21:39:21 rpi4-0058 sudo[2227]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:21 rpi4-0058 volumio[1358]: info: Shairport-Sync Started Apr 22 21:39:21 rpi4-0058 sudo[2219]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 21:39:21 rpi4-0058 sudo[2219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 22 21:39:21 rpi4-0058 sudo[2219]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:22 rpi4-0058 volumio[1358]: info: TidalConnect service stoped! Apr 22 21:39:22 rpi4-0058 volumio[1358]: info: TidalConnect service stoped! Apr 22 21:39:22 rpi4-0058 volumio[1358]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 22 21:39:22 rpi4-0058 volumio[1358]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 22 21:39:23 rpi4-0058 sudo[2255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 22 21:39:23 rpi4-0058 sudo[2255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:23 rpi4-0058 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 22 21:39:23 rpi4-0058 sudo[2255]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:23 rpi4-0058 sudo[2262]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 21:39:23 rpi4-0058 sudo[2262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:23 rpi4-0058 sudo[2262]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:23 rpi4-0058 sudo[2263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 21:39:23 rpi4-0058 sudo[2263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreStateMachine::getcurrentVolume Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioRetrievevolume Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: MPD Permissions set Apr 22 21:39:23 rpi4-0058 systemd[1]: mpd.service: Deactivated successfully. Apr 22 21:39:23 rpi4-0058 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 22 21:39:23 rpi4-0058 systemd[1]: mpd.socket: Deactivated successfully. Apr 22 21:39:23 rpi4-0058 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 22 21:39:23 rpi4-0058 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: VolumeController:: Volume=100 Mute =false Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreStateMachine::pushState Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPushState Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output update for this device Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreStateMachine::updateTrackBlock Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrackBlock Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioRetrievevolume Apr 22 21:39:23 rpi4-0058 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 22 21:39:23 rpi4-0058 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 22 21:39:23 rpi4-0058 volumio[1358]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: TidalConnect service stoped! Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: TidalConnect service stoped! Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: Executing endpoint tc_getconfig Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: VolumeController:: Volume=100 Mute =false Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreStateMachine::pushState Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPushState Apr 22 21:39:23 rpi4-0058 vtcs[2259]: STARTING TidalConnect services, version: 1.5.0.45 Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output update for this device Apr 22 21:39:23 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output Apr 22 21:39:23 rpi4-0058 volumio[1358]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 22 21:39:24 rpi4-0058 vtcs[2259]: STARTED TidalConnect services. Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: Executing endpoint tc_connect Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: Connecting to TidalConnect Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: CoreCommandRouter::servicePushState Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: CoreStateMachine::pushState Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPushState Apr 22 21:39:24 rpi4-0058 sudo[2275]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 21:39:24 rpi4-0058 sudo[2275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 22 21:39:24 rpi4-0058 sudo[2275]: pam_unix(sudo:session): session closed for user root Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output update for this device Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received tidalconnect Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: CoreCommandRouter::servicePushState Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: CoreStateMachine::pushState Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: CoreCommandRouter::volumioPushState Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output update for this device Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: MRS: Pushing multiroomSync output Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: CorePlayQueue::getTrack 0 Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received tidalconnect Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 22 21:39:24 rpi4-0058 volumio[1358]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 22 21:39:24 rpi4-0058 volumio[1358]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 21:39:24 rpi4-0058 volumio[1358]: Error: ENOENT: no such file or directory, unlink '/tmp/sAbYBGS4aSXrFvPqyaUtTiFFVywP4x9mPbfTnwgtsw9oxVJeK30yWnuJaH20is58FiXEltEvdAVRWaXYD1dfaKdt4EmW20EspQLb' Apr 22 21:39:24 rpi4-0058 volumio[1358]: at Object.unlinkSync (node:fs:1899:3) Apr 22 21:39:24 rpi4-0058 volumio[1358]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11238) Apr 22 21:39:24 rpi4-0058 volumio[1358]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774) Apr 22 21:39:24 rpi4-0058 volumio[1358]: at TidalConnectClient.emit (node:events:514:28) Apr 22 21:39:24 rpi4-0058 volumio[1358]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44661) Apr 22 21:39:24 rpi4-0058 volumio[1358]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41631 Apr 22 21:39:24 rpi4-0058 volumio[1358]: at Array.forEach () Apr 22 21:39:24 rpi4-0058 volumio[1358]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41509) Apr 22 21:39:24 rpi4-0058 volumio[1358]: at Socket.emit (node:events:514:28) Apr 22 21:39:24 rpi4-0058 volumio[1358]: at addChunk (node:internal/streams/readable:343:12) Apr 22 21:39:24 rpi4-0058 volumio[1358]: at readableAddChunk (node:internal/streams/readable:316:9) Apr 22 21:39:24 rpi4-0058 volumio[1358]: at Readable.push (node:internal/streams/readable:253:10) Apr 22 21:39:24 rpi4-0058 volumio[1358]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) { Apr 22 21:39:24 rpi4-0058 volumio[1358]: errno: -2, Apr 22 21:39:24 rpi4-0058 volumio[1358]: syscall: 'unlink', Apr 22 21:39:24 rpi4-0058 volumio[1358]: code: 'ENOENT', Apr 22 21:39:24 rpi4-0058 volumio[1358]: path: '/tmp/sAbYBGS4aSXrFvPqyaUtTiFFVywP4x9mPbfTnwgtsw9oxVJeK30yWnuJaH20is58FiXEltEvdAVRWaXYD1dfaKdt4EmW20EspQLb' Apr 22 21:39:24 rpi4-0058 volumio[1358]: } Apr 22 21:39:24 rpi4-0058 volumio[1358]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 21:39:26 rpi4-0058 sudo[2315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-22 21:38' Apr 22 21:39:26 rpi4-0058 sudo[2315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 22 21:39:26 rpi4-0058 go-librespot[1596]: time="2025-04-22T21:39:26+02:00" level=trace msg="sent dealer ping" Apr 22 21:39:26 rpi4-0058 go-librespot[1596]: time="2025-04-22T21:39:26+02:00" level=trace msg="received dealer pong" PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="77459d3da1093d939f1a501571c373c95e4a2652" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="0da3792b433b4daf69b90315de2841f333f7f716" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Apr 22 11:07:15 UTC 2025" VOLUMIO_VERSION="0.058" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cdc65c6dbdd6f51850072894d2c25614"