May 31 18:19:02 dioracast go-librespot[550]: time="2026-05-31T18:19:02+02:00" level=trace msg="received accesspoint ping" May 31 18:19:02 dioracast go-librespot[550]: time="2026-05-31T18:19:02+02:00" level=trace msg="received accesspoint pong ack" May 31 18:19:02 dioracast go-librespot[550]: time="2026-05-31T18:19:02+02:00" level=trace msg="sent dealer ping" May 31 18:19:02 dioracast go-librespot[550]: time="2026-05-31T18:19:02+02:00" level=trace msg="received dealer pong" May 31 18:19:32 dioracast go-librespot[550]: time="2026-05-31T18:19:32+02:00" level=trace msg="sent dealer ping" May 31 18:19:32 dioracast go-librespot[550]: time="2026-05-31T18:19:32+02:00" level=trace msg="received dealer pong" May 31 18:20:02 dioracast go-librespot[550]: time="2026-05-31T18:20:02+02:00" level=trace msg="sent dealer ping" May 31 18:20:02 dioracast go-librespot[550]: time="2026-05-31T18:20:02+02:00" level=trace msg="received dealer pong" May 31 18:20:32 dioracast go-librespot[550]: time="2026-05-31T18:20:32+02:00" level=trace msg="sent dealer ping" May 31 18:20:32 dioracast go-librespot[550]: time="2026-05-31T18:20:32+02:00" level=trace msg="received dealer pong" May 31 18:20:37 dioracast volumio[1202]: info: CorePlayQueue::getTrack 0 May 31 18:20:37 dioracast volumio[1202]: info: CorePlayQueue::getTrack 7 May 31 18:20:37 dioracast volumio[1202]: info: Prefetching next song May 31 18:20:38 dioracast volumio[1202]: info: [jellyfin-play] Stream URL for Nautilius: https://jellyfin.igoadm.pl/Audio/ca5d6ab886f347e084907ff94d9758b6/stream.mpc?static=true&mediaSourceId=ca5d6ab886f347e084907ff94d9758b6&tag=f5243976441405e19e7391f98e5bfb0e May 31 18:20:38 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand addid "https://jellyfin.igoadm.pl/Audio/ca5d6ab886f347e084907ff94d9758b6/stream.mpc?static=true&mediaSourceId=ca5d6ab886f347e084907ff94d9758b6&tag=f5243976441405e19e7391f98e5bfb0e&t.mpc" May 31 18:20:38 dioracast volumio[1202]: info: May 31 18:20:38 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:38 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:38 dioracast volumio[1202]: info: sendMpdCommand addid "https://jellyfin.igoadm.pl/Audio/ca5d6ab886f347e084907ff94d9758b6/stream.mpc?static=true&mediaSourceId=ca5d6ab886f347e084907ff94d9758b6&tag=f5243976441405e19e7391f98e5bfb0e&t.mpc" took 2 milliseconds May 31 18:20:38 dioracast volumio[1202]: verbose: MPD COMMAND [object Object] May 31 18:20:38 dioracast volumio[1202]: verbose: MPD COMMAND [object Object] May 31 18:20:38 dioracast volumio[1202]: verbose: MPD COMMAND [object Object] May 31 18:20:38 dioracast volumio[1202]: info: May 31 18:20:38 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:38 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:38 dioracast volumio[1202]: info: May 31 18:20:38 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:38 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:38 dioracast volumio[1202]: info: May 31 18:20:38 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:38 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:38 dioracast volumio[1202]: info: May 31 18:20:38 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:38 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:38 dioracast volumio[1202]: info: ------------------------------ 7ms May 31 18:20:38 dioracast volumio[1202]: info: ------------------------------ 4ms May 31 18:20:38 dioracast volumio[1202]: info: ------------------------------ 3ms May 31 18:20:38 dioracast volumio[1202]: info: ------------------------------ 2ms May 31 18:20:38 dioracast volumio[1202]: info: [jellyfin-play] Prefetched and added song to MPD queue: Nautilius May 31 18:20:38 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand consume 1 May 31 18:20:38 dioracast volumio[1202]: info: May 31 18:20:38 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:38 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:38 dioracast volumio[1202]: info: May 31 18:20:38 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:38 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:38 dioracast volumio[1202]: info: May 31 18:20:38 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:38 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:38 dioracast volumio[1202]: info: ------------------------------ 7ms May 31 18:20:38 dioracast volumio[1202]: info: sendMpdCommand consume 1 took 5 milliseconds May 31 18:20:38 dioracast volumio[1202]: info: ------------------------------ 5ms May 31 18:20:38 dioracast volumio[1202]: info: ------------------------------ 5ms May 31 18:20:38 dioracast volumio[1202]: info: ------------------------------ 4ms May 31 18:20:40 dioracast volumio[1202]: info: May 31 18:20:40 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:40 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:40 dioracast volumio[1202]: info: May 31 18:20:40 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:40 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:40 dioracast volumio[1202]: info: May 31 18:20:40 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:40 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:40 dioracast volumio[1202]: info: May 31 18:20:40 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:40 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:40 dioracast volumio[1202]: info: May 31 18:20:40 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:40 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:40 dioracast volumio[1202]: info: May 31 18:20:40 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:40 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:40 dioracast volumio[1202]: info: May 31 18:20:40 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:40 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:40 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:40 dioracast volumio[1202]: info: May 31 18:20:40 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:40 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:40 dioracast volumio[1202]: info: ------------------------------ 4ms May 31 18:20:40 dioracast volumio[1202]: info: sendMpdCommand status took 3 milliseconds May 31 18:20:40 dioracast volumio[1202]: info: ------------------------------ 3ms May 31 18:20:40 dioracast volumio[1202]: info: sendMpdCommand status took 2 milliseconds May 31 18:20:40 dioracast volumio[1202]: info: ------------------------------ 2ms May 31 18:20:40 dioracast volumio[1202]: info: sendMpdCommand status took 1 milliseconds May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 18:20:40 dioracast volumio[1202]: info: sendMpdCommand status took 3 milliseconds May 31 18:20:40 dioracast volumio[1202]: info: ------------------------------ 3ms May 31 18:20:40 dioracast volumio[1202]: info: sendMpdCommand status took 2 milliseconds May 31 18:20:40 dioracast volumio[1202]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 18:20:40 dioracast volumio[1202]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 18:20:40 dioracast volumio[1202]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::parseTrackInfo May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::parseTrackInfo May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::parseTrackInfo May 31 18:20:40 dioracast volumio[1202]: info: ControllerMpd::pushState May 31 18:20:40 dioracast volumio[1202]: info: CoreCommandRouter::servicePushState May 31 18:20:40 dioracast volumio[1202]: info: CorePlayQueue::getTrack 0 May 31 18:20:40 dioracast volumio[1202]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"906 Kbps","isStreaming":false,"title":"Nautilius","artist":"Mech","album":"Bluffmania","uri":"https://jellyfin.igoadm.pl/Audio/ca5d6ab886f347e084907ff94d9758b6/stream.mpc?static=true&mediaSourceId=ca5d6ab886f347e084907ff94d9758b6&tag=f5243976441405e19e7391f98e5bfb0e&t.mpc","trackType":"mpc"} May 31 18:20:40 dioracast volumio[1202]: verbose: CURRENT POSITION 0 May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::syncState stateService play May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::syncState currentStatus play May 31 18:20:40 dioracast volumio[1202]: info: Received an update from plugin. extracting info from payload May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:40 dioracast volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 18:20:40 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:40 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:40 dioracast volumio[1202]: info: ControllerMpd::pushState May 31 18:20:40 dioracast volumio[1202]: info: CoreCommandRouter::servicePushState May 31 18:20:40 dioracast volumio[1202]: info: CorePlayQueue::getTrack 0 May 31 18:20:40 dioracast volumio[1202]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"906 Kbps","isStreaming":false,"title":"Nautilius","artist":"Mech","album":"Bluffmania","uri":"https://jellyfin.igoadm.pl/Audio/ca5d6ab886f347e084907ff94d9758b6/stream.mpc?static=true&mediaSourceId=ca5d6ab886f347e084907ff94d9758b6&tag=f5243976441405e19e7391f98e5bfb0e&t.mpc","trackType":"mpc"} May 31 18:20:40 dioracast volumio[1202]: verbose: CURRENT POSITION 0 May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::syncState stateService play May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::syncState currentStatus play May 31 18:20:40 dioracast volumio[1202]: info: Received an update from plugin. extracting info from payload May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:40 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:40 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:40 dioracast volumio[1202]: info: ControllerMpd::pushState May 31 18:20:40 dioracast volumio[1202]: info: CoreCommandRouter::servicePushState May 31 18:20:40 dioracast volumio[1202]: info: CorePlayQueue::getTrack 0 May 31 18:20:40 dioracast volumio[1202]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"906 Kbps","isStreaming":false,"title":"Nautilius","artist":"Mech","album":"Bluffmania","uri":"https://jellyfin.igoadm.pl/Audio/ca5d6ab886f347e084907ff94d9758b6/stream.mpc?static=true&mediaSourceId=ca5d6ab886f347e084907ff94d9758b6&tag=f5243976441405e19e7391f98e5bfb0e&t.mpc","trackType":"mpc"} May 31 18:20:40 dioracast volumio[1202]: verbose: CURRENT POSITION 0 May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::syncState stateService play May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::syncState currentStatus play May 31 18:20:40 dioracast volumio[1202]: info: Received an update from plugin. extracting info from payload May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:40 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:40 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:40 dioracast volumio[1202]: info: ------------------------------ 25ms May 31 18:20:40 dioracast volumio[1202]: info: ------------------------------ 24ms May 31 18:20:40 dioracast volumio[1202]: info: ------------------------------ 24ms May 31 18:20:40 dioracast volumio[1202]: info: sendMpdCommand playlistinfo took 20 milliseconds May 31 18:20:40 dioracast volumio[1202]: info: sendMpdCommand playlistinfo took 20 milliseconds May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::parseTrackInfo May 31 18:20:40 dioracast volumio[1202]: verbose: ControllerMpd::parseTrackInfo May 31 18:20:40 dioracast volumio[1202]: info: ControllerMpd::pushState May 31 18:20:40 dioracast volumio[1202]: info: CoreCommandRouter::servicePushState May 31 18:20:40 dioracast volumio[1202]: info: CorePlayQueue::getTrack 0 May 31 18:20:40 dioracast volumio[1202]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"906 Kbps","isStreaming":false,"title":"Nautilius","artist":"Mech","album":"Bluffmania","uri":"https://jellyfin.igoadm.pl/Audio/ca5d6ab886f347e084907ff94d9758b6/stream.mpc?static=true&mediaSourceId=ca5d6ab886f347e084907ff94d9758b6&tag=f5243976441405e19e7391f98e5bfb0e&t.mpc","trackType":"mpc"} May 31 18:20:40 dioracast volumio[1202]: verbose: CURRENT POSITION 0 May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::syncState stateService play May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::syncState currentStatus play May 31 18:20:40 dioracast volumio[1202]: info: Received an update from plugin. extracting info from payload May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:40 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:40 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:40 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:40 dioracast volumio[1202]: info: ------------------------------ 31ms May 31 18:20:40 dioracast volumio[1202]: info: Pushing Favourites {"favourite":false} May 31 18:20:40 dioracast volumio[1202]: info: FusionDsp - Volumio is playing May 31 18:20:40 dioracast volumio[1202]: info: FusionDsp - Volumio is playing May 31 18:20:40 dioracast volumio[1202]: info: FusionDsp - Volumio is playing May 31 18:20:40 dioracast volumio[1202]: info: FusionDsp - Volumio is playing May 31 18:20:40 dioracast volumio[1202]: info: FusionDsp - Volumio is playing May 31 18:20:40 dioracast volumio[1202]: info: FusionDsp - Volumio is playing May 31 18:20:40 dioracast volumio[1202]: info: FusionDsp - Volumio is playing May 31 18:20:40 dioracast volumio[1202]: info: FusionDsp - Volumio is playing May 31 18:20:40 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:40 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:40 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:40 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:40 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:40 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:40 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:40 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:41 dioracast volumio[1202]: info: [jellyfin-play]: Reported 'stop' for song: Piłem z diabłem bruderschaft (at 398722 ms) May 31 18:20:41 dioracast volumio[1202]: info: CoreStateMachine::startPlaybackTimer May 31 18:20:41 dioracast volumio[1202]: info: CorePlayQueue::getTrack 7 May 31 18:20:41 dioracast volumio[1202]: info: [jellyfin-play]: Reported 'start' for song: Nautilius (at 45 ms) May 31 18:20:42 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 7 May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 7 May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 7 May 31 18:20:42 dioracast volumio[1202]: info: FusionDsp - Volumio is playing May 31 18:20:42 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:42 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:42 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:42 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:42 dioracast volumio[1202]: error: updateQueue error: null May 31 18:20:42 dioracast volumio[1202]: error: updateQueue error: null May 31 18:20:42 dioracast volumio[1202]: error: updateQueue error: null May 31 18:20:42 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 5ms May 31 18:20:42 dioracast volumio[1202]: info: sendMpdCommand status took 4 milliseconds May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 4ms May 31 18:20:42 dioracast volumio[1202]: info: sendMpdCommand status took 3 milliseconds May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 3ms May 31 18:20:42 dioracast volumio[1202]: info: sendMpdCommand status took 2 milliseconds May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:42 dioracast volumio[1202]: info: ControllerMpd::pushState May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::servicePushState May 31 18:20:42 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 7 May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 7 May 31 18:20:42 dioracast volumio[1202]: 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} May 31 18:20:42 dioracast volumio[1202]: verbose: CURRENT POSITION 7 May 31 18:20:42 dioracast volumio[1202]: info: CoreStateMachine::syncState stateService stop May 31 18:20:42 dioracast volumio[1202]: info: CoreStateMachine::syncState currentStatus play May 31 18:20:42 dioracast volumio[1202]: info: CoreStateMachine::play index undefined May 31 18:20:42 dioracast volumio[1202]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:20:42 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 4 May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:42 dioracast volumio[1202]: info: ControllerMpd::pushState May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::servicePushState May 31 18:20:42 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 4 May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 4 May 31 18:20:42 dioracast volumio[1202]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd May 31 18:20:42 dioracast volumio[1202]: info: ControllerMpd::pushState May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::servicePushState May 31 18:20:42 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 4 May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 4 May 31 18:20:42 dioracast volumio[1202]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 4 May 31 18:20:42 dioracast volumio[1202]: info: CoreStateMachine::startPlaybackTimer May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 4 May 31 18:20:42 dioracast volumio[1202]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/ikopr@e07f5aab29374a898ffe85b636deea21/song@songId=052f6f87062a245d68c8eaf11b63e6b3 May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 22ms May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 21ms May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 21ms May 31 18:20:42 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 4 May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:42 dioracast volumio[1202]: error: updateQueue error: null May 31 18:20:42 dioracast volumio[1202]: info: sendMpdCommand status took 24 milliseconds May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 25ms May 31 18:20:42 dioracast volumio[1202]: info: sendMpdCommand status took 23 milliseconds May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:42 dioracast volumio[1202]: info: ControllerMpd::pushState May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::servicePushState May 31 18:20:42 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 4 May 31 18:20:42 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:42 dioracast volumio[1202]: info: CorePlayQueue::getTrack 4 May 31 18:20:42 dioracast volumio[1202]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 28ms May 31 18:20:42 dioracast volumio[1202]: info: FusionDsp - Volumio is playing May 31 18:20:42 dioracast volumio[1202]: info: FusionDsp - Volumio is not playing May 31 18:20:42 dioracast volumio[1202]: info: FusionDsp - Clipped samples monitor stopped May 31 18:20:42 dioracast volumio[1202]: info: FusionDsp - Volumio is not playing May 31 18:20:42 dioracast volumio[1202]: info: FusionDsp - Clipped samples monitor stopped May 31 18:20:42 dioracast volumio[1202]: info: FusionDsp - Volumio is not playing May 31 18:20:42 dioracast volumio[1202]: info: FusionDsp - Clipped samples monitor stopped May 31 18:20:42 dioracast volumio[1202]: info: FusionDsp - Volumio is not playing May 31 18:20:42 dioracast volumio[1202]: info: FusionDsp - Clipped samples monitor stopped May 31 18:20:42 dioracast volumio[1202]: info: FusionDsp - Volumio is not playing May 31 18:20:42 dioracast volumio[1202]: info: FusionDsp - Clipped samples monitor stopped May 31 18:20:42 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:42 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:42 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:42 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:42 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:42 dioracast volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 31 18:20:42 dioracast volumio[1202]: info: [jellyfin-play] Stream URL for Cztery ściany: https://jellyfin.igoadm.pl/Audio/052f6f87062a245d68c8eaf11b63e6b3/stream.mpc?static=true&mediaSourceId=052f6f87062a245d68c8eaf11b63e6b3&tag=24a116d49e162d166a51ae7dd3a04a0c May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand stop May 31 18:20:42 dioracast volumio[1202]: info: sendMpdCommand stop took 0 milliseconds May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand clear May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: sendMpdCommand clear took 0 milliseconds May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand load "https://jellyfin.igoadm.pl/Audio/052f6f87062a245d68c8eaf11b63e6b3/stream.mpc?static=true&mediaSourceId=052f6f87062a245d68c8eaf11b63e6b3&tag=24a116d49e162d166a51ae7dd3a04a0c&t.mpc" May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: error: updateQueue error: null May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 3ms May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand addid "https://jellyfin.igoadm.pl/Audio/052f6f87062a245d68c8eaf11b63e6b3/stream.mpc?static=true&mediaSourceId=052f6f87062a245d68c8eaf11b63e6b3&tag=24a116d49e162d166a51ae7dd3a04a0c&t.mpc" May 31 18:20:42 dioracast volumio[1202]: error: updateQueue error: null May 31 18:20:42 dioracast volumio[1202]: error: updateQueue error: null May 31 18:20:42 dioracast volumio[1202]: error: updateQueue error: null May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 229ms May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 229ms May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 228ms May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: sendMpdCommand addid "https://jellyfin.igoadm.pl/Audio/052f6f87062a245d68c8eaf11b63e6b3/stream.mpc?static=true&mediaSourceId=052f6f87062a245d68c8eaf11b63e6b3&tag=24a116d49e162d166a51ae7dd3a04a0c&t.mpc" took 2 milliseconds May 31 18:20:42 dioracast volumio[1202]: verbose: MPD COMMAND [object Object] May 31 18:20:42 dioracast volumio[1202]: verbose: MPD COMMAND [object Object] May 31 18:20:42 dioracast volumio[1202]: verbose: MPD COMMAND [object Object] May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 6ms May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 4ms May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 3ms May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 2ms May 31 18:20:42 dioracast volumio[1202]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 18:20:42 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand play May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: May 31 18:20:42 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:42 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 3ms May 31 18:20:42 dioracast volumio[1202]: info: sendMpdCommand play took 3 milliseconds May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 3ms May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 2ms May 31 18:20:42 dioracast volumio[1202]: info: ------------------------------ 3ms May 31 18:20:42 dioracast volumio[1202]: info: camilladsp respawn in 100 ms (attempt 1/10) May 31 18:20:42 dioracast kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 3 May 31 18:20:42 dioracast kernel: xhci_hcd 0000:01:00.0: @000000040cd061f0 00000000 00000000 0e000000 02048000 May 31 18:20:43 dioracast volumio[1202]: info: [jellyfin-play]: Reported 'stop' for song: Nautilius (at 839 ms) May 31 18:20:43 dioracast volumio[1202]: info: May 31 18:20:43 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:43 dioracast volumio[1202]: info: May 31 18:20:43 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:43 dioracast volumio[1202]: info: May 31 18:20:43 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:43 dioracast volumio[1202]: info: May 31 18:20:43 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:43 dioracast volumio[1202]: info: May 31 18:20:43 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand status took 9 milliseconds May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 18:20:43 dioracast volumio[1202]: info: May 31 18:20:43 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand status took 15 milliseconds May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand status took 15 milliseconds May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand status took 14 milliseconds May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand status took 15 milliseconds May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 18:20:43 dioracast volumio[1202]: info: May 31 18:20:43 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:43 dioracast volumio[1202]: info: May 31 18:20:43 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:43 dioracast volumio[1202]: info: May 31 18:20:43 dioracast volumio[1202]: ---------------------------- MPD announces system playlist update May 31 18:20:43 dioracast volumio[1202]: info: Ignoring MPD Status Update May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:43 dioracast volumio[1202]: info: May 31 18:20:43 dioracast volumio[1202]: ---------------------------- MPD announces state update: player May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::getState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::sendMpdCommand status May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand status took 18 milliseconds May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand status took 18 milliseconds May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand playlistinfo took 17 milliseconds May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand status took 15 milliseconds May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand playlistinfo took 11 milliseconds May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand playlistinfo took 11 milliseconds May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand playlistinfo took 11 milliseconds May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand playlistinfo took 10 milliseconds May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand status took 9 milliseconds May 31 18:20:43 dioracast volumio[1202]: info: sendMpdCommand status took 5 milliseconds May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseTrackInfo May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseTrackInfo May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseTrackInfo May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseTrackInfo May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseTrackInfo May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:43 dioracast volumio[1202]: verbose: ControllerMpd::parseState May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::servicePushState May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:43 dioracast volumio[1202]: info: CorePlayQueue::getTrack 4 May 31 18:20:43 dioracast volumio[1202]: 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} May 31 18:20:43 dioracast volumio[1202]: verbose: CURRENT POSITION 4 May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::syncState stateService stop May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::syncState currentStatus stop May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:43 dioracast volumio[1202]: info: No code May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:43 dioracast volumio[1202]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::servicePushState May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:43 dioracast volumio[1202]: info: CorePlayQueue::getTrack 4 May 31 18:20:43 dioracast volumio[1202]: 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} May 31 18:20:43 dioracast volumio[1202]: verbose: CURRENT POSITION 4 May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::syncState stateService stop May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::syncState currentStatus stop May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:43 dioracast volumio[1202]: info: No code May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:43 dioracast volumio[1202]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 31 18:20:43 dioracast volumio[1202]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 31 18:20:43 dioracast volumio[1202]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::servicePushState May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:43 dioracast volumio[1202]: info: CorePlayQueue::getTrack 4 May 31 18:20:43 dioracast volumio[1202]: 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} May 31 18:20:43 dioracast volumio[1202]: verbose: CURRENT POSITION 4 May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::syncState stateService stop May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::syncState currentStatus stop May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:43 dioracast volumio[1202]: info: No code May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:43 dioracast volumio[1202]: info: ControllerMpd::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::servicePushState May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:43 dioracast volumio[1202]: info: CorePlayQueue::getTrack 4 May 31 18:20:43 dioracast volumio[1202]: 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} May 31 18:20:43 dioracast volumio[1202]: verbose: CURRENT POSITION 4 May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::syncState stateService stop May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::syncState currentStatus stop May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:43 dioracast volumio[1202]: info: No code May 31 18:20:43 dioracast volumio[1202]: info: CoreStateMachine::pushState May 31 18:20:43 dioracast volumio[1202]: info: CoreCommandRouter::volumioPushState May 31 18:20:43 dioracast volumio[1202]: info: ------------------------------ 59ms May 31 18:20:43 dioracast volumio[1202]: info: ------------------------------ 70ms May 31 18:20:43 dioracast volumio[1202]: info: ------------------------------ 57ms May 31 18:20:43 dioracast volumio[1202]: info: ------------------------------ 69ms May 31 18:20:43 dioracast volumio[1202]: info: ------------------------------ 68ms May 31 18:20:43 dioracast volumio[1202]: info: ------------------------------ 67ms May 31 18:20:43 dioracast volumio[1202]: info: ------------------------------ 51ms May 31 18:20:43 dioracast volumio[1202]: info: ------------------------------ 46ms May 31 18:20:43 dioracast volumio[1202]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 18:20:43 dioracast volumio[1202]: TypeError: Cannot read properties of undefined (reading 'split') May 31 18:20:43 dioracast volumio[1202]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:280:60) May 31 18:20:43 dioracast volumio[1202]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 31 18:20:43 dioracast volumio[1202]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) May 31 18:20:43 dioracast volumio[1202]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 18:20:43 dioracast sudo[19184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-31 18:19' May 31 18:20:43 dioracast sudo[19184]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"