-- Logs begin at Thu 2019-02-14 14:11:58 +04, end at Sat 2025-09-13 11:23:49 +04. --
Sep 13 11:22:02 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:22:06 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:22:07 volumio ntpd[807]: Soliciting pool server 2a13:8a40::368
Sep 13 11:22:10 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:22:14 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:22:18 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:22:22 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare
Sep 13 11:22:22 volumio volumio[924]: info: Share music successfully unmounted
Sep 13 11:22:22 volumio sudo[3554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=dronson,password=VtzbV40z41b32vSfHkCBM!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.0.121/music /mnt/NAS/music
Sep 13 11:22:22 volumio sudo[3554]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 13 11:22:22 volumio kernel: CIFS: Attempting to mount //192.168.0.121/music
Sep 13 11:22:22 volumio sudo[3554]: pam_unix(sudo:session): session closed for user root
Sep 13 11:22:22 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE
Sep 13 11:22:22 volumio kernel: CIFS: VFS: \\192.168.0.121 Send error in SessSetup = -13
Sep 13 11:22:22 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13
Sep 13 11:22:22 volumio volumio[924]: info: An error occurred mounting the new share. Rolling back configuration
Sep 13 11:22:22 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:22:26 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:22:30 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:22:31 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb
Sep 13 11:22:31 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand rescan
Sep 13 11:22:31 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status
Sep 13 11:22:31 volumio volumio[924]: info:
Sep 13 11:22:31 volumio volumio[924]: ---------------------------- MPD announces state update: update
Sep 13 11:22:31 volumio volumio[924]: info: sendMpdCommand rescan took 4 milliseconds
Sep 13 11:22:31 volumio volumio[924]: info: ControllerMpd::getState
Sep 13 11:22:31 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status
Sep 13 11:22:31 volumio volumio[924]: info: sendMpdCommand status took 3 milliseconds
Sep 13 11:22:31 volumio volumio[924]: info: sendMpdCommand status took 2 milliseconds
Sep 13 11:22:31 volumio volumio[924]: verbose: ControllerMpd::parseState
Sep 13 11:22:31 volumio volumio[924]: info: Command Router : Notfying DB Updatetrue
Sep 13 11:22:31 volumio volumio[924]: verbose: ControllerMpd::parseState
Sep 13 11:22:31 volumio volumio[924]: info: ControllerMpd::pushState
Sep 13 11:22:31 volumio volumio[924]: info: CoreCommandRouter::servicePushState
Sep 13 11:22:31 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:22:31 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:22:31 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:22:31 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:22:31 volumio volumio[924]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Sep 13 11:22:31 volumio volumio[924]: verbose: CURRENT POSITION 0
Sep 13 11:22:31 volumio volumio[924]: info: CoreStateMachine::syncState stateService stop
Sep 13 11:22:31 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus stop
Sep 13 11:22:31 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:22:31 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:22:31 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 13 11:22:31 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:22:31 volumio volumio[924]: info: No code
Sep 13 11:22:31 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:22:31 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:22:31 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:22:31 volumio volumio[924]: info: ------------------------------ 74ms
Sep 13 11:22:31 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status
Sep 13 11:22:31 volumio volumio[924]: info:
Sep 13 11:22:31 volumio volumio[924]: ---------------------------- MPD announces state update: update
Sep 13 11:22:31 volumio volumio[924]: info: ControllerMpd::getState
Sep 13 11:22:31 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status
Sep 13 11:22:31 volumio volumio[924]: info: sendMpdCommand status took 4 milliseconds
Sep 13 11:22:31 volumio volumio[924]: info: sendMpdCommand status took 3 milliseconds
Sep 13 11:22:31 volumio volumio[924]: verbose: ControllerMpd::parseState
Sep 13 11:22:31 volumio volumio[924]: info: Command Router : Notfying DB Updatefalse
Sep 13 11:22:31 volumio volumio[924]: info: CoreCommandRouter::Close All Modals sent
Sep 13 11:22:31 volumio volumio[924]: verbose: ControllerMpd::parseState
Sep 13 11:22:31 volumio volumio[924]: info: ControllerMpd::pushState
Sep 13 11:22:31 volumio volumio[924]: info: CoreCommandRouter::servicePushState
Sep 13 11:22:31 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:22:31 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:22:31 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:22:31 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:22:31 volumio volumio[924]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Sep 13 11:22:31 volumio volumio[924]: verbose: CURRENT POSITION 0
Sep 13 11:22:31 volumio volumio[924]: info: CoreStateMachine::syncState stateService stop
Sep 13 11:22:31 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus stop
Sep 13 11:22:31 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:22:31 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:22:31 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 13 11:22:31 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:22:31 volumio volumio[924]: info: No code
Sep 13 11:22:31 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:22:31 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:22:31 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:22:31 volumio volumio[924]: info: ------------------------------ 49ms
Sep 13 11:22:34 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:22:38 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:22:42 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:22:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:22:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:22:54 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:22:58 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:23:02 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:23:06 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:23:10 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:23:11 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare
Sep 13 11:23:11 volumio volumio[924]: info: Share music successfully unmounted
Sep 13 11:23:11 volumio sudo[3634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=dronson,password=VtzbV40z41b32vSfHkCBM!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.0.121/music /mnt/NAS/music
Sep 13 11:23:11 volumio sudo[3634]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 13 11:23:11 volumio kernel: CIFS: Attempting to mount //192.168.0.121/music
Sep 13 11:23:12 volumio sudo[3634]: pam_unix(sudo:session): session closed for user root
Sep 13 11:23:12 volumio volumio[924]: info: An error occurred mounting the new share. Rolling back configuration
Sep 13 11:23:12 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE
Sep 13 11:23:12 volumio kernel: CIFS: VFS: \\192.168.0.121 Send error in SessSetup = -13
Sep 13 11:23:12 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13
Sep 13 11:23:12 volumio ntpd[807]: Soliciting pool server 2001:ac8:81:65:0:2:0:2
Sep 13 11:23:14 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:23:19 volumio volumio[924]: info: CoreCommandRouter::volumioGetState
Sep 13 11:23:19 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 13 11:23:22 volumio volumio[924]: info: Retrieving Cloud Streaming UI
Sep 13 11:23:22 volumio volumio[924]: info: Getting Tidal Cloud Configuration
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 13 11:23:22 volumio volumio[924]: info: Getting Qobuz Cloud Configuration
Sep 13 11:23:22 volumio volumio[924]: info: Asking plugin for UI Config
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 13 11:23:22 volumio volumio[924]: info: Getting Spotify Cloud Configuration
Sep 13 11:23:22 volumio volumio[924]: info: Asking plugin for UI Config
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 13 11:23:22 volumio volumio[924]: info: Saving Spotify Acccount
Sep 13 11:23:22 volumio volumio[924]: info: Got Tidal Cloud Configuration
Sep 13 11:23:22 volumio volumio[924]: info: Got it
Sep 13 11:23:22 volumio volumio[924]: info: Got it
Sep 13 11:23:22 volumio volumio[924]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 13 11:23:22 volumio volumio[924]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::volumioGetBrowseSources
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::volumioGetBrowseSources
Sep 13 11:23:22 volumio volumio[924]: info: CoreCommandRouter::volumioGetBrowseSources
Sep 13 11:23:23 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:23:23 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Sep 13 11:23:27 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:23:31 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:23:35 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:23:39 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:23:43 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:23:43 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb
Sep 13 11:23:43 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand update
Sep 13 11:23:43 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status
Sep 13 11:23:43 volumio volumio[924]: info:
Sep 13 11:23:43 volumio volumio[924]: ---------------------------- MPD announces state update: update
Sep 13 11:23:43 volumio volumio[924]: info: sendMpdCommand update took 2 milliseconds
Sep 13 11:23:43 volumio volumio[924]: info: ControllerMpd::getState
Sep 13 11:23:43 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status
Sep 13 11:23:43 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status
Sep 13 11:23:43 volumio volumio[924]: info:
Sep 13 11:23:43 volumio volumio[924]: ---------------------------- MPD announces state update: update
Sep 13 11:23:43 volumio volumio[924]: info: ControllerMpd::getState
Sep 13 11:23:43 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status
Sep 13 11:23:43 volumio volumio[924]: info: sendMpdCommand status took 2 milliseconds
Sep 13 11:23:43 volumio volumio[924]: info: sendMpdCommand status took 2 milliseconds
Sep 13 11:23:43 volumio volumio[924]: verbose: ControllerMpd::parseState
Sep 13 11:23:43 volumio volumio[924]: info: Command Router : Notfying DB Updatefalse
Sep 13 11:23:43 volumio volumio[924]: info: CoreCommandRouter::Close All Modals sent
Sep 13 11:23:43 volumio volumio[924]: verbose: ControllerMpd::parseState
Sep 13 11:23:43 volumio volumio[924]: info: ControllerMpd::pushState
Sep 13 11:23:43 volumio volumio[924]: info: CoreCommandRouter::servicePushState
Sep 13 11:23:43 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:23:43 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:43 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:23:43 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:43 volumio volumio[924]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Sep 13 11:23:43 volumio volumio[924]: verbose: CURRENT POSITION 0
Sep 13 11:23:43 volumio volumio[924]: info: CoreStateMachine::syncState stateService stop
Sep 13 11:23:43 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus stop
Sep 13 11:23:43 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:23:43 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:43 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:23:43 volumio volumio[924]: info: No code
Sep 13 11:23:43 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:23:43 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:43 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:23:43 volumio volumio[924]: info: ------------------------------ 80ms
Sep 13 11:23:43 volumio volumio[924]: info: sendMpdCommand status took 80 milliseconds
Sep 13 11:23:43 volumio volumio[924]: info: sendMpdCommand status took 79 milliseconds
Sep 13 11:23:43 volumio volumio[924]: verbose: ControllerMpd::parseState
Sep 13 11:23:43 volumio volumio[924]: info: Command Router : Notfying DB Updatefalse
Sep 13 11:23:43 volumio volumio[924]: info: CoreCommandRouter::Close All Modals sent
Sep 13 11:23:43 volumio volumio[924]: verbose: ControllerMpd::parseState
Sep 13 11:23:43 volumio volumio[924]: info: ControllerMpd::pushState
Sep 13 11:23:43 volumio volumio[924]: info: CoreCommandRouter::servicePushState
Sep 13 11:23:43 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:23:43 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:43 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:23:43 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:43 volumio volumio[924]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Sep 13 11:23:43 volumio volumio[924]: verbose: CURRENT POSITION 0
Sep 13 11:23:43 volumio volumio[924]: info: CoreStateMachine::syncState stateService stop
Sep 13 11:23:43 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus stop
Sep 13 11:23:43 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:23:43 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:43 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:23:43 volumio volumio[924]: info: No code
Sep 13 11:23:43 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:23:43 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:43 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:23:43 volumio volumio[924]: info: ------------------------------ 118ms
Sep 13 11:23:45 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb
Sep 13 11:23:45 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand rescan
Sep 13 11:23:45 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status
Sep 13 11:23:45 volumio volumio[924]: info:
Sep 13 11:23:45 volumio volumio[924]: ---------------------------- MPD announces state update: update
Sep 13 11:23:45 volumio volumio[924]: info: sendMpdCommand rescan took 2 milliseconds
Sep 13 11:23:45 volumio volumio[924]: info: ControllerMpd::getState
Sep 13 11:23:45 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status
Sep 13 11:23:45 volumio volumio[924]: info:
Sep 13 11:23:45 volumio volumio[924]: ---------------------------- MPD announces state update: update
Sep 13 11:23:45 volumio volumio[924]: info: ControllerMpd::getState
Sep 13 11:23:45 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status
Sep 13 11:23:45 volumio volumio[924]: info: sendMpdCommand status took 3 milliseconds
Sep 13 11:23:45 volumio volumio[924]: info: sendMpdCommand status took 2 milliseconds
Sep 13 11:23:45 volumio volumio[924]: verbose: ControllerMpd::parseState
Sep 13 11:23:45 volumio volumio[924]: info: Command Router : Notfying DB Updatefalse
Sep 13 11:23:45 volumio volumio[924]: info: CoreCommandRouter::Close All Modals sent
Sep 13 11:23:45 volumio volumio[924]: verbose: ControllerMpd::parseState
Sep 13 11:23:45 volumio volumio[924]: info: ControllerMpd::pushState
Sep 13 11:23:45 volumio volumio[924]: info: CoreCommandRouter::servicePushState
Sep 13 11:23:45 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:23:45 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:45 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:23:45 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:45 volumio volumio[924]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Sep 13 11:23:45 volumio volumio[924]: verbose: CURRENT POSITION 0
Sep 13 11:23:45 volumio volumio[924]: info: CoreStateMachine::syncState stateService stop
Sep 13 11:23:45 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus stop
Sep 13 11:23:45 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:23:45 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:45 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:23:45 volumio volumio[924]: info: No code
Sep 13 11:23:45 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:23:45 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:45 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:23:45 volumio volumio[924]: info: ------------------------------ 47ms
Sep 13 11:23:45 volumio volumio[924]: info: sendMpdCommand status took 45 milliseconds
Sep 13 11:23:45 volumio volumio[924]: info: sendMpdCommand status took 46 milliseconds
Sep 13 11:23:45 volumio volumio[924]: verbose: ControllerMpd::parseState
Sep 13 11:23:45 volumio volumio[924]: info: Command Router : Notfying DB Updatefalse
Sep 13 11:23:45 volumio volumio[924]: info: CoreCommandRouter::Close All Modals sent
Sep 13 11:23:45 volumio volumio[924]: verbose: ControllerMpd::parseState
Sep 13 11:23:45 volumio volumio[924]: info: ControllerMpd::pushState
Sep 13 11:23:45 volumio volumio[924]: info: CoreCommandRouter::servicePushState
Sep 13 11:23:45 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:23:45 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:45 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:23:45 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:45 volumio volumio[924]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Sep 13 11:23:45 volumio volumio[924]: verbose: CURRENT POSITION 0
Sep 13 11:23:45 volumio volumio[924]: info: CoreStateMachine::syncState stateService stop
Sep 13 11:23:45 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus stop
Sep 13 11:23:45 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:23:45 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:45 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:23:45 volumio volumio[924]: info: No code
Sep 13 11:23:45 volumio volumio[924]: info: CoreStateMachine::pushState
Sep 13 11:23:45 volumio volumio[924]: info: CorePlayQueue::getTrack 0
Sep 13 11:23:45 volumio volumio[924]: info: CoreCommandRouter::volumioPushState
Sep 13 11:23:45 volumio volumio[924]: info: ------------------------------ 83ms
Sep 13 11:23:47 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 13 11:23:47 volumio volumio[924]: info: Disabling MyMusic plugin upnp
Sep 13 11:23:47 volumio sudo[3756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
Sep 13 11:23:47 volumio sudo[3756]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 13 11:23:47 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD...
Sep 13 11:23:47 volumio volumio[924]: error: Upnp client error: Error: This socket has been ended by the other party
Sep 13 11:23:49 volumio volumio[924]: info: Enabling MyMusic plugin upnp
Sep 13 11:23:49 volumio volumio[924]: info: Enabling plugin upnp
Sep 13 11:23:49 volumio volumio[924]: info: Loading plugin "upnp"...
Sep 13 11:23:49 volumio volumio[924]: info: [1757748229383] Starting Upmpd Daemon
Sep 13 11:23:49 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 13 11:23:49 volumio volumio[924]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 13 11:23:49 volumio volumio[924]: Error: listen EADDRINUSE: address already in use :::6599
Sep 13 11:23:49 volumio volumio[924]: at Server.setupListenHandle [as _listen2] (net.js:1318:16)
Sep 13 11:23:49 volumio volumio[924]: at listenInCluster (net.js:1366:12)
Sep 13 11:23:49 volumio volumio[924]: at Server.listen (net.js:1452:7)
Sep 13 11:23:49 volumio volumio[924]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17)
Sep 13 11:23:49 volumio volumio[924]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38)
Sep 13 11:23:49 volumio volumio[924]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19)
Sep 13 11:23:49 volumio volumio[924]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
Sep 13 11:23:49 volumio volumio[924]: at processTicksAndRejections (internal/process/task_queues.js:75:11) {
Sep 13 11:23:49 volumio volumio[924]: code: 'EADDRINUSE',
Sep 13 11:23:49 volumio volumio[924]: errno: -98,
Sep 13 11:23:49 volumio volumio[924]: syscall: 'listen',
Sep 13 11:23:49 volumio volumio[924]: address: '::',
Sep 13 11:23:49 volumio volumio[924]: port: 6599
Sep 13 11:23:49 volumio volumio[924]: }
Sep 13 11:23:49 volumio volumio[924]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 13 11:23:49 volumio sudo[3768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-13 11:22
Sep 13 11:23:49 volumio sudo[3768]: 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"