-- Logs begin at Thu 2019-02-14 08:11:58 -02, end at Sun 2025-08-31 12:51:30 -02. -- Aug 31 12:50:00 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:02 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Aug 31 12:50:03 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 12:50:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 31 12:50:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 31 12:50:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:05 volumio volumio[1045]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L ihor-ThinkPad-X260.local. -m SMB3_11 Aug 31 12:50:05 volumio volumio[1045]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L openmediavault.local. -m SMB3_11 Aug 31 12:50:08 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:13 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:16 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:20 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:24 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:28 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:32 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:36 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:40 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:43 volumio ntpd[6517]: ntpd exiting on signal 15 (Terminated) Aug 31 12:50:43 volumio ntpd[6517]: 193.106.144.7 local addr 192.168.88.10 -> Aug 31 12:50:43 volumio ntpd[6517]: 194.8.146.38 local addr 192.168.88.10 -> Aug 31 12:50:43 volumio ntpd[6517]: 213.231.2.124 local addr 192.168.88.10 -> Aug 31 12:50:43 volumio ntpd[6517]: 91.236.251.13 local addr 192.168.88.10 -> Aug 31 12:50:43 volumio ntpd[6517]: 91.236.251.5 local addr 192.168.88.10 -> Aug 31 12:50:43 volumio ntpd[6517]: 193.34.155.3 local addr 192.168.88.10 -> Aug 31 12:50:43 volumio ntpd[6517]: 91.236.251.24 local addr 192.168.88.10 -> Aug 31 12:50:43 volumio ntpd[6517]: 188.191.239.24 local addr 192.168.88.10 -> Aug 31 12:50:43 volumio ntpd[6517]: 193.106.144.13 local addr 192.168.88.10 -> Aug 31 12:50:43 volumio ntpd[6517]: 91.231.182.17 local addr 192.168.88.10 -> Aug 31 12:50:43 volumio ntpd[6517]: 188.191.234.54 local addr 192.168.88.10 -> Aug 31 12:50:43 volumio ntpd[6517]: 62.149.0.30 local addr 192.168.88.10 -> Aug 31 12:50:43 volumio ntpd[6517]: 91.236.251.234 local addr 192.168.88.10 -> Aug 31 12:50:43 volumio systemd[1]: Stopping Network Time Service... Aug 31 12:50:43 volumio systemd[1]: ntp.service: Succeeded. Aug 31 12:50:43 volumio systemd[1]: Stopped Network Time Service. Aug 31 12:50:43 volumio systemd[1]: Starting Network Time Service... Aug 31 12:50:43 volumio ntpd[7678]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 31 12:50:43 volumio ntpd[7678]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 31 12:50:43 volumio systemd[1]: Started Network Time Service. Aug 31 12:50:43 volumio ntpd[7684]: proto: precision = 0.583 usec (-21) Aug 31 12:50:43 volumio ntpd[7684]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 31 12:50:43 volumio ntpd[7684]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 31 12:50:43 volumio ntpd[7684]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 978 days ago Aug 31 12:50:43 volumio ntpd[7684]: Listen and drop on 0 v6wildcard [::]:123 Aug 31 12:50:43 volumio ntpd[7684]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 31 12:50:43 volumio ntpd[7684]: Listen normally on 2 lo 127.0.0.1:123 Aug 31 12:50:43 volumio ntpd[7684]: Listen normally on 3 eth0 169.254.83.12:123 Aug 31 12:50:43 volumio ntpd[7684]: Listen normally on 4 wlan0 192.168.88.10:123 Aug 31 12:50:43 volumio ntpd[7684]: Listening on routing socket on fd #21 for interface updates Aug 31 12:50:43 volumio ntpd[7684]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 31 12:50:43 volumio ntpd[7684]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 31 12:50:44 volumio ntpd[7684]: Soliciting pool server 162.159.200.1 Aug 31 12:50:44 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:45 volumio ntpd[7684]: Soliciting pool server 31.28.161.71 Aug 31 12:50:45 volumio ntpd[7684]: Soliciting pool server 193.106.144.6 Aug 31 12:50:46 volumio ntpd[7684]: Soliciting pool server 162.159.200.123 Aug 31 12:50:46 volumio ntpd[7684]: Soliciting pool server 109.110.82.19 Aug 31 12:50:46 volumio ntpd[7684]: Soliciting pool server 193.106.144.7 Aug 31 12:50:47 volumio ntpd[7684]: Soliciting pool server 91.231.182.17 Aug 31 12:50:47 volumio ntpd[7684]: Soliciting pool server 176.120.61.236 Aug 31 12:50:47 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Aug 31 12:50:47 volumio volumio[1045]: info: Adding a new share Aug 31 12:50:47 volumio volumio[1045]: info: No correspondence found in configuration for share nas on IP 192.168.88.2 Aug 31 12:50:47 volumio volumio[1045]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.88.2 -N -m SMB3_11 Aug 31 12:50:47 volumio nmbd[755]: [2025/08/31 12:50:47.504413, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 31 12:50:47 volumio nmbd[755]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.88.10 for name WORKGROUP<1d>. Aug 31 12:50:47 volumio nmbd[755]: This response was from IP 192.168.88.243, reporting an IP address of 192.168.88.243. Aug 31 12:50:47 volumio ntpd[7684]: Soliciting pool server 193.84.22.254 Aug 31 12:50:47 volumio volumio[1045]: info: Negotiated SMB version with nas: SMB3_11 Aug 31 12:50:47 volumio volumio[1045]: info: Detected device nas with version SMB3_11 Aug 31 12:50:47 volumio volumio[1045]: info: Set SMB version 3.11 in CIFS options: vers=3.11 Aug 31 12:50:47 volumio sudo[7706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=nas,password=123123,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.88.2/ /mnt/NAS/nas Aug 31 12:50:47 volumio sudo[7706]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:50:47 volumio kernel: CIFS VFS: Unknown vers= option specified: 3.11 Aug 31 12:50:47 volumio sudo[7706]: pam_unix(sudo:session): session closed for user root Aug 31 12:50:47 volumio volumio[1045]: info: Error mounting nas on IP 192.168.88.2 : (22): Invalid argument Aug 31 12:50:47 volumio volumio[1045]: Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) Aug 31 12:50:48 volumio ntpd[7684]: Soliciting pool server 193.106.144.13 Aug 31 12:50:48 volumio ntpd[7684]: Soliciting pool server 91.198.10.1 Aug 31 12:50:48 volumio ntpd[7684]: Soliciting pool server 91.236.251.31 Aug 31 12:50:48 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 12:50:48 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:49 volumio ntpd[7684]: Soliciting pool server 194.54.80.29 Aug 31 12:50:49 volumio ntpd[7684]: Soliciting pool server 91.236.251.13 Aug 31 12:50:50 volumio ntpd[7684]: Soliciting pool server 94.158.46.150 Aug 31 12:50:50 volumio ntpd[7684]: Soliciting pool server 2a02:c6c1:c:207::13 Aug 31 12:50:53 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:54 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 31 12:50:54 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand rescan Aug 31 12:50:54 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:54 volumio volumio[1045]: info: Aug 31 12:50:54 volumio volumio[1045]: ---------------------------- MPD announces state update: update Aug 31 12:50:54 volumio volumio[1045]: info: sendMpdCommand rescan took 11 milliseconds Aug 31 12:50:54 volumio volumio[1045]: info: ControllerMpd::getState Aug 31 12:50:54 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:54 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:54 volumio volumio[1045]: info: Aug 31 12:50:54 volumio volumio[1045]: ---------------------------- MPD announces state update: update Aug 31 12:50:54 volumio volumio[1045]: info: ControllerMpd::getState Aug 31 12:50:54 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:54 volumio volumio[1045]: info: sendMpdCommand status took 10 milliseconds Aug 31 12:50:54 volumio volumio[1045]: info: sendMpdCommand status took 1 milliseconds Aug 31 12:50:54 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:55 volumio volumio[1045]: info: Command Router : Notfying DB Updatefalse Aug 31 12:50:55 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:50:55 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:55 volumio volumio[1045]: info: ControllerMpd::pushState Aug 31 12:50:55 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Aug 31 12:50:55 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:55 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:55 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:55 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:55 volumio volumio[1045]: 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} Aug 31 12:50:55 volumio volumio[1045]: verbose: CURRENT POSITION 0 Aug 31 12:50:55 volumio volumio[1045]: info: CoreStateMachine::syncState stateService stop Aug 31 12:50:55 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:50:55 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:55 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:55 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:55 volumio volumio[1045]: info: No code Aug 31 12:50:55 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:55 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:55 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:55 volumio volumio[1045]: info: ------------------------------ 1253ms Aug 31 12:50:55 volumio volumio[1045]: info: sendMpdCommand status took 1245 milliseconds Aug 31 12:50:55 volumio volumio[1045]: info: sendMpdCommand status took 1244 milliseconds Aug 31 12:50:55 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:55 volumio volumio[1045]: info: Command Router : Notfying DB Updatefalse Aug 31 12:50:55 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:50:55 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:55 volumio volumio[1045]: info: ControllerMpd::pushState Aug 31 12:50:55 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Aug 31 12:50:55 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:55 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:55 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:55 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:55 volumio volumio[1045]: 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} Aug 31 12:50:55 volumio volumio[1045]: verbose: CURRENT POSITION 0 Aug 31 12:50:55 volumio volumio[1045]: info: CoreStateMachine::syncState stateService stop Aug 31 12:50:55 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:50:55 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:55 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:55 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:55 volumio volumio[1045]: info: No code Aug 31 12:50:55 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:55 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:55 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:55 volumio volumio[1045]: info: ------------------------------ 1293ms Aug 31 12:50:56 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 31 12:50:56 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand update Aug 31 12:50:56 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:56 volumio volumio[1045]: info: Aug 31 12:50:56 volumio volumio[1045]: ---------------------------- MPD announces state update: update Aug 31 12:50:56 volumio volumio[1045]: info: sendMpdCommand update took 2 milliseconds Aug 31 12:50:56 volumio volumio[1045]: info: ControllerMpd::getState Aug 31 12:50:56 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:56 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:56 volumio volumio[1045]: info: Aug 31 12:50:56 volumio volumio[1045]: ---------------------------- MPD announces state update: update Aug 31 12:50:56 volumio volumio[1045]: info: ControllerMpd::getState Aug 31 12:50:56 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:56 volumio volumio[1045]: info: sendMpdCommand status took 2 milliseconds Aug 31 12:50:56 volumio volumio[1045]: info: sendMpdCommand status took 2 milliseconds Aug 31 12:50:56 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:56 volumio volumio[1045]: info: Command Router : Notfying DB Updatefalse Aug 31 12:50:56 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:50:56 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:56 volumio volumio[1045]: info: ControllerMpd::pushState Aug 31 12:50:56 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Aug 31 12:50:56 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:56 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:56 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:56 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:56 volumio volumio[1045]: 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} Aug 31 12:50:56 volumio volumio[1045]: verbose: CURRENT POSITION 0 Aug 31 12:50:56 volumio volumio[1045]: info: CoreStateMachine::syncState stateService stop Aug 31 12:50:56 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:50:56 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:56 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:56 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:56 volumio volumio[1045]: info: No code Aug 31 12:50:56 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:56 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:56 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:56 volumio volumio[1045]: info: ------------------------------ 260ms Aug 31 12:50:56 volumio volumio[1045]: info: sendMpdCommand status took 260 milliseconds Aug 31 12:50:56 volumio volumio[1045]: info: sendMpdCommand status took 259 milliseconds Aug 31 12:50:56 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:56 volumio volumio[1045]: info: Command Router : Notfying DB Updatefalse Aug 31 12:50:56 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:50:56 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:56 volumio volumio[1045]: info: ControllerMpd::pushState Aug 31 12:50:56 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Aug 31 12:50:56 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:56 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:56 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:56 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:56 volumio volumio[1045]: 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} Aug 31 12:50:56 volumio volumio[1045]: verbose: CURRENT POSITION 0 Aug 31 12:50:56 volumio volumio[1045]: info: CoreStateMachine::syncState stateService stop Aug 31 12:50:56 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:50:56 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:56 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:56 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:56 volumio volumio[1045]: info: No code Aug 31 12:50:56 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:56 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:56 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:56 volumio volumio[1045]: info: ------------------------------ 308ms Aug 31 12:50:56 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:50:57 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 31 12:50:57 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand update Aug 31 12:50:57 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:57 volumio volumio[1045]: info: Aug 31 12:50:57 volumio volumio[1045]: ---------------------------- MPD announces state update: update Aug 31 12:50:57 volumio volumio[1045]: info: sendMpdCommand update took 2 milliseconds Aug 31 12:50:57 volumio volumio[1045]: info: ControllerMpd::getState Aug 31 12:50:57 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:57 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:57 volumio volumio[1045]: info: Aug 31 12:50:57 volumio volumio[1045]: ---------------------------- MPD announces state update: update Aug 31 12:50:57 volumio volumio[1045]: info: sendMpdCommand status took 2 milliseconds Aug 31 12:50:57 volumio volumio[1045]: info: ControllerMpd::getState Aug 31 12:50:57 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:57 volumio volumio[1045]: info: sendMpdCommand status took 2 milliseconds Aug 31 12:50:57 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:58 volumio volumio[1045]: info: Command Router : Notfying DB Updatetrue Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:50:58 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:58 volumio volumio[1045]: info: ControllerMpd::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: 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} Aug 31 12:50:58 volumio volumio[1045]: verbose: CURRENT POSITION 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::syncState stateService stop Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:58 volumio volumio[1045]: info: No code Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:58 volumio volumio[1045]: info: ------------------------------ 545ms Aug 31 12:50:58 volumio volumio[1045]: info: sendMpdCommand status took 544 milliseconds Aug 31 12:50:58 volumio volumio[1045]: info: sendMpdCommand status took 544 milliseconds Aug 31 12:50:58 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:58 volumio volumio[1045]: info: Command Router : Notfying DB Updatefalse Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:50:58 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:58 volumio volumio[1045]: info: ControllerMpd::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: 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} Aug 31 12:50:58 volumio volumio[1045]: verbose: CURRENT POSITION 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::syncState stateService stop Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:58 volumio volumio[1045]: info: No code Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:58 volumio volumio[1045]: info: ------------------------------ 594ms Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 31 12:50:58 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand rescan Aug 31 12:50:58 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:58 volumio volumio[1045]: info: Aug 31 12:50:58 volumio volumio[1045]: ---------------------------- MPD announces state update: update Aug 31 12:50:58 volumio volumio[1045]: info: sendMpdCommand rescan took 2 milliseconds Aug 31 12:50:58 volumio volumio[1045]: info: ControllerMpd::getState Aug 31 12:50:58 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:58 volumio volumio[1045]: info: sendMpdCommand status took 2 milliseconds Aug 31 12:50:58 volumio volumio[1045]: info: sendMpdCommand status took 1 milliseconds Aug 31 12:50:58 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:58 volumio volumio[1045]: info: Command Router : Notfying DB Updatetrue Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:50:58 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:58 volumio volumio[1045]: info: ControllerMpd::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: 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} Aug 31 12:50:58 volumio volumio[1045]: verbose: CURRENT POSITION 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::syncState stateService stop Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:58 volumio volumio[1045]: info: No code Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:58 volumio volumio[1045]: info: ------------------------------ 284ms Aug 31 12:50:58 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:58 volumio volumio[1045]: info: Aug 31 12:50:58 volumio volumio[1045]: ---------------------------- MPD announces state update: update Aug 31 12:50:58 volumio volumio[1045]: info: ControllerMpd::getState Aug 31 12:50:58 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:50:58 volumio volumio[1045]: info: sendMpdCommand status took 5 milliseconds Aug 31 12:50:58 volumio volumio[1045]: info: sendMpdCommand status took 5 milliseconds Aug 31 12:50:58 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:58 volumio volumio[1045]: info: Command Router : Notfying DB Updatefalse Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:50:58 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:50:58 volumio volumio[1045]: info: ControllerMpd::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: 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} Aug 31 12:50:58 volumio volumio[1045]: verbose: CURRENT POSITION 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::syncState stateService stop Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:58 volumio volumio[1045]: info: No code Aug 31 12:50:58 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:50:58 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:50:58 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:50:58 volumio volumio[1045]: info: ------------------------------ 47ms Aug 31 12:51:00 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:51:03 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 31 12:51:03 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand update Aug 31 12:51:03 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:51:03 volumio volumio[1045]: info: Aug 31 12:51:03 volumio volumio[1045]: ---------------------------- MPD announces state update: update Aug 31 12:51:03 volumio volumio[1045]: info: sendMpdCommand update took 2 milliseconds Aug 31 12:51:03 volumio volumio[1045]: info: ControllerMpd::getState Aug 31 12:51:03 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:51:03 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:51:03 volumio volumio[1045]: info: Aug 31 12:51:03 volumio volumio[1045]: ---------------------------- MPD announces state update: update Aug 31 12:51:03 volumio volumio[1045]: info: ControllerMpd::getState Aug 31 12:51:03 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Aug 31 12:51:03 volumio volumio[1045]: info: sendMpdCommand status took 2 milliseconds Aug 31 12:51:03 volumio volumio[1045]: info: sendMpdCommand status took 2 milliseconds Aug 31 12:51:03 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:51:03 volumio volumio[1045]: info: Command Router : Notfying DB Updatefalse Aug 31 12:51:03 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:51:03 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:51:03 volumio volumio[1045]: info: ControllerMpd::pushState Aug 31 12:51:03 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Aug 31 12:51:03 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:51:03 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:51:03 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:51:03 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:51:03 volumio volumio[1045]: 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} Aug 31 12:51:03 volumio volumio[1045]: verbose: CURRENT POSITION 0 Aug 31 12:51:03 volumio volumio[1045]: info: CoreStateMachine::syncState stateService stop Aug 31 12:51:03 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:51:03 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:51:03 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:51:03 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:51:03 volumio volumio[1045]: info: No code Aug 31 12:51:03 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:51:03 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:51:03 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:51:03 volumio volumio[1045]: info: ------------------------------ 230ms Aug 31 12:51:03 volumio volumio[1045]: info: sendMpdCommand status took 230 milliseconds Aug 31 12:51:03 volumio volumio[1045]: info: sendMpdCommand status took 229 milliseconds Aug 31 12:51:03 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:51:03 volumio volumio[1045]: info: Command Router : Notfying DB Updatefalse Aug 31 12:51:03 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Aug 31 12:51:03 volumio volumio[1045]: verbose: ControllerMpd::parseState Aug 31 12:51:03 volumio volumio[1045]: info: ControllerMpd::pushState Aug 31 12:51:03 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Aug 31 12:51:03 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:51:03 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:51:03 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:51:03 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:51:03 volumio volumio[1045]: 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} Aug 31 12:51:03 volumio volumio[1045]: verbose: CURRENT POSITION 0 Aug 31 12:51:03 volumio volumio[1045]: info: CoreStateMachine::syncState stateService stop Aug 31 12:51:03 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Aug 31 12:51:03 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:51:03 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:51:03 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:51:03 volumio volumio[1045]: info: No code Aug 31 12:51:03 volumio volumio[1045]: info: CoreStateMachine::pushState Aug 31 12:51:03 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Aug 31 12:51:03 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Aug 31 12:51:03 volumio volumio[1045]: info: ------------------------------ 275ms Aug 31 12:51:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:51:07 volumio volumio[1045]: info: Disabling MyMusic plugin upnp Aug 31 12:51:07 volumio sudo[7813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 31 12:51:07 volumio sudo[7813]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:51:07 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Aug 31 12:51:07 volumio volumio[1045]: error: Upnp client error: Error: This socket has been ended by the other party Aug 31 12:51:08 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:51:09 volumio volumio[1045]: info: Disabling MyMusic plugin upnp_browser Aug 31 12:51:09 volumio volumio[1045]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Aug 31 12:51:09 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 12:51:09 volumio volumio[1045]: info: Disabling plugin upnp_browser Aug 31 12:51:09 volumio volumio[1045]: info: Done. Aug 31 12:51:10 volumio volumio[1045]: info: Disabling MyMusic plugin upnp Aug 31 12:51:10 volumio sudo[7835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 31 12:51:10 volumio sudo[7835]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:51:12 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:51:16 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:51:20 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:51:23 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Aug 31 12:51:24 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 12:51:24 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:51:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 31 12:51:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 31 12:51:26 volumio volumio[1045]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L openmediavault.local. -m SMB3_11 Aug 31 12:51:28 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 12:51:30 volumio volumio[1045]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 12:51:30 volumio volumio[1045]: Error: dns service error: unknown Aug 31 12:51:30 volumio volumio[1045]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Aug 31 12:51:30 volumio volumio[1045]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Aug 31 12:51:30 volumio volumio[1045]: errorCode: -65537 Aug 31 12:51:30 volumio volumio[1045]: } Aug 31 12:51:30 volumio volumio[1045]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 12:51:30 volumio sudo[7892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-31 12:50 Aug 31 12:51:30 volumio sudo[7892]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:55:15 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="2f3686ade78abf3b92c180a0c5b20c6b"