-- Logs begin at Wed 2024-02-21 15:42:47 EST, end at Sun 2024-04-21 09:31:50 EDT. --
Apr 21 09:30:02 paul wpa_supplicant[751]: RRM: Ignoring radio measurement request: Not RRM network
Apr 21 09:30:02 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:30:06 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:30:10 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:30:12 paul wpa_supplicant[751]: RRM: Ignoring radio measurement request: Not RRM network
Apr 21 09:30:14 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:30:18 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:30:22 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:30:26 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:30:30 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:30:34 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:30:38 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:30:42 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:30:46 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:30:50 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:30:54 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 09:30:58 paul volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
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)
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"