Jan 13 21:25:01 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 13 21:25:06 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 13 21:25:08 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 104. Jan 13 21:25:08 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 21:25:08 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 21:25:08 volumio upmpdcli[4521]: Could not open config: /tmp/upmpdcli.conf Jan 13 21:25:08 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:25:08 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 21:25:10 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 13 21:25:13 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 13 21:25:17 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 13 21:25:21 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 13 21:25:23 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 105. Jan 13 21:25:23 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 21:25:23 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 21:25:23 volumio upmpdcli[4553]: Could not open config: /tmp/upmpdcli.conf Jan 13 21:25:23 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:25:23 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 21:25:25 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 13 21:25:28 volumio volumio[1140]: info: CoreCommandRouter::volumioSeek Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::seek Jan 13 21:25:28 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Jan 13 21:25:28 volumio volumio[1140]: info: TRACKBLOCK {"service":"jellyfin","uri":"jellyfin/Giuseppe@44c26661631346b0baff7fa321e4ebb3/song@songId=759c377e41adbf0253b9e48a497a1f19","albumart":"/albumart?sourceicon=music_service%2Fjellyfin%2Fdist%2Fassets%2Fimages%2Fsong.png","artist":"","album":"","name":"01 - Non mi lasciare qui","title":"01 - Non mi lasciare qui","duration":269,"trackType":"mp3"} Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::startPlaybackTimer Jan 13 21:25:28 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 13 21:25:28 volumio volumio[1140]: info: ControllerMpd::seek Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::pushState Jan 13 21:25:28 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 21:25:28 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Jan 13 21:25:28 volumio volumio[1140]: error: null Jan 13 21:25:28 volumio volumio[1140]: info: ControllerMpd::getState Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Jan 13 21:25:28 volumio volumio[1140]: info: Jan 13 21:25:28 volumio volumio[1140]: ---------------------------- MPD announces state update: player Jan 13 21:25:28 volumio volumio[1140]: info: ControllerMpd::getState Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Jan 13 21:25:28 volumio volumio[1140]: info: Jan 13 21:25:28 volumio volumio[1140]: ---------------------------- MPD announces state update: player Jan 13 21:25:28 volumio volumio[1140]: info: ControllerMpd::getState Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Jan 13 21:25:28 volumio volumio[1140]: info: Jan 13 21:25:28 volumio volumio[1140]: ---------------------------- MPD announces state update: player Jan 13 21:25:28 volumio volumio[1140]: info: ControllerMpd::getState Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Jan 13 21:25:28 volumio volumio[1140]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 13 21:25:28 volumio volumio[1140]: info: sendMpdCommand status took 40 milliseconds Jan 13 21:25:28 volumio volumio[1140]: info: sendMpdCommand status took 31 milliseconds Jan 13 21:25:28 volumio volumio[1140]: info: sendMpdCommand status took 25 milliseconds Jan 13 21:25:28 volumio volumio[1140]: info: sendMpdCommand status took 19 milliseconds Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::parseState Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::parseState Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::parseState Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::parseState Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 21:25:28 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 13 21:25:28 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 13 21:25:28 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 13 21:25:28 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Jan 13 21:25:28 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Jan 13 21:25:28 volumio volumio[1140]: info: ControllerMpd::pushState Jan 13 21:25:28 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Jan 13 21:25:28 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Jan 13 21:25:28 volumio volumio[1140]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":144000,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"stream.mp3?static=true&mediaSourceId=759c377e41adbf0253b9e48a497a1f19&tag=a22d5b7153b96c3c36491ed8d109e2ff&t.mp3","artist":null,"album":"Il Pane Il Vino E La Visione by PoLiNi_46","uri":"http://192.168.178.34:8096/Audio/759c377e41adbf0253b9e48a497a1f19/stream.mp3?static=true&mediaSourceId=759c377e41adbf0253b9e48a497a1f19&tag=a22d5b7153b96c3c36491ed8d109e2ff&t.mp3","trackType":"mp3"} Jan 13 21:25:28 volumio volumio[1140]: verbose: CURRENT POSITION 0 Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::syncState stateService pause Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus pause Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::pushState Jan 13 21:25:28 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::stPlaybackTimer Jan 13 21:25:28 volumio volumio[1140]: info: ControllerMpd::pushState Jan 13 21:25:28 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Jan 13 21:25:28 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Jan 13 21:25:28 volumio volumio[1140]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":144000,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"stream.mp3?static=true&mediaSourceId=759c377e41adbf0253b9e48a497a1f19&tag=a22d5b7153b96c3c36491ed8d109e2ff&t.mp3","artist":null,"album":"Il Pane Il Vino E La Visione by PoLiNi_46","uri":"http://192.168.178.34:8096/Audio/759c377e41adbf0253b9e48a497a1f19/stream.mp3?static=true&mediaSourceId=759c377e41adbf0253b9e48a497a1f19&tag=a22d5b7153b96c3c36491ed8d109e2ff&t.mp3","trackType":"mp3"} Jan 13 21:25:28 volumio volumio[1140]: verbose: CURRENT POSITION 0 Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::syncState stateService pause Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus pause Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::pushState Jan 13 21:25:28 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::stPlaybackTimer Jan 13 21:25:28 volumio volumio[1140]: info: ControllerMpd::pushState Jan 13 21:25:28 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Jan 13 21:25:28 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Jan 13 21:25:28 volumio volumio[1140]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":144000,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"stream.mp3?static=true&mediaSourceId=759c377e41adbf0253b9e48a497a1f19&tag=a22d5b7153b96c3c36491ed8d109e2ff&t.mp3","artist":null,"album":"Il Pane Il Vino E La Visione by PoLiNi_46","uri":"http://192.168.178.34:8096/Audio/759c377e41adbf0253b9e48a497a1f19/stream.mp3?static=true&mediaSourceId=759c377e41adbf0253b9e48a497a1f19&tag=a22d5b7153b96c3c36491ed8d109e2ff&t.mp3","trackType":"mp3"} Jan 13 21:25:28 volumio volumio[1140]: verbose: CURRENT POSITION 0 Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::syncState stateService pause Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus pause Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::pushState Jan 13 21:25:28 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Jan 13 21:25:28 volumio volumio[1140]: info: CoreStateMachine::stPlaybackTimer Jan 13 21:25:28 volumio volumio[1140]: info: ------------------------------ 187ms Jan 13 21:25:28 volumio volumio[1140]: info: ------------------------------ 178ms Jan 13 21:25:28 volumio volumio[1140]: info: ------------------------------ 172ms Jan 13 21:25:28 volumio volumio[1140]: info: Pushing Favourites {"favourite":false} Jan 13 21:25:28 volumio volumio[1140]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 13 21:25:28 volumio volumio[1140]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 13 21:25:28 volumio volumio[1140]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 13 21:25:29 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 13 21:25:34 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 13 21:25:37 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 13 21:25:38 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 106. Jan 13 21:25:38 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 21:25:39 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 21:25:39 volumio upmpdcli[4572]: Could not open config: /tmp/upmpdcli.conf Jan 13 21:25:39 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:25:39 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 21:25:46 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 13 21:25:46 volumio volumio[1140]: info: CURURI: music-library Jan 13 21:25:46 volumio volumio[1140]: info: Preload queue cleared Jan 13 21:25:48 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 13 21:25:48 volumio volumio[1140]: info: CURURI: music-library/NAS Jan 13 21:25:48 volumio volumio[1140]: info: Preload queue cleared Jan 13 21:25:50 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 13 21:25:50 volumio volumio[1140]: info: CURURI: music-library/NAS/Musica_Mini Jan 13 21:25:50 volumio volumio[1140]: info: Preload queue cleared Jan 13 21:25:54 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 107. Jan 13 21:25:54 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 21:25:54 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 21:25:54 volumio upmpdcli[4607]: Could not open config: /tmp/upmpdcli.conf Jan 13 21:25:54 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:25:54 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 21:26:09 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 108. Jan 13 21:26:09 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 21:26:09 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 13 21:26:09 volumio upmpdcli[4625]: Could not open config: /tmp/upmpdcli.conf Jan 13 21:26:09 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:26:09 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 21:26:15 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 13 21:26:15 volumio volumio[1140]: info: CURURI: music-library/NAS/Musica_Mini/PinkFloyd Jan 13 21:26:15 volumio volumio[1140]: info: Preload queue cleared Jan 13 21:26:15 volumio volumio[1140]: info: Preloading song: music-library/NAS/Musica_Mini/PinkFloyd/Pink Floyd - Wish You Were Here.mp3 Jan 13 21:26:15 volumio volumio[1140]: info: Preloading song: music-library/NAS/Musica_Mini/PinkFloyd/Pink Floyd - Dark Side of the Moon (Entire Album).mp3 Jan 13 21:26:15 volumio volumio[1140]: info: Exploding uri music-library/NAS/Musica_Mini/PinkFloyd/Pink Floyd - Wish You Were Here.mp3 in service mpd Jan 13 21:26:15 volumio volumio[1140]: info: Exploding uri music-library/NAS/Musica_Mini/PinkFloyd/Pink Floyd - Dark Side of the Moon (Entire Album).mp3 in service mpd Jan 13 21:26:15 volumio volumio[1140]: info: ALBUMART /albumart?cacheid=300&web=//extralarge&path=%2Fmnt%2FNAS%2FMusica_Mini%2FPinkFloyd%2FPink%20Floyd%20-%20Wish%20You%20Were%20Here.mp3&metadata=false Jan 13 21:26:15 volumio volumio[1140]: info: URI /mnt/NAS/Musica_Mini/PinkFloyd/Pink Floyd - Wish You Were Here.mp3 Jan 13 21:26:15 volumio volumio[1140]: info: ALBUMART /albumart?cacheid=300&web=Pink%20Floyd/Dark%20Side%20of%20the%20Moon/extralarge&path=%2Fmnt%2FNAS%2FMusica_Mini%2FPinkFloyd%2FPink%20Floyd%20-%20Dark%20Side%20of%20the%20Moon%20(Entire%20Album).mp3&metadata=false Jan 13 21:26:15 volumio volumio[1140]: info: URI /mnt/NAS/Musica_Mini/PinkFloyd/Pink Floyd - Dark Side of the Moon (Entire Album).mp3 Jan 13 21:26:19 volumio volumio[1140]: info: Preload queue cleared Jan 13 21:26:19 volumio volumio[1140]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 13 21:26:19 volumio volumio[1140]: info: CoreStateMachine::ClearQueue Jan 13 21:26:19 volumio volumio[1140]: info: CoreStateMachine::stop Jan 13 21:26:19 volumio volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 21:26:19 volumio volumio[1140]: info: CoreStateMachine::updateTrackBlock Jan 13 21:26:19 volumio volumio[1140]: info: CorePlayQueue::getTrackBlock Jan 13 21:26:19 volumio volumio[1140]: info: CoreStateMachine::stPlaybackTimer Jan 13 21:26:19 volumio volumio[1140]: info: CoreStateMachine::pushState Jan 13 21:26:19 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Jan 13 21:26:19 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 21:26:19 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Jan 13 21:26:19 volumio volumio[1140]: info: CoreStateMachine::serviceStop Jan 13 21:26:19 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Jan 13 21:26:19 volumio volumio[1140]: info: CoreCommandRouter::serviceStop Jan 13 21:26:19 volumio volumio[1140]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 13 21:26:19 volumio volumio[1140]: info: ControllerMpd::stop Jan 13 21:26:19 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 21:26:19 volumio volumio[1140]: info: CorePlayQueue::clearPlayQueue Jan 13 21:26:19 volumio volumio[1140]: info: CorePlayQueue::saveQueue Jan 13 21:26:19 volumio volumio[1140]: info: CoreCommandRouter::volumioPushQueue Jan 13 21:26:19 volumio volumio[1140]: info: CoreStateMachine::addQueueItems Jan 13 21:26:19 volumio volumio[1140]: info: CorePlayQueue::addQueueItems Jan 13 21:26:19 volumio volumio[1140]: info: Preload queue cleared Jan 13 21:26:19 volumio volumio[1140]: info: Adding Item to queue: music-library/NAS/Musica_Mini/PinkFloyd/Pink Floyd - Wish You Were Here.mp3 Jan 13 21:26:19 volumio volumio[1140]: info: Using cached record of: music-library/NAS/Musica_Mini/PinkFloyd/Pink Floyd - Wish You Were Here.mp3 Jan 13 21:26:19 volumio volumio[1140]: info: Adding Item to queue: music-library/NAS/Musica_Mini/PinkFloyd/Pink Floyd - Dark Side of the Moon (Entire Album).mp3 Jan 13 21:26:19 volumio volumio[1140]: info: Using cached record of: music-library/NAS/Musica_Mini/PinkFloyd/Pink Floyd - Dark Side of the Moon (Entire Album).mp3 Jan 13 21:26:19 volumio volumio[1140]: info: CoreCommandRouter::volumioPushQueue Jan 13 21:26:19 volumio volumio[1140]: info: CorePlayQueue::saveQueue Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::updateTrackBlock Jan 13 21:26:20 volumio volumio[1140]: info: CorePlayQueue::getTrackBlock Jan 13 21:26:20 volumio volumio[1140]: info: CoreCommandRouter::volumioPlay Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::play index 1 Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::stop Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::play index undefined Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 21:26:20 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::startPlaybackTimer Jan 13 21:26:20 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::clearAddPlayTracks NAS/Musica_Mini/PinkFloyd/Pink Floyd - Dark Side of the Moon (Entire Album).mp3 Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 21:26:20 volumio volumio[1140]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 13 21:26:20 volumio volumio[1140]: info: ControllerMpd::getState Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Jan 13 21:26:20 volumio volumio[1140]: info: Jan 13 21:26:20 volumio volumio[1140]: ---------------------------- MPD announces state update: player Jan 13 21:26:20 volumio volumio[1140]: info: sendMpdCommand stop took 106 milliseconds Jan 13 21:26:20 volumio volumio[1140]: info: ControllerMpd::getState Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Jan 13 21:26:20 volumio volumio[1140]: info: sendMpdCommand stop took 26 milliseconds Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand clear Jan 13 21:26:20 volumio volumio[1140]: info: Jan 13 21:26:20 volumio volumio[1140]: ---------------------------- MPD announces state update: player Jan 13 21:26:20 volumio volumio[1140]: info: ControllerMpd::getState Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Jan 13 21:26:20 volumio volumio[1140]: info: Jan 13 21:26:20 volumio volumio[1140]: ---------------------------- MPD announces state update: player Jan 13 21:26:20 volumio volumio[1140]: info: ControllerMpd::getState Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Jan 13 21:26:20 volumio volumio[1140]: info: Jan 13 21:26:20 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Jan 13 21:26:20 volumio volumio[1140]: info: Ignoring MPD Status Update Jan 13 21:26:20 volumio volumio[1140]: info: sendMpdCommand status took 17 milliseconds Jan 13 21:26:20 volumio volumio[1140]: info: sendMpdCommand status took 16 milliseconds Jan 13 21:26:20 volumio volumio[1140]: info: sendMpdCommand clear took 14 milliseconds Jan 13 21:26:20 volumio volumio[1140]: info: sendMpdCommand status took 12 milliseconds Jan 13 21:26:20 volumio volumio[1140]: info: sendMpdCommand status took 10 milliseconds Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::parseState Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::parseState Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand add "NAS/Musica_Mini/PinkFloyd/Pink Floyd - Dark Side of the Moon (Entire Album).mp3" Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::parseState Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::parseState Jan 13 21:26:20 volumio volumio[1140]: info: ControllerMpd::pushState Jan 13 21:26:20 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::pushState Jan 13 21:26:20 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Jan 13 21:26:20 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 21:26:20 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Jan 13 21:26:20 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Jan 13 21:26:20 volumio volumio[1140]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 13 21:26:20 volumio volumio[1140]: verbose: CURRENT POSITION 1 Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::syncState stateService stop Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus stop Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::pushState Jan 13 21:26:20 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Jan 13 21:26:20 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Jan 13 21:26:20 volumio volumio[1140]: info: No code Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::pushState Jan 13 21:26:20 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Jan 13 21:26:20 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Jan 13 21:26:20 volumio volumio[1140]: info: ControllerMpd::pushState Jan 13 21:26:20 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::pushState Jan 13 21:26:20 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Jan 13 21:26:20 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Jan 13 21:26:20 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Jan 13 21:26:20 volumio volumio[1140]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 13 21:26:20 volumio volumio[1140]: verbose: CURRENT POSITION 1 Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::syncState stateService stop Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus stop Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::pushState Jan 13 21:26:20 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Jan 13 21:26:20 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Jan 13 21:26:20 volumio volumio[1140]: info: No code Jan 13 21:26:20 volumio volumio[1140]: info: CoreStateMachine::pushState Jan 13 21:26:20 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Jan 13 21:26:20 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Jan 13 21:26:20 volumio volumio[1140]: info: ------------------------------ 200ms Jan 13 21:26:20 volumio volumio[1140]: info: ------------------------------ 198ms Jan 13 21:26:20 volumio volumio[1140]: info: Jan 13 21:26:20 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Jan 13 21:26:20 volumio volumio[1140]: info: Ignoring MPD Status Update Jan 13 21:26:20 volumio volumio[1140]: info: Jan 13 21:26:20 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Jan 13 21:26:20 volumio volumio[1140]: info: Ignoring MPD Status Update Jan 13 21:26:20 volumio volumio[1140]: error: updateQueue error: null Jan 13 21:26:20 volumio volumio[1140]: info: Jan 13 21:26:20 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Jan 13 21:26:20 volumio volumio[1140]: info: Ignoring MPD Status Update Jan 13 21:26:20 volumio volumio[1140]: info: ------------------------------ 209ms Jan 13 21:26:20 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 204 milliseconds Jan 13 21:26:20 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 203 milliseconds Jan 13 21:26:20 volumio volumio[1140]: info: sendMpdCommand add "NAS/Musica_Mini/PinkFloyd/Pink Floyd - Dark Side of the Moon (Entire Album).mp3" took 203 milliseconds Jan 13 21:26:20 volumio volumio[1140]: info: ------------------------------ 17ms Jan 13 21:26:20 volumio volumio[1140]: info: ------------------------------ 15ms Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Jan 13 21:26:20 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand play Jan 13 21:26:20 volumio volumio[1140]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 13 21:26:20 volumio volumio[1140]: info: ------------------------------ 236ms Jan 13 21:26:20 volumio volumio[1140]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 21:26:20 volumio volumio[1140]: TypeError: Cannot read properties of undefined (reading 'split') Jan 13 21:26:20 volumio volumio[1140]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:280:60) Jan 13 21:26:20 volumio volumio[1140]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 13 21:26:20 volumio volumio[1140]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jan 13 21:26:20 volumio volumio[1140]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 21:26:22 volumio sudo[4672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-13 21:25' Jan 13 21:26:22 volumio sudo[4672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"