-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Sat 2025-06-14 22:02:34 JST. -- Jun 14 22:02:02 volumio1 systemd-timedated[1072]: Changed local time to Sat Jun 14 22:02:02 2025 Jun 14 22:02:02 volumio1 systemd[1]: Starting Daily apt download activities... Jun 14 22:02:02 volumio1 sudo[1070]: pam_unix(sudo:session): session closed for user root Jun 14 22:02:02 volumio1 volumio-time-update[576]: volumio-time-update-util: System time updated successfully. Jun 14 22:02:02 volumio1 systemd[1]: Started Volumio Time Update Utility. Jun 14 22:02:02 volumio1 nmbd[835]: [2025/06/14 22:02:02.258102, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 14 22:02:02 volumio1 nmbd[835]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 14 22:02:02 volumio1 systemd[1]: Started Samba NMB Daemon. Jun 14 22:02:02 volumio1 systemd[1]: Starting Samba Winbind Daemon... Jun 14 22:02:02 volumio1 nmbd[835]: [2025/06/14 22:02:02.279780, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 14 22:02:02 volumio1 nmbd[835]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.210 for name WORKGROUP<1d>. Jun 14 22:02:02 volumio1 nmbd[835]: This response was from IP 192.168.1.178, reporting an IP address of 192.168.1.178. Jun 14 22:02:02 volumio1 ntpd[1050]: Soliciting pool server 162.159.200.1 Jun 14 22:02:02 volumio1 ntpd[1050]: Soliciting pool server 18.180.64.47 Jun 14 22:02:02 volumio1 winbindd[1081]: [2025/06/14 22:02:02.514351, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 14 22:02:02 volumio1 winbindd[1081]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 14 22:02:02 volumio1 winbindd[1081]: [2025/06/14 22:02:02.554622, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 14 22:02:02 volumio1 winbindd[1081]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 14 22:02:02 volumio1 systemd[1]: Started Samba Winbind Daemon. Jun 14 22:02:02 volumio1 ntpd[1050]: Soliciting pool server 217.142.230.155 Jun 14 22:02:02 volumio1 systemd[1]: Starting Samba SMB Daemon... Jun 14 22:02:02 volumio1 volumio-remote-updater[577]: [2025-06-14 22:02:02] [connect] Successful connection Jun 14 22:02:03 volumio1 smbd[1098]: [2025/06/14 22:02:03.183041, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 14 22:02:03 volumio1 systemd[1]: Started Samba SMB Daemon. Jun 14 22:02:03 volumio1 volumio[1024]: info: Loading plugin "network"... Jun 14 22:02:03 volumio1 volumio[1024]: info: Refreshing Cached IP Addresses Jun 14 22:02:03 volumio1 smbd[1098]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 14 22:02:03 volumio1 systemd[1]: Reached target Multi-User System. Jun 14 22:02:03 volumio1 systemd[1]: Reached target Graphical Interface. Jun 14 22:02:03 volumio1 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 14 22:02:03 volumio1 sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 22:02:03 volumio1 sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 22:02:03 volumio1 sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 22:02:03 volumio1 sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 22:02:03 volumio1 sudo[1108]: pam_unix(sudo:session): session closed for user root Jun 14 22:02:03 volumio1 sudo[1110]: pam_unix(sudo:session): session closed for user root Jun 14 22:02:03 volumio1 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 14 22:02:03 volumio1 systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 14 22:02:03 volumio1 volumio[1024]: info: Loading plugin "services"... Jun 14 22:02:03 volumio1 volumio[1024]: info: Loading plugin "alsa_controller"... Jun 14 22:02:03 volumio1 sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 14 22:02:03 volumio1 sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 22:02:03 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 22:02:03 volumio1 volumio[1024]: info: Loading plugin "wizard"... Jun 14 22:02:03 volumio1 volumio[1024]: info: Loading plugin "networkfs"... Jun 14 22:02:03 volumio1 ntpd[1050]: Soliciting pool server 160.16.113.133 Jun 14 22:02:03 volumio1 ntpd[1050]: Soliciting pool server 103.131.151.20 Jun 14 22:02:03 volumio1 volumio[1024]: info: Starting Udev Watcher for removable devices Jun 14 22:02:03 volumio1 sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.111.111:MusicPrograms/ /mnt/NAS/MusicPrograms Jun 14 22:02:03 volumio1 sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 22:02:03 volumio1 volumio[1024]: info: Ignoring mount for partition: boot Jun 14 22:02:03 volumio1 volumio[1024]: info: Ignoring mount for partition: volumio Jun 14 22:02:03 volumio1 volumio[1024]: info: Ignoring mount for partition: volumio_data Jun 14 22:02:03 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 22:02:03 volumio1 volumio[1024]: info: Loading plugin "volumio_command_line_client"... Jun 14 22:02:03 volumio1 volumio[1024]: info: Loading plugin "upnp"... Jun 14 22:02:03 volumio1 volumio[1024]: info: [1749906123503] Starting Upmpd Daemon Jun 14 22:02:03 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 22:02:03 volumio1 volumio[1024]: info: Loading plugin "my_music"... Jun 14 22:02:03 volumio1 volumio[1024]: info: Loading plugin "mpd"... Jun 14 22:02:03 volumio1 volumio[1024]: info: Loading plugin "upnp_browser"... Jun 14 22:02:04 volumio1 volumio[1024]: info: Loading plugin "alarm-clock"... Jun 14 22:02:04 volumio1 systemd[1]: apt-daily.service: Succeeded. Jun 14 22:02:04 volumio1 systemd[1]: Started Daily apt download activities. Jun 14 22:02:04 volumio1 volumio[1024]: info: Loading plugin "airplay_emulation"... Jun 14 22:02:04 volumio1 volumio[1024]: info: Starting Shairport Sync Jun 14 22:02:04 volumio1 volumio[1024]: info: Loading plugin "last_100"... Jun 14 22:02:04 volumio1 systemd[1]: Starting Daily apt upgrade and clean activities... Jun 14 22:02:04 volumio1 volumio[1024]: info: Loading plugin "webradio"... Jun 14 22:02:04 volumio1 volumio[1024]: info: Loading plugin "i2s_dacs"... Jun 14 22:02:04 volumio1 volumio[1024]: info: I2S DAC not set, start Auto-detection Jun 14 22:02:04 volumio1 volumio[1024]: info: Loading plugin "volumiodiscovery"... Jun 14 22:02:04 volumio1 ntpd[1050]: Soliciting pool server 45.77.20.103 Jun 14 22:02:04 volumio1 volumio[1024]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 14 22:02:04 volumio1 volumio[1024]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 22:02:04 volumio1 volumio[1024]: *** WARNING *** For more information see Jun 14 22:02:04 volumio1 volumio[1024]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 14 22:02:04 volumio1 volumio[1024]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 22:02:04 volumio1 volumio[1024]: *** WARNING *** For more information see Jun 14 22:02:04 volumio1 node[1024]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 14 22:02:04 volumio1 node[1024]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 22:02:04 volumio1 node[1024]: *** WARNING *** For more information see Jun 14 22:02:04 volumio1 node[1024]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 14 22:02:04 volumio1 node[1024]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 22:02:04 volumio1 node[1024]: *** WARNING *** For more information see Jun 14 22:02:04 volumio1 volumio[1024]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 14 22:02:04 volumio1 volumio[1024]: info: Discovery: Started advertising with name: Volumio1 Jun 14 22:02:04 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 22:02:04 volumio1 volumio[1024]: info: Loading plugin "outputs"... Jun 14 22:02:04 volumio1 volumio[1024]: info: Loading plugin "albumart"... Jun 14 22:02:04 volumio1 volumio[1024]: info: Plugin example_plugin is not enabled Jun 14 22:02:04 volumio1 volumio[1024]: info: Loading plugin "inputs"... Jun 14 22:02:04 volumio1 volumio[1024]: info: Loading plugin "updater_comm"... Jun 14 22:02:04 volumio1 volumio[1024]: info: Plugin mpdemulation is not enabled Jun 14 22:02:04 volumio1 volumio[1024]: info: Loading plugin "rest_api"... Jun 14 22:02:04 volumio1 volumio[1024]: info: Loading plugin "websocket"... Jun 14 22:02:04 volumio1 volumio[1024]: info: Starting Socket.io Server version 2.3.0 Jun 14 22:02:04 volumio1 volumio[1024]: Forking 3 albumart workers Jun 14 22:02:04 volumio1 volumio[1024]: info: Loading i18n strings for locale ja Jun 14 22:02:04 volumio1 volumio[1024]: Updating browse sources language Jun 14 22:02:04 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::initPlayerControls Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 22:02:05 volumio1 volumio[1024]: Express server listening on port 3000 Jun 14 22:02:05 volumio1 volumio[1024]: [Metrics] WebUI: 4s 765.44ms Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreStateMachine::resetVolumioState Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreStateMachine::getcurrentVolume Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 22:02:05 volumio1 volumio[1024]: info: Volumio Network Manager: Network status updated: 2 Jun 14 22:02:05 volumio1 volumio[1024]: verbose: New Socket.io Connection to 192.168.1.210 from 192.168.1.247 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 14 22:02:05 volumio1 volumio[1024]: verbose: New Socket.io Connection to 192.168.1.210 from 192.168.1.247 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 14 22:02:05 volumio1 volumio[1024]: verbose: New Socket.io Connection to 192.168.1.210 from 192.168.1.247 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 14 22:02:05 volumio1 volumio[1024]: verbose: New Socket.io Connection to 192.168.1.210 from 192.168.1.247 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 14 22:02:05 volumio1 volumio-remote-updater[577]: [2025-06-14 22:02:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749906122 101 Jun 14 22:02:05 volumio1 volumio[1024]: 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: 5 Jun 14 22:02:05 volumio1 volumio[1024]: verbose: New Socket.io Connection to 192.168.1.210:3000 from 192.168.1.194 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 14 22:02:05 volumio1 volumio[1024]: info: Reloading queue from file Jun 14 22:02:05 volumio1 volumio[1024]: info: Setting Device type: Raspberry PI Jun 14 22:02:05 volumio1 volumio[1024]: info: VolumeController:: Volume=100 Mute =false Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreStateMachine::pushState Jun 14 22:02:05 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::volumioPushState Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreStateMachine::updateTrackBlock Jun 14 22:02:05 volumio1 volumio[1024]: info: CorePlayQueue::getTrackBlock Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 22:02:05 volumio1 volumio[1024]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 14 22:02:05 volumio1 volumio[1024]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Jun 14 22:02:05 volumio1 volumio[1024]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreStateMachine::setRepeat false single undefined Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreStateMachine::pushState Jun 14 22:02:05 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::volumioPushState Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreStateMachine::setRandom false Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreStateMachine::pushState Jun 14 22:02:05 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::volumioPushState Jun 14 22:02:05 volumio1 volumio[1024]: Starting albumart workers Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetState Jun 14 22:02:05 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:05 volumio1 volumio[1024]: info: VolumeController:: Volume=100 Mute =false Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreStateMachine::pushState Jun 14 22:02:05 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::volumioPushState Jun 14 22:02:05 volumio1 volumio[1024]: Starting albumart workers Jun 14 22:02:05 volumio1 systemd[1]: apt-daily-upgrade.service: Succeeded. Jun 14 22:02:05 volumio1 systemd[1]: Started Daily apt upgrade and clean activities. Jun 14 22:02:05 volumio1 systemd[1]: Startup finished in 11.734s (kernel) + 28.616s (userspace) = 40.351s. Jun 14 22:02:05 volumio1 volumio[1024]: Starting albumart workers Jun 14 22:02:05 volumio1 volumio[1024]: info: Received Get System Info Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 22:02:05 volumio1 volumio[1024]: info: Discovery: Getting this device information Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetState Jun 14 22:02:05 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetVisibleSources Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetState Jun 14 22:02:05 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetState Jun 14 22:02:05 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 14 22:02:05 volumio1 volumio[1024]: info: Received Get System Info Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 22:02:05 volumio1 volumio[1024]: info: Discovery: Getting this device information Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetState Jun 14 22:02:05 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetState Jun 14 22:02:05 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:05 volumio1 volumio[1024]: info: Listing playlists Jun 14 22:02:05 volumio1 volumio[1024]: info: Discovery: adding c40d5258-c99b-45e2-a3b0-9035dac377a9 Jun 14 22:02:05 volumio1 volumio[1024]: info: Discovery: Found device Volumio1 Jun 14 22:02:05 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetState Jun 14 22:02:05 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:05 volumio1 volumio[1024]: info: Discovery: adding 3169233e-d1c8-4df8-9428-7d25774e2318 Jun 14 22:02:05 volumio1 volumio[1024]: info: Discovery: Found device khadas-volumio Jun 14 22:02:05 volumio1 volumio[1024]: info: Discovery: Connecting to remote: 192.168.1.194 Jun 14 22:02:06 volumio1 volumio[1024]: info: Discovery: Connected to remote: 192.168.1.194 Jun 14 22:02:06 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 14 22:02:07 volumio1 sudo[1120]: pam_unix(sudo:session): session closed for user root Jun 14 22:02:09 volumio1 ntpd[1050]: receive: Unexpected origin timestamp 0xebf7f150.72916218 does not match aorg 0000000000.00000000 from server@108.160.132.224 xmt 0xebf7f151.79fb1dc9 Jun 14 22:02:09 volumio1 ntpd[1050]: receive: Unexpected origin timestamp 0xebf7f150.728c769e does not match aorg 0000000000.00000000 from server@103.131.151.20 xmt 0xebf7f151.7a01797c Jun 14 22:02:09 volumio1 ntpd[1050]: Soliciting pool server 45.76.221.157 Jun 14 22:02:10 volumio1 ntpd[1050]: Soliciting pool server 45.76.211.39 Jun 14 22:02:10 volumio1 systemd[1]: systemd-fsckd.service: Succeeded. Jun 14 22:02:11 volumio1 ntpd[1050]: Soliciting pool server 162.159.200.123 Jun 14 22:02:12 volumio1 ntpd[1050]: Soliciting pool server 103.131.151.30 Jun 14 22:02:12 volumio1 systemd[1]: systemd-hostnamed.service: Succeeded. Jun 14 22:02:14 volumio1 sudo[1307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 22:02:14 volumio1 sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 22:02:14 volumio1 sudo[1307]: pam_unix(sudo:session): session closed for user root Jun 14 22:02:14 volumio1 sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 22:02:14 volumio1 sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 22:02:14 volumio1 sudo[1309]: pam_unix(sudo:session): session closed for user root Jun 14 22:02:14 volumio1 sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 14 22:02:14 volumio1 sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 22:02:14 volumio1 systemd[1]: Started UPnP Renderer front-end to MPD. Jun 14 22:02:14 volumio1 sudo[1313]: pam_unix(sudo:session): session closed for user root Jun 14 22:02:14 volumio1 volumio[1024]: info: Upmpdcli Daemon Started Jun 14 22:02:14 volumio1 volumio[1315]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 14 22:02:16 volumio1 volumio[1315]: .............++++ Jun 14 22:02:23 volumio1 volumio[1315]: ....................................................................................++++ Jun 14 22:02:23 volumio1 volumio[1315]: e is 65537 (0x010001) Jun 14 22:02:23 volumio1 volumio[1315]: writing RSA key Jun 14 22:02:27 volumio1 sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 22:02:27 volumio1 sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 22:02:27 volumio1 sudo[1378]: pam_unix(sudo:session): session closed for user root Jun 14 22:02:27 volumio1 sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 22:02:27 volumio1 sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 22:02:27 volumio1 sudo[1380]: pam_unix(sudo:session): session closed for user root Jun 14 22:02:27 volumio1 volumio[1024]: verbose: New Socket.io Connection to 192.168.1.210 from 192.168.1.247 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 14 22:02:27 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 14 22:02:28 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetVisibleSources Jun 14 22:02:28 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 22:02:28 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetState Jun 14 22:02:28 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:28 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 14 22:02:28 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 14 22:02:28 volumio1 volumio[1024]: info: Received Get System Info Jun 14 22:02:28 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 22:02:28 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 22:02:28 volumio1 volumio[1024]: info: Discovery: Getting this device information Jun 14 22:02:28 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetState Jun 14 22:02:28 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:28 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 22:02:28 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetState Jun 14 22:02:28 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:28 volumio1 volumio[1024]: info: Listing playlists Jun 14 22:02:28 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 14 22:02:28 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 14 22:02:28 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetState Jun 14 22:02:28 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:29 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 14 22:02:29 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 14 22:02:29 volumio1 volumio[1024]: info: Received Get System Info Jun 14 22:02:29 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 22:02:29 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 22:02:29 volumio1 volumio[1024]: info: Discovery: Getting this device information Jun 14 22:02:29 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetState Jun 14 22:02:29 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:29 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 22:02:31 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 14 22:02:31 volumio1 volumio[1024]: info: Received Get System Info Jun 14 22:02:31 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 22:02:31 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 22:02:31 volumio1 volumio[1024]: info: Discovery: Getting this device information Jun 14 22:02:31 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetState Jun 14 22:02:31 volumio1 volumio[1024]: info: CorePlayQueue::getTrack 0 Jun 14 22:02:31 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 14 22:02:32 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 14 22:02:33 volumio1 systemd[1]: systemd-timedated.service: Succeeded. Jun 14 22:02:34 volumio1 volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jun 14 22:02:34 volumio1 volumio[1024]: verbose: ControllerMpd::sendMpdCommand rescan Jun 14 22:02:34 volumio1 volumio[1024]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 22:02:34 volumio1 volumio[1024]: TypeError: Cannot read property 'then' of undefined Jun 14 22:02:34 volumio1 volumio[1024]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Jun 14 22:02:34 volumio1 volumio[1024]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1886:15) Jun 14 22:02:34 volumio1 volumio[1024]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jun 14 22:02:34 volumio1 volumio[1024]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1087:26) Jun 14 22:02:34 volumio1 volumio[1024]: at Socket.emit (events.js:315:20) Jun 14 22:02:34 volumio1 volumio[1024]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 14 22:02:34 volumio1 volumio[1024]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 14 22:02:34 volumio1 volumio[1024]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 22:02:34 volumio1 sudo[1411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-14 22:01 Jun 14 22:02:34 volumio1 sudo[1411]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"