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