-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-10-16 09:01:07 CEST. -- Oct 16 09:00:01 volumio-2 volumio[960]: info: CoreCommandRouter::volumioGetState Oct 16 09:00:01 volumio-2 volumio[960]: info: CorePlayQueue::getTrack 0 Oct 16 09:00:07 volumio-2 volumiologrotate[639]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 16 09:00:07 volumio-2 volumiologrotate[639]: ls: cannot access '2': No such file or directory Oct 16 09:00:11 volumio-2 volumio[960]: info: CoreCommandRouter::volumioGetState Oct 16 09:00:11 volumio-2 volumio[960]: info: CorePlayQueue::getTrack 0 Oct 16 09:00:11 volumio-2 volumio[960]: info: Listing playlists Oct 16 09:00:11 volumio-2 volumio[960]: info: Listing playlists Oct 16 09:00:21 volumio-2 volumio[960]: info: CoreCommandRouter::volumioGetState Oct 16 09:00:21 volumio-2 volumio[960]: info: CorePlayQueue::getTrack 0 Oct 16 09:00:31 volumio-2 volumio[960]: info: CoreCommandRouter::volumioGetState Oct 16 09:00:31 volumio-2 volumio[960]: info: CorePlayQueue::getTrack 0 Oct 16 09:00:31 volumio-2 volumio[960]: info: Listing playlists Oct 16 09:00:31 volumio-2 volumio[960]: info: Listing playlists Oct 16 09:00:41 volumio-2 volumio[960]: info: CoreCommandRouter::volumioGetState Oct 16 09:00:41 volumio-2 volumio[960]: info: CorePlayQueue::getTrack 0 Oct 16 09:00:51 volumio-2 volumio[960]: info: CoreCommandRouter::volumioGetState Oct 16 09:00:51 volumio-2 volumio[960]: info: CorePlayQueue::getTrack 0 Oct 16 09:00:51 volumio-2 volumio[960]: info: Listing playlists Oct 16 09:00:51 volumio-2 volumio[960]: info: Listing playlists Oct 16 09:00:53 volumio-2 volumio[960]: info: Clearing queue after UPNP request Oct 16 09:00:54 volumio-2 volumio[960]: info: CoreStateMachine::ClearQueue Oct 16 09:00:54 volumio-2 volumio[960]: info: CoreStateMachine::stop Oct 16 09:00:54 volumio-2 volumio[960]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 09:00:54 volumio-2 volumio[960]: info: CorePlayQueue::clearPlayQueue Oct 16 09:00:54 volumio-2 volumio[960]: info: CorePlayQueue::saveQueue Oct 16 09:00:54 volumio-2 volumio[960]: info: CoreCommandRouter::volumioPushState Oct 16 09:00:54 volumio-2 volumio[960]: info: CoreCommandRouter::volumioPushQueue Oct 16 09:00:54 volumio-2 volumio[960]: error: Upnp client error: Error: This socket has been ended by the other party Oct 16 09:00:55 volumio-2 volumio[960]: info: Starting UPNP Playback Oct 16 09:00:55 volumio-2 volumio[960]: info: Preparing playback through UPNP Oct 16 09:00:55 volumio-2 volumio[960]: info: CoreCommandRouter::volumioGetState Oct 16 09:00:55 volumio-2 volumio[960]: info: CorePlayQueue::getTrack 0 Oct 16 09:00:55 volumio-2 volumio[960]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 5ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 3ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 3ms Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 3ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 3ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 2ms Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 3ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 3ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 2ms Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 3ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 3ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 2ms Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 3ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 2ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 2ms Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 3ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 2ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 1ms Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces state update: player Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::getState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces state update: player Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::getState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces state update: player Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::getState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces state update: player Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand status took 5 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::getState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand status took 5 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand status took 3 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces state update: player Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::getState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces state update: player Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::getState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand status took 11 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand status took 6 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand status took 2 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::servicePushState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: In UPNP mode Oct 16 09:00:56 volumio-2 volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":"None","album":"None","uri":"http://192.168.0.62:8097/flow/6hmMTQov/media_player.volumio_2_upnp_av_2/24412aea27794a03b59c51168b1f0a82.mp3","trackType":"mp3"} Oct 16 09:00:56 volumio-2 volumio[960]: verbose: CURRENT POSITION 0 Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState stateService play Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState currentStatus stop Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::servicePushState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: In UPNP mode Oct 16 09:00:56 volumio-2 volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":"None","album":"None","uri":"http://192.168.0.62:8097/flow/6hmMTQov/media_player.volumio_2_upnp_av_2/24412aea27794a03b59c51168b1f0a82.mp3","trackType":"mp3"} Oct 16 09:00:56 volumio-2 volumio[960]: verbose: CURRENT POSITION 0 Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState stateService play Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:00:56 volumio-2 volumio[960]: info: Received an update from plugin. extracting info from payload Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::volumioPushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::volumioPushState Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::servicePushState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: In UPNP mode Oct 16 09:00:56 volumio-2 volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":"None","album":"None","uri":"http://192.168.0.62:8097/flow/6hmMTQov/media_player.volumio_2_upnp_av_2/24412aea27794a03b59c51168b1f0a82.mp3","trackType":"mp3"} Oct 16 09:00:56 volumio-2 volumio[960]: verbose: CURRENT POSITION 0 Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState stateService play Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:00:56 volumio-2 volumio[960]: info: Received an update from plugin. extracting info from payload Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::volumioPushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::volumioPushState Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 37ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 43ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 42ms Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces state update: player Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand playlistinfo took 30 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::getState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::servicePushState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: In UPNP mode Oct 16 09:00:56 volumio-2 volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":"None","album":"None","uri":"http://192.168.0.62:8097/flow/6hmMTQov/media_player.volumio_2_upnp_av_2/24412aea27794a03b59c51168b1f0a82.mp3","trackType":"mp3"} Oct 16 09:00:56 volumio-2 volumio[960]: verbose: CURRENT POSITION 0 Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState stateService play Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:00:56 volumio-2 volumio[960]: info: Received an update from plugin. extracting info from payload Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::volumioPushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::volumioPushState Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::servicePushState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: In UPNP mode Oct 16 09:00:56 volumio-2 volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24412aea27794a03b59c51168b1f0a82.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.0.62:8097/flow/6hmMTQov/media_player.volumio_2_upnp_av_2/24412aea27794a03b59c51168b1f0a82.mp3","trackType":"mp3"} Oct 16 09:00:56 volumio-2 volumio[960]: verbose: CURRENT POSITION 0 Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState stateService play Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:00:56 volumio-2 volumio[960]: info: Received an update from plugin. extracting info from payload Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CorePlayQueue::getTrack 0 Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::servicePushState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: In UPNP mode Oct 16 09:00:56 volumio-2 volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24412aea27794a03b59c51168b1f0a82.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.0.62:8097/flow/6hmMTQov/media_player.volumio_2_upnp_av_2/24412aea27794a03b59c51168b1f0a82.mp3","trackType":"mp3"} Oct 16 09:00:56 volumio-2 volumio[960]: verbose: CURRENT POSITION 0 Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState stateService play Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:00:56 volumio-2 volumio[960]: info: Received an update from plugin. extracting info from payload Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CorePlayQueue::getTrack 0 Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 60ms Oct 16 09:00:56 volumio-2 volumio[960]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 16 09:00:56 volumio-2 volumio[960]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 55ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 51ms Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces state update: player Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::getState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces system playlist update Oct 16 09:00:56 volumio-2 volumio[960]: info: Ignoring MPD Status Update Oct 16 09:00:56 volumio-2 volumio[960]: info: Oct 16 09:00:56 volumio-2 volumio[960]: ---------------------------- MPD announces state update: player Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::getState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 27ms Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand status took 25 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 5ms Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand status took 4 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 3ms Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand status took 3 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:00:56 volumio-2 volumio[960]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::servicePushState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: In UPNP mode Oct 16 09:00:56 volumio-2 volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"24412aea27794a03b59c51168b1f0a82.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.0.62:8097/flow/6hmMTQov/media_player.volumio_2_upnp_av_2/24412aea27794a03b59c51168b1f0a82.mp3","trackType":"mp3"} Oct 16 09:00:56 volumio-2 volumio[960]: verbose: CURRENT POSITION 0 Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState stateService play Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:00:56 volumio-2 volumio[960]: info: Received an update from plugin. extracting info from payload Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CorePlayQueue::getTrack 0 Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::servicePushState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: In UPNP mode Oct 16 09:00:56 volumio-2 volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"24412aea27794a03b59c51168b1f0a82.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.0.62:8097/flow/6hmMTQov/media_player.volumio_2_upnp_av_2/24412aea27794a03b59c51168b1f0a82.mp3","trackType":"mp3"} Oct 16 09:00:56 volumio-2 volumio[960]: verbose: CURRENT POSITION 0 Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState stateService play Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:00:56 volumio-2 volumio[960]: info: Received an update from plugin. extracting info from payload Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CorePlayQueue::getTrack 0 Oct 16 09:00:56 volumio-2 volumio[960]: info: ControllerMpd::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::servicePushState Oct 16 09:00:56 volumio-2 volumio[960]: verbose: In UPNP mode Oct 16 09:00:56 volumio-2 volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"24412aea27794a03b59c51168b1f0a82.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.0.62:8097/flow/6hmMTQov/media_player.volumio_2_upnp_av_2/24412aea27794a03b59c51168b1f0a82.mp3","trackType":"mp3"} Oct 16 09:00:56 volumio-2 volumio[960]: verbose: CURRENT POSITION 0 Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState stateService play Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:00:56 volumio-2 volumio[960]: info: Received an update from plugin. extracting info from payload Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 16 09:00:56 volumio-2 volumio[960]: info: CoreStateMachine::pushState Oct 16 09:00:56 volumio-2 volumio[960]: info: CorePlayQueue::getTrack 0 Oct 16 09:00:56 volumio-2 volumio[960]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 16 09:00:56 volumio-2 volumio[960]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 16 09:00:56 volumio-2 volumio[960]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 39ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 17ms Oct 16 09:00:56 volumio-2 volumio[960]: info: ------------------------------ 17ms Oct 16 09:01:00 volumio-2 dhcpcd[873]: wlan0: carrier lost Oct 16 09:01:00 volumio-2 wpa_supplicant[861]: wlan0: Associated with 60:b5:8d:35:70:94 Oct 16 09:01:00 volumio-2 wpa_supplicant[861]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:b5:8d:35:70:94 completed [id=0 id_str=] Oct 16 09:01:00 volumio-2 wpa_supplicant[861]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 16 09:01:00 volumio-2 avahi-daemon[629]: Withdrawing address record for 192.168.0.103 on wlan0. Oct 16 09:01:00 volumio-2 avahi-daemon[629]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.103. Oct 16 09:01:00 volumio-2 avahi-daemon[629]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 16 09:01:00 volumio-2 dhcpcd[873]: wlan0: deleting route to 192.168.0.0/24 Oct 16 09:01:00 volumio-2 dhcpcd[873]: wlan0: deleting default route via 192.168.0.1 Oct 16 09:01:00 volumio-2 volumio[960]: info: Discovery: A device disappeared from network Oct 16 09:01:00 volumio-2 dhcpcd[873]: wlan0: carrier acquired Oct 16 09:01:00 volumio-2 dhcpcd[873]: wlan0: IAID dd:bc:e5:f2 Oct 16 09:01:00 volumio-2 dhcpcd[873]: wlan0: rebinding lease of 192.168.0.103 Oct 16 09:01:00 volumio-2 dhcpcd[873]: wlan0: probing address 192.168.0.103/24 Oct 16 09:01:01 volumio-2 dhcpcd[873]: wlan0: soliciting an IPv6 router Oct 16 09:01:01 volumio-2 ntpd[760]: Deleting interface #3 wlan0, 192.168.0.103#123, interface stats: received=147, sent=147, dropped=0, active_time=156 secs Oct 16 09:01:01 volumio-2 ntpd[760]: 86.59.113.124 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 37.252.189.117 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 83.215.130.11 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 151.236.30.71 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 91.206.8.36 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 152.53.15.127 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 78.41.116.149 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 46.102.157.67 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 193.171.23.163 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 152.53.132.244 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 178.251.64.52 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 91.206.8.34 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 162.159.200.1 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 91.206.237.2 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 162.159.200.123 local addr 192.168.0.103 -> Oct 16 09:01:01 volumio-2 ntpd[760]: 185.144.161.170 local addr 192.168.0.103 -> Oct 16 09:01:06 volumio-2 dhcpcd[873]: wlan0: leased 192.168.0.103 for 864000 seconds Oct 16 09:01:06 volumio-2 avahi-daemon[629]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.103. Oct 16 09:01:06 volumio-2 avahi-daemon[629]: New relevant interface wlan0.IPv4 for mDNS. Oct 16 09:01:06 volumio-2 avahi-daemon[629]: Registering new address record for 192.168.0.103 on wlan0.IPv4. Oct 16 09:01:06 volumio-2 dhcpcd[873]: wlan0: adding route to 192.168.0.0/24 Oct 16 09:01:06 volumio-2 dhcpcd[873]: wlan0: adding default route via 192.168.0.1 Oct 16 09:01:06 volumio-2 volumio[960]: info: CoreCommandRouter::volumioGetState Oct 16 09:01:06 volumio-2 volumio[960]: info: CorePlayQueue::getTrack 0 Oct 16 09:01:06 volumio-2 volumio[960]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Oct 16 09:01:06 volumio-2 volumio[960]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Oct 16 09:01:06 volumio-2 volumio[960]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Oct 16 09:01:06 volumio-2 volumio[960]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Oct 16 09:01:06 volumio-2 volumio[960]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 16 09:01:06 volumio-2 volumio[960]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Oct 16 09:01:06 volumio-2 volumio[960]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Oct 16 09:01:06 volumio-2 volumio[960]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 16 09:01:06 volumio-2 volumio[960]: at /volumio/node_modules/express/lib/router/index.js:281:22 Oct 16 09:01:06 volumio-2 volumio[960]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Oct 16 09:01:06 volumio-2 volumio[960]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Oct 16 09:01:06 volumio-2 volumio[960]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Oct 16 09:01:06 volumio-2 volumio[960]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Oct 16 09:01:06 volumio-2 volumio[960]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Oct 16 09:01:06 volumio-2 volumio[960]: at /volumio/node_modules/express/lib/router/index.js:284:7 Oct 16 09:01:06 volumio-2 volumio[960]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Oct 16 09:01:06 volumio-2 volumio[960]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Oct 16 09:01:07 volumio-2 volumio[960]: info: Listing playlists Oct 16 09:01:07 volumio-2 volumio[960]: info: Listing playlists Oct 16 09:01:07 volumio-2 volumio[960]: info: Discovery: adding 75a255ea-049f-4c3b-bd31-8980e9c72f51 Oct 16 09:01:07 volumio-2 volumio[960]: info: Discovery: Found device Volumio 2 Oct 16 09:01:07 volumio-2 volumio[960]: info: CoreCommandRouter::volumioGetState Oct 16 09:01:07 volumio-2 volumio[960]: info: CorePlayQueue::getTrack 0 Oct 16 09:01:07 volumio-2 volumio[960]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 09:01:07 volumio-2 volumio[960]: TypeError: Cannot read property 'name' of undefined Oct 16 09:01:07 volumio-2 volumio[960]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Oct 16 09:01:07 volumio-2 volumio[960]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Oct 16 09:01:07 volumio-2 volumio[960]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:272:40) Oct 16 09:01:07 volumio-2 volumio[960]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Oct 16 09:01:07 volumio-2 volumio[960]: at Browser.emit (events.js:315:20) Oct 16 09:01:07 volumio-2 volumio[960]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Oct 16 09:01:07 volumio-2 volumio[960]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Oct 16 09:01:07 volumio-2 volumio[960]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Oct 16 09:01:07 volumio-2 volumio[960]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Oct 16 09:01:07 volumio-2 volumio[960]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Oct 16 09:01:07 volumio-2 volumio[960]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Oct 16 09:01:07 volumio-2 volumio[960]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Oct 16 09:01:07 volumio-2 volumio[960]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 09:01:07 volumio-2 volumiologrotate[639]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 16 09:01:07 volumio-2 volumiologrotate[639]: ls: cannot access '2': No such file or directory Oct 16 09:01:07 volumio-2 sudo[1731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 09:00 Oct 16 09:01:07 volumio-2 sudo[1731]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"