-- Logs begin at Thu 2024-08-15 08:20:34 CEST, end at Sat 2024-08-24 23:21:52 CEST. -- Aug 24 23:20:00 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 24 23:20:01 rivowoonkamer volumio[3106]: info: Preload queue cleared Aug 24 23:20:07 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 24 23:20:07 rivowoonkamer volumio[3106]: info: Preload queue cleared Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: Preload queue cleared Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: Preloading song: upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@e13050ae393ff04b0dab Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: Preloading song: upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@234adae3f54787bdd5fc Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: Preloading song: upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@9c6a30f7f035d79723cd Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: Preloading song: upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@704ad766a11610b0209c Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: Preloading song: upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@bca1dc7f4a4d5c230025 Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: Preloading song: upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@8c38ac05cca6442606b4 Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: Preloading song: upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@63f22d6f2c4e6d998768 Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: Preloading song: upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@6e8bc481fa5a5e8a138c Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: Preloading song: upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@aaf44b47a90ab18991df Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: Preloading song: upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@54f87dd10b7ddc72f3df Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: Preloading song: upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@97bfdd97096fac44f475 Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: Exploding uri upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@e13050ae393ff04b0dab in service upnp_browser Aug 24 23:20:08 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Listing playlists Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Listing playlists Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Exploding uri upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@234adae3f54787bdd5fc in service upnp_browser Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Exploding uri upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@9c6a30f7f035d79723cd in service upnp_browser Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Exploding uri upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@704ad766a11610b0209c in service upnp_browser Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Executing endpoint getSimilarAlbums Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Executing endpoint getSimilarAlbums Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Exploding uri upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@bca1dc7f4a4d5c230025 in service upnp_browser Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Executing endpoint metavolumio Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Executing endpoint metavolumio Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Executing endpoint metavolumio Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Executing endpoint metavolumio Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Exploding uri upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@8c38ac05cca6442606b4 in service upnp_browser Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Exploding uri upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@63f22d6f2c4e6d998768 in service upnp_browser Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Exploding uri upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@6e8bc481fa5a5e8a138c in service upnp_browser Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Exploding uri upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@aaf44b47a90ab18991df in service upnp_browser Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Exploding uri upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@54f87dd10b7ddc72f3df in service upnp_browser Aug 24 23:20:09 rivowoonkamer volumio[3106]: info: Exploding uri upnp/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@97bfdd97096fac44f475 in service upnp_browser Aug 24 23:20:10 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 24 23:20:10 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 24 23:20:10 rivowoonkamer volumio[3106]: info: Discovery: Getting this device information Aug 24 23:20:10 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:20:10 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Preload queue cleared Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::ClearQueue Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::stop Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::stPlaybackTimer Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::updateTrackBlock Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::getTrackBlock Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::pushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::getTrack 10 Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioPushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: MRS: Pushing multiroomSync output update for this device Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: MRS: Pushing multiroomSync output Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::serviceStop Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::getTrack 10 Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::serviceStop Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: [1724534412146] ControllerUPNPBrowser::stop Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::clearPlayQueue Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::saveQueue Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioPushQueue Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::addQueueItems Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::addQueueItems Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Preload queue cleared Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Adding Item to queue: upnp/folder/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@8cf3bc0f506a5c458a6c Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Exploding uri upnp/folder/http://192.168.178.22:32469/ContentDirectory/94e6ac91-209a-58ae-81bc-29e2fe9bd5c9/control.xml@8cf3bc0f506a5c458a6c in service upnp_browser Aug 24 23:20:12 rivowoonkamer volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Updating RAAT Signal Path Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Aug 24 23:20:12 rivowoonkamer volumio[3106]: ---------------------------- MPD announces state update: player Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: sendMpdCommand stop took 40 milliseconds Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: ControllerMpd::getState Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: sendMpdCommand status took 2 milliseconds Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::parseState Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: ControllerMpd::pushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::servicePushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::pushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::getTrack 10 Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioPushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: MRS: Pushing multiroomSync output update for this device Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: MRS: Pushing multiroomSync output Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::getTrack 10 Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp4","artist":null,"album":null,"uri":"http://192.168.178.22:32469/object/0d04539c206f96363e2b/file.mp4","trackType":"mp4"} Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: CURRENT POSITION 10 Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::syncState stateService stop Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::syncState currentStatus stop Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::pushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::getTrack 10 Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioPushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: MRS: Pushing multiroomSync output update for this device Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: MRS: Pushing multiroomSync output Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: No code Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::pushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::getTrack 10 Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioPushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: MRS: Pushing multiroomSync output update for this device Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: MRS: Pushing multiroomSync output Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: ------------------------------ 45ms Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioPushQueue Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::saveQueue Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::updateTrackBlock Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::getTrackBlock Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioPlay Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::play index 0 Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::stop Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::play index undefined Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::getTrack 0 Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::startPlaybackTimer Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::getTrack 0 Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: [1724534412273] ControllerUPNPBrowser::clearAddPlayTrack Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: sendMpdCommand stop took 1 milliseconds Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::sendMpdCommand clear Aug 24 23:20:12 rivowoonkamer volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Aug 24 23:20:12 rivowoonkamer volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Aug 24 23:20:12 rivowoonkamer volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Updating RAAT Signal Path Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Updating RAAT Signal Path Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Updating RAAT Signal Path Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Aug 24 23:20:12 rivowoonkamer volumio[3106]: ---------------------------- MPD announces system playlist update Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Ignoring MPD Status Update Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: sendMpdCommand clear took 26 milliseconds Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.22:32469/object/e13050ae393ff04b0dab/file.wav" Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: MCU Signalled Playback Inactive Aug 24 23:20:12 rivowoonkamer volumio[3106]: error: updateQueue error: null Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: ------------------------------ 6ms Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.22:32469/object/e13050ae393ff04b0dab/file.wav" Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Aug 24 23:20:12 rivowoonkamer volumio[3106]: ---------------------------- MPD announces system playlist update Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Ignoring MPD Status Update Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: sendMpdCommand add "http://192.168.178.22:32469/object/e13050ae393ff04b0dab/file.wav" took 2 milliseconds Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::sendMpdCommand play Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: ------------------------------ 4ms Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: sendMpdCommand play took 2 milliseconds Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Aug 24 23:20:12 rivowoonkamer volumio[3106]: ---------------------------- MPD announces state update: player Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: ControllerMpd::getState Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Aug 24 23:20:12 rivowoonkamer volumio[3106]: ---------------------------- MPD announces state update: player Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: sendMpdCommand status took 553 milliseconds Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: ControllerMpd::getState Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::sendMpdCommand status Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::parseState Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: sendMpdCommand status took 5 milliseconds Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::parseState Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: ControllerMpd::pushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::servicePushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::getTrack 0 Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":275,"samplerate":"88.2 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.wav","artist":null,"album":null,"uri":"http://192.168.178.22:32469/object/e13050ae393ff04b0dab/file.wav","trackType":"wav"} Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: CURRENT POSITION 0 Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::syncState stateService play Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::syncState currentStatus stop Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: ------------------------------ 563ms Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: ControllerMpd::parseTrackInfo Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: ControllerMpd::pushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::servicePushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CorePlayQueue::getTrack 0 Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1188,"duration":275,"samplerate":"88.2 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.wav","artist":null,"album":null,"uri":"http://192.168.178.22:32469/object/e13050ae393ff04b0dab/file.wav","trackType":"wav"} Aug 24 23:20:12 rivowoonkamer volumio[3106]: verbose: CURRENT POSITION 0 Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::syncState stateService play Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::syncState currentStatus play Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Received an update from plugin. extracting info from payload Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::pushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioPushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: MRS: Pushing multiroomSync output update for this device Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: MRS: Pushing multiroomSync output Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreStateMachine::pushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioPushState Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: MRS: Pushing multiroomSync output update for this device Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: MRS: Pushing multiroomSync output Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: ------------------------------ 46ms Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Signalling Playback active due to playback status change Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Signalling Playback active due to playback status change Aug 24 23:20:12 rivowoonkamer volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Aug 24 23:20:12 rivowoonkamer volumio[3106]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Updating RAAT Signal Path Aug 24 23:20:12 rivowoonkamer volumio[3106]: info: Updating RAAT Signal Path Aug 24 23:20:13 rivowoonkamer volumio[3106]: info: MCU Signalled Playback Active Aug 24 23:20:19 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:20:29 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:20:29 rivowoonkamer volumio[3106]: info: Listing playlists Aug 24 23:20:29 rivowoonkamer volumio[3106]: info: Listing playlists Aug 24 23:20:39 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:20:40 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 24 23:20:40 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 24 23:20:40 rivowoonkamer volumio[3106]: info: Discovery: Getting this device information Aug 24 23:20:40 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:20:40 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 24 23:20:43 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 24 23:20:44 rivowoonkamer volumio[3106]: info: Preload queue cleared Aug 24 23:20:49 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:20:49 rivowoonkamer volumio[3106]: info: Listing playlists Aug 24 23:20:49 rivowoonkamer volumio[3106]: info: Listing playlists Aug 24 23:20:49 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 24 23:20:49 rivowoonkamer volumio[3106]: info: Preload queue cleared Aug 24 23:20:55 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 24 23:20:55 rivowoonkamer volumio[3106]: info: Preload queue cleared Aug 24 23:20:56 rivowoonkamer volumio[3106]: info: Executing endpoint getSimilarAlbums Aug 24 23:20:56 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 24 23:20:56 rivowoonkamer volumio[3106]: info: Executing endpoint getSimilarAlbums Aug 24 23:20:56 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 24 23:20:56 rivowoonkamer volumio[3106]: info: Executing endpoint metavolumio Aug 24 23:20:56 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 24 23:20:56 rivowoonkamer volumio[3106]: info: Executing endpoint metavolumio Aug 24 23:20:56 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 24 23:20:56 rivowoonkamer volumio[3106]: info: Executing endpoint metavolumio Aug 24 23:20:56 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 24 23:20:56 rivowoonkamer volumio[3106]: info: Executing endpoint metavolumio Aug 24 23:20:56 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 24 23:20:59 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:21:09 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:21:09 rivowoonkamer volumio[3106]: info: Listing playlists Aug 24 23:21:09 rivowoonkamer volumio[3106]: info: Listing playlists Aug 24 23:21:10 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 24 23:21:10 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 24 23:21:10 rivowoonkamer volumio[3106]: info: Discovery: Getting this device information Aug 24 23:21:10 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:21:10 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 24 23:21:19 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:21:29 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:21:29 rivowoonkamer volumio[3106]: info: Listing playlists Aug 24 23:21:29 rivowoonkamer volumio[3106]: info: Listing playlists Aug 24 23:21:39 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:21:41 rivowoonkamer volumio[3106]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.176 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Aug 24 23:21:41 rivowoonkamer volumio[3106]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.176 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Aug 24 23:21:41 rivowoonkamer volumio[3106]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.176 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Aug 24 23:21:41 rivowoonkamer volumio[3106]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.176 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Aug 24 23:21:45 rivowoonkamer volumio[3106]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.176 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13 Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetVisibleSources Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetQueue Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CoreStateMachine::getQueue Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CorePlayQueue::getQueue Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: Listing playlists Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: Received Get System Info Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: Discovery: Getting this device information Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:21:45 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 24 23:21:46 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 24 23:21:46 rivowoonkamer volumio[3106]: info: Preload queue cleared Aug 24 23:21:47 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 24 23:21:47 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 24 23:21:47 rivowoonkamer volumio[3106]: info: Discovery: Getting this device information Aug 24 23:21:47 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:21:47 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 24 23:21:49 rivowoonkamer volumio[3106]: info: CoreCommandRouter::volumioGetState Aug 24 23:21:49 rivowoonkamer volumio[3106]: info: Listing playlists Aug 24 23:21:49 rivowoonkamer volumio[3106]: info: Listing playlists Aug 24 23:21:51 rivowoonkamer volumio[3106]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 24 23:21:51 rivowoonkamer volumio[3106]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 23:21:51 rivowoonkamer volumio[3106]: TypeError: Cannot read property 'length' of undefined Aug 24 23:21:51 rivowoonkamer volumio[3106]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 24 23:21:51 rivowoonkamer volumio[3106]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 24 23:21:51 rivowoonkamer volumio[3106]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 24 23:21:51 rivowoonkamer volumio[3106]: at Parser.emit (events.js:400:28) Aug 24 23:21:51 rivowoonkamer volumio[3106]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 24 23:21:51 rivowoonkamer volumio[3106]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 24 23:21:51 rivowoonkamer volumio[3106]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 24 23:21:51 rivowoonkamer volumio[3106]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 24 23:21:51 rivowoonkamer volumio[3106]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 24 23:21:51 rivowoonkamer volumio[3106]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 24 23:21:51 rivowoonkamer volumio[3106]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 24 23:21:51 rivowoonkamer volumio[3106]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 24 23:21:51 rivowoonkamer volumio[3106]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 24 23:21:51 rivowoonkamer volumio[3106]: at IncomingMessage.emit (events.js:412:35) Aug 24 23:21:51 rivowoonkamer volumio[3106]: at endReadableNT (internal/streams/readable.js:1333:12) Aug 24 23:21:51 rivowoonkamer volumio[3106]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Aug 24 23:21:51 rivowoonkamer volumio[3106]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 23:21:52 rivowoonkamer sudo[17193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-24 23:20 Aug 24 23:21:52 rivowoonkamer sudo[17193]: 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="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 05:06:46 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="420eec03f218d056e8306370042003fc"