-- Logs begin at Wed 2024-02-21 15:42:47 EST, end at Sun 2024-04-21 09:32:03 EDT. --
Apr 21 09:31:02 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:31:06 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:31:11 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:31:14 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:31:18 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:31:22 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:31:23 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 21 09:31:23 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 21 09:31:23 paul volumio[836]: info: Discovery: Getting this device information
Apr 21 09:31:23 paul volumio[836]: info: CoreCommandRouter::volumioGetState
Apr 21 09:31:23 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:23 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 21 09:31:26 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:31:30 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 21 09:31:30 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 21 09:31:30 paul volumio[836]: info: Discovery: Getting this device information
Apr 21 09:31:30 paul volumio[836]: info: CoreCommandRouter::volumioGetState
Apr 21 09:31:30 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:30 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 21 09:31:30 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:31:34 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:31:37 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare
Apr 21 09:31:37 paul volumio[836]: info: Adding a new share
Apr 21 09:31:37 paul volumio[836]: info: No correspondence found in configuration for share Paul on IP 192.168.1.128
Apr 21 09:31:37 paul sudo[1495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.128/Thispc/music /mnt/NAS/Paul
Apr 21 09:31:37 paul sudo[1495]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 09:31:37 paul kernel: Key type cifs.spnego registered
Apr 21 09:31:37 paul kernel: Key type cifs.idmap registered
Apr 21 09:31:37 paul kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount.
Apr 21 09:31:37 paul kernel: CIFS: Attempting to mount \\192.168.1.128\Thispc
Apr 21 09:31:38 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand rescan
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:41 paul volumio[836]: info:
Apr 21 09:31:41 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:41 paul volumio[836]: info: sendMpdCommand rescan took 6 milliseconds
Apr 21 09:31:41 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:41 paul volumio[836]: info:
Apr 21 09:31:41 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:41 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:41 paul volumio[836]: info:
Apr 21 09:31:41 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:41 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:41 paul volumio[836]: info:
Apr 21 09:31:41 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:41 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:41 paul volumio[836]: info: sendMpdCommand status took 9 milliseconds
Apr 21 09:31:41 paul volumio[836]: info: sendMpdCommand status took 6 milliseconds
Apr 21 09:31:41 paul volumio[836]: info: sendMpdCommand status took 5 milliseconds
Apr 21 09:31:41 paul volumio[836]: info: sendMpdCommand status took 5 milliseconds
Apr 21 09:31:41 paul volumio[836]: info: sendMpdCommand status took 5 milliseconds
Apr 21 09:31:41 paul volumio[836]: info: sendMpdCommand status took 4 milliseconds
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:41 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:41 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:41 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:41 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: 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}
Apr 21 09:31:41 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: No code
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: 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}
Apr 21 09:31:41 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: No code
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: 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}
Apr 21 09:31:41 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: No code
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: ------------------------------ 190ms
Apr 21 09:31:41 paul volumio[836]: info: ------------------------------ 186ms
Apr 21 09:31:41 paul volumio[836]: info: ------------------------------ 186ms
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:41 paul volumio[836]: info:
Apr 21 09:31:41 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:41 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:41 paul volumio[836]: info:
Apr 21 09:31:41 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:41 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:41 paul volumio[836]: info: sendMpdCommand status took 192 milliseconds
Apr 21 09:31:41 paul volumio[836]: info: sendMpdCommand status took 190 milliseconds
Apr 21 09:31:41 paul volumio[836]: info: sendMpdCommand status took 4 milliseconds
Apr 21 09:31:41 paul volumio[836]: info: sendMpdCommand status took 4 milliseconds
Apr 21 09:31:41 paul volumio[836]: info: sendMpdCommand status took 4 milliseconds
Apr 21 09:31:41 paul volumio[836]: info: sendMpdCommand status took 3 milliseconds
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:41 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:41 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:41 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:41 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:41 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: 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}
Apr 21 09:31:41 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: No code
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: 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}
Apr 21 09:31:41 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: No code
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: 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}
Apr 21 09:31:41 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: No code
Apr 21 09:31:41 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:41 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:41 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:41 paul volumio[836]: info: ------------------------------ 320ms
Apr 21 09:31:41 paul volumio[836]: info: ------------------------------ 132ms
Apr 21 09:31:41 paul volumio[836]: info: ------------------------------ 132ms
Apr 21 09:31:42 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand update
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:43 paul volumio[836]: info:
Apr 21 09:31:43 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:43 paul volumio[836]: info: sendMpdCommand update took 4 milliseconds
Apr 21 09:31:43 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:43 paul volumio[836]: info:
Apr 21 09:31:43 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:43 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:43 paul volumio[836]: info:
Apr 21 09:31:43 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:43 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:43 paul volumio[836]: info:
Apr 21 09:31:43 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:43 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:43 paul volumio[836]: info: sendMpdCommand status took 6 milliseconds
Apr 21 09:31:43 paul volumio[836]: info: sendMpdCommand status took 6 milliseconds
Apr 21 09:31:43 paul volumio[836]: info: sendMpdCommand status took 5 milliseconds
Apr 21 09:31:43 paul volumio[836]: info: sendMpdCommand status took 4 milliseconds
Apr 21 09:31:43 paul volumio[836]: info: sendMpdCommand status took 3 milliseconds
Apr 21 09:31:43 paul volumio[836]: info: sendMpdCommand status took 3 milliseconds
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:43 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:43 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:43 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:43 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: 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}
Apr 21 09:31:43 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: No code
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: 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}
Apr 21 09:31:43 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: No code
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: 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}
Apr 21 09:31:43 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: No code
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: ------------------------------ 135ms
Apr 21 09:31:43 paul volumio[836]: info: ------------------------------ 134ms
Apr 21 09:31:43 paul volumio[836]: info: ------------------------------ 133ms
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:43 paul volumio[836]: info:
Apr 21 09:31:43 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:43 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:43 paul volumio[836]: info:
Apr 21 09:31:43 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:43 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:43 paul volumio[836]: info: sendMpdCommand status took 138 milliseconds
Apr 21 09:31:43 paul volumio[836]: info: sendMpdCommand status took 138 milliseconds
Apr 21 09:31:43 paul volumio[836]: info: sendMpdCommand status took 5 milliseconds
Apr 21 09:31:43 paul volumio[836]: info: sendMpdCommand status took 4 milliseconds
Apr 21 09:31:43 paul volumio[836]: info: sendMpdCommand status took 4 milliseconds
Apr 21 09:31:43 paul volumio[836]: info: sendMpdCommand status took 3 milliseconds
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:43 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:43 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:43 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:43 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:43 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: 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}
Apr 21 09:31:43 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: No code
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: 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}
Apr 21 09:31:43 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: No code
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: 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}
Apr 21 09:31:43 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: No code
Apr 21 09:31:43 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:43 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:43 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:43 paul volumio[836]: info: ------------------------------ 277ms
Apr 21 09:31:43 paul volumio[836]: info: ------------------------------ 144ms
Apr 21 09:31:43 paul volumio[836]: info: ------------------------------ 143ms
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand update
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:44 paul volumio[836]: info:
Apr 21 09:31:44 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:44 paul volumio[836]: info: sendMpdCommand update took 3 milliseconds
Apr 21 09:31:44 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:44 paul volumio[836]: info:
Apr 21 09:31:44 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:44 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:44 paul volumio[836]: info:
Apr 21 09:31:44 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:44 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:44 paul volumio[836]: info:
Apr 21 09:31:44 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:44 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:44 paul volumio[836]: info: sendMpdCommand status took 9 milliseconds
Apr 21 09:31:44 paul volumio[836]: info: sendMpdCommand status took 6 milliseconds
Apr 21 09:31:44 paul volumio[836]: info: sendMpdCommand status took 5 milliseconds
Apr 21 09:31:44 paul volumio[836]: info: sendMpdCommand status took 5 milliseconds
Apr 21 09:31:44 paul volumio[836]: info: sendMpdCommand status took 4 milliseconds
Apr 21 09:31:44 paul volumio[836]: info: sendMpdCommand status took 3 milliseconds
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:44 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:44 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:44 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:44 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: 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}
Apr 21 09:31:44 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: No code
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: 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}
Apr 21 09:31:44 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: No code
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: 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}
Apr 21 09:31:44 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: No code
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: ------------------------------ 149ms
Apr 21 09:31:44 paul volumio[836]: info: ------------------------------ 145ms
Apr 21 09:31:44 paul volumio[836]: info: ------------------------------ 144ms
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:44 paul volumio[836]: info:
Apr 21 09:31:44 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:44 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:44 paul volumio[836]: info:
Apr 21 09:31:44 paul volumio[836]: ---------------------------- MPD announces state update: update
Apr 21 09:31:44 paul volumio[836]: info: ControllerMpd::getState
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 09:31:44 paul volumio[836]: info: sendMpdCommand status took 150 milliseconds
Apr 21 09:31:44 paul volumio[836]: info: sendMpdCommand status took 149 milliseconds
Apr 21 09:31:44 paul volumio[836]: info: sendMpdCommand status took 5 milliseconds
Apr 21 09:31:44 paul volumio[836]: info: sendMpdCommand status took 4 milliseconds
Apr 21 09:31:44 paul volumio[836]: info: sendMpdCommand status took 4 milliseconds
Apr 21 09:31:44 paul volumio[836]: info: sendMpdCommand status took 3 milliseconds
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:44 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:44 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:44 paul volumio[836]: info: Command Router : Notfying DB Updatefalse
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::Close All Modals sent
Apr 21 09:31:44 paul volumio[836]: verbose: ControllerMpd::parseState
Apr 21 09:31:44 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: 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}
Apr 21 09:31:44 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: No code
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: 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}
Apr 21 09:31:44 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: No code
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: ControllerMpd::pushState
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::servicePushState
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: 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}
Apr 21 09:31:44 paul volumio[836]: verbose: CURRENT POSITION 0
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::syncState stateService stop
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: No code
Apr 21 09:31:44 paul volumio[836]: info: CoreStateMachine::pushState
Apr 21 09:31:44 paul volumio[836]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:44 paul volumio[836]: info: ------------------------------ 284ms
Apr 21 09:31:44 paul volumio[836]: info: ------------------------------ 139ms
Apr 21 09:31:44 paul volumio[836]: info: ------------------------------ 139ms
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares
Apr 21 09:31:44 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService
Apr 21 09:31:46 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:31:47 paul sudo[1495]: pam_unix(sudo:session): session closed for user root
Apr 21 09:31:47 paul volumio[836]: info: Error mounting Paul on IP 192.168.1.128 : (115): Operation now in progress
Apr 21 09:31:47 paul volumio[836]: Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
Apr 21 09:31:47 paul kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Apr 21 09:31:47 paul kernel: CIFS: VFS: cifs_mount failed w/return code = -115
Apr 21 09:31:48 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Apr 21 09:31:49 paul volumio[836]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 21 09:31:49 paul volumio[836]: Error: dns service error: unknown
Apr 21 09:31:49 paul volumio[836]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30)
Apr 21 09:31:49 paul volumio[836]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) {
Apr 21 09:31:49 paul volumio[836]: errorCode: -65537
Apr 21 09:31:49 paul volumio[836]: }
Apr 21 09:31:49 paul volumio[836]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 21 09:31:50 paul sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-21 09:30
Apr 21 09:31:50 paul sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 09:31:50 paul sudo[1563]: pam_unix(sudo:session): session closed for user root
Apr 21 09:31:50 paul volumio-remote-updater[506]: [2024-04-21 09:31:50] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Apr 21 09:31:50 paul volumio-remote-updater[506]: [2024-04-21 09:31:50] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Apr 21 09:31:50 paul systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Apr 21 09:31:50 paul systemd[1]: volumio.service: Failed with result 'exit-code'.
Apr 21 09:31:50 paul systemd[1]: Started dynamicswap service.
Apr 21 09:31:50 paul systemd[1]: dynamicswap.service: Succeeded.
Apr 21 09:31:50 paul systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Apr 21 09:31:50 paul systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1.
Apr 21 09:31:51 paul systemd[1]: Started dynamicswap service.
Apr 21 09:31:51 paul systemd[1]: Stopped Volumio Backend Module.
Apr 21 09:31:51 paul systemd[1]: Started Volumio Backend Module.
Apr 21 09:31:51 paul systemd[1]: dynamicswap.service: Succeeded.
Apr 21 09:31:52 paul volumio[1577]: info: -------------------------------------------
Apr 21 09:31:52 paul volumio[1577]: info: ----- Volumio3 ----
Apr 21 09:31:52 paul volumio[1577]: info: -------------------------------------------
Apr 21 09:31:52 paul volumio[1577]: info: ----- System startup ----
Apr 21 09:31:52 paul volumio[1577]: info: -------------------------------------------
Apr 21 09:31:52 paul volumio[1577]: info: MYVOLUMIO Environment detected
Apr 21 09:31:52 paul volumio[1577]: info: Plugin folders cleanup
Apr 21 09:31:52 paul volumio[1577]: info: Scanning into folder /volumio/app/plugins/
Apr 21 09:31:52 paul volumio[1577]: info: Scanning category audio_interface
Apr 21 09:31:52 paul volumio[1577]: info: Scanning category miscellanea
Apr 21 09:31:52 paul volumio[1577]: info: Scanning category music_service
Apr 21 09:31:52 paul volumio[1577]: info: Scanning category plugins.json
Apr 21 09:31:52 paul volumio[1577]: info: Scanning category system_controller
Apr 21 09:31:52 paul volumio[1577]: info: Scanning category user_interface
Apr 21 09:31:52 paul volumio[1577]: info: Scanning into folder /data/plugins/
Apr 21 09:31:52 paul volumio[1577]: info: Scanning category music_service
Apr 21 09:31:52 paul volumio[1577]: info: Scanning category user_interface
Apr 21 09:31:52 paul volumio[1577]: info: Plugin folders cleanup completed
Apr 21 09:31:52 paul volumio[1577]: info: -------------------------------------------
Apr 21 09:31:52 paul volumio[1577]: info: ----- Core plugins startup ----
Apr 21 09:31:52 paul volumio[1577]: info: -------------------------------------------
Apr 21 09:31:52 paul volumio[1577]: info: Loading plugins from folder /volumio/app/plugins/
Apr 21 09:31:52 paul volumio[1577]: info: Adding plugin upnp to MyMusic Plugins
Apr 21 09:31:52 paul volumio[1577]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 21 09:31:52 paul volumio[1577]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 21 09:31:52 paul volumio[1577]: info: Loading plugins from folder /data/plugins/
Apr 21 09:31:52 paul volumio[1577]: info: Loading plugin "system"...
Apr 21 09:31:52 paul volumio[1577]: info: Loading plugin "appearance"...
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "network"...
Apr 21 09:31:53 paul volumio[1577]: info: Refreshing Cached IP Addresses
Apr 21 09:31:53 paul sudo[1603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 21 09:31:53 paul sudo[1603]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 09:31:53 paul sudo[1603]: pam_unix(sudo:session): session closed for user root
Apr 21 09:31:53 paul sudo[1605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 21 09:31:53 paul sudo[1605]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "services"...
Apr 21 09:31:53 paul sudo[1605]: pam_unix(sudo:session): session closed for user root
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "alsa_controller"...
Apr 21 09:31:53 paul sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Apr 21 09:31:53 paul sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 09:31:53 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "wizard"...
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "networkfs"...
Apr 21 09:31:53 paul volumio[1577]: info: Starting Udev Watcher for removable devices
Apr 21 09:31:53 paul sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.128/Thispc/music /mnt/NAS/Paul
Apr 21 09:31:53 paul sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 09:31:53 paul kernel: CIFS: Attempting to mount \\192.168.1.128\Thispc
Apr 21 09:31:53 paul volumio[1577]: info: Ignoring mount for partition: boot
Apr 21 09:31:53 paul volumio[1577]: info: Ignoring mount for partition: volumio
Apr 21 09:31:53 paul volumio[1577]: info: Ignoring mount for partition: volumio_data
Apr 21 09:31:53 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "volumio_command_line_client"...
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "upnp"...
Apr 21 09:31:53 paul volumio[1577]: info: [1713706313285] Starting Upmpd Daemon
Apr 21 09:31:53 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "my_music"...
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "mpd"...
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "upnp_browser"...
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "alarm-clock"...
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "airplay_emulation"...
Apr 21 09:31:53 paul volumio[1577]: info: Starting Shairport Sync
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "last_100"...
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "webradio"...
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "i2s_dacs"...
Apr 21 09:31:53 paul volumio[1577]: info: I2S DAC not set, start Auto-detection
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "volumiodiscovery"...
Apr 21 09:31:53 paul volumio[1577]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 21 09:31:53 paul node[1577]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 21 09:31:53 paul volumio[1577]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 21 09:31:53 paul node[1577]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 21 09:31:53 paul volumio[1577]: *** WARNING *** For more information see
Apr 21 09:31:53 paul node[1577]: *** WARNING *** For more information see
Apr 21 09:31:53 paul volumio[1577]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 21 09:31:53 paul node[1577]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 21 09:31:53 paul volumio[1577]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 21 09:31:53 paul node[1577]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 21 09:31:53 paul volumio[1577]: *** WARNING *** For more information see
Apr 21 09:31:53 paul node[1577]: *** WARNING *** For more information see
Apr 21 09:31:53 paul volumio[1577]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 21 09:31:53 paul volumio[1577]: info: Discovery: Started advertising with name: Paul
Apr 21 09:31:53 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "outputs"...
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "albumart"...
Apr 21 09:31:53 paul volumio[1577]: info: Plugin example_plugin is not enabled
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "inputs"...
Apr 21 09:31:53 paul volumio[1577]: info: Loading plugin "updater_comm"...
Apr 21 09:31:53 paul volumio[1577]: info: Plugin mpdemulation is not enabled
Apr 21 09:31:54 paul volumio[1577]: info: Loading plugin "rest_api"...
Apr 21 09:31:54 paul volumio[1577]: info: Loading plugin "websocket"...
Apr 21 09:31:54 paul volumio[1577]: info: Loading plugin "pandora"...
Apr 21 09:31:54 paul volumio[1577]: Forking 3 albumart workers
Apr 21 09:31:54 paul volumio[1577]: Starting albumart workers
Apr 21 09:31:54 paul volumio[1577]: Starting albumart workers
Apr 21 09:31:54 paul volumio[1577]: info: Applying required configuration parameters for plugin pandora
Apr 21 09:31:54 paul volumio[1577]: Starting albumart workers
Apr 21 09:31:54 paul volumio[1577]: info: Loading plugin "randomizer"...
Apr 21 09:31:55 paul volumio[1577]: info: Applying required configuration parameters for plugin randomizer
Apr 21 09:31:55 paul volumio[1577]: info: Loading i18n strings for locale en
Apr 21 09:31:55 paul volumio[1577]: Updating browse sources language
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::initPlayerControls
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 21 09:31:55 paul volumio[1577]: Express server listening on port 3000
Apr 21 09:31:55 paul volumio[1577]: [Metrics] WebUI: 3s 585.06ms
Apr 21 09:31:55 paul volumio[1577]: info: CoreStateMachine::resetVolumioState
Apr 21 09:31:55 paul volumio[1577]: info: CoreStateMachine::getcurrentVolume
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::volumioRetrievevolume
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 09:31:55 paul volumio[1577]: verbose: New Socket.io Connection to 192.168.1.217 from 192.168.1.199 UA: okhttp/4.9.2 Total Clients: 1
Apr 21 09:31:55 paul volumio[1577]: verbose: New Socket.io Connection to 192.168.1.217 from 192.168.1.199 UA: okhttp/4.9.2 Total Clients: 2
Apr 21 09:31:55 paul volumio[1577]: verbose: New Socket.io Connection to 192.168.1.217 from 192.168.1.199 UA: okhttp/4.9.2 Total Clients: 3
Apr 21 09:31:55 paul volumio[1577]: verbose: New Socket.io Connection to 192.168.1.217 from 192.168.1.199 UA: okhttp/4.9.2 Total Clients: 4
Apr 21 09:31:55 paul volumio[1577]: info: Reloading queue from file
Apr 21 09:31:55 paul volumio[1577]: info: CoreStateMachine::setRepeat null single undefined
Apr 21 09:31:55 paul volumio[1577]: info: CoreStateMachine::pushState
Apr 21 09:31:55 paul volumio[1577]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:55 paul volumio[1577]: info: CoreStateMachine::setRandom null
Apr 21 09:31:55 paul volumio[1577]: info: CoreStateMachine::pushState
Apr 21 09:31:55 paul volumio[1577]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:55 paul volumio[1577]: info: Setting Device type: Raspberry PI
Apr 21 09:31:55 paul volumio[1577]: info: VolumeController:: Volume=86 Mute =false
Apr 21 09:31:55 paul volumio[1577]: info: CoreStateMachine::pushState
Apr 21 09:31:55 paul volumio[1577]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:55 paul volumio[1577]: info: CoreStateMachine::updateTrackBlock
Apr 21 09:31:55 paul volumio[1577]: info: CorePlayQueue::getTrackBlock
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::volumioRetrievevolume
Apr 21 09:31:55 paul volumio[1577]: verbose: New Socket.io Connection to 192.168.1.217 from 192.168.1.199 UA: okhttp/4.9.2 Total Clients: 5
Apr 21 09:31:55 paul volumio[1577]: info: Discovery: adding 1125842e-ff9a-4d21-94fe-4b3b6d134833
Apr 21 09:31:55 paul volumio[1577]: info: Discovery: Found device Paul
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::volumioGetState
Apr 21 09:31:55 paul volumio[1577]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:55 paul volumio[1577]: info: VolumeController:: Volume=86 Mute =false
Apr 21 09:31:55 paul volumio[1577]: info: CoreStateMachine::pushState
Apr 21 09:31:55 paul volumio[1577]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::volumioPushState
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::volumioGetState
Apr 21 09:31:55 paul volumio[1577]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:55 paul volumio-remote-updater[506]: [2024-04-21 09:31:55] [connect] Successful connection
Apr 21 09:31:55 paul volumio-remote-updater[506]: [2024-04-21 09:31:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713706315 101
Apr 21 09:31:55 paul volumio[1577]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 6
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Apr 21 09:31:55 paul volumio[1577]: verbose: New Socket.io Connection to 192.168.1.217 from 192.168.1.199 UA: okhttp/4.9.2 Total Clients: 7
Apr 21 09:31:55 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:31:56 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:31:56 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 21 09:31:56 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 21 09:31:56 paul volumio[1577]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 21 09:31:56 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 21 09:31:56 paul volumio[1577]: info: CoreCommandRouter::volumioGetState
Apr 21 09:31:56 paul volumio[1577]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:56 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 21 09:31:56 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 21 09:31:56 paul volumio[1577]: info: Received Get System Info
Apr 21 09:31:56 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 21 09:31:56 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 21 09:31:56 paul volumio[1577]: info: Discovery: Getting this device information
Apr 21 09:31:56 paul volumio[1577]: info: CoreCommandRouter::volumioGetState
Apr 21 09:31:56 paul volumio[1577]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:56 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 21 09:31:56 paul volumio[1577]: info: CoreCommandRouter::volumioGetState
Apr 21 09:31:56 paul volumio[1577]: info: CorePlayQueue::getTrack 0
Apr 21 09:31:56 paul volumio[1577]: info: Listing playlists
Apr 21 09:31:56 paul volumio[1577]: info: CoreCommandRouter::volumioGetQueue
Apr 21 09:31:56 paul volumio[1577]: info: CoreStateMachine::getQueue
Apr 21 09:31:56 paul volumio[1577]: info: CorePlayQueue::getQueue
Apr 21 09:31:57 paul sudo[1609]: pam_unix(sudo:session): session closed for user root
Apr 21 09:31:58 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:32:00 paul volumio[1577]: verbose: New Socket.io Connection to 192.168.1.217 from 192.168.1.199 UA: okhttp/4.9.2 Total Clients: 8
Apr 21 09:32:02 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:32:02 paul volumio[1577]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb
Apr 21 09:32:02 paul volumio[1577]: verbose: ControllerMpd::sendMpdCommand update
Apr 21 09:32:02 paul volumio[1577]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 21 09:32:02 paul volumio[1577]: TypeError: Cannot read property 'then' of undefined
Apr 21 09:32:02 paul volumio[1577]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6)
Apr 21 09:32:02 paul volumio[1577]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1898:15)
Apr 21 09:32:02 paul volumio[1577]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32)
Apr 21 09:32:02 paul volumio[1577]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1049:26)
Apr 21 09:32:02 paul volumio[1577]: at Socket.emit (events.js:315:20)
Apr 21 09:32:02 paul volumio[1577]: at /volumio/node_modules/socket.io/lib/socket.js:503:12
Apr 21 09:32:02 paul volumio[1577]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
Apr 21 09:32:02 paul volumio[1577]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 21 09:32:03 paul sudo[1695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-21 09:31
Apr 21 09:32:03 paul sudo[1695]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET"
VOLUMIO_VERSION="3.631"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"