-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Sat 2025-01-11 20:56:46 EST. -- Jan 11 20:55:00 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:01 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:55:03 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:55:04 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand rescan Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:05 volumio-ss volumio[740]: info: Jan 11 20:55:05 volumio-ss volumio[740]: ---------------------------- MPD announces state update: update Jan 11 20:55:05 volumio-ss volumio[740]: info: sendMpdCommand rescan took 11 milliseconds Jan 11 20:55:05 volumio-ss volumio[740]: info: ControllerMpd::getState Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:05 volumio-ss volumio[740]: info: Jan 11 20:55:05 volumio-ss volumio[740]: ---------------------------- MPD announces state update: update Jan 11 20:55:05 volumio-ss volumio[740]: info: ControllerMpd::getState Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:05 volumio-ss volumio[740]: info: Jan 11 20:55:05 volumio-ss volumio[740]: ---------------------------- MPD announces state update: update Jan 11 20:55:05 volumio-ss volumio[740]: info: ControllerMpd::getState Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:05 volumio-ss volumio[740]: info: sendMpdCommand status took 16 milliseconds Jan 11 20:55:05 volumio-ss volumio[740]: info: sendMpdCommand status took 9 milliseconds Jan 11 20:55:05 volumio-ss volumio[740]: info: sendMpdCommand status took 8 milliseconds Jan 11 20:55:05 volumio-ss volumio[740]: info: sendMpdCommand status took 6 milliseconds Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:05 volumio-ss volumio[740]: info: Command Router : Notfying DB Updatefalse Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:05 volumio-ss volumio[740]: info: Command Router : Notfying DB Updatefalse Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:05 volumio-ss volumio[740]: info: ControllerMpd::pushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreCommandRouter::servicePushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreStateMachine::pushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreCommandRouter::volumioPushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:05 volumio-ss volumio[740]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 11 20:55:05 volumio-ss volumio[740]: info: ControllerMpd::pushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreCommandRouter::servicePushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreStateMachine::pushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreCommandRouter::volumioPushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:05 volumio-ss volumio[740]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 11 20:55:05 volumio-ss volumio[740]: info: ------------------------------ 186ms Jan 11 20:55:05 volumio-ss volumio[740]: info: ------------------------------ 177ms Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:05 volumio-ss volumio[740]: info: Jan 11 20:55:05 volumio-ss volumio[740]: ---------------------------- MPD announces state update: update Jan 11 20:55:05 volumio-ss volumio[740]: info: ControllerMpd::getState Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:05 volumio-ss volumio[740]: info: sendMpdCommand status took 181 milliseconds Jan 11 20:55:05 volumio-ss volumio[740]: info: sendMpdCommand status took 180 milliseconds Jan 11 20:55:05 volumio-ss volumio[740]: info: sendMpdCommand status took 5 milliseconds Jan 11 20:55:05 volumio-ss volumio[740]: info: sendMpdCommand status took 4 milliseconds Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:05 volumio-ss volumio[740]: info: Command Router : Notfying DB Updatefalse Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:05 volumio-ss volumio[740]: info: Command Router : Notfying DB Updatefalse Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:55:05 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:05 volumio-ss volumio[740]: info: ControllerMpd::pushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreCommandRouter::servicePushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreStateMachine::pushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreCommandRouter::volumioPushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:05 volumio-ss volumio[740]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 11 20:55:05 volumio-ss volumio[740]: info: ControllerMpd::pushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreCommandRouter::servicePushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreStateMachine::pushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:05 volumio-ss volumio[740]: info: CoreCommandRouter::volumioPushState Jan 11 20:55:05 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:05 volumio-ss volumio[740]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 11 20:55:05 volumio-ss volumio[740]: info: ------------------------------ 298ms Jan 11 20:55:05 volumio-ss volumio[740]: info: ------------------------------ 122ms Jan 11 20:55:08 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:09 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:55:11 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:55:12 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:12 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:55:14 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:55:16 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:16 volumio-ss volumiologrotate[484]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jan 11 20:55:17 volumio-ss volumiologrotate[484]: ls: cannot access 'SS': No such file or directory Jan 11 20:55:17 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:55:18 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 11 20:55:18 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 11 20:55:20 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:20 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:55:20 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:55:23 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:55:24 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:26 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:55:28 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:29 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:55:32 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:55:32 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:35 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:55:36 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:38 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:55:39 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Jan 11 20:55:39 volumio-ss volumio[740]: info: Adding a new share Jan 11 20:55:39 volumio-ss volumio[740]: info: No correspondence found in configuration for share NAS on IP 10.0.0.172 Jan 11 20:55:39 volumio-ss sudo[10261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.0.172/NAS /mnt/NAS/NAS Jan 11 20:55:39 volumio-ss sudo[10261]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 20:55:39 volumio-ss kernel: Key type cifs.spnego registered Jan 11 20:55:39 volumio-ss kernel: Key type cifs.idmap registered Jan 11 20:55:39 volumio-ss 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. Jan 11 20:55:39 volumio-ss kernel: CIFS: Attempting to mount //10.0.0.172/NAS Jan 11 20:55:39 volumio-ss kernel: cryptd: max_cpu_qlen set to 1000 Jan 11 20:55:40 volumio-ss sudo[10261]: pam_unix(sudo:session): session closed for user root Jan 11 20:55:40 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:40 volumio-ss volumio[740]: info: Jan 11 20:55:40 volumio-ss volumio[740]: ---------------------------- MPD announces state update: update Jan 11 20:55:40 volumio-ss volumio[740]: info: ControllerMpd::getState Jan 11 20:55:40 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:40 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:40 volumio-ss volumio[740]: info: Jan 11 20:55:40 volumio-ss volumio[740]: ---------------------------- MPD announces state update: update Jan 11 20:55:40 volumio-ss volumio[740]: info: ControllerMpd::getState Jan 11 20:55:40 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:40 volumio-ss volumio[740]: info: sendMpdCommand status took 10 milliseconds Jan 11 20:55:40 volumio-ss volumio[740]: info: sendMpdCommand status took 8 milliseconds Jan 11 20:55:40 volumio-ss volumio[740]: info: sendMpdCommand status took 6 milliseconds Jan 11 20:55:40 volumio-ss volumio[740]: info: sendMpdCommand status took 5 milliseconds Jan 11 20:55:40 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:41 volumio-ss volumio[740]: info: Command Router : Notfying DB Updatetrue Jan 11 20:55:41 volumio-ss volumio[740]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:55:41 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:41 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:41 volumio-ss volumio[740]: info: Command Router : Notfying DB Updatetrue Jan 11 20:55:41 volumio-ss volumio[740]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:55:41 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:41 volumio-ss volumio[740]: info: ControllerMpd::pushState Jan 11 20:55:41 volumio-ss volumio[740]: info: CoreCommandRouter::servicePushState Jan 11 20:55:41 volumio-ss volumio[740]: info: CoreStateMachine::pushState Jan 11 20:55:41 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:41 volumio-ss volumio[740]: info: CoreCommandRouter::volumioPushState Jan 11 20:55:41 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:41 volumio-ss volumio[740]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 11 20:55:41 volumio-ss volumio[740]: info: ControllerMpd::pushState Jan 11 20:55:41 volumio-ss volumio[740]: info: CoreCommandRouter::servicePushState Jan 11 20:55:41 volumio-ss volumio[740]: info: CoreStateMachine::pushState Jan 11 20:55:41 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:41 volumio-ss volumio[740]: info: CoreCommandRouter::volumioPushState Jan 11 20:55:41 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:41 volumio-ss volumio[740]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 11 20:55:41 volumio-ss volumio[740]: info: ------------------------------ 1206ms Jan 11 20:55:41 volumio-ss volumio[740]: info: ------------------------------ 1202ms Jan 11 20:55:41 volumio-ss volumio[740]: info: [1736646941406] Database update started Jan 11 20:55:41 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 11 20:55:41 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:41 volumio-ss volumio[740]: info: Jan 11 20:55:41 volumio-ss volumio[740]: ---------------------------- MPD announces state update: update Jan 11 20:55:41 volumio-ss volumio[740]: info: ControllerMpd::getState Jan 11 20:55:41 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:41 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:41 volumio-ss volumio[740]: info: Jan 11 20:55:41 volumio-ss volumio[740]: ---------------------------- MPD announces state update: update Jan 11 20:55:41 volumio-ss volumio[740]: info: ControllerMpd::getState Jan 11 20:55:41 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:55:41 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:41 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:55:41 volumio-ss volumio[740]: info: sendMpdCommand status took 34 milliseconds Jan 11 20:55:41 volumio-ss volumio[740]: info: sendMpdCommand status took 32 milliseconds Jan 11 20:55:41 volumio-ss volumio[740]: info: sendMpdCommand status took 31 milliseconds Jan 11 20:55:41 volumio-ss volumio[740]: info: sendMpdCommand status took 29 milliseconds Jan 11 20:55:41 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:42 volumio-ss volumio[740]: info: Command Router : Notfying DB Updatefalse Jan 11 20:55:42 volumio-ss volumio[740]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:55:42 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:42 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:42 volumio-ss volumio[740]: info: Command Router : Notfying DB Updatefalse Jan 11 20:55:42 volumio-ss volumio[740]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:55:42 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:55:42 volumio-ss volumio[740]: info: ControllerMpd::pushState Jan 11 20:55:42 volumio-ss volumio[740]: info: CoreCommandRouter::servicePushState Jan 11 20:55:42 volumio-ss volumio[740]: info: CoreStateMachine::pushState Jan 11 20:55:42 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:42 volumio-ss volumio[740]: info: CoreCommandRouter::volumioPushState Jan 11 20:55:42 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:42 volumio-ss volumio[740]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 11 20:55:42 volumio-ss volumio[740]: info: ControllerMpd::pushState Jan 11 20:55:42 volumio-ss volumio[740]: info: CoreCommandRouter::servicePushState Jan 11 20:55:42 volumio-ss volumio[740]: info: CoreStateMachine::pushState Jan 11 20:55:42 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:42 volumio-ss volumio[740]: info: CoreCommandRouter::volumioPushState Jan 11 20:55:42 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:55:42 volumio-ss volumio[740]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 11 20:55:42 volumio-ss volumio[740]: info: ------------------------------ 967ms Jan 11 20:55:42 volumio-ss volumio[740]: info: ------------------------------ 963ms Jan 11 20:55:44 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:45 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:55:46 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:55:48 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:48 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:55:52 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:54 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:55:56 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:55:56 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:55:57 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand rescan Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:56:00 volumio-ss volumio[740]: info: Jan 11 20:56:00 volumio-ss volumio[740]: ---------------------------- MPD announces state update: update Jan 11 20:56:00 volumio-ss volumio[740]: info: sendMpdCommand rescan took 4 milliseconds Jan 11 20:56:00 volumio-ss volumio[740]: info: ControllerMpd::getState Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:56:00 volumio-ss volumio[740]: info: Jan 11 20:56:00 volumio-ss volumio[740]: ---------------------------- MPD announces state update: update Jan 11 20:56:00 volumio-ss volumio[740]: info: ControllerMpd::getState Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:56:00 volumio-ss volumio[740]: info: sendMpdCommand status took 9 milliseconds Jan 11 20:56:00 volumio-ss volumio[740]: info: sendMpdCommand status took 6 milliseconds Jan 11 20:56:00 volumio-ss volumio[740]: info: sendMpdCommand status took 6 milliseconds Jan 11 20:56:00 volumio-ss volumio[740]: info: sendMpdCommand status took 4 milliseconds Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:56:00 volumio-ss volumio[740]: info: Command Router : Notfying DB Updatetrue Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:56:00 volumio-ss volumio[740]: info: Command Router : Notfying DB Updatetrue Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:56:00 volumio-ss volumio[740]: info: ControllerMpd::pushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreCommandRouter::servicePushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreStateMachine::pushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreCommandRouter::volumioPushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:56:00 volumio-ss volumio[740]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 11 20:56:00 volumio-ss volumio[740]: info: ControllerMpd::pushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreCommandRouter::servicePushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreStateMachine::pushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreCommandRouter::volumioPushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:56:00 volumio-ss volumio[740]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 11 20:56:00 volumio-ss volumio[740]: info: ------------------------------ 123ms Jan 11 20:56:00 volumio-ss volumio[740]: info: ------------------------------ 120ms Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:56:00 volumio-ss volumio[740]: info: Jan 11 20:56:00 volumio-ss volumio[740]: ---------------------------- MPD announces state update: update Jan 11 20:56:00 volumio-ss volumio[740]: info: ControllerMpd::getState Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:56:00 volumio-ss volumio[740]: info: Jan 11 20:56:00 volumio-ss volumio[740]: ---------------------------- MPD announces state update: update Jan 11 20:56:00 volumio-ss volumio[740]: info: ControllerMpd::getState Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::sendMpdCommand status Jan 11 20:56:00 volumio-ss volumio[740]: info: sendMpdCommand status took 20 milliseconds Jan 11 20:56:00 volumio-ss volumio[740]: info: sendMpdCommand status took 19 milliseconds Jan 11 20:56:00 volumio-ss volumio[740]: info: sendMpdCommand status took 18 milliseconds Jan 11 20:56:00 volumio-ss volumio[740]: info: sendMpdCommand status took 17 milliseconds Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:56:00 volumio-ss volumio[740]: info: Command Router : Notfying DB Updatefalse Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:56:00 volumio-ss volumio[740]: info: Command Router : Notfying DB Updatefalse Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreCommandRouter::Close All Modals sent Jan 11 20:56:00 volumio-ss volumio[740]: verbose: ControllerMpd::parseState Jan 11 20:56:00 volumio-ss volumio[740]: info: ControllerMpd::pushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreCommandRouter::servicePushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreStateMachine::pushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreCommandRouter::volumioPushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:56:00 volumio-ss volumio[740]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 11 20:56:00 volumio-ss volumio[740]: info: ControllerMpd::pushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreCommandRouter::servicePushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreStateMachine::pushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:56:00 volumio-ss volumio[740]: info: CoreCommandRouter::volumioPushState Jan 11 20:56:00 volumio-ss volumio[740]: info: CorePlayQueue::getTrack 0 Jan 11 20:56:00 volumio-ss volumio[740]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 11 20:56:00 volumio-ss volumio[740]: info: ------------------------------ 132ms Jan 11 20:56:00 volumio-ss volumio[740]: info: ------------------------------ 131ms Jan 11 20:56:04 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:56:05 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:56:05 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:56:08 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 20:56:08 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:56:13 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:56:17 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:56:17 volumio-ss volumiologrotate[484]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jan 11 20:56:17 volumio-ss volumiologrotate[484]: ls: cannot access 'SS': No such file or directory Jan 11 20:56:20 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 11 20:56:20 volumio-ss volumio[740]: info: Preload queue cleared Jan 11 20:56:21 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:56:24 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:56:25 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:56:28 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:56:29 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 11 20:56:29 volumio-ss volumio[740]: info: Preload queue cleared Jan 11 20:56:32 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:56:33 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 11 20:56:33 volumio-ss volumio[740]: info: Preload queue cleared Jan 11 20:56:36 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:56:38 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 11 20:56:38 volumio-ss volumio[740]: info: Preload queue cleared Jan 11 20:56:40 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:56:43 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 11 20:56:43 volumio-ss volumio[740]: info: Preload queue cleared Jan 11 20:56:43 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.173 Jan 11 20:56:44 volumio-ss volumio[740]: info: Discovery: Remote connection error: 10.0.0.174 Jan 11 20:56:45 volumio-ss volumio[740]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 11 20:56:45 volumio-ss volumio[740]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 20:56:45 volumio-ss volumio[740]: TypeError: Cannot read property 'length' of undefined Jan 11 20:56:45 volumio-ss volumio[740]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jan 11 20:56:45 volumio-ss volumio[740]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jan 11 20:56:45 volumio-ss volumio[740]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jan 11 20:56:45 volumio-ss volumio[740]: at Parser.emit (events.js:315:20) Jan 11 20:56:45 volumio-ss volumio[740]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jan 11 20:56:45 volumio-ss volumio[740]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jan 11 20:56:45 volumio-ss volumio[740]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jan 11 20:56:45 volumio-ss volumio[740]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jan 11 20:56:45 volumio-ss volumio[740]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jan 11 20:56:45 volumio-ss volumio[740]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jan 11 20:56:45 volumio-ss volumio[740]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jan 11 20:56:45 volumio-ss volumio[740]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jan 11 20:56:45 volumio-ss volumio[740]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jan 11 20:56:45 volumio-ss volumio[740]: at IncomingMessage.emit (events.js:327:22) Jan 11 20:56:45 volumio-ss volumio[740]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 11 20:56:45 volumio-ss volumio[740]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 11 20:56:45 volumio-ss volumio[740]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 20:56:46 volumio-ss sudo[10490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-11 20:55 Jan 11 20:56:46 volumio-ss sudo[10490]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"