-- 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"