-- Logs begin at Sat 2025-11-01 21:59:33 UTC, end at Sun 2025-11-02 06:45:08 UTC. -- Nov 02 06:44:04 volumio volumio[1059]: error: Upnp client error: Error: This socket has been ended by the other party Nov 02 06:44:06 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState Nov 02 06:44:06 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:06 volumio volumio[1059]: info: Listing playlists Nov 02 06:44:06 volumio volumio[1059]: info: Listing playlists Nov 02 06:44:08 volumio go-librespot[1819]: time="2025-11-02T06:44:08Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DXcF6B6QPhFDv" Nov 02 06:44:08 volumio go-librespot[1819]: time="2025-11-02T06:44:08Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Nov 02 06:44:15 volumio go-librespot[1819]: time="2025-11-02T06:44:15Z" level=trace msg="sent dealer ping" Nov 02 06:44:15 volumio go-librespot[1819]: time="2025-11-02T06:44:15Z" level=trace msg="received dealer pong" Nov 02 06:44:16 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState Nov 02 06:44:16 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:17 volumio go-librespot[1819]: time="2025-11-02T06:44:17Z" level=trace msg="received accesspoint ping" Nov 02 06:44:17 volumio go-librespot[1819]: time="2025-11-02T06:44:17Z" level=trace msg="received accesspoint pong ack" Nov 02 06:44:26 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState Nov 02 06:44:26 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:26 volumio volumio[1059]: info: Listing playlists Nov 02 06:44:26 volumio volumio[1059]: info: Listing playlists Nov 02 06:44:36 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState Nov 02 06:44:36 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:42 volumio volumio[1059]: info: Clearing queue after UPNP request Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::ClearQueue Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::stop Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 06:44:43 volumio volumio[1059]: info: CorePlayQueue::clearPlayQueue Nov 02 06:44:43 volumio volumio[1059]: info: CorePlayQueue::saveQueue Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::volumioPushQueue Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: error: updateQueue error: null Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 3ms Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces state update: options Nov 02 06:44:43 volumio volumio[1059]: info: ControllerMpd::getState Nov 02 06:44:43 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces state update: options Nov 02 06:44:43 volumio volumio[1059]: info: ControllerMpd::getState Nov 02 06:44:43 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces state update: options Nov 02 06:44:43 volumio volumio[1059]: info: ControllerMpd::getState Nov 02 06:44:43 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Nov 02 06:44:43 volumio volumio[1059]: info: sendMpdCommand status took 1 milliseconds Nov 02 06:44:43 volumio volumio[1059]: info: sendMpdCommand status took 2 milliseconds Nov 02 06:44:43 volumio volumio[1059]: verbose: ControllerMpd::parseState Nov 02 06:44:43 volumio volumio[1059]: verbose: ControllerMpd::parseState Nov 02 06:44:43 volumio volumio[1059]: info: ControllerMpd::pushState Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::pushState Nov 02 06:44:43 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Nov 02 06:44:43 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:43 volumio volumio[1059]: 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} Nov 02 06:44:43 volumio volumio[1059]: verbose: CURRENT POSITION 0 Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::syncState stateService stop Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::pushState Nov 02 06:44:43 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Nov 02 06:44:43 volumio volumio[1059]: info: No code Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::pushState Nov 02 06:44:43 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Nov 02 06:44:43 volumio volumio[1059]: info: ControllerMpd::pushState Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::pushState Nov 02 06:44:43 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Nov 02 06:44:43 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:43 volumio volumio[1059]: 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} Nov 02 06:44:43 volumio volumio[1059]: verbose: CURRENT POSITION 0 Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::syncState stateService stop Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::pushState Nov 02 06:44:43 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Nov 02 06:44:43 volumio volumio[1059]: info: No code Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::pushState Nov 02 06:44:43 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 13ms Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 13ms Nov 02 06:44:43 volumio volumio[1059]: info: Starting UPNP Playback Nov 02 06:44:43 volumio volumio[1059]: info: Preparing playback through UPNP Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState Nov 02 06:44:43 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 02 06:44:43 volumio volumio[1059]: info: sendMpdCommand status took 13 milliseconds Nov 02 06:44:43 volumio volumio[1059]: verbose: ControllerMpd::parseState Nov 02 06:44:43 volumio volumio[1059]: info: ControllerMpd::pushState Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::pushState Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Nov 02 06:44:43 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:43 volumio volumio[1059]: 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} Nov 02 06:44:43 volumio volumio[1059]: verbose: CURRENT POSITION 0 Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::syncState stateService stop Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::pushState Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Nov 02 06:44:43 volumio volumio[1059]: info: No code Nov 02 06:44:43 volumio volumio[1059]: info: CoreStateMachine::pushState Nov 02 06:44:43 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 21ms Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 2ms Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 0ms Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: Nov 02 06:44:43 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:43 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 0ms Nov 02 06:44:43 volumio volumio[1059]: info: ------------------------------ 0ms Nov 02 06:44:44 volumio volumio[1059]: info: Nov 02 06:44:44 volumio volumio[1059]: ---------------------------- MPD announces state update: player Nov 02 06:44:44 volumio volumio[1059]: info: ControllerMpd::getState Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Nov 02 06:44:44 volumio volumio[1059]: info: Nov 02 06:44:44 volumio volumio[1059]: ---------------------------- MPD announces state update: player Nov 02 06:44:44 volumio volumio[1059]: info: ControllerMpd::getState Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Nov 02 06:44:44 volumio volumio[1059]: info: Nov 02 06:44:44 volumio volumio[1059]: ---------------------------- MPD announces state update: player Nov 02 06:44:44 volumio volumio[1059]: info: ControllerMpd::getState Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Nov 02 06:44:44 volumio volumio[1059]: info: sendMpdCommand status took 241 milliseconds Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::parseState Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 06:44:44 volumio volumio[1059]: info: Nov 02 06:44:44 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:44 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:44 volumio volumio[1059]: info: Nov 02 06:44:44 volumio volumio[1059]: ---------------------------- MPD announces state update: player Nov 02 06:44:44 volumio volumio[1059]: info: ControllerMpd::getState Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Nov 02 06:44:44 volumio volumio[1059]: info: sendMpdCommand status took 241 milliseconds Nov 02 06:44:44 volumio volumio[1059]: info: sendMpdCommand status took 241 milliseconds Nov 02 06:44:44 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::parseState Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::parseState Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Nov 02 06:44:44 volumio volumio[1059]: info: ControllerMpd::pushState Nov 02 06:44:44 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Nov 02 06:44:44 volumio volumio[1059]: verbose: In UPNP mode Nov 02 06:44:44 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"405 Kbps","isStreaming":false,"title":"002b2ebde6ab44098247b4ae7d5949ef.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.3:8097/flow/gDXc8ZXT/uuid:548a2cb5-2925-37ea-1818-f0d4e2ecf286/002b2ebde6ab44098247b4ae7d5949ef.flac","trackType":"flac"} Nov 02 06:44:44 volumio volumio[1059]: verbose: CURRENT POSITION 0 Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Nov 02 06:44:44 volumio volumio[1059]: info: ------------------------------ 246ms Nov 02 06:44:44 volumio volumio[1059]: info: Nov 02 06:44:44 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:44 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:44 volumio volumio[1059]: info: Nov 02 06:44:44 volumio volumio[1059]: ---------------------------- MPD announces state update: player Nov 02 06:44:44 volumio volumio[1059]: info: ControllerMpd::getState Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Nov 02 06:44:44 volumio volumio[1059]: info: Nov 02 06:44:44 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Nov 02 06:44:44 volumio volumio[1059]: info: Ignoring MPD Status Update Nov 02 06:44:44 volumio volumio[1059]: info: Nov 02 06:44:44 volumio volumio[1059]: ---------------------------- MPD announces state update: player Nov 02 06:44:44 volumio volumio[1059]: info: ControllerMpd::getState Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Nov 02 06:44:44 volumio volumio[1059]: info: ------------------------------ 4ms Nov 02 06:44:44 volumio volumio[1059]: info: sendMpdCommand status took 4 milliseconds Nov 02 06:44:44 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 02 06:44:44 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 02 06:44:44 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:44 volumio volumio[1059]: info: sendMpdCommand status took 1 milliseconds Nov 02 06:44:44 volumio volumio[1059]: info: ------------------------------ 1ms Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::parseState Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::parseState Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 06:44:44 volumio volumio[1059]: info: ControllerMpd::pushState Nov 02 06:44:44 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Nov 02 06:44:44 volumio volumio[1059]: verbose: In UPNP mode Nov 02 06:44:44 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":753,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"363 Kbps","isStreaming":false,"title":"002b2ebde6ab44098247b4ae7d5949ef.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.3:8097/flow/gDXc8ZXT/uuid:548a2cb5-2925-37ea-1818-f0d4e2ecf286/002b2ebde6ab44098247b4ae7d5949ef.flac","trackType":"flac"} Nov 02 06:44:44 volumio volumio[1059]: verbose: CURRENT POSITION 0 Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Nov 02 06:44:44 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Nov 02 06:44:44 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::pushState Nov 02 06:44:44 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:44 volumio volumio[1059]: info: ControllerMpd::pushState Nov 02 06:44:44 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Nov 02 06:44:44 volumio volumio[1059]: verbose: In UPNP mode Nov 02 06:44:44 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":836,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"374 Kbps","isStreaming":false,"title":"002b2ebde6ab44098247b4ae7d5949ef.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.3:8097/flow/gDXc8ZXT/uuid:548a2cb5-2925-37ea-1818-f0d4e2ecf286/002b2ebde6ab44098247b4ae7d5949ef.flac","trackType":"flac"} Nov 02 06:44:44 volumio volumio[1059]: verbose: CURRENT POSITION 0 Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Nov 02 06:44:44 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Nov 02 06:44:44 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::pushState Nov 02 06:44:44 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:44 volumio volumio[1059]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 02 06:44:44 volumio volumio[1059]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 02 06:44:44 volumio volumio[1059]: info: ------------------------------ 249ms Nov 02 06:44:44 volumio volumio[1059]: info: ------------------------------ 247ms Nov 02 06:44:44 volumio volumio[1059]: info: sendMpdCommand status took 4 milliseconds Nov 02 06:44:44 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 02 06:44:44 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::parseState Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Nov 02 06:44:44 volumio volumio[1059]: info: ControllerMpd::pushState Nov 02 06:44:44 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Nov 02 06:44:44 volumio volumio[1059]: verbose: In UPNP mode Nov 02 06:44:44 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"371 Kbps","isStreaming":false,"title":"002b2ebde6ab44098247b4ae7d5949ef.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.3:8097/flow/gDXc8ZXT/uuid:548a2cb5-2925-37ea-1818-f0d4e2ecf286/002b2ebde6ab44098247b4ae7d5949ef.flac","trackType":"flac"} Nov 02 06:44:44 volumio volumio[1059]: verbose: CURRENT POSITION 0 Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Nov 02 06:44:44 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Nov 02 06:44:44 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::pushState Nov 02 06:44:44 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:44 volumio volumio[1059]: info: ControllerMpd::pushState Nov 02 06:44:44 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Nov 02 06:44:44 volumio volumio[1059]: verbose: In UPNP mode Nov 02 06:44:44 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"371 Kbps","isStreaming":false,"title":"002b2ebde6ab44098247b4ae7d5949ef.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.3:8097/flow/gDXc8ZXT/uuid:548a2cb5-2925-37ea-1818-f0d4e2ecf286/002b2ebde6ab44098247b4ae7d5949ef.flac","trackType":"flac"} Nov 02 06:44:44 volumio volumio[1059]: verbose: CURRENT POSITION 0 Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Nov 02 06:44:44 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Nov 02 06:44:44 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::pushState Nov 02 06:44:44 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:44 volumio volumio[1059]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 02 06:44:44 volumio volumio[1059]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 02 06:44:44 volumio volumio[1059]: info: ------------------------------ 10ms Nov 02 06:44:44 volumio volumio[1059]: info: ------------------------------ 7ms Nov 02 06:44:44 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 02 06:44:44 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Nov 02 06:44:44 volumio volumio[1059]: info: ControllerMpd::pushState Nov 02 06:44:44 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Nov 02 06:44:44 volumio volumio[1059]: verbose: In UPNP mode Nov 02 06:44:44 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"371 Kbps","isStreaming":false,"title":"002b2ebde6ab44098247b4ae7d5949ef.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.1.3:8097/flow/gDXc8ZXT/uuid:548a2cb5-2925-37ea-1818-f0d4e2ecf286/002b2ebde6ab44098247b4ae7d5949ef.flac","trackType":"flac"} Nov 02 06:44:44 volumio volumio[1059]: verbose: CURRENT POSITION 0 Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Nov 02 06:44:44 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Nov 02 06:44:44 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 02 06:44:44 volumio volumio[1059]: info: CoreStateMachine::pushState Nov 02 06:44:44 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:44 volumio volumio[1059]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 02 06:44:44 volumio volumio[1059]: info: ------------------------------ 8ms Nov 02 06:44:45 volumio go-librespot[1819]: time="2025-11-02T06:44:45Z" level=trace msg="sent dealer ping" Nov 02 06:44:45 volumio go-librespot[1819]: time="2025-11-02T06:44:45Z" level=trace msg="received dealer pong" Nov 02 06:44:46 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState Nov 02 06:44:46 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:46 volumio volumio[1059]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Nov 02 06:44:46 volumio volumio[1059]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 02 06:44:46 volumio volumio[1059]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 02 06:44:46 volumio volumio[1059]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Nov 02 06:44:46 volumio volumio[1059]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 02 06:44:46 volumio volumio[1059]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Nov 02 06:44:46 volumio volumio[1059]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Nov 02 06:44:46 volumio volumio[1059]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 02 06:44:46 volumio volumio[1059]: at /volumio/node_modules/express/lib/router/index.js:281:22 Nov 02 06:44:46 volumio volumio[1059]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 02 06:44:46 volumio volumio[1059]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 02 06:44:46 volumio volumio[1059]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Nov 02 06:44:46 volumio volumio[1059]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 02 06:44:46 volumio volumio[1059]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Nov 02 06:44:46 volumio volumio[1059]: at /volumio/node_modules/express/lib/router/index.js:284:7 Nov 02 06:44:46 volumio volumio[1059]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 02 06:44:46 volumio volumio[1059]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 02 06:44:46 volumio volumio[1059]: info: Listing playlists Nov 02 06:44:46 volumio volumio[1059]: info: Listing playlists Nov 02 06:44:56 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState Nov 02 06:44:56 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:44:56 volumio volumio[1059]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Nov 02 06:44:56 volumio volumio[1059]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 02 06:44:56 volumio volumio[1059]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 02 06:44:56 volumio volumio[1059]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Nov 02 06:44:56 volumio volumio[1059]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 02 06:44:56 volumio volumio[1059]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Nov 02 06:44:56 volumio volumio[1059]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Nov 02 06:44:56 volumio volumio[1059]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 02 06:44:56 volumio volumio[1059]: at /volumio/node_modules/express/lib/router/index.js:281:22 Nov 02 06:44:56 volumio volumio[1059]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 02 06:44:56 volumio volumio[1059]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 02 06:44:56 volumio volumio[1059]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Nov 02 06:44:56 volumio volumio[1059]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 02 06:44:56 volumio volumio[1059]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Nov 02 06:44:56 volumio volumio[1059]: at /volumio/node_modules/express/lib/router/index.js:284:7 Nov 02 06:44:56 volumio volumio[1059]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 02 06:44:56 volumio volumio[1059]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 02 06:45:06 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState Nov 02 06:45:06 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:45:06 volumio volumio[1059]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Nov 02 06:45:06 volumio volumio[1059]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 02 06:45:06 volumio volumio[1059]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 02 06:45:06 volumio volumio[1059]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Nov 02 06:45:06 volumio volumio[1059]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 02 06:45:06 volumio volumio[1059]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Nov 02 06:45:06 volumio volumio[1059]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Nov 02 06:45:06 volumio volumio[1059]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 02 06:45:06 volumio volumio[1059]: at /volumio/node_modules/express/lib/router/index.js:281:22 Nov 02 06:45:06 volumio volumio[1059]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 02 06:45:06 volumio volumio[1059]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 02 06:45:06 volumio volumio[1059]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Nov 02 06:45:06 volumio volumio[1059]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Nov 02 06:45:06 volumio volumio[1059]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Nov 02 06:45:06 volumio volumio[1059]: at /volumio/node_modules/express/lib/router/index.js:284:7 Nov 02 06:45:06 volumio volumio[1059]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Nov 02 06:45:06 volumio volumio[1059]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Nov 02 06:45:06 volumio volumio[1059]: info: Listing playlists Nov 02 06:45:06 volumio volumio[1059]: info: Listing playlists Nov 02 06:45:08 volumio volumio[1059]: verbose: New Socket.io Connection to 192.168.1.186 from 192.168.1.237 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Nov 02 06:45:08 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 06:45:08 volumio volumio[1059]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 06:45:08 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 06:45:08 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState Nov 02 06:45:08 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Nov 02 06:45:08 volumio volumio[1059]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 06:45:08 volumio volumio[1059]: TypeError: Cannot read property 'name' of undefined Nov 02 06:45:08 volumio volumio[1059]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 02 06:45:08 volumio volumio[1059]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 02 06:45:08 volumio volumio[1059]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Nov 02 06:45:08 volumio volumio[1059]: at Socket.emit (events.js:400:28) Nov 02 06:45:08 volumio volumio[1059]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 02 06:45:08 volumio volumio[1059]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Nov 02 06:45:08 volumio volumio[1059]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 06:45:08 volumio sudo[16429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 06:44 Nov 02 06:45:08 volumio sudo[16429]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"