-- Logs begin at Tue 2026-04-14 19:50:09 EEST, end at Tue 2026-04-14 20:16:35 EEST. --
Apr 14 20:15:01 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 14 20:15:01 volumio volumio[926]: info: CURURI: music-library
Apr 14 20:15:01 volumio volumio[926]: error: Failed LSINFO: null
Apr 14 20:15:01 volumio volumio[926]: info: Preload queue cleared
Apr 14 20:15:06 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 14 20:15:06 volumio volumio[926]: info: Preload queue cleared
Apr 14 20:15:07 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 14 20:15:07 volumio volumio[926]: info: Preload queue cleared
Apr 14 20:15:09 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 14 20:15:09 volumio volumio[926]: info: Preload queue cleared
Apr 14 20:15:12 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 14 20:15:12 volumio volumio[926]: info: Preload queue cleared
Apr 14 20:15:21 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 14 20:15:21 volumio volumio[926]: info: Preload queue cleared
Apr 14 20:15:23 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 14 20:15:23 volumio volumio[926]: info: Preload queue cleared
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$0
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$1
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$2
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$3
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$4
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$5
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$6
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$7
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$8
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$9
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$A
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$B
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$C
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$D
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$E
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$F
Apr 14 20:15:23 volumio volumio[926]: info: Preloading song: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$10
Apr 14 20:15:23 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$0 in service upnp_browser
Apr 14 20:15:23 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$1 in service upnp_browser
Apr 14 20:15:23 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$2 in service upnp_browser
Apr 14 20:15:23 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$3 in service upnp_browser
Apr 14 20:15:23 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$4 in service upnp_browser
Apr 14 20:15:23 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$5 in service upnp_browser
Apr 14 20:15:23 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$6 in service upnp_browser
Apr 14 20:15:23 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$7 in service upnp_browser
Apr 14 20:15:23 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$8 in service upnp_browser
Apr 14 20:15:23 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$9 in service upnp_browser
Apr 14 20:15:23 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$A in service upnp_browser
Apr 14 20:15:23 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$B in service upnp_browser
Apr 14 20:15:24 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$C in service upnp_browser
Apr 14 20:15:24 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$D in service upnp_browser
Apr 14 20:15:24 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$E in service upnp_browser
Apr 14 20:15:24 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$F in service upnp_browser
Apr 14 20:15:24 volumio volumio[926]: info: Exploding uri upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$10 in service upnp_browser
Apr 14 20:15:26 volumio volumio[926]: info: Preload queue cleared
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::ClearQueue
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::stop
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::stPlaybackTimer
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::updateTrackBlock
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrackBlock
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::serviceStop
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::serviceStop
Apr 14 20:15:26 volumio volumio[926]: info: [1776186926480] ControllerUPNPBrowser::stop
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::clearPlayQueue
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::saveQueue
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushQueue
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::addQueueItems
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::addQueueItems
Apr 14 20:15:26 volumio volumio[926]: info: Preload queue cleared
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/folder/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$11
Apr 14 20:15:26 volumio volumio[926]: info: Exploding uri upnp/folder/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$11 in service upnp_browser
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$0
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$0
Apr 14 20:15:26 volumio volumio[926]: info:
Apr 14 20:15:26 volumio volumio[926]: ---------------------------- MPD announces state update: player
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand stop took 13 milliseconds
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::getState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 20:15:26 volumio volumio[926]: info:
Apr 14 20:15:26 volumio volumio[926]: ---------------------------- MPD announces state update: player
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::getState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand status took 2 milliseconds
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand status took 2 milliseconds
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::servicePushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Soy como un niño","artist":"Chayanne","album":"Pobre Diabla Banda Sonora","uri":"http://192.168.0.115:8200/MediaItems/94.flac","trackType":"flac"}
Apr 14 20:15:26 volumio volumio[926]: verbose: CURRENT POSITION 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::syncState stateService stop
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus stop
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: No code
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::servicePushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Soy como un niño","artist":"Chayanne","album":"Pobre Diabla Banda Sonora","uri":"http://192.168.0.115:8200/MediaItems/94.flac","trackType":"flac"}
Apr 14 20:15:26 volumio volumio[926]: verbose: CURRENT POSITION 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::syncState stateService stop
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus stop
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: No code
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: ------------------------------ 59ms
Apr 14 20:15:26 volumio volumio[926]: info: ------------------------------ 59ms
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushQueue
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::saveQueue
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::updateTrackBlock
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrackBlock
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPlay
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::play index 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::addQueueItems
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::addQueueItems
Apr 14 20:15:26 volumio volumio[926]: info: Preload queue cleared
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$1
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$1
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$2
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$2
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$3
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$3
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$4
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$4
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$5
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$5
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$6
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$6
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$7
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$7
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$8
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$8
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$9
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$9
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$A
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$A
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$B
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$B
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$C
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$C
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$D
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$D
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$E
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$E
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$F
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$F
Apr 14 20:15:26 volumio volumio[926]: info: Adding Item to queue: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$10
Apr 14 20:15:26 volumio volumio[926]: info: Using cached record of: upnp/http://192.168.0.115:8200/ctl/ContentDir@64$9$0$2$10
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::stop
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushQueue
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::saveQueue
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::play index undefined
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::updateTrackBlock
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrackBlock
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::startPlaybackTimer
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: info: [1776186926580] ControllerUPNPBrowser::clearAddPlayTrack
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand stop took 2 milliseconds
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand clear
Apr 14 20:15:26 volumio volumio[926]: info:
Apr 14 20:15:26 volumio volumio[926]: ---------------------------- MPD announces system playlist update
Apr 14 20:15:26 volumio volumio[926]: info: Ignoring MPD Status Update
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand clear took 9 milliseconds
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.115:8200/MediaItems/2583.flac"
Apr 14 20:15:26 volumio volumio[926]: info:
Apr 14 20:15:26 volumio volumio[926]: ---------------------------- MPD announces system playlist update
Apr 14 20:15:26 volumio volumio[926]: info: Ignoring MPD Status Update
Apr 14 20:15:26 volumio volumio[926]: error: updateQueue error: null
Apr 14 20:15:26 volumio volumio[926]: info: ------------------------------ 3ms
Apr 14 20:15:26 volumio volumio[926]: error: updateQueue error: null
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.115:8200/MediaItems/2583.flac"
Apr 14 20:15:26 volumio volumio[926]: info: ------------------------------ 173ms
Apr 14 20:15:26 volumio volumio[926]: info:
Apr 14 20:15:26 volumio volumio[926]: ---------------------------- MPD announces system playlist update
Apr 14 20:15:26 volumio volumio[926]: info: Ignoring MPD Status Update
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand add "http://192.168.0.115:8200/MediaItems/2583.flac" took 2 milliseconds
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand play
Apr 14 20:15:26 volumio volumio[926]: info:
Apr 14 20:15:26 volumio volumio[926]: ---------------------------- MPD announces system playlist update
Apr 14 20:15:26 volumio volumio[926]: info: Ignoring MPD Status Update
Apr 14 20:15:26 volumio volumio[926]: info: ------------------------------ 5ms
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand play took 3 milliseconds
Apr 14 20:15:26 volumio volumio[926]: info: ------------------------------ 4ms
Apr 14 20:15:26 volumio volumio[926]: info:
Apr 14 20:15:26 volumio volumio[926]: ---------------------------- MPD announces state update: player
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::getState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 20:15:26 volumio volumio[926]: info:
Apr 14 20:15:26 volumio volumio[926]: ---------------------------- MPD announces state update: player
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::getState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 20:15:26 volumio volumio[926]: info:
Apr 14 20:15:26 volumio volumio[926]: ---------------------------- MPD announces state update: player
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::getState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 20:15:26 volumio volumio[926]: info:
Apr 14 20:15:26 volumio volumio[926]: ---------------------------- MPD announces state update: player
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::getState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand status took 11 milliseconds
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand status took 9 milliseconds
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand status took 12 milliseconds
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand status took 13 milliseconds
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand playlistinfo took 11 milliseconds
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand playlistinfo took 11 milliseconds
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::servicePushState
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2583.flac","artist":null,"album":null,"uri":"http://192.168.0.115:8200/MediaItems/2583.flac","trackType":"flac"}
Apr 14 20:15:26 volumio volumio[926]: verbose: CURRENT POSITION 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::syncState stateService play
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus stop
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::servicePushState
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2583.flac","artist":null,"album":null,"uri":"http://192.168.0.115:8200/MediaItems/2583.flac","trackType":"flac"}
Apr 14 20:15:26 volumio volumio[926]: verbose: CURRENT POSITION 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::syncState stateService play
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus play
Apr 14 20:15:26 volumio volumio[926]: info: Received an update from plugin. extracting info from payload
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::servicePushState
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2583.flac","artist":null,"album":null,"uri":"http://192.168.0.115:8200/MediaItems/2583.flac","trackType":"flac"}
Apr 14 20:15:26 volumio volumio[926]: verbose: CURRENT POSITION 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::syncState stateService play
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus play
Apr 14 20:15:26 volumio volumio[926]: info: Received an update from plugin. extracting info from payload
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: ------------------------------ 43ms
Apr 14 20:15:26 volumio volumio[926]: info: ------------------------------ 70ms
Apr 14 20:15:26 volumio volumio[926]: info: ------------------------------ 67ms
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand playlistinfo took 49 milliseconds
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::servicePushState
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2583.flac","artist":null,"album":null,"uri":"http://192.168.0.115:8200/MediaItems/2583.flac","trackType":"flac"}
Apr 14 20:15:26 volumio volumio[926]: verbose: CURRENT POSITION 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::syncState stateService play
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus play
Apr 14 20:15:26 volumio volumio[926]: info: Received an update from plugin. extracting info from payload
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: ------------------------------ 83ms
Apr 14 20:15:26 volumio volumio[926]: info:
Apr 14 20:15:26 volumio volumio[926]: ---------------------------- MPD announces system playlist update
Apr 14 20:15:26 volumio volumio[926]: info: Ignoring MPD Status Update
Apr 14 20:15:26 volumio volumio[926]: info:
Apr 14 20:15:26 volumio volumio[926]: ---------------------------- MPD announces state update: player
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::getState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 20:15:26 volumio volumio[926]: info: ------------------------------ 6ms
Apr 14 20:15:26 volumio volumio[926]: info:
Apr 14 20:15:26 volumio volumio[926]: ---------------------------- MPD announces system playlist update
Apr 14 20:15:26 volumio volumio[926]: info: Ignoring MPD Status Update
Apr 14 20:15:26 volumio volumio[926]: info:
Apr 14 20:15:26 volumio volumio[926]: ---------------------------- MPD announces state update: player
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::getState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand status took 3 milliseconds
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 20:15:26 volumio volumio[926]: info: ------------------------------ 4ms
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand status took 4 milliseconds
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseState
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo
Apr 14 20:15:26 volumio volumio[926]: info: ControllerMpd::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::servicePushState
Apr 14 20:15:26 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:26 volumio volumio[926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"817 Kbps","isStreaming":false,"title":"I was made for lovin' you","artist":"Kiss","album":"Дискотека 80-х ( часть 9 )","uri":"http://192.168.0.115:8200/MediaItems/2583.flac","trackType":"flac"}
Apr 14 20:15:26 volumio volumio[926]: verbose: CURRENT POSITION 1
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::syncState stateService play
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus play
Apr 14 20:15:26 volumio volumio[926]: info: Received an update from plugin. extracting info from payload
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:26 volumio volumio[926]: info: ------------------------------ 28ms
Apr 14 20:15:26 volumio volumio[926]: info: sendMpdCommand playlistinfo took 22 milliseconds
Apr 14 20:15:26 volumio volumio[926]: verbose: ControllerMpd::parseTrackInfo
Apr 14 20:15:27 volumio volumio[926]: info: ControllerMpd::pushState
Apr 14 20:15:27 volumio volumio[926]: info: CoreCommandRouter::servicePushState
Apr 14 20:15:27 volumio volumio[926]: info: CorePlayQueue::getTrack 1
Apr 14 20:15:27 volumio volumio[926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"817 Kbps","isStreaming":false,"title":"I was made for lovin' you","artist":"Kiss","album":"Дискотека 80-х ( часть 9 )","uri":"http://192.168.0.115:8200/MediaItems/2583.flac","trackType":"flac"}
Apr 14 20:15:27 volumio volumio[926]: verbose: CURRENT POSITION 1
Apr 14 20:15:27 volumio volumio[926]: info: CoreStateMachine::syncState stateService play
Apr 14 20:15:27 volumio volumio[926]: info: CoreStateMachine::syncState currentStatus play
Apr 14 20:15:27 volumio volumio[926]: info: Received an update from plugin. extracting info from payload
Apr 14 20:15:27 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:27 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:27 volumio volumio[926]: info: CoreStateMachine::pushState
Apr 14 20:15:27 volumio volumio[926]: info: CoreCommandRouter::volumioPushState
Apr 14 20:15:27 volumio volumio[926]: info: ------------------------------ 56ms
Apr 14 20:15:27 volumio volumio[926]: info: CoreCommandRouter::volumioGetQueue
Apr 14 20:15:27 volumio volumio[926]: info: CoreStateMachine::getQueue
Apr 14 20:15:27 volumio volumio[926]: info: CorePlayQueue::getQueue
Apr 14 20:15:27 volumio volumio[926]: info: CoreCommandRouter::volumioGetQueue
Apr 14 20:15:27 volumio volumio[926]: info: CoreStateMachine::getQueue
Apr 14 20:15:27 volumio volumio[926]: info: CorePlayQueue::getQueue
Apr 14 20:15:27 volumio volumio[926]: info: CoreCommandRouter::volumioGetQueue
Apr 14 20:15:27 volumio volumio[926]: info: CoreStateMachine::getQueue
Apr 14 20:15:27 volumio volumio[926]: info: CorePlayQueue::getQueue
Apr 14 20:15:27 volumio volumio[926]: info: CoreCommandRouter::volumioGetQueue
Apr 14 20:15:27 volumio volumio[926]: info: CoreStateMachine::getQueue
Apr 14 20:15:27 volumio volumio[926]: info: CorePlayQueue::getQueue
Apr 14 20:15:53 volumio ntpd[1199]: 136.243.177.133 local addr 192.168.0.192 ->
Apr 14 20:16:21 volumio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 14 20:16:21 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Apr 14 20:16:21 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Apr 14 20:16:21 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Apr 14 20:16:21 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs
Apr 14 20:16:21 volumio volumio[926]: info: Received Get System Version
Apr 14 20:16:21 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 14 20:16:21 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds
Apr 14 20:16:27 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Apr 14 20:16:27 volumio volumio[926]: info: CoreCommandRouter::Reload Ui
Apr 14 20:16:27 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Apr 14 20:16:27 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Apr 14 20:16:27 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Apr 14 20:16:28 volumio sudo[6067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 14 20:16:28 volumio sudo[6067]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 14 20:16:28 volumio sudo[6067]: pam_unix(sudo:session): session closed for user root
Apr 14 20:16:28 volumio sudo[6070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 14 20:16:28 volumio sudo[6070]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 14 20:16:28 volumio sudo[6070]: pam_unix(sudo:session): session closed for user root
Apr 14 20:16:28 volumio volumio[926]: verbose: New Socket.io Connection to 192.168.0.192 from 192.168.0.227 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/147.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4
Apr 14 20:16:28 volumio volumio[926]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 14 20:16:28 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 14 20:16:28 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Apr 14 20:16:28 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Apr 14 20:16:28 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Apr 14 20:16:28 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Apr 14 20:16:28 volumio volumio[926]: info: Received Get System Info
Apr 14 20:16:28 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 14 20:16:28 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 14 20:16:28 volumio volumio[926]: info: Discovery: Getting this device information
Apr 14 20:16:28 volumio volumio[926]: info: CoreCommandRouter::volumioGetState
Apr 14 20:16:28 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 14 20:16:28 volumio volumio-remote-updater[643]: No test mode
Apr 14 20:16:28 volumio volumio-remote-updater[643]: No alpha test mode
Apr 14 20:16:28 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Apr 14 20:16:28 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 14 20:16:29 volumio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 14 20:16:29 volumio volumio[926]: info: Retrieving Cloud Streaming UI
Apr 14 20:16:29 volumio volumio[926]: info: Getting Tidal Cloud Configuration
Apr 14 20:16:29 volumio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 14 20:16:29 volumio volumio[926]: info: Getting Qobuz Cloud Configuration
Apr 14 20:16:29 volumio volumio[926]: info: Asking plugin for UI Config
Apr 14 20:16:29 volumio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 14 20:16:29 volumio volumio[926]: info: Getting Spotify Cloud Configuration
Apr 14 20:16:29 volumio volumio[926]: info: Asking plugin for UI Config
Apr 14 20:16:29 volumio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 14 20:16:29 volumio volumio[926]: info: Saving Spotify Acccount
Apr 14 20:16:29 volumio volumio[926]: info: Got Tidal Cloud Configuration
Apr 14 20:16:29 volumio volumio[926]: info: Got it
Apr 14 20:16:29 volumio volumio[926]: info: Got it
Apr 14 20:16:29 volumio volumio[926]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Apr 14 20:16:29 volumio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 14 20:16:29 volumio volumio[926]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Apr 14 20:16:29 volumio volumio[926]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for BLE notification issues
\n- Fix for mDNS/BCT
\n
\nNEW ADDITIONS
\n\n- Update Tidal SDK
\n- Update Qobuz Connect SDK
\n
\n","title":"Update v3.912","updateavailable":true}
Apr 14 20:16:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 14 20:16:29 volumio volumio[926]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Apr 14 20:16:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Apr 14 20:16:29 volumio volumio[926]: info: Loading i18n strings for locale en
Apr 14 20:16:29 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Apr 14 20:16:29 volumio volumio[926]: info: Setting timezone to Europe/Bucharest
Apr 14 20:16:30 volumio sudo[6079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Apr 14 20:16:30 volumio sudo[6079]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 14 20:16:30 volumio sudo[6079]: pam_unix(sudo:session): session closed for user root
Apr 14 20:16:30 volumio sudo[6097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Bucharest /etc/localtime
Apr 14 20:16:30 volumio sudo[6097]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 14 20:16:30 volumio sudo[6097]: pam_unix(sudo:session): session closed for user root
Apr 14 20:16:30 volumio sudo[6100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Apr 14 20:16:30 volumio sudo[6100]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 14 20:16:32 volumio sudo[6100]: pam_unix(sudo:session): session closed for user root
Apr 14 20:16:32 volumio sudo[6103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Bucharest
Apr 14 20:16:32 volumio sudo[6103]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 14 20:16:32 volumio dbus-daemon[647]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.20' (uid=0 pid=6104 comm="/usr/bin/timedatectl set-timezone Europe/Bucharest")
Apr 14 20:16:32 volumio systemd[1]: Starting Time & Date Service...
Apr 14 20:16:32 volumio dbus-daemon[647]: [system] Successfully activated service 'org.freedesktop.timedate1'
Apr 14 20:16:32 volumio systemd[1]: Started Time & Date Service.
Apr 14 20:16:32 volumio sudo[6103]: pam_unix(sudo:session): session closed for user root
Apr 14 20:16:32 volumio volumio[926]: info: Fetching Streaming Services browse cache
Apr 14 20:16:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 14 20:16:32 volumio volumio[926]: Updating browse sources language
Apr 14 20:16:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 14 20:16:32 volumio volumio[926]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 14 20:16:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 14 20:16:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Apr 14 20:16:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Apr 14 20:16:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Apr 14 20:16:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Apr 14 20:16:32 volumio volumio[926]: info: Received Get System Info
Apr 14 20:16:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 14 20:16:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 14 20:16:32 volumio volumio[926]: info: Discovery: Getting this device information
Apr 14 20:16:32 volumio volumio[926]: info: CoreCommandRouter::volumioGetState
Apr 14 20:16:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 14 20:16:32 volumio volumio-remote-updater[643]: No test mode
Apr 14 20:16:32 volumio volumio-remote-updater[643]: No alpha test mode
Apr 14 20:16:32 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 14 20:16:33 volumio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 14 20:16:33 volumio volumio[926]: info: Retrieving Cloud Streaming UI
Apr 14 20:16:33 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Apr 14 20:16:33 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 14 20:16:33 volumio volumio[926]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for BLE notification issues
\n- Fix for mDNS/BCT
\n
\nNEW ADDITIONS
\n\n- Update Tidal SDK
\n- Update Qobuz Connect SDK
\n
\n","title":"Update v3.912","updateavailable":true}
Apr 14 20:16:34 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 14 20:16:34 volumio volumio[926]: info: Getting Tidal Cloud Configuration
Apr 14 20:16:34 volumio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 14 20:16:34 volumio volumio[926]: info: Getting Qobuz Cloud Configuration
Apr 14 20:16:34 volumio volumio[926]: info: Asking plugin for UI Config
Apr 14 20:16:34 volumio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 14 20:16:34 volumio volumio[926]: info: Getting Spotify Cloud Configuration
Apr 14 20:16:34 volumio volumio[926]: info: Asking plugin for UI Config
Apr 14 20:16:34 volumio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 14 20:16:34 volumio volumio[926]: info: Saving Spotify Acccount
Apr 14 20:16:34 volumio volumio[926]: info: Got Tidal Cloud Configuration
Apr 14 20:16:34 volumio volumio[926]: info: Got it
Apr 14 20:16:34 volumio volumio[926]: info: Got it
Apr 14 20:16:34 volumio volumio[926]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Apr 14 20:16:34 volumio volumio[926]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 14 20:16:34 volumio volumio[926]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Apr 14 20:16:34 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Apr 14 20:16:34 volumio volumio[926]: info: Loading i18n strings for locale en
Apr 14 20:16:34 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Apr 14 20:16:34 volumio volumio[926]: info: Setting timezone to Etc/Unknown
Apr 14 20:16:34 volumio sudo[6112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Apr 14 20:16:34 volumio sudo[6112]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 14 20:16:34 volumio sudo[6112]: pam_unix(sudo:session): session closed for user root
Apr 14 20:16:34 volumio sudo[6115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Etc/Unknown /etc/localtime
Apr 14 20:16:34 volumio sudo[6115]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 14 20:16:34 volumio sudo[6115]: pam_unix(sudo:session): session closed for user root
Apr 14 20:16:34 volumio sudo[6118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Apr 14 20:16:34 volumio sudo[6118]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 14 20:16:34 volumio sudo[6118]: pam_unix(sudo:session): session closed for user root
Apr 14 20:16:34 volumio volumio[926]: /bin/chmod: cannot operate on dangling symlink '/etc/localtime'
Apr 14 20:16:34 volumio volumio[926]: error: Could not set timezone: Error: Command failed: /usr/bin/sudo /bin/chmod 777 /etc/localtime
Apr 14 20:16:34 volumio volumio[926]: /bin/chmod: cannot operate on dangling symlink '/etc/localtime'
Apr 14 20:16:34 volumio sudo[6121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Etc/Unknown
Apr 14 20:16:34 volumio sudo[6121]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 14 20:16:34 volumio sudo[6121]: pam_unix(sudo:session): session closed for user root
Apr 14 20:16:34 volumio volumio[926]: Failed to set time zone: Invalid time zone 'Etc/Unknown'
Apr 14 20:16:34 volumio volumio[926]: info: Could not set timezone, retrying
Apr 14 20:16:34 volumio volumio[926]: Updating browse sources language
Apr 14 20:16:34 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 14 20:16:34 volumio volumio[926]: info: Fetching Streaming Services browse cache
Apr 14 20:16:35 volumio sudo[6124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Etc/Unknown
Apr 14 20:16:35 volumio sudo[6124]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 14 20:16:35 volumio sudo[6124]: pam_unix(sudo:session): session closed for user root
Apr 14 20:16:35 volumio volumio[926]: Failed to set time zone: Invalid time zone 'Etc/Unknown'
Apr 14 20:16:35 volumio volumio[926]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 14 20:16:35 volumio volumio[926]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Etc/Unknown'
Apr 14 20:16:35 volumio volumio[926]: Failed to set time zone: Invalid time zone 'Etc/Unknown'
Apr 14 20:16:35 volumio volumio[926]: at checkExecSyncError (child_process.js:790:11)
Apr 14 20:16:35 volumio volumio[926]: at execSync (child_process.js:863:15)
Apr 14 20:16:35 volumio volumio[926]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1680:9)
Apr 14 20:16:35 volumio volumio[926]: at listOnTimeout (internal/timers.js:557:17)
Apr 14 20:16:35 volumio volumio[926]: at processTimers (internal/timers.js:500:7) {
Apr 14 20:16:35 volumio volumio[926]: status: 1,
Apr 14 20:16:35 volumio volumio[926]: signal: null,
Apr 14 20:16:35 volumio volumio[926]: output: [
Apr 14 20:16:35 volumio volumio[926]: null,
Apr 14 20:16:35 volumio volumio[926]: '',
Apr 14 20:16:35 volumio volumio[926]: "Failed to set time zone: Invalid time zone 'Etc/Unknown'\n"
Apr 14 20:16:35 volumio volumio[926]: ],
Apr 14 20:16:35 volumio volumio[926]: pid: 6123,
Apr 14 20:16:35 volumio volumio[926]: stdout: '',
Apr 14 20:16:35 volumio volumio[926]: stderr: "Failed to set time zone: Invalid time zone 'Etc/Unknown'\n"
Apr 14 20:16:35 volumio volumio[926]: }
Apr 14 20:16:35 volumio volumio[926]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 14 20:16:35 volumio sudo[6135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-14 20:15
Apr 14 20:16:35 volumio sudo[6135]: 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="49c352e1d55e9b76c3bd7b0e3940507619bf455a"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="1d32690fc900ac8c739e7eabd35ed0f570899eb8"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri 19 Dec 2025 02:40:03 PM CET"
VOLUMIO_VERSION="3.887"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="05e5ed27b04d8d1e8a8a87ca324a64c8"