-- Logs begin at Thu 2025-06-12 22:22:08 CST, end at Thu 2025-06-12 22:32:59 CST. -- Jun 12 22:31:02 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 12 22:31:02 volumio volumio[955]: info: Preload queue cleared Jun 12 22:31:02 volumio volumio[955]: info: Preloading song: upnp/http://192.168.25.186:50001/ContentDirectory/control@30$1$601$@601 Jun 12 22:31:02 volumio volumio[955]: info: Exploding uri upnp/http://192.168.25.186:50001/ContentDirectory/control@30$1$601$@601 in service upnp_browser Jun 12 22:31:02 volumio volumio[955]: info: No valid Plugin REST Endpoint Jun 12 22:31:12 volumio volumio[955]: info: Preload queue cleared Jun 12 22:31:12 volumio volumio[955]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::ClearQueue Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::stop Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Jun 12 22:31:12 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::pushState Jun 12 22:31:12 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 12 22:31:12 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 22:31:12 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::serviceStop Jun 12 22:31:12 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 12 22:31:12 volumio volumio[955]: info: CoreCommandRouter::serviceStop Jun 12 22:31:12 volumio volumio[955]: info: [1749738672260] ControllerUPNPBrowser::stop Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Jun 12 22:31:12 volumio volumio[955]: info: CorePlayQueue::clearPlayQueue Jun 12 22:31:12 volumio volumio[955]: info: CorePlayQueue::saveQueue Jun 12 22:31:12 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::addQueueItems Jun 12 22:31:12 volumio volumio[955]: info: CorePlayQueue::addQueueItems Jun 12 22:31:12 volumio volumio[955]: info: Preload queue cleared Jun 12 22:31:12 volumio volumio[955]: info: Adding Item to queue: upnp/folder/http://192.168.25.186:50001/ContentDirectory/control@30$1$601 Jun 12 22:31:12 volumio volumio[955]: info: Exploding uri upnp/folder/http://192.168.25.186:50001/ContentDirectory/control@30$1$601 in service upnp_browser Jun 12 22:31:12 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Jun 12 22:31:12 volumio volumio[955]: info: CorePlayQueue::saveQueue Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Jun 12 22:31:12 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Jun 12 22:31:12 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::play index 0 Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::stop Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::play index undefined Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 22:31:12 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Jun 12 22:31:12 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 12 22:31:12 volumio volumio[955]: info: [1749738672330] ControllerUPNPBrowser::clearAddPlayTrack Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Jun 12 22:31:12 volumio volumio[955]: info: Jun 12 22:31:12 volumio volumio[955]: ---------------------------- MPD announces state update: player Jun 12 22:31:12 volumio volumio[955]: info: sendMpdCommand stop took 75 milliseconds Jun 12 22:31:12 volumio volumio[955]: info: ControllerMpd::getState Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:31:12 volumio volumio[955]: info: sendMpdCommand stop took 7 milliseconds Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Jun 12 22:31:12 volumio volumio[955]: info: Jun 12 22:31:12 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jun 12 22:31:12 volumio volumio[955]: info: Ignoring MPD Status Update Jun 12 22:31:12 volumio volumio[955]: info: sendMpdCommand status took 4 milliseconds Jun 12 22:31:12 volumio volumio[955]: info: sendMpdCommand clear took 3 milliseconds Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.25.186:50002/transcoder/genericoder.cgi/id=601.wav?format=WAV&downsample=false" Jun 12 22:31:12 volumio volumio[955]: error: updateQueue error: null Jun 12 22:31:12 volumio volumio[955]: info: ------------------------------ 6ms Jun 12 22:31:12 volumio volumio[955]: info: sendMpdCommand playlistinfo took 454 milliseconds Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.25.186:50002/transcoder/genericoder.cgi/id=601.wav?format=WAV&downsample=false" Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:31:12 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 12 22:31:12 volumio volumio[955]: info: ------------------------------ 470ms Jun 12 22:31:12 volumio volumio[955]: info: Jun 12 22:31:12 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jun 12 22:31:12 volumio volumio[955]: info: Ignoring MPD Status Update Jun 12 22:31:12 volumio volumio[955]: info: sendMpdCommand add "http://192.168.25.186:50002/transcoder/genericoder.cgi/id=601.wav?format=WAV&downsample=false" took 9 milliseconds Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Jun 12 22:31:12 volumio volumio[955]: info: ------------------------------ 4ms Jun 12 22:31:12 volumio volumio[955]: info: sendMpdCommand play took 3 milliseconds Jun 12 22:31:12 volumio volumio[955]: info: Jun 12 22:31:12 volumio volumio[955]: ---------------------------- MPD announces state update: player Jun 12 22:31:12 volumio volumio[955]: info: ControllerMpd::getState Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:31:12 volumio volumio[955]: info: Jun 12 22:31:12 volumio volumio[955]: ---------------------------- MPD announces state update: player Jun 12 22:31:12 volumio volumio[955]: info: sendMpdCommand status took 6 milliseconds Jun 12 22:31:12 volumio volumio[955]: info: ControllerMpd::getState Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:31:12 volumio volumio[955]: info: sendMpdCommand status took 8 milliseconds Jun 12 22:31:12 volumio volumio[955]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:31:12 volumio volumio[955]: info: ControllerMpd::pushState Jun 12 22:31:12 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jun 12 22:31:12 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 12 22:31:12 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=601.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.25.186:50002/transcoder/genericoder.cgi/id=601.wav?format=WAV&downsample=false","trackType":""} Jun 12 22:31:12 volumio volumio[955]: verbose: CURRENT POSITION 0 Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Jun 12 22:31:12 volumio volumio[955]: info: ------------------------------ 27ms Jun 12 22:31:12 volumio volumio[955]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 12 22:31:12 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:31:12 volumio volumio[955]: info: ControllerMpd::pushState Jun 12 22:31:12 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jun 12 22:31:12 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 12 22:31:12 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":318,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=601.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.25.186:50002/transcoder/genericoder.cgi/id=601.wav?format=WAV&downsample=false","trackType":""} Jun 12 22:31:12 volumio volumio[955]: verbose: CURRENT POSITION 0 Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jun 12 22:31:12 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jun 12 22:31:12 volumio volumio[955]: info: CoreStateMachine::pushState Jun 12 22:31:12 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 22:31:12 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jun 12 22:31:13 volumio volumio[955]: info: CoreStateMachine::pushState Jun 12 22:31:13 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jun 12 22:31:13 volumio volumio[955]: info: ------------------------------ 86ms Jun 12 22:31:32 volumio ntpd[3062]: ntpd exiting on signal 15 (Terminated) Jun 12 22:31:32 volumio ntpd[3062]: 193.182.111.12 local addr 192.168.25.153 -> Jun 12 22:31:32 volumio ntpd[3062]: 117.80.112.205 local addr 192.168.25.153 -> Jun 12 22:31:32 volumio ntpd[3062]: 193.182.111.143 local addr 192.168.25.153 -> Jun 12 22:31:32 volumio ntpd[3062]: 193.182.111.141 local addr 192.168.25.153 -> Jun 12 22:31:32 volumio ntpd[3062]: 162.159.200.123 local addr 192.168.25.153 -> Jun 12 22:31:32 volumio systemd[1]: Stopping Network Time Service... Jun 12 22:31:32 volumio ntpd[3062]: 162.159.200.1 local addr 192.168.25.153 -> Jun 12 22:31:32 volumio systemd[1]: ntp.service: Succeeded. Jun 12 22:31:32 volumio systemd[1]: Stopped Network Time Service. Jun 12 22:31:33 volumio systemd[1]: Starting Network Time Service... Jun 12 22:31:33 volumio ntpd[3296]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 12 22:31:33 volumio ntpd[3296]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 12 22:31:33 volumio ntpd[3302]: proto: precision = 0.259 usec (-22) Jun 12 22:31:33 volumio systemd[1]: Started Network Time Service. Jun 12 22:31:33 volumio ntpd[3302]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 12 22:31:33 volumio ntpd[3302]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 12 22:31:33 volumio ntpd[3302]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 898 days ago Jun 12 22:31:33 volumio ntpd[3302]: Listen and drop on 0 v6wildcard [::]:123 Jun 12 22:31:33 volumio ntpd[3302]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 12 22:31:33 volumio ntpd[3302]: Listen normally on 2 lo 127.0.0.1:123 Jun 12 22:31:33 volumio ntpd[3302]: Listen normally on 3 eth0 192.168.25.153:123 Jun 12 22:31:33 volumio ntpd[3302]: Listening on routing socket on fd #20 for interface updates Jun 12 22:31:33 volumio ntpd[3302]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 12 22:31:33 volumio ntpd[3302]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 12 22:31:34 volumio ntpd[3302]: Soliciting pool server 185.209.85.222 Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 22:31:35 volumio volumio[955]: info: Retrieving Cloud Streaming UI Jun 12 22:31:35 volumio volumio[955]: info: Getting Tidal Cloud Configuration Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 22:31:35 volumio volumio[955]: info: Getting Qobuz Cloud Configuration Jun 12 22:31:35 volumio volumio[955]: info: Asking plugin for UI Config Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 22:31:35 volumio volumio[955]: info: Getting Spotify Cloud Configuration Jun 12 22:31:35 volumio volumio[955]: info: Asking plugin for UI Config Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 22:31:35 volumio volumio[955]: info: Saving Spotify Acccount Jun 12 22:31:35 volumio volumio[955]: info: Got Tidal Cloud Configuration Jun 12 22:31:35 volumio volumio[955]: info: Got it Jun 12 22:31:35 volumio volumio[955]: info: Got it Jun 12 22:31:35 volumio volumio[955]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 22:31:35 volumio volumio[955]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::volumioGetBrowseSources Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::volumioGetBrowseSources Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::volumioGetBrowseSources Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:31:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 12 22:31:35 volumio ntpd[3302]: Soliciting pool server 108.59.2.24 Jun 12 22:31:35 volumio ntpd[3302]: Soliciting pool server 202.118.1.81 Jun 12 22:31:36 volumio ntpd[3302]: Soliciting pool server 202.118.1.130 Jun 12 22:31:36 volumio ntpd[3302]: Soliciting pool server 119.28.206.193 Jun 12 22:31:37 volumio ntpd[3302]: Soliciting pool server 193.182.111.14 Jun 12 22:31:37 volumio ntpd[3302]: Soliciting pool server 211.68.71.26 Jun 12 22:31:37 volumio ntpd[3302]: Soliciting pool server 193.182.111.141 Jun 12 22:31:38 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jun 12 22:31:38 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand rescan Jun 12 22:31:38 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:31:38 volumio volumio[955]: info: Jun 12 22:31:38 volumio volumio[955]: ---------------------------- MPD announces state update: update Jun 12 22:31:38 volumio volumio[955]: info: sendMpdCommand rescan took 7 milliseconds Jun 12 22:31:38 volumio volumio[955]: info: ControllerMpd::getState Jun 12 22:31:38 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:31:38 volumio volumio[955]: info: sendMpdCommand status took 9 milliseconds Jun 12 22:31:38 volumio volumio[955]: info: sendMpdCommand status took 4 milliseconds Jun 12 22:31:38 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 12 22:31:38 volumio ntpd[3302]: Soliciting pool server 193.182.111.12 Jun 12 22:31:38 volumio ntpd[3302]: Soliciting pool server 45.76.221.157 Jun 12 22:31:38 volumio ntpd[3302]: Soliciting pool server 193.182.111.142 Jun 12 22:31:39 volumio ntpd[3302]: Soliciting pool server 193.182.111.12 Jun 12 22:31:40 volumio ntpd[3302]: Soliciting pool server 108.59.2.24 Jun 12 22:31:41 volumio ntpd[3302]: Soliciting pool server 193.182.111.143 Jun 12 22:32:00 volumio volumio-remote-updater[603]: [2025-06-12 22:32:00] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Jun 12 22:32:01 volumio volumio[955]: info: Command Router : Notfying DB Updatetrue Jun 12 22:32:01 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 12 22:32:01 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:32:01 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:32:01 volumio volumio[955]: info: Jun 12 22:32:01 volumio volumio[955]: ---------------------------- MPD announces state update: update Jun 12 22:32:01 volumio volumio[955]: info: ControllerMpd::getState Jun 12 22:32:01 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:32:01 volumio volumio[955]: info: sendMpdCommand playlistinfo took 36 milliseconds Jun 12 22:32:01 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:32:01 volumio volumio[955]: info: ControllerMpd::pushState Jun 12 22:32:01 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jun 12 22:32:01 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 12 22:32:01 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26727,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=601.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.25.186:50002/transcoder/genericoder.cgi/id=601.wav?format=WAV&downsample=false","trackType":""} Jun 12 22:32:01 volumio volumio[955]: verbose: CURRENT POSITION 0 Jun 12 22:32:01 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jun 12 22:32:01 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jun 12 22:32:01 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jun 12 22:32:01 volumio volumio[955]: info: CoreStateMachine::pushState Jun 12 22:32:01 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 22:32:01 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jun 12 22:32:01 volumio volumio[955]: info: CoreStateMachine::pushState Jun 12 22:32:01 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jun 12 22:32:01 volumio volumio[955]: info: ------------------------------ 22457ms Jun 12 22:32:01 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:01 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jun 12 22:32:01 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jun 12 22:32:01 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:01 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:01 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:01 volumio volumio[955]: info: sendMpdCommand status took 116 milliseconds Jun 12 22:32:01 volumio volumio[955]: info: sendMpdCommand status took 114 milliseconds Jun 12 22:32:01 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 12 22:32:05 volumio volumio-remote-updater[603]: [2025-06-12 22:32:05] [connect] Successful connection Jun 12 22:32:15 volumio volumio[955]: info: Command Router : Notfying DB Updatefalse Jun 12 22:32:15 volumio volumio[955]: info: CoreCommandRouter::Close All Modals sent Jun 12 22:32:15 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 12 22:32:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 3 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 4 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 5 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 7 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 8 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 9 Jun 12 22:32:15 volumio volumio[955]: info: sendMpdCommand playlistinfo took 98 milliseconds Jun 12 22:32:15 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:32:15 volumio volumio[955]: info: ControllerMpd::pushState Jun 12 22:32:15 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jun 12 22:32:15 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 12 22:32:15 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49221,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=601.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.25.186:50002/transcoder/genericoder.cgi/id=601.wav?format=WAV&downsample=false","trackType":""} Jun 12 22:32:15 volumio volumio[955]: verbose: CURRENT POSITION 0 Jun 12 22:32:15 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jun 12 22:32:15 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jun 12 22:32:15 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jun 12 22:32:15 volumio volumio[955]: info: CoreStateMachine::pushState Jun 12 22:32:15 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 22:32:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jun 12 22:32:15 volumio volumio[955]: info: CoreStateMachine::pushState Jun 12 22:32:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jun 12 22:32:15 volumio volumio[955]: info: ------------------------------ 14462ms Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 10 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 11 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 12 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 13 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 14 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 15 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 16 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 17 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 18 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 19 Jun 12 22:32:15 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 20 Jun 12 22:32:16 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 21 Jun 12 22:32:16 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 12 22:32:16 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 12 22:32:16 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 12 22:32:17 volumio volumio[955]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 Jun 12 22:32:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 12 22:32:18 volumio volumio-remote-updater[603]: [2025-06-12 22:32:18] [connect] Successful connection Jun 12 22:32:18 volumio volumio-remote-updater[603]: [2025-06-12 22:32:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749738738 101 Jun 12 22:32:18 volumio volumio[955]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 23 Jun 12 22:32:18 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.25.153 from 192.168.25.110 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 24 Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetVisibleSources Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 12 22:32:18 volumio volumio[955]: info: Received Get System Info Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 22:32:18 volumio volumio[955]: info: Discovery: Getting this device information Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 12 22:32:18 volumio volumio[955]: info: Listing playlists Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jun 12 22:32:18 volumio volumio[955]: info: CoreStateMachine::getQueue Jun 12 22:32:18 volumio volumio[955]: info: CorePlayQueue::getQueue Jun 12 22:32:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 12 22:32:19 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:23 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:32 volumio volumio[955]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Jun 12 22:32:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:39 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:43 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:47 volumio nmbd[860]: [2025/06/12 22:32:47.945119, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 12 22:32:47 volumio nmbd[860]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.25.153 for name WORKGROUP<1d>. Jun 12 22:32:47 volumio nmbd[860]: This response was from IP 192.168.25.30, reporting an IP address of 192.168.25.30. Jun 12 22:32:51 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:55 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:32:58 volumio volumio[955]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 22:32:58 volumio volumio[955]: Error: connect ETIMEDOUT 162.125.2.5:443 Jun 12 22:32:58 volumio volumio[955]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 12 22:32:58 volumio volumio[955]: errno: -110, Jun 12 22:32:58 volumio volumio[955]: code: 'ETIMEDOUT', Jun 12 22:32:58 volumio volumio[955]: syscall: 'connect', Jun 12 22:32:58 volumio volumio[955]: address: '162.125.2.5', Jun 12 22:32:58 volumio volumio[955]: port: 443 Jun 12 22:32:58 volumio volumio[955]: } Jun 12 22:32:58 volumio volumio[955]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 22:32:59 volumio sudo[3536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-12 22:31 Jun 12 22:32:59 volumio sudo[3536]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"