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