-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Wed 2024-07-17 19:29:17 BST. -- Jul 17 19:28:03 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:28:07 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:28:11 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:28:15 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:28:19 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:28:23 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:28:27 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:28:31 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Jul 17 19:28:31 volumiopi volumio[897]: info: Adding a new share Jul 17 19:28:31 volumiopi volumio[897]: info: No correspondence found in configuration for share Music on IP 192.168.1.174 Jul 17 19:28:31 volumiopi sudo[11114]: 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.174/Music /mnt/NAS/Music Jul 17 19:28:31 volumiopi sudo[11114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 19:28:31 volumiopi kernel: Key type cifs.spnego registered Jul 17 19:28:31 volumiopi kernel: Key type cifs.idmap registered Jul 17 19:28:31 volumiopi 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. Jul 17 19:28:31 volumiopi kernel: CIFS: Attempting to mount \\192.168.1.174\Music Jul 17 19:28:31 volumiopi kernel: CIFS: VFS: \\192.168.1.174 failed to connect to IPC (rc=-13) Jul 17 19:28:31 volumiopi kernel: CIFS: VFS: session 6603ef9f has no tcon available for a dfs referral request Jul 17 19:28:31 volumiopi kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Jul 17 19:28:31 volumiopi sudo[11114]: pam_unix(sudo:session): session closed for user root Jul 17 19:28:31 volumiopi volumio[897]: info: Permission denied for Music on IP 192.168.1.174 Jul 17 19:28:31 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:28:32 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 17 19:28:35 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:28:39 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:28:43 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Jul 17 19:28:44 volumiopi volumio[897]: info: Share Music successfully unmounted Jul 17 19:28:44 volumiopi sudo[11184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=volumio,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.174/Music /mnt/NAS/Music Jul 17 19:28:44 volumiopi sudo[11184]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 19:28:44 volumiopi kernel: CIFS: Attempting to mount \\192.168.1.174\Music Jul 17 19:28:44 volumiopi sudo[11184]: pam_unix(sudo:session): session closed for user root Jul 17 19:28:44 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:44 volumiopi volumio[897]: info: Jul 17 19:28:44 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:28:44 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:28:44 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:44 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:44 volumiopi volumio[897]: info: Jul 17 19:28:44 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:28:44 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:28:44 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:44 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:44 volumiopi volumio[897]: info: Jul 17 19:28:44 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:28:44 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:28:44 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:44 volumiopi volumio[897]: info: sendMpdCommand status took 7 milliseconds Jul 17 19:28:44 volumiopi volumio[897]: info: sendMpdCommand status took 5 milliseconds Jul 17 19:28:44 volumiopi volumio[897]: info: sendMpdCommand status took 4 milliseconds Jul 17 19:28:44 volumiopi volumio[897]: info: sendMpdCommand status took 4 milliseconds Jul 17 19:28:44 volumiopi volumio[897]: info: sendMpdCommand status took 4 milliseconds Jul 17 19:28:44 volumiopi volumio[897]: info: sendMpdCommand status took 3 milliseconds Jul 17 19:28:44 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:44 volumiopi volumio[897]: info: Command Router : Notfying DB Updatetrue Jul 17 19:28:44 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:44 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:44 volumiopi volumio[897]: info: Command Router : Notfying DB Updatetrue Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:28:44 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:44 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:44 volumiopi volumio[897]: info: Command Router : Notfying DB Updatetrue Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:28:44 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:44 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:44 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:44 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:44 volumiopi volumio[897]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 19:28:44 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:44 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:44 volumiopi volumio[897]: info: No code Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:44 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:44 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:44 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:44 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:44 volumiopi volumio[897]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 19:28:44 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:44 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:44 volumiopi volumio[897]: info: No code Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:44 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:44 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:44 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:44 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:44 volumiopi volumio[897]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 19:28:44 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:44 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:44 volumiopi volumio[897]: info: No code Jul 17 19:28:44 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:44 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:44 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:44 volumiopi volumio[897]: info: ------------------------------ 235ms Jul 17 19:28:44 volumiopi volumio[897]: info: ------------------------------ 232ms Jul 17 19:28:44 volumiopi volumio[897]: info: ------------------------------ 232ms Jul 17 19:28:44 volumiopi volumio[897]: info: [1721240924599] Database update started Jul 17 19:28:45 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 17 19:28:47 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:28:48 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:48 volumiopi volumio[897]: info: Jul 17 19:28:48 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:28:48 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:28:48 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:48 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:48 volumiopi volumio[897]: info: Jul 17 19:28:48 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:28:48 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:28:48 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:48 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:48 volumiopi volumio[897]: info: Jul 17 19:28:48 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:28:48 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:28:48 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:48 volumiopi volumio[897]: info: sendMpdCommand status took 4 milliseconds Jul 17 19:28:48 volumiopi volumio[897]: info: sendMpdCommand status took 3 milliseconds Jul 17 19:28:48 volumiopi volumio[897]: info: sendMpdCommand status took 3 milliseconds Jul 17 19:28:48 volumiopi volumio[897]: info: sendMpdCommand status took 2 milliseconds Jul 17 19:28:48 volumiopi volumio[897]: info: sendMpdCommand status took 2 milliseconds Jul 17 19:28:48 volumiopi volumio[897]: info: sendMpdCommand status took 2 milliseconds Jul 17 19:28:48 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:48 volumiopi volumio[897]: info: Command Router : Notfying DB Updatefalse Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:28:48 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:48 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:48 volumiopi volumio[897]: info: Command Router : Notfying DB Updatefalse Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:28:48 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:48 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:48 volumiopi volumio[897]: info: Command Router : Notfying DB Updatefalse Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:28:48 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:48 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:48 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:48 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:48 volumiopi volumio[897]: 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} Jul 17 19:28:48 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:48 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:48 volumiopi volumio[897]: info: No code Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:48 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:48 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:48 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:48 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:48 volumiopi volumio[897]: 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} Jul 17 19:28:48 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:48 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:48 volumiopi volumio[897]: info: No code Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:48 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:48 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:48 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:48 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:48 volumiopi volumio[897]: 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} Jul 17 19:28:48 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:48 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:48 volumiopi volumio[897]: info: No code Jul 17 19:28:48 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:48 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:48 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:48 volumiopi volumio[897]: info: ------------------------------ 108ms Jul 17 19:28:48 volumiopi volumio[897]: info: ------------------------------ 107ms Jul 17 19:28:48 volumiopi volumio[897]: info: ------------------------------ 107ms Jul 17 19:28:51 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 17 19:28:53 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand update Jul 17 19:28:53 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:53 volumiopi volumio[897]: info: Jul 17 19:28:53 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:28:53 volumiopi volumio[897]: info: sendMpdCommand update took 2 milliseconds Jul 17 19:28:53 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:28:53 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:53 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:53 volumiopi volumio[897]: info: Jul 17 19:28:53 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:28:53 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:28:53 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:53 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:53 volumiopi volumio[897]: info: Jul 17 19:28:53 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:28:53 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:28:53 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:53 volumiopi volumio[897]: info: sendMpdCommand status took 5 milliseconds Jul 17 19:28:53 volumiopi volumio[897]: info: sendMpdCommand status took 5 milliseconds Jul 17 19:28:53 volumiopi volumio[897]: info: sendMpdCommand status took 4 milliseconds Jul 17 19:28:53 volumiopi volumio[897]: info: sendMpdCommand status took 3 milliseconds Jul 17 19:28:53 volumiopi volumio[897]: info: sendMpdCommand status took 2 milliseconds Jul 17 19:28:53 volumiopi volumio[897]: info: sendMpdCommand status took 2 milliseconds Jul 17 19:28:53 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:53 volumiopi volumio[897]: info: Command Router : Notfying DB Updatetrue Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:28:53 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:53 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:53 volumiopi volumio[897]: info: Command Router : Notfying DB Updatetrue Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:28:53 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:53 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:53 volumiopi volumio[897]: info: Command Router : Notfying DB Updatetrue Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:28:53 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:53 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:53 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:53 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:53 volumiopi volumio[897]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 19:28:53 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:53 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:53 volumiopi volumio[897]: info: No code Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:53 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:53 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:53 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:53 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:53 volumiopi volumio[897]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 19:28:53 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:53 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:53 volumiopi volumio[897]: info: No code Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:53 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:53 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:53 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:53 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:53 volumiopi volumio[897]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 19:28:53 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:53 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:53 volumiopi volumio[897]: info: No code Jul 17 19:28:53 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:53 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:53 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:53 volumiopi volumio[897]: info: ------------------------------ 93ms Jul 17 19:28:53 volumiopi volumio[897]: info: ------------------------------ 92ms Jul 17 19:28:53 volumiopi volumio[897]: info: ------------------------------ 91ms Jul 17 19:28:55 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 17 19:28:55 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand rescan Jul 17 19:28:55 volumiopi volumio[897]: info: sendMpdCommand rescan took 2 milliseconds Jul 17 19:28:55 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:28:56 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 17 19:28:56 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand rescan Jul 17 19:28:56 volumiopi volumio[897]: info: sendMpdCommand rescan took 1 milliseconds Jul 17 19:28:57 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:57 volumiopi volumio[897]: info: Jul 17 19:28:57 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:28:57 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:28:57 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:57 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:57 volumiopi volumio[897]: info: Jul 17 19:28:57 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:28:57 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:28:57 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:57 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:57 volumiopi volumio[897]: info: Jul 17 19:28:57 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:28:57 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:28:57 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:28:57 volumiopi volumio[897]: info: sendMpdCommand status took 4 milliseconds Jul 17 19:28:57 volumiopi volumio[897]: info: sendMpdCommand status took 3 milliseconds Jul 17 19:28:57 volumiopi volumio[897]: info: sendMpdCommand status took 3 milliseconds Jul 17 19:28:57 volumiopi volumio[897]: info: sendMpdCommand status took 3 milliseconds Jul 17 19:28:57 volumiopi volumio[897]: info: sendMpdCommand status took 3 milliseconds Jul 17 19:28:57 volumiopi volumio[897]: info: sendMpdCommand status took 2 milliseconds Jul 17 19:28:57 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:57 volumiopi volumio[897]: info: Command Router : Notfying DB Updatetrue Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:28:57 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:57 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:57 volumiopi volumio[897]: info: Command Router : Notfying DB Updatetrue Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:28:57 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:57 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:57 volumiopi volumio[897]: info: Command Router : Notfying DB Updatetrue Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:28:57 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:28:57 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:57 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:57 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:57 volumiopi volumio[897]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 19:28:57 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:57 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:57 volumiopi volumio[897]: info: No code Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:57 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:57 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:57 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:57 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:57 volumiopi volumio[897]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 19:28:57 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:57 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:57 volumiopi volumio[897]: info: No code Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:57 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:57 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:57 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:57 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:57 volumiopi volumio[897]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 19:28:57 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:57 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:57 volumiopi volumio[897]: info: No code Jul 17 19:28:57 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:28:57 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:28:57 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:28:57 volumiopi volumio[897]: info: ------------------------------ 121ms Jul 17 19:28:57 volumiopi volumio[897]: info: ------------------------------ 121ms Jul 17 19:28:57 volumiopi volumio[897]: info: ------------------------------ 121ms Jul 17 19:28:59 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 17 19:29:01 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:29:01 volumiopi volumio[897]: info: Jul 17 19:29:01 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:29:01 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:29:01 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:29:01 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:29:01 volumiopi volumio[897]: info: Jul 17 19:29:01 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:29:01 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:29:01 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:29:01 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:29:01 volumiopi volumio[897]: info: Jul 17 19:29:01 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:29:01 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:29:01 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:29:01 volumiopi volumio[897]: info: sendMpdCommand status took 5 milliseconds Jul 17 19:29:01 volumiopi volumio[897]: info: sendMpdCommand status took 4 milliseconds Jul 17 19:29:01 volumiopi volumio[897]: info: sendMpdCommand status took 5 milliseconds Jul 17 19:29:01 volumiopi volumio[897]: info: sendMpdCommand status took 4 milliseconds Jul 17 19:29:01 volumiopi volumio[897]: info: sendMpdCommand status took 4 milliseconds Jul 17 19:29:01 volumiopi volumio[897]: info: sendMpdCommand status took 3 milliseconds Jul 17 19:29:01 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:29:01 volumiopi volumio[897]: info: Command Router : Notfying DB Updatetrue Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:29:01 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:29:01 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:29:01 volumiopi volumio[897]: info: Command Router : Notfying DB Updatetrue Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:29:01 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:29:01 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:29:01 volumiopi volumio[897]: info: Command Router : Notfying DB Updatetrue Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:29:01 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:29:01 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:01 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:01 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:01 volumiopi volumio[897]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 19:29:01 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:01 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:01 volumiopi volumio[897]: info: No code Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:01 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:01 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:01 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:01 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:01 volumiopi volumio[897]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 19:29:01 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:01 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:01 volumiopi volumio[897]: info: No code Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:01 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:01 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:01 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:01 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:01 volumiopi volumio[897]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 19:29:01 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:01 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:01 volumiopi volumio[897]: info: No code Jul 17 19:29:01 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:01 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:01 volumiopi volumio[897]: info: ------------------------------ 150ms Jul 17 19:29:01 volumiopi volumio[897]: info: ------------------------------ 150ms Jul 17 19:29:01 volumiopi volumio[897]: info: ------------------------------ 149ms Jul 17 19:29:01 volumiopi volumio[897]: info: CoreCommandRouter::volumioGetState Jul 17 19:29:01 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:05 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:29:05 volumiopi volumio[897]: info: Jul 17 19:29:05 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:29:05 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:29:05 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:29:05 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:29:05 volumiopi volumio[897]: info: Jul 17 19:29:05 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:29:05 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:29:05 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:29:05 volumiopi volumio[897]: info: sendMpdCommand status took 2 milliseconds Jul 17 19:29:05 volumiopi volumio[897]: info: sendMpdCommand status took 2 milliseconds Jul 17 19:29:05 volumiopi volumio[897]: info: sendMpdCommand status took 1 milliseconds Jul 17 19:29:05 volumiopi volumio[897]: info: sendMpdCommand status took 1 milliseconds Jul 17 19:29:05 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:29:05 volumiopi volumio[897]: info: Command Router : Notfying DB Updatefalse Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:29:05 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:29:05 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:29:05 volumiopi volumio[897]: info: Command Router : Notfying DB Updatefalse Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:29:05 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:29:05 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:05 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:05 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:05 volumiopi volumio[897]: 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} Jul 17 19:29:05 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:05 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:05 volumiopi volumio[897]: info: No code Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:05 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:05 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:05 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:05 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:05 volumiopi volumio[897]: 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} Jul 17 19:29:05 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:05 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:05 volumiopi volumio[897]: info: No code Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:05 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:05 volumiopi volumio[897]: info: ------------------------------ 53ms Jul 17 19:29:05 volumiopi volumio[897]: info: ------------------------------ 53ms Jul 17 19:29:05 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:29:05 volumiopi volumio[897]: info: Jul 17 19:29:05 volumiopi volumio[897]: ---------------------------- MPD announces state update: update Jul 17 19:29:05 volumiopi volumio[897]: info: ControllerMpd::getState Jul 17 19:29:05 volumiopi volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 17 19:29:05 volumiopi volumio[897]: info: sendMpdCommand status took 1 milliseconds Jul 17 19:29:05 volumiopi volumio[897]: info: sendMpdCommand status took 1 milliseconds Jul 17 19:29:05 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:29:05 volumiopi volumio[897]: info: Command Router : Notfying DB Updatefalse Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::Close All Modals sent Jul 17 19:29:05 volumiopi volumio[897]: verbose: ControllerMpd::parseState Jul 17 19:29:05 volumiopi volumio[897]: info: ControllerMpd::pushState Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::servicePushState Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:05 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:05 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:05 volumiopi volumio[897]: 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} Jul 17 19:29:05 volumiopi volumio[897]: verbose: CURRENT POSITION 0 Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:05 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:05 volumiopi volumio[897]: info: No code Jul 17 19:29:05 volumiopi volumio[897]: info: CoreStateMachine::pushState Jul 17 19:29:05 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::volumioPushState Jul 17 19:29:05 volumiopi volumio[897]: info: ------------------------------ 30ms Jul 17 19:29:05 volumiopi volumio[897]: info: CoreCommandRouter::volumioGetState Jul 17 19:29:05 volumiopi volumio[897]: info: CorePlayQueue::getTrack 0 Jul 17 19:29:11 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 17 19:29:12 volumiopi volumio[897]: info: Preload queue cleared Jul 17 19:29:13 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 17 19:29:13 volumiopi volumio[897]: info: Preload queue cleared Jul 17 19:29:15 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 17 19:29:15 volumiopi volumio[897]: info: Preload queue cleared Jul 17 19:29:17 volumiopi volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 17 19:29:17 volumiopi volumio[897]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 19:29:17 volumiopi volumio[897]: TypeError: Cannot read property 'length' of undefined Jul 17 19:29:17 volumiopi volumio[897]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 17 19:29:17 volumiopi volumio[897]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 17 19:29:17 volumiopi volumio[897]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 17 19:29:17 volumiopi volumio[897]: at Parser.emit (events.js:315:20) Jul 17 19:29:17 volumiopi volumio[897]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 17 19:29:17 volumiopi volumio[897]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 17 19:29:17 volumiopi volumio[897]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 17 19:29:17 volumiopi volumio[897]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 17 19:29:17 volumiopi volumio[897]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 17 19:29:17 volumiopi volumio[897]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 17 19:29:17 volumiopi volumio[897]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 17 19:29:17 volumiopi volumio[897]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 17 19:29:17 volumiopi volumio[897]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 17 19:29:17 volumiopi volumio[897]: at IncomingMessage.emit (events.js:327:22) Jul 17 19:29:17 volumiopi volumio[897]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 17 19:29:17 volumiopi volumio[897]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 17 19:29:17 volumiopi volumio[897]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 19:29:17 volumiopi sudo[11247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-17 19:28 Jul 17 19:29:17 volumiopi sudo[11247]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"