-- Logs begin at Mon 2026-01-26 18:33:31 +07, end at Mon 2026-01-26 18:44:36 +07. -- Jan 26 18:43:01 volumio dhcpcd[749]: eth0: probing address 192.168.1.34/24 Jan 26 18:43:02 volumio volumio[889]: info: Volumio Network Manager: Network status updated: 0 Jan 26 18:43:05 volumio nmbd[814]: [2026/01/26 18:43:05.119711, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jan 26 18:43:05 volumio nmbd[814]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Jan 26 18:43:06 volumio dhcpcd[749]: eth0: leased 192.168.1.34 for 3600 seconds Jan 26 18:43:06 volumio dhcpcd[749]: eth0: adding route to 192.168.1.0/24 Jan 26 18:43:06 volumio dhcpcd[749]: eth0: adding default route via 192.168.1.1 Jan 26 18:43:06 volumio avahi-daemon[671]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.34. Jan 26 18:43:06 volumio avahi-daemon[671]: New relevant interface eth0.IPv4 for mDNS. Jan 26 18:43:06 volumio avahi-daemon[671]: Registering new address record for 192.168.1.34 on eth0.IPv4. Jan 26 18:43:06 volumio ntpd[3868]: ntpd exiting on signal 15 (Terminated) Jan 26 18:43:06 volumio systemd[1]: Stopping Network Time Service... Jan 26 18:43:06 volumio systemd[1]: ntp.service: Succeeded. Jan 26 18:43:06 volumio systemd[1]: Stopped Network Time Service. Jan 26 18:43:06 volumio volumio[889]: info: CoreCommandRouter::volumioPlay Jan 26 18:43:06 volumio volumio[889]: info: CoreStateMachine::play index undefined Jan 26 18:43:06 volumio volumio[889]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 18:43:06 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:06 volumio volumio[889]: info: CoreStateMachine::startPlaybackTimer Jan 26 18:43:06 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:06 volumio volumio[889]: verbose: ControllerMpd::clearAddPlayTracks USB/My_Book_3.0/MUSIC/NHAC HAI NGOAI/Anh Tuyet/Di Tim - Anh Tuyet - Vol 7/05MuaThuCanhNau - 05MuaThuCanhNau.wav Jan 26 18:43:06 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand stop Jan 26 18:43:06 volumio volumio[889]: info: sendMpdCommand stop took 1 milliseconds Jan 26 18:43:06 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand clear Jan 26 18:43:06 volumio volumio[889]: info: Jan 26 18:43:06 volumio volumio[889]: ---------------------------- MPD announces system playlist update Jan 26 18:43:06 volumio volumio[889]: info: Ignoring MPD Status Update Jan 26 18:43:06 volumio volumio[889]: info: sendMpdCommand clear took 1 milliseconds Jan 26 18:43:06 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand add "USB/My_Book_3.0/MUSIC/NHAC HAI NGOAI/Anh Tuyet/Di Tim - Anh Tuyet - Vol 7/05MuaThuCanhNau - 05MuaThuCanhNau.wav" Jan 26 18:43:06 volumio volumio[889]: error: updateQueue error: null Jan 26 18:43:06 volumio volumio[889]: info: ------------------------------ 4ms Jan 26 18:43:06 volumio systemd[1]: Starting Network Time Service... Jan 26 18:43:06 volumio ntpd[3981]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 26 18:43:06 volumio ntpd[3981]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 26 18:43:06 volumio systemd[1]: Started Network Time Service. Jan 26 18:43:06 volumio ntpd[3987]: proto: precision = 0.180 usec (-22) Jan 26 18:43:06 volumio ntpd[3987]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 26 18:43:06 volumio ntpd[3987]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 26 18:43:06 volumio ntpd[3987]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1126 days ago Jan 26 18:43:06 volumio ntpd[3987]: Listen and drop on 0 v6wildcard [::]:123 Jan 26 18:43:06 volumio ntpd[3987]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 26 18:43:06 volumio ntpd[3987]: Listen normally on 2 lo 127.0.0.1:123 Jan 26 18:43:06 volumio ntpd[3987]: Listen normally on 3 eth0 192.168.1.34:123 Jan 26 18:43:06 volumio ntpd[3987]: Listening on routing socket on fd #20 for interface updates Jan 26 18:43:06 volumio ntpd[3987]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 26 18:43:06 volumio ntpd[3987]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 26 18:43:07 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 18:43:07 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 18:43:07 volumio volumio[889]: info: Discovery: Getting this device information Jan 26 18:43:07 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:07 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:07 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 18:43:07 volumio volumio[889]: verbose: New Socket.io Connection to 192.168.1.34:3000 from 192.168.1.35 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jan 26 18:43:07 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 26 18:43:07 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 26 18:43:07 volumio volumio[889]: info: Discovery: adding 43045d15-58b4-4a01-9822-68dc8f19c60f Jan 26 18:43:07 volumio volumio[889]: info: Discovery: Found device Volumio Jan 26 18:43:07 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:07 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:07 volumio ntpd[3987]: Soliciting pool server 160.25.232.6 Jan 26 18:43:07 volumio volumio[889]: verbose: New Socket.io Connection to 192.168.1.34:3000 from 192.168.1.35 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jan 26 18:43:07 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 18:43:07 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 18:43:07 volumio volumio[889]: info: Discovery: Getting this device information Jan 26 18:43:07 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:07 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:07 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 18:43:08 volumio ntpd[3987]: Soliciting pool server 103.186.65.246 Jan 26 18:43:08 volumio ntpd[3987]: Soliciting pool server 103.221.223.143 Jan 26 18:43:09 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 26 18:43:09 volumio volumio[889]: info: CURURI: music-library Jan 26 18:43:09 volumio volumio[889]: error: Failed LSINFO: null Jan 26 18:43:09 volumio volumio[889]: info: Preload queue cleared Jan 26 18:43:09 volumio ntpd[3987]: Soliciting pool server 45.252.250.189 Jan 26 18:43:09 volumio ntpd[3987]: Soliciting pool server 45.252.250.189 Jan 26 18:43:12 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 26 18:43:12 volumio volumio[889]: info: CURURI: music-library Jan 26 18:43:12 volumio volumio[889]: error: Failed LSINFO: null Jan 26 18:43:12 volumio volumio[889]: info: Preload queue cleared Jan 26 18:43:12 volumio volumio[889]: info: Volumio Network Manager: Network status updated: 1 Jan 26 18:43:14 volumio volumio[889]: verbose: New Socket.io Connection to 192.168.1.34:3000 from 192.168.1.35 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jan 26 18:43:27 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:43:27 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 26 18:43:27 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:43:27 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 18:43:27 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:27 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:27 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:27 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:27 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:27 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:27 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:27 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 26 18:43:27 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 26 18:43:32 volumio volumio[889]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 26 18:43:32 volumio volumio[889]: info: Preparing to save Alsa Options, stopping services first Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 26 18:43:32 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::volumioPause Jan 26 18:43:32 volumio volumio[889]: info: CoreStateMachine::pause Jan 26 18:43:32 volumio volumio[889]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB To IIS Out"}} Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 18:43:32 volumio volumio[889]: info: Setting mixer PCM for card USB To IIS Out Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 26 18:43:32 volumio volumio[889]: info: Updating Volume Controller Parameters: Device: 5 Name: USB To IIS Out Mixer: PCM Max Vol: 100 Vol Curve; linear Vol Steps: 1 Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 26 18:43:32 volumio volumio[889]: info: Disabling external Volume Control Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:43:32 volumio volumio[889]: info: Preparing to generate the ALSA configuration file Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 26 18:43:32 volumio volumio[889]: info: Asound.conf file unchanged, so no further update is needed Jan 26 18:43:32 volumio volumio[889]: info: Output device has changed, restarting MPD Jan 26 18:43:32 volumio volumio[889]: info: Output device has changed, restarting Shairport Sync Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:43:32 volumio sudo[4039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 18:43:32 volumio sudo[4039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:32 volumio sudo[4039]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:32 volumio sudo[4041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 18:43:32 volumio sudo[4041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:32 volumio systemd[1]: Stopping Music Player Daemon... Jan 26 18:43:32 volumio volumio[889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 18:43:32 volumio systemd[1]: mpd.service: Succeeded. Jan 26 18:43:32 volumio systemd[1]: Stopped Music Player Daemon. Jan 26 18:43:32 volumio volumio[889]: info: MPD Permissions set Jan 26 18:43:32 volumio systemd[1]: Starting Music Player Daemon... Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 18:43:32 volumio volumio[889]: info: VolumeController:: Volume=13 Mute =false Jan 26 18:43:32 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:32 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:43:32 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:32 volumio volumio[889]: info: Starting Shairport Sync Jan 26 18:43:32 volumio sudo[4047]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 18:43:32 volumio sudo[4047]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:32 volumio sudo[4047]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:32 volumio sudo[4059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 18:43:32 volumio sudo[4059]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 26 18:43:32 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 26 18:43:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 26 18:43:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 26 18:43:32 volumio sudo[4059]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:32 volumio volumio[889]: info: Shairport-Sync Started Jan 26 18:43:32 volumio volumio[889]: (node:889) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jan 26 18:43:32 volumio volumio[889]: (Use `node --trace-warnings ...` to show where the warning was created) Jan 26 18:43:32 volumio volumio[889]: (node:889) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jan 26 18:43:32 volumio volumio[889]: (node:889) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jan 26 18:43:32 volumio volumio[889]: (node:889) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jan 26 18:43:32 volumio volumio[889]: (node:889) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jan 26 18:43:33 volumio mpd[4060]: Jan 26 18:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 18:43:33 volumio systemd[1]: Started Music Player Daemon. Jan 26 18:43:33 volumio sudo[4041]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:33 volumio volumio[889]: error: updateQueue error: null Jan 26 18:43:36 volumio volumio[889]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 26 18:43:36 volumio volumio[889]: info: CoreStateMachine::getcurrentVolume Jan 26 18:43:36 volumio volumio[889]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 18:43:36 volumio volumio[889]: info: VolumeController:: Volume=13 Mute =false Jan 26 18:43:36 volumio sudo[4129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 26 18:43:36 volumio sudo[4129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 18:43:36 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:36 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:36 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:43:36 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:36 volumio volumio[889]: info: CoreStateMachine::updateTrackBlock Jan 26 18:43:36 volumio volumio[889]: info: CorePlayQueue::getTrackBlock Jan 26 18:43:36 volumio volumio[889]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 18:43:36 volumio systemd[1]: Stopping MPD Monitor Service... Jan 26 18:43:36 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 26 18:43:36 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 26 18:43:36 volumio systemd[1]: Stopped MPD Monitor Service. Jan 26 18:43:36 volumio systemd[1]: Started MPD Monitor Service. Jan 26 18:43:36 volumio sudo[4129]: pam_unix(sudo:session): session closed for user root Jan 26 18:43:36 volumio mpd_monitor.sh[4132]: MPD Monitor Service: Starting MPD Monitor Service Jan 26 18:43:36 volumio volumio[889]: info: Successfully started MPD Monitor Jan 26 18:43:36 volumio volumio[889]: info: VolumeController:: Volume=13 Mute =false Jan 26 18:43:36 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:36 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:36 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:43:44 volumio volumio[889]: info: Retrieving Cloud Streaming UI Jan 26 18:43:44 volumio volumio[889]: info: Getting Tidal Cloud Configuration Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:43:44 volumio volumio[889]: info: Getting Qobuz Cloud Configuration Jan 26 18:43:44 volumio volumio[889]: info: Asking plugin for UI Config Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:43:44 volumio volumio[889]: info: Getting Spotify Cloud Configuration Jan 26 18:43:44 volumio volumio[889]: info: Asking plugin for UI Config Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:43:44 volumio volumio[889]: info: Saving Spotify Acccount Jan 26 18:43:44 volumio volumio[889]: info: Got Tidal Cloud Configuration Jan 26 18:43:44 volumio volumio[889]: info: Got it Jan 26 18:43:44 volumio volumio[889]: info: Got it Jan 26 18:43:44 volumio volumio[889]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:43:44 volumio volumio[889]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 18:43:44 volumio volumio[889]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 18:43:45 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:43:45 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 26 18:43:47 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 26 18:43:47 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand rescan Jan 26 18:43:47 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:47 volumio volumio[889]: info: Jan 26 18:43:47 volumio volumio[889]: ---------------------------- MPD announces state update: update Jan 26 18:43:47 volumio volumio[889]: info: sendMpdCommand rescan took 3 milliseconds Jan 26 18:43:47 volumio volumio[889]: info: ControllerMpd::getState Jan 26 18:43:47 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:47 volumio volumio[889]: info: sendMpdCommand status took 2 milliseconds Jan 26 18:43:47 volumio volumio[889]: info: sendMpdCommand status took 2 milliseconds Jan 26 18:43:47 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:47 volumio volumio[889]: info: Command Router : Notfying DB Updatetrue Jan 26 18:43:47 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:43:47 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:47 volumio volumio[889]: info: ControllerMpd::pushState Jan 26 18:43:47 volumio volumio[889]: info: CoreCommandRouter::servicePushState Jan 26 18:43:47 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:47 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:47 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:43:47 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:47 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:47 volumio volumio[889]: 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} Jan 26 18:43:47 volumio volumio[889]: verbose: CURRENT POSITION 4 Jan 26 18:43:47 volumio volumio[889]: info: CoreStateMachine::syncState stateService stop Jan 26 18:43:47 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop Jan 26 18:43:47 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:47 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:47 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:43:47 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:47 volumio volumio[889]: info: No code Jan 26 18:43:47 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:47 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:47 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:47 volumio volumio[889]: info: ------------------------------ 69ms Jan 26 18:43:47 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:47 volumio volumio[889]: info: Jan 26 18:43:47 volumio volumio[889]: ---------------------------- MPD announces state update: update Jan 26 18:43:47 volumio volumio[889]: info: ControllerMpd::getState Jan 26 18:43:47 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:47 volumio volumio[889]: info: sendMpdCommand status took 5 milliseconds Jan 26 18:43:47 volumio volumio[889]: info: sendMpdCommand status took 4 milliseconds Jan 26 18:43:47 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:47 volumio volumio[889]: info: Command Router : Notfying DB Updatefalse Jan 26 18:43:47 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:43:47 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:47 volumio volumio[889]: info: ControllerMpd::pushState Jan 26 18:43:47 volumio volumio[889]: info: CoreCommandRouter::servicePushState Jan 26 18:43:47 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:47 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:47 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:47 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:47 volumio volumio[889]: 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} Jan 26 18:43:47 volumio volumio[889]: verbose: CURRENT POSITION 4 Jan 26 18:43:47 volumio volumio[889]: info: CoreStateMachine::syncState stateService stop Jan 26 18:43:47 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop Jan 26 18:43:47 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:47 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:47 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:43:47 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:47 volumio volumio[889]: info: No code Jan 26 18:43:47 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:47 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:47 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:47 volumio volumio[889]: info: ------------------------------ 53ms Jan 26 18:43:49 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:43:50 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 26 18:43:50 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand rescan Jan 26 18:43:50 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:50 volumio volumio[889]: info: Jan 26 18:43:50 volumio volumio[889]: ---------------------------- MPD announces state update: update Jan 26 18:43:50 volumio volumio[889]: info: sendMpdCommand rescan took 2 milliseconds Jan 26 18:43:50 volumio volumio[889]: info: ControllerMpd::getState Jan 26 18:43:50 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:50 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:50 volumio volumio[889]: info: Jan 26 18:43:50 volumio volumio[889]: ---------------------------- MPD announces state update: update Jan 26 18:43:50 volumio volumio[889]: info: ControllerMpd::getState Jan 26 18:43:50 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:50 volumio volumio[889]: info: sendMpdCommand status took 2 milliseconds Jan 26 18:43:50 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:50 volumio volumio[889]: info: Command Router : Notfying DB Updatefalse Jan 26 18:43:50 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:43:50 volumio volumio[889]: info: sendMpdCommand status took 39 milliseconds Jan 26 18:43:50 volumio volumio[889]: info: sendMpdCommand status took 38 milliseconds Jan 26 18:43:50 volumio volumio[889]: info: sendMpdCommand status took 37 milliseconds Jan 26 18:43:50 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:50 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:50 volumio volumio[889]: info: Command Router : Notfying DB Updatefalse Jan 26 18:43:50 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:43:50 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:50 volumio volumio[889]: info: ControllerMpd::pushState Jan 26 18:43:50 volumio volumio[889]: info: CoreCommandRouter::servicePushState Jan 26 18:43:50 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:50 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:50 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:43:50 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:50 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:50 volumio volumio[889]: 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} Jan 26 18:43:50 volumio volumio[889]: verbose: CURRENT POSITION 4 Jan 26 18:43:50 volumio volumio[889]: info: CoreStateMachine::syncState stateService stop Jan 26 18:43:50 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop Jan 26 18:43:50 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:50 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:50 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:50 volumio volumio[889]: info: No code Jan 26 18:43:50 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:50 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:50 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:50 volumio volumio[889]: info: ControllerMpd::pushState Jan 26 18:43:50 volumio volumio[889]: info: CoreCommandRouter::servicePushState Jan 26 18:43:50 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:50 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:50 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:50 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:50 volumio volumio[889]: 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} Jan 26 18:43:50 volumio volumio[889]: verbose: CURRENT POSITION 4 Jan 26 18:43:50 volumio volumio[889]: info: CoreStateMachine::syncState stateService stop Jan 26 18:43:50 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop Jan 26 18:43:50 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:50 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:50 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:50 volumio volumio[889]: info: No code Jan 26 18:43:50 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:50 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:50 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:50 volumio volumio[889]: info: ------------------------------ 93ms Jan 26 18:43:50 volumio volumio[889]: info: ------------------------------ 109ms Jan 26 18:43:53 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:43:53 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 26 18:43:53 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand rescan Jan 26 18:43:53 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:53 volumio volumio[889]: info: Jan 26 18:43:53 volumio volumio[889]: ---------------------------- MPD announces state update: update Jan 26 18:43:53 volumio volumio[889]: info: sendMpdCommand rescan took 2 milliseconds Jan 26 18:43:53 volumio volumio[889]: info: ControllerMpd::getState Jan 26 18:43:53 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:53 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:53 volumio volumio[889]: info: Jan 26 18:43:53 volumio volumio[889]: ---------------------------- MPD announces state update: update Jan 26 18:43:53 volumio volumio[889]: info: ControllerMpd::getState Jan 26 18:43:53 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:53 volumio volumio[889]: info: sendMpdCommand status took 3 milliseconds Jan 26 18:43:53 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:53 volumio volumio[889]: info: Command Router : Notfying DB Updatefalse Jan 26 18:43:53 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:43:53 volumio volumio[889]: info: sendMpdCommand status took 34 milliseconds Jan 26 18:43:53 volumio volumio[889]: info: sendMpdCommand status took 33 milliseconds Jan 26 18:43:53 volumio volumio[889]: info: sendMpdCommand status took 32 milliseconds Jan 26 18:43:53 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:53 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:53 volumio volumio[889]: info: Command Router : Notfying DB Updatefalse Jan 26 18:43:53 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:43:53 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:53 volumio volumio[889]: info: ControllerMpd::pushState Jan 26 18:43:53 volumio volumio[889]: info: CoreCommandRouter::servicePushState Jan 26 18:43:53 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:53 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:53 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:43:53 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:53 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:53 volumio volumio[889]: 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} Jan 26 18:43:53 volumio volumio[889]: verbose: CURRENT POSITION 4 Jan 26 18:43:53 volumio volumio[889]: info: CoreStateMachine::syncState stateService stop Jan 26 18:43:53 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop Jan 26 18:43:53 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:53 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:53 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:53 volumio volumio[889]: info: No code Jan 26 18:43:53 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:53 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:53 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:53 volumio volumio[889]: info: ControllerMpd::pushState Jan 26 18:43:53 volumio volumio[889]: info: CoreCommandRouter::servicePushState Jan 26 18:43:53 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:53 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:53 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:53 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:53 volumio volumio[889]: 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} Jan 26 18:43:53 volumio volumio[889]: verbose: CURRENT POSITION 4 Jan 26 18:43:53 volumio volumio[889]: info: CoreStateMachine::syncState stateService stop Jan 26 18:43:53 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop Jan 26 18:43:53 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:53 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:53 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:53 volumio volumio[889]: info: No code Jan 26 18:43:53 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:53 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:53 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:53 volumio volumio[889]: info: ------------------------------ 88ms Jan 26 18:43:53 volumio volumio[889]: info: ------------------------------ 103ms Jan 26 18:43:57 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:43:59 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 26 18:43:59 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand update Jan 26 18:43:59 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:59 volumio volumio[889]: info: Jan 26 18:43:59 volumio volumio[889]: ---------------------------- MPD announces state update: update Jan 26 18:43:59 volumio volumio[889]: info: sendMpdCommand update took 2 milliseconds Jan 26 18:43:59 volumio volumio[889]: info: ControllerMpd::getState Jan 26 18:43:59 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:59 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:59 volumio volumio[889]: info: Jan 26 18:43:59 volumio volumio[889]: ---------------------------- MPD announces state update: update Jan 26 18:43:59 volumio volumio[889]: info: ControllerMpd::getState Jan 26 18:43:59 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:43:59 volumio volumio[889]: info: sendMpdCommand status took 2 milliseconds Jan 26 18:43:59 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:59 volumio volumio[889]: info: Command Router : Notfying DB Updatefalse Jan 26 18:43:59 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:43:59 volumio volumio[889]: info: sendMpdCommand status took 29 milliseconds Jan 26 18:43:59 volumio volumio[889]: info: sendMpdCommand status took 30 milliseconds Jan 26 18:43:59 volumio volumio[889]: info: sendMpdCommand status took 29 milliseconds Jan 26 18:43:59 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:59 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:59 volumio volumio[889]: info: Command Router : Notfying DB Updatefalse Jan 26 18:43:59 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:43:59 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:43:59 volumio volumio[889]: info: ControllerMpd::pushState Jan 26 18:43:59 volumio volumio[889]: info: CoreCommandRouter::servicePushState Jan 26 18:43:59 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:59 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:59 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:43:59 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:59 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:59 volumio volumio[889]: 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} Jan 26 18:43:59 volumio volumio[889]: verbose: CURRENT POSITION 4 Jan 26 18:43:59 volumio volumio[889]: info: CoreStateMachine::syncState stateService stop Jan 26 18:43:59 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop Jan 26 18:43:59 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:59 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:59 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:59 volumio volumio[889]: info: No code Jan 26 18:43:59 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:59 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:59 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:59 volumio volumio[889]: info: ControllerMpd::pushState Jan 26 18:43:59 volumio volumio[889]: info: CoreCommandRouter::servicePushState Jan 26 18:43:59 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:59 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:59 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:59 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:59 volumio volumio[889]: 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} Jan 26 18:43:59 volumio volumio[889]: verbose: CURRENT POSITION 4 Jan 26 18:43:59 volumio volumio[889]: info: CoreStateMachine::syncState stateService stop Jan 26 18:43:59 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop Jan 26 18:43:59 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:59 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:59 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:59 volumio volumio[889]: info: No code Jan 26 18:43:59 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:43:59 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:43:59 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:43:59 volumio volumio[889]: info: ------------------------------ 87ms Jan 26 18:43:59 volumio volumio[889]: info: ------------------------------ 104ms Jan 26 18:44:01 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:44:05 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:44:09 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:44:10 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 26 18:44:10 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand rescan Jan 26 18:44:10 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:44:10 volumio volumio[889]: info: Jan 26 18:44:10 volumio volumio[889]: ---------------------------- MPD announces state update: update Jan 26 18:44:10 volumio volumio[889]: info: sendMpdCommand rescan took 2 milliseconds Jan 26 18:44:10 volumio volumio[889]: info: ControllerMpd::getState Jan 26 18:44:10 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:44:10 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:44:10 volumio volumio[889]: info: Jan 26 18:44:10 volumio volumio[889]: ---------------------------- MPD announces state update: update Jan 26 18:44:10 volumio volumio[889]: info: ControllerMpd::getState Jan 26 18:44:10 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:44:10 volumio volumio[889]: info: sendMpdCommand status took 2 milliseconds Jan 26 18:44:10 volumio volumio[889]: info: sendMpdCommand status took 1 milliseconds Jan 26 18:44:10 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:44:10 volumio volumio[889]: info: Command Router : Notfying DB Updatefalse Jan 26 18:44:10 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:44:10 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:44:10 volumio volumio[889]: info: ControllerMpd::pushState Jan 26 18:44:10 volumio volumio[889]: info: CoreCommandRouter::servicePushState Jan 26 18:44:10 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:44:10 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:10 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:44:10 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:44:10 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:10 volumio volumio[889]: 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} Jan 26 18:44:10 volumio volumio[889]: verbose: CURRENT POSITION 4 Jan 26 18:44:10 volumio volumio[889]: info: CoreStateMachine::syncState stateService stop Jan 26 18:44:10 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop Jan 26 18:44:10 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:44:10 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:10 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:44:10 volumio volumio[889]: info: No code Jan 26 18:44:10 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:44:10 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:10 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:44:10 volumio volumio[889]: info: ------------------------------ 61ms Jan 26 18:44:10 volumio volumio[889]: info: sendMpdCommand status took 60 milliseconds Jan 26 18:44:10 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:44:10 volumio volumio[889]: info: Command Router : Notfying DB Updatefalse Jan 26 18:44:10 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:44:10 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:44:10 volumio volumio[889]: info: ControllerMpd::pushState Jan 26 18:44:10 volumio volumio[889]: info: CoreCommandRouter::servicePushState Jan 26 18:44:10 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:44:10 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:10 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:44:10 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:10 volumio volumio[889]: 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} Jan 26 18:44:10 volumio volumio[889]: verbose: CURRENT POSITION 4 Jan 26 18:44:10 volumio volumio[889]: info: CoreStateMachine::syncState stateService stop Jan 26 18:44:10 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop Jan 26 18:44:10 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:44:10 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:10 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:44:10 volumio volumio[889]: info: No code Jan 26 18:44:10 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:44:10 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:10 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:44:10 volumio volumio[889]: info: ------------------------------ 128ms Jan 26 18:44:13 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:44:13 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 18:44:13 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 18:44:13 volumio volumio[889]: info: Discovery: Getting this device information Jan 26 18:44:13 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 26 18:44:13 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:13 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 18:44:13 volumio volumio[889]: verbose: New Socket.io Connection to 192.168.1.34:3000 from 192.168.1.35 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jan 26 18:44:13 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 26 18:44:13 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 26 18:44:14 volumio ntpd[3987]: Soliciting pool server 2401:5b60:0:1::52 Jan 26 18:44:15 volumio ntpd[3987]: Soliciting pool server 103.72.56.71 Jan 26 18:44:17 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:44:17 volumio ntpd[3987]: Soliciting pool server 103.221.223.185 Jan 26 18:44:18 volumio ntpd[3987]: Soliciting pool server 115.165.161.155 Jan 26 18:44:21 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:44:22 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 26 18:44:22 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand rescan Jan 26 18:44:22 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:44:22 volumio volumio[889]: info: Jan 26 18:44:22 volumio volumio[889]: ---------------------------- MPD announces state update: update Jan 26 18:44:22 volumio volumio[889]: info: sendMpdCommand rescan took 6 milliseconds Jan 26 18:44:22 volumio volumio[889]: info: ControllerMpd::getState Jan 26 18:44:22 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:44:22 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:44:22 volumio volumio[889]: info: Jan 26 18:44:22 volumio volumio[889]: ---------------------------- MPD announces state update: update Jan 26 18:44:22 volumio volumio[889]: info: ControllerMpd::getState Jan 26 18:44:22 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:44:22 volumio volumio[889]: info: sendMpdCommand status took 8 milliseconds Jan 26 18:44:22 volumio volumio[889]: info: sendMpdCommand status took 5 milliseconds Jan 26 18:44:22 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:44:22 volumio volumio[889]: info: Command Router : Notfying DB Updatefalse Jan 26 18:44:22 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:44:22 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:44:22 volumio volumio[889]: info: ControllerMpd::pushState Jan 26 18:44:22 volumio volumio[889]: info: CoreCommandRouter::servicePushState Jan 26 18:44:22 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:44:22 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:22 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:44:22 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:44:22 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:22 volumio volumio[889]: 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} Jan 26 18:44:22 volumio volumio[889]: verbose: CURRENT POSITION 4 Jan 26 18:44:22 volumio volumio[889]: info: CoreStateMachine::syncState stateService stop Jan 26 18:44:22 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop Jan 26 18:44:22 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:44:22 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:22 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:44:22 volumio volumio[889]: info: No code Jan 26 18:44:22 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:44:22 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:22 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:44:22 volumio volumio[889]: info: ------------------------------ 90ms Jan 26 18:44:22 volumio volumio[889]: info: sendMpdCommand status took 87 milliseconds Jan 26 18:44:22 volumio volumio[889]: info: sendMpdCommand status took 85 milliseconds Jan 26 18:44:22 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:44:22 volumio volumio[889]: info: Command Router : Notfying DB Updatefalse Jan 26 18:44:22 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:44:22 volumio volumio[889]: verbose: ControllerMpd::parseState Jan 26 18:44:22 volumio volumio[889]: info: ControllerMpd::pushState Jan 26 18:44:22 volumio volumio[889]: info: CoreCommandRouter::servicePushState Jan 26 18:44:22 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:44:22 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:22 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:44:22 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:44:22 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:22 volumio volumio[889]: 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} Jan 26 18:44:22 volumio volumio[889]: verbose: CURRENT POSITION 4 Jan 26 18:44:22 volumio volumio[889]: info: CoreStateMachine::syncState stateService stop Jan 26 18:44:22 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop Jan 26 18:44:22 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:44:22 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:22 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:44:22 volumio volumio[889]: info: No code Jan 26 18:44:22 volumio volumio[889]: info: CoreStateMachine::pushState Jan 26 18:44:22 volumio volumio[889]: info: CorePlayQueue::getTrack 4 Jan 26 18:44:22 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 26 18:44:22 volumio volumio[889]: info: ------------------------------ 145ms Jan 26 18:44:25 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:44:29 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:44:31 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 26 18:44:31 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 26 18:44:33 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:44:36 volumio volumio[889]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 18:44:36 volumio volumio[889]: Error: dns service error: unknown Jan 26 18:44:36 volumio volumio[889]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jan 26 18:44:36 volumio volumio[889]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jan 26 18:44:36 volumio volumio[889]: errorCode: -65537 Jan 26 18:44:36 volumio volumio[889]: } Jan 26 18:44:36 volumio volumio[889]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 18:44:36 volumio sudo[4320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-26 18:43 Jan 26 18:44:36 volumio sudo[4320]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:31:13 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="674d725e3f749f2a23785c9f94a28aa3"