-- Logs begin at Wed 2025-07-02 20:10:58 +07, end at Wed 2025-07-02 20:31:39 +07. --
Jul 02 20:30:05 volumio volumio[1121]: info: Clearing queue after UPNP request
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::ClearQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::stop
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 02 20:30:06 volumio volumio[1121]: info: CorePlayQueue::clearPlayQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CorePlayQueue::saveQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioPushQueue
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: error: updateQueue error: null
Jul 02 20:30:06 volumio volumio[1121]: error: updateQueue error: null
Jul 02 20:30:06 volumio volumio[1121]: error: updateQueue error: null
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 0ms
Jul 02 20:30:06 volumio volumio[1121]: info: Starting UPNP Playback
Jul 02 20:30:06 volumio volumio[1121]: info: Preparing playback through UPNP
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState
Jul 02 20:30:06 volumio volumio[1121]: info: CorePlayQueue::getTrack 0
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 0ms
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 2ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 0ms
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces system playlist update
Jul 02 20:30:06 volumio volumio[1121]: info: Ignoring MPD Status Update
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 1ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 0ms
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces state update: player
Jul 02 20:30:06 volumio volumio[1121]: info: ControllerMpd::getState
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces state update: player
Jul 02 20:30:06 volumio volumio[1121]: info: ControllerMpd::getState
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces state update: player
Jul 02 20:30:06 volumio volumio[1121]: info: ControllerMpd::getState
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces state update: player
Jul 02 20:30:06 volumio volumio[1121]: info: ControllerMpd::getState
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces state update: player
Jul 02 20:30:06 volumio volumio[1121]: info: ControllerMpd::getState
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status
Jul 02 20:30:06 volumio volumio[1121]: info:
Jul 02 20:30:06 volumio volumio[1121]: ---------------------------- MPD announces state update: player
Jul 02 20:30:06 volumio volumio[1121]: info: ControllerMpd::getState
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status
Jul 02 20:30:06 volumio volumio[1121]: info: sendMpdCommand status took 4 milliseconds
Jul 02 20:30:06 volumio volumio[1121]: info: sendMpdCommand status took 4 milliseconds
Jul 02 20:30:06 volumio volumio[1121]: info: sendMpdCommand status took 3 milliseconds
Jul 02 20:30:06 volumio volumio[1121]: info: sendMpdCommand status took 4 milliseconds
Jul 02 20:30:06 volumio volumio[1121]: info: sendMpdCommand status took 2 milliseconds
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::parseState
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::parseState
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::parseState
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::parseState
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::parseState
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 02 20:30:06 volumio volumio[1121]: info: sendMpdCommand status took 4 milliseconds
Jul 02 20:30:06 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 02 20:30:06 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 1 milliseconds
Jul 02 20:30:06 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 1 milliseconds
Jul 02 20:30:06 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 1 milliseconds
Jul 02 20:30:06 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 1 milliseconds
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::parseState
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo
Jul 02 20:30:06 volumio volumio[1121]: info: ControllerMpd::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::servicePushState
Jul 02 20:30:06 volumio volumio[1121]: verbose: In UPNP mode
Jul 02 20:30:06 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":391,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Feel","artist":"Jacob Collier","album":"Djesse Vol. 2","uri":"http://192.168.1.5:15269/tidal/113133551.flac","trackType":"tidal"}
Jul 02 20:30:06 volumio volumio[1121]: verbose: CURRENT POSITION 0
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus stop
Jul 02 20:30:06 volumio volumio[1121]: info: ControllerMpd::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::servicePushState
Jul 02 20:30:06 volumio volumio[1121]: verbose: In UPNP mode
Jul 02 20:30:06 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113,"duration":391,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"608 Kbps","isStreaming":false,"title":"Feel","artist":"Jacob Collier","album":"Djesse Vol. 2","uri":"http://192.168.1.5:15269/tidal/113133551.flac","trackType":"tidal"}
Jul 02 20:30:06 volumio volumio[1121]: verbose: CURRENT POSITION 0
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus play
Jul 02 20:30:06 volumio volumio[1121]: info: Received an update from plugin. extracting info from payload
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState
Jul 02 20:30:06 volumio volumio[1121]: info: ControllerMpd::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::servicePushState
Jul 02 20:30:06 volumio volumio[1121]: verbose: In UPNP mode
Jul 02 20:30:06 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":182,"duration":391,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"551 Kbps","isStreaming":false,"title":"Feel","artist":"Jacob Collier","album":"Djesse Vol. 2","uri":"http://192.168.1.5:15269/tidal/113133551.flac","trackType":"tidal"}
Jul 02 20:30:06 volumio volumio[1121]: verbose: CURRENT POSITION 0
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus play
Jul 02 20:30:06 volumio volumio[1121]: info: Received an update from plugin. extracting info from payload
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState
Jul 02 20:30:06 volumio volumio[1121]: info: ControllerMpd::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::servicePushState
Jul 02 20:30:06 volumio volumio[1121]: verbose: In UPNP mode
Jul 02 20:30:06 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":227,"duration":391,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"551 Kbps","isStreaming":false,"title":"Feel","artist":"Jacob Collier","album":"Djesse Vol. 2","uri":"http://192.168.1.5:15269/tidal/113133551.flac","trackType":"tidal"}
Jul 02 20:30:06 volumio volumio[1121]: verbose: CURRENT POSITION 0
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus play
Jul 02 20:30:06 volumio volumio[1121]: info: Received an update from plugin. extracting info from payload
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState
Jul 02 20:30:06 volumio volumio[1121]: info: ControllerMpd::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::servicePushState
Jul 02 20:30:06 volumio volumio[1121]: verbose: In UPNP mode
Jul 02 20:30:06 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":391,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"673 Kbps","isStreaming":false,"title":"Feel","artist":"Jacob Collier","album":"Djesse Vol. 2","uri":"http://192.168.1.5:15269/tidal/113133551.flac","trackType":"tidal"}
Jul 02 20:30:06 volumio volumio[1121]: verbose: CURRENT POSITION 0
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus play
Jul 02 20:30:06 volumio volumio[1121]: info: Received an update from plugin. extracting info from payload
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 30ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 41ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 41ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 42ms
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 41ms
Jul 02 20:30:06 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 36 milliseconds
Jul 02 20:30:06 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo
Jul 02 20:30:06 volumio volumio[1121]: info: ControllerMpd::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::servicePushState
Jul 02 20:30:06 volumio volumio[1121]: verbose: In UPNP mode
Jul 02 20:30:06 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":391,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"673 Kbps","isStreaming":false,"title":"Feel","artist":"Jacob Collier","album":"Djesse Vol. 2","uri":"http://192.168.1.5:15269/tidal/113133551.flac","trackType":"tidal"}
Jul 02 20:30:06 volumio volumio[1121]: verbose: CURRENT POSITION 0
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus play
Jul 02 20:30:06 volumio volumio[1121]: info: Received an update from plugin. extracting info from payload
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::pushState
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState
Jul 02 20:30:06 volumio volumio[1121]: info: ------------------------------ 52ms
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioGetQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CorePlayQueue::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioGetQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CorePlayQueue::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioGetQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CorePlayQueue::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioGetQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CorePlayQueue::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioGetQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CorePlayQueue::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioGetQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CorePlayQueue::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioGetQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CorePlayQueue::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioGetQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CorePlayQueue::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioGetQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CorePlayQueue::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreCommandRouter::volumioGetQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CoreStateMachine::getQueue
Jul 02 20:30:06 volumio volumio[1121]: info: CorePlayQueue::getQueue
Jul 02 20:30:06 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT
Jul 02 20:30:06 volumio systemd[1]: mpd.service: Failed with result 'signal'.
Jul 02 20:30:07 volumio volumio[1121]: error: Upnp client error: Error: This socket has been ended by the other party
Jul 02 20:30:21 volumio systemd[1]: Starting Music Player Daemon...
Jul 02 20:30:21 volumio sudo[6630]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jul 02 20:30:21 volumio sudo[6630]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 02 20:30:21 volumio sudo[6630]: pam_unix(sudo:session): session closed for user root
Jul 02 20:30:22 volumio mpd[6632]: Jul 02 20:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jul 02 20:30:22 volumio volumio[1121]: error: error
Jul 02 20:30:22 volumio systemd[1]: Started Music Player Daemon.
Jul 02 20:30:23 volumio volumio[1121]: error: error
Jul 02 20:30:49 volumio ntpd[1427]: Soliciting pool server 27.71.27.209
Jul 02 20:30:49 volumio ntpd[1427]: Soliciting pool server 103.184.124.254
Jul 02 20:30:52 volumio ntpd[1427]: Soliciting pool server 115.165.161.155
Jul 02 20:30:52 volumio volumio[1121]: error: Upnp client error: Error: This socket has been ended by the other party
Jul 02 20:31:00 volumio ntpd[1427]: Soliciting pool server 103.188.82.179
Jul 02 20:31:12 volumio volumio[1121]: error: error
Jul 02 20:31:13 volumio volumio[1121]: error: error
Jul 02 20:31:28 volumio volumio[1121]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.5 UA: unknown Engine version: 3 Transport: websocket Total Clients: 4
Jul 02 20:31:28 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState
Jul 02 20:31:28 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Jul 02 20:31:28 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Jul 02 20:31:28 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 02 20:31:28 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 02 20:31:28 volumio volumio[1121]: info: Discovery: Getting this device information
Jul 02 20:31:28 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState
Jul 02 20:31:28 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 02 20:31:29 volumio volumio[1121]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5
Jul 02 20:31:31 volumio sudo[6932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 02 20:31:31 volumio sudo[6932]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 02 20:31:31 volumio sudo[6932]: pam_unix(sudo:session): session closed for user root
Jul 02 20:31:31 volumio sudo[6934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 02 20:31:31 volumio sudo[6934]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 02 20:31:31 volumio sudo[6934]: pam_unix(sudo:session): session closed for user root
Jul 02 20:31:31 volumio volumio[1121]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6
Jul 02 20:31:31 volumio volumio[1121]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 02 20:31:31 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 02 20:31:31 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState
Jul 02 20:31:31 volumio volumio[1121]: info: CoreCommandRouter::volumioGetQueue
Jul 02 20:31:31 volumio volumio[1121]: info: CoreStateMachine::getQueue
Jul 02 20:31:31 volumio volumio[1121]: info: CorePlayQueue::getQueue
Jul 02 20:31:31 volumio volumio[1121]: info: Listing playlists
Jul 02 20:31:31 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jul 02 20:31:31 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jul 02 20:31:31 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Jul 02 20:31:31 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Jul 02 20:31:31 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 02 20:31:31 volumio volumio[1121]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jacob%20Collier/Djesse%20Vol.%202/5fe4125d-03e1-4ff7-a545-5107d252fabe.jpg'
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 02 20:31:34 volumio volumio[1121]: info: Retrieving Cloud Streaming UI
Jul 02 20:31:34 volumio volumio[1121]: info: Getting Tidal Cloud Configuration
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 02 20:31:34 volumio volumio[1121]: info: Getting Qobuz Cloud Configuration
Jul 02 20:31:34 volumio volumio[1121]: info: Asking plugin for UI Config
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 02 20:31:34 volumio volumio[1121]: info: Getting Spotify Cloud Configuration
Jul 02 20:31:34 volumio volumio[1121]: info: Asking plugin for UI Config
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 02 20:31:34 volumio volumio[1121]: info: Saving Spotify Acccount
Jul 02 20:31:34 volumio volumio[1121]: info: Got Tidal Cloud Configuration
Jul 02 20:31:34 volumio volumio[1121]: info: Got it
Jul 02 20:31:34 volumio volumio[1121]: info: Got it
Jul 02 20:31:34 volumio volumio[1121]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 02 20:31:34 volumio volumio[1121]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 02 20:31:34 volumio volumio[1121]: error: MPD error: Error: This socket has been ended by the other party
Jul 02 20:31:34 volumio volumio[1121]: error: This socket has been ended by the other party {"code":"EPIPE"}
Jul 02 20:31:34 volumio volumio[1121]: error: MPD error: Error: This socket has been ended by the other party
Jul 02 20:31:34 volumio volumio[1121]: error: This socket has been ended by the other party {"code":"EPIPE"}
Jul 02 20:31:34 volumio volumio[1121]: error: MPD error: Error: This socket has been ended by the other party
Jul 02 20:31:34 volumio volumio[1121]: error: This socket has been ended by the other party {"code":"EPIPE"}
Jul 02 20:31:34 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Jul 02 20:31:35 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 02 20:31:35 volumio volumio[1121]: info: Received Get System Info
Jul 02 20:31:35 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 02 20:31:35 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 02 20:31:35 volumio volumio[1121]: info: Discovery: Getting this device information
Jul 02 20:31:35 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState
Jul 02 20:31:35 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 02 20:31:37 volumio volumio[1121]: info: Disabling MyMusic plugin upnp
Jul 02 20:31:37 volumio sudo[6950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
Jul 02 20:31:37 volumio sudo[6950]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 02 20:31:37 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD...
Jul 02 20:31:37 volumio volumio[1121]: error: Upnp client error: Error: This socket has been ended by the other party
Jul 02 20:31:38 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 02 20:31:38 volumio volumio[1121]: error: MPD error: Error: This socket has been ended by the other party
Jul 02 20:31:38 volumio volumio[1121]: error: This socket has been ended by the other party {"code":"EPIPE"}
Jul 02 20:31:38 volumio volumio[1121]: error: MPD error: Error: This socket has been ended by the other party
Jul 02 20:31:38 volumio volumio[1121]: error: This socket has been ended by the other party {"code":"EPIPE"}
Jul 02 20:31:38 volumio volumio[1121]: error: MPD error: Error: This socket has been ended by the other party
Jul 02 20:31:38 volumio volumio[1121]: error: This socket has been ended by the other party {"code":"EPIPE"}
Jul 02 20:31:39 volumio volumio[1121]: info: Enabling MyMusic plugin upnp
Jul 02 20:31:39 volumio volumio[1121]: info: Enabling plugin upnp
Jul 02 20:31:39 volumio volumio[1121]: info: Loading plugin "upnp"...
Jul 02 20:31:39 volumio volumio[1121]: info: [1751463099304] Starting Upmpd Daemon
Jul 02 20:31:39 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jul 02 20:31:39 volumio volumio[1121]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 02 20:31:39 volumio volumio[1121]: Error: listen EADDRINUSE: address already in use :::6599
Jul 02 20:31:39 volumio volumio[1121]: at Server.setupListenHandle [as _listen2] (net.js:1331:16)
Jul 02 20:31:39 volumio volumio[1121]: at listenInCluster (net.js:1379:12)
Jul 02 20:31:39 volumio volumio[1121]: at Server.listen (net.js:1465:7)
Jul 02 20:31:39 volumio volumio[1121]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17)
Jul 02 20:31:39 volumio volumio[1121]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38)
Jul 02 20:31:39 volumio volumio[1121]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19)
Jul 02 20:31:39 volumio volumio[1121]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
Jul 02 20:31:39 volumio volumio[1121]: at processTicksAndRejections (internal/process/task_queues.js:77:11) {
Jul 02 20:31:39 volumio volumio[1121]: code: 'EADDRINUSE',
Jul 02 20:31:39 volumio volumio[1121]: errno: -98,
Jul 02 20:31:39 volumio volumio[1121]: syscall: 'listen',
Jul 02 20:31:39 volumio volumio[1121]: address: '::',
Jul 02 20:31:39 volumio volumio[1121]: port: 6599
Jul 02 20:31:39 volumio volumio[1121]: }
Jul 02 20:31:39 volumio volumio[1121]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 02 20:31:39 volumio sudo[6979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-02 20:30
Jul 02 20:31:39 volumio sudo[6979]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST"
VOLUMIO_VERSION="3.816"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"