-- Logs begin at Wed 2025-12-24 15:56:40 CET, end at Thu 2025-12-25 06:16:55 CET. -- Dec 25 06:15:04 kueche sudo[23652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://fritz.box/nas/http:/fritz.box/nas?sid=45d19a7b4c37a0a3 /mnt/NAS/Fritz Dec 25 06:15:04 kueche sudo[23652]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:15:04 kueche sudo[23652]: pam_unix(sudo:session): session closed for user root Dec 25 06:15:04 kueche volumio[23312]: info: Cannot mount NAS Fritz at system boot, trial number 4 ,retrying in 5 seconds Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 25 06:15:05 kueche volumio[23312]: info: Adding plugin bluetooth to MyMusic Plugins Dec 25 06:15:05 kueche volumio[23312]: info: Adding plugin multiroom to MyMusic Plugins Dec 25 06:15:05 kueche volumio[23312]: info: Adding plugin metavolumio to MyMusic Plugins Dec 25 06:15:05 kueche volumio[23312]: info: Adding plugin cd_controller to MyMusic Plugins Dec 25 06:15:05 kueche volumio[23312]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 25 06:15:05 kueche volumio[23312]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 25 06:15:05 kueche volumio[23312]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 25 06:15:05 kueche volumio[23312]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 25 06:15:06 kueche volumio[23312]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 25 06:15:06 kueche volumio[23312]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 25 06:15:06 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:15:06 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:15:06 kueche volumio[23312]: info: Starting MyVolumio Remote Streaming Endpoints Dec 25 06:15:06 kueche volumio[23312]: info: MyVolumio login type: Token Dec 25 06:15:06 kueche volumio[23312]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 25 06:15:06 kueche volumio[23312]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 25 06:15:06 kueche volumio[23312]: info: Starting Streaming Service Transparent Proxy Dec 25 06:15:07 kueche volumio[23312]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 25 06:15:07 kueche volumio[23312]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 25 06:15:07 kueche volumio[23312]: info: Streaming services startup Dec 25 06:15:07 kueche volumio[23312]: info: Starting Streaming Daemon Dec 25 06:15:07 kueche sudo[23677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 25 06:15:07 kueche sudo[23677]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:15:07 kueche volumio[23312]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 25 06:15:07 kueche sudo[23677]: pam_unix(sudo:session): session closed for user root Dec 25 06:15:07 kueche volumio[23312]: error: Cannot start Volumio Streaming Daemon Dec 25 06:15:07 kueche volumio[23312]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 25 06:15:07 kueche volumio[23312]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 25 06:15:07 kueche volumio[23312]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 25 06:15:08 kueche volumio[23312]: STREAMING PROXY: Starting server on port 3245 Dec 25 06:15:08 kueche volumio[23312]: Node JS runtime: 14 Dec 25 06:15:08 kueche volumio[23312]: info: MyVolumio token set successfully Dec 25 06:15:08 kueche volumio[23312]: info: MYVOLUMIO: Adding device Dec 25 06:15:08 kueche volumio[23312]: info: MYVOLUMIO: Evaluating Server Dec 25 06:15:09 kueche volumio[23312]: info: MyVolumio status changed Dec 25 06:15:09 kueche volumio[23312]: info: Streaming services startup Dec 25 06:15:09 kueche volumio[23312]: info: Starting Streaming Daemon Dec 25 06:15:09 kueche volumio[23312]: info: Removing browser output: myVolumio user plan is not superstar Dec 25 06:15:09 kueche volumio[23312]: info: Removing audio output: Dec 25 06:15:09 kueche volumio[23312]: info: Stoppping Tunnel 1 Dec 25 06:15:09 kueche sudo[23705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 25 06:15:09 kueche sudo[23705]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:15:09 kueche sudo[23708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 25 06:15:09 kueche sudo[23708]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:15:09 kueche sudo[23705]: pam_unix(sudo:session): session closed for user root Dec 25 06:15:09 kueche volumio[23312]: error: Cannot start Volumio Streaming Daemon Dec 25 06:15:09 kueche volumio[23312]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 25 06:15:09 kueche volumio[23312]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 25 06:15:09 kueche sudo[23708]: pam_unix(sudo:session): session closed for user root Dec 25 06:15:09 kueche volumio[23312]: info: Remote SSH Stopped Dec 25 06:15:09 kueche sudo[23749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://fritz.box/nas/http:/fritz.box/nas?sid=45d19a7b4c37a0a3 /mnt/NAS/Fritz Dec 25 06:15:09 kueche sudo[23749]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:15:09 kueche sudo[23749]: pam_unix(sudo:session): session closed for user root Dec 25 06:15:09 kueche volumio[23312]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Dec 25 06:15:18 kueche volumio[23312]: info: Setting Geolocation for MyVolumio to eu10 Dec 25 06:15:18 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:15:18 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:15:18 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:15:19 kueche volumio[23312]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 25 06:15:19 kueche volumio[23312]: info: Updating MyVolumio device info Dec 25 06:15:19 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:15:20 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 06:15:20 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:15:20 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 25 06:15:20 kueche volumio-remote-updater[552]: No test mode Dec 25 06:15:20 kueche volumio-remote-updater[552]: No alpha test mode Dec 25 06:15:20 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 25 06:15:20 kueche volumio[23312]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES





NEW ADDITIONS



 

 

","title":"Update v3.886","updateavailable":true} Dec 25 06:15:20 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 25 06:15:20 kueche volumio[23312]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 25 06:15:21 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 25 06:15:21 kueche volumio[23312]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 25 06:15:21 kueche volumio[23312]: info: CoreCommandRouter::volumioGetState Dec 25 06:15:21 kueche volumio[23312]: info: CorePlayQueue::getTrack 0 Dec 25 06:15:23 kueche volumio[23312]: info: MYVOLUMIO: Adding device Dec 25 06:15:23 kueche volumio[23312]: info: MYVOLUMIO: Evaluating Server Dec 25 06:15:24 kueche volumio[23312]: info: Setting Geolocation for MyVolumio to eu6 Dec 25 06:15:24 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:15:24 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:15:24 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:15:25 kueche volumio[23312]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 25 06:15:25 kueche volumio[23312]: info: Updating MyVolumio device info Dec 25 06:15:25 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:15:25 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:15:25 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:15:26 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:15:26 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:15:26 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 25 06:15:26 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 25 06:15:26 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 25 06:15:26 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 25 06:15:26 kueche volumio[23312]: info: CoreCommandRouter::volumioGetBrowseSources Dec 25 06:15:26 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 06:15:26 kueche volumio[23312]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 25 06:15:27 kueche volumio[23312]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 25 06:15:27 kueche volumio[23312]: info: Completed starting MyVolumio Plugin Dec 25 06:15:27 kueche volumio[23312]: [Metrics] CommandRouter: 42s 218.80ms Dec 25 06:15:27 kueche volumio[23312]: info: CoreCommandRouter::volumiosetStartupVolume Dec 25 06:15:27 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:15:27 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:15:27 kueche volumio[23312]: info: CoreCommandRouter::Close All Modals sent Dec 25 06:15:27 kueche volumio[23312]: info: CoreCommandRouter::Close All Modals sent Dec 25 06:15:28 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 25 06:15:28 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 25 06:15:28 kueche volumio[23312]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 25 06:15:34 kueche volumio[23312]: info: BOOT COMPLETED Dec 25 06:15:36 kueche ifplugd(eth0)[744]: Link beat lost. Dec 25 06:15:37 kueche dhcpcd[873]: eth0: carrier lost Dec 25 06:15:37 kueche kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Dec 25 06:15:37 kueche avahi-daemon[539]: Withdrawing address record for 192.168.178.73 on eth0. Dec 25 06:15:37 kueche dhcpcd[873]: eth0: deleting route to 192.168.178.0/24 Dec 25 06:15:37 kueche dhcpcd[873]: eth0: deleting default route via 192.168.178.1 Dec 25 06:15:37 kueche avahi-daemon[539]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.178.73. Dec 25 06:15:37 kueche avahi-daemon[539]: Interface eth0.IPv4 no longer relevant for mDNS. Dec 25 06:15:37 kueche volumio[23312]: info: Discovery: A device disappeared from network Dec 25 06:15:37 kueche volumio[23312]: info: Discovery: Device kueche disappeared from network Dec 25 06:15:37 kueche volumio[23312]: info: Discovery: A device disappeared from network Dec 25 06:15:37 kueche volumio[23312]: info: Discovery: Device wohnen disappeared from network Dec 25 06:15:37 kueche volumio[23312]: info: Discovery: Disconnected from remote: 192.168.178.39 Dec 25 06:15:37 kueche ntpd[23372]: ntpd exiting on signal 15 (Terminated) Dec 25 06:15:37 kueche ntpd[23372]: 192.168.178.1 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche systemd[1]: Stopping Network Time Service... Dec 25 06:15:37 kueche ntpd[23372]: 148.251.235.164 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche ntpd[23372]: 5.45.97.204 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche ntpd[23372]: 213.172.105.106 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche ntpd[23372]: 213.239.234.28 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche ntpd[23372]: 46.4.54.78 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche ntpd[23372]: 129.70.132.34 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche ntpd[23372]: 51.75.67.47 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche ntpd[23372]: 162.159.200.123 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche systemd[1]: ntp.service: Succeeded. Dec 25 06:15:37 kueche ntpd[23372]: 82.165.178.31 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche ntpd[23372]: 176.9.44.212 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche ntpd[23372]: 139.162.152.20 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche ntpd[23372]: 178.238.232.141 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche ntpd[23372]: 85.215.189.120 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche ntpd[23372]: 185.232.69.65 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche systemd[1]: Stopped Network Time Service. Dec 25 06:15:37 kueche ntpd[23372]: 94.130.23.46 local addr 192.168.178.73 -> Dec 25 06:15:37 kueche systemd[1]: Starting Network Time Service... Dec 25 06:15:37 kueche ntpd[23869]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 25 06:15:37 kueche ntpd[23869]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Dec 25 06:15:37 kueche systemd[1]: Started Network Time Service. Dec 25 06:15:37 kueche ntpd[23875]: proto: precision = 0.625 usec (-21) Dec 25 06:15:37 kueche ntpd[23875]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 25 06:15:37 kueche ntpd[23875]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 25 06:15:37 kueche ntpd[23875]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 363 days ago Dec 25 06:15:37 kueche ntpd[23875]: Listen and drop on 0 v6wildcard [::]:123 Dec 25 06:15:37 kueche ntpd[23875]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 25 06:15:37 kueche ntpd[23875]: Listen normally on 2 lo 127.0.0.1:123 Dec 25 06:15:37 kueche ntpd[23875]: Listening on routing socket on fd #19 for interface updates Dec 25 06:15:37 kueche ntpd[23875]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 25 06:15:37 kueche ntpd[23875]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 25 06:15:38 kueche ntpd[23875]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 25 06:15:38 kueche ifplugd(eth0)[744]: Link beat detected. Dec 25 06:15:39 kueche dhcpcd[873]: eth0: carrier acquired Dec 25 06:15:39 kueche dhcpcd[873]: eth0: IAID eb:b1:b2:d6 Dec 25 06:15:39 kueche kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 25 06:15:39 kueche dhcpcd[873]: eth0: soliciting an IPv6 router Dec 25 06:15:39 kueche dhcpcd[873]: eth0: rebinding lease of 192.168.178.73 Dec 25 06:15:39 kueche dhcpcd[873]: eth0: probing address 192.168.178.73/24 Dec 25 06:15:39 kueche ntpd[23875]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 25 06:15:40 kueche ntpd[23875]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 25 06:15:41 kueche ntpd[23875]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 25 06:15:44 kueche dhcpcd[873]: eth0: leased 192.168.178.73 for 864000 seconds Dec 25 06:15:44 kueche dhcpcd[873]: eth0: adding route to 192.168.178.0/24 Dec 25 06:15:44 kueche dhcpcd[873]: eth0: adding default route via 192.168.178.1 Dec 25 06:15:44 kueche avahi-daemon[539]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.73. Dec 25 06:15:44 kueche avahi-daemon[539]: New relevant interface eth0.IPv4 for mDNS. Dec 25 06:15:44 kueche avahi-daemon[539]: Registering new address record for 192.168.178.73 on eth0.IPv4. Dec 25 06:15:44 kueche ntpd[23875]: ntpd exiting on signal 15 (Terminated) Dec 25 06:15:44 kueche systemd[1]: Stopping Network Time Service... Dec 25 06:15:44 kueche systemd[1]: ntp.service: Succeeded. Dec 25 06:15:44 kueche systemd[1]: Stopped Network Time Service. Dec 25 06:15:45 kueche systemd[1]: Starting Network Time Service... Dec 25 06:15:45 kueche ntpd[23910]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 25 06:15:45 kueche ntpd[23910]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Dec 25 06:15:45 kueche systemd[1]: Started Network Time Service. Dec 25 06:15:45 kueche ntpd[23918]: proto: precision = 0.573 usec (-21) Dec 25 06:15:45 kueche ntpd[23918]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 25 06:15:45 kueche volumio[23312]: info: Discovery: adding a0d54c44-6986-4ca3-a5a9-17977cbd4fa7 Dec 25 06:15:45 kueche volumio[23312]: info: Discovery: Found device wohnen Dec 25 06:15:45 kueche ntpd[23918]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 25 06:15:45 kueche ntpd[23918]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 363 days ago Dec 25 06:15:45 kueche ntpd[23918]: Listen and drop on 0 v6wildcard [::]:123 Dec 25 06:15:45 kueche ntpd[23918]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 25 06:15:45 kueche ntpd[23918]: Listen normally on 2 lo 127.0.0.1:123 Dec 25 06:15:45 kueche ntpd[23918]: Listen normally on 3 eth0 192.168.178.73:123 Dec 25 06:15:45 kueche ntpd[23918]: Listening on routing socket on fd #20 for interface updates Dec 25 06:15:45 kueche ntpd[23918]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 25 06:15:45 kueche ntpd[23918]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 25 06:15:45 kueche volumio[23312]: info: Discovery: Connecting to remote: 192.168.178.39 Dec 25 06:15:45 kueche volumio[23312]: info: Discovery: Connected to remote: 192.168.178.39 Dec 25 06:15:46 kueche dhcpcd[873]: eth0: carrier lost Dec 25 06:15:46 kueche kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Dec 25 06:15:46 kueche avahi-daemon[539]: Withdrawing address record for 192.168.178.73 on eth0. Dec 25 06:15:46 kueche dhcpcd[873]: eth0: deleting route to 192.168.178.0/24 Dec 25 06:15:46 kueche avahi-daemon[539]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.178.73. Dec 25 06:15:46 kueche dhcpcd[873]: eth0: deleting default route via 192.168.178.1 Dec 25 06:15:46 kueche avahi-daemon[539]: Interface eth0.IPv4 no longer relevant for mDNS. Dec 25 06:15:46 kueche volumio[23312]: info: Discovery: A device disappeared from network Dec 25 06:15:46 kueche volumio[23312]: info: Discovery: A device disappeared from network Dec 25 06:15:46 kueche volumio[23312]: info: Discovery: Device wohnen disappeared from network Dec 25 06:15:46 kueche volumio[23312]: info: Discovery: Disconnected from remote: 192.168.178.39 Dec 25 06:15:46 kueche ntpd[23918]: ntpd exiting on signal 15 (Terminated) Dec 25 06:15:46 kueche ntpd[23918]: 192.168.178.1 local addr 192.168.178.73 -> Dec 25 06:15:46 kueche systemd[1]: Stopping Network Time Service... Dec 25 06:15:46 kueche systemd[1]: ntp.service: Succeeded. Dec 25 06:15:46 kueche systemd[1]: Stopped Network Time Service. Dec 25 06:15:46 kueche systemd[1]: Starting Network Time Service... Dec 25 06:15:46 kueche ntpd[23963]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 25 06:15:46 kueche ntpd[23963]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Dec 25 06:15:46 kueche systemd[1]: Started Network Time Service. Dec 25 06:15:46 kueche volumio[23312]: info: Volumio Network Manager: Network status updated: 0 Dec 25 06:15:46 kueche ntpd[23982]: proto: precision = 0.625 usec (-21) Dec 25 06:15:46 kueche ntpd[23982]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 25 06:15:46 kueche ntpd[23982]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 25 06:15:46 kueche ntpd[23982]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 363 days ago Dec 25 06:15:46 kueche ntpd[23982]: Listen and drop on 0 v6wildcard [::]:123 Dec 25 06:15:46 kueche ntpd[23982]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 25 06:15:46 kueche ntpd[23982]: Listen normally on 2 lo 127.0.0.1:123 Dec 25 06:15:46 kueche ntpd[23982]: Listening on routing socket on fd #19 for interface updates Dec 25 06:15:46 kueche ntpd[23982]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 25 06:15:46 kueche ntpd[23982]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 25 06:15:47 kueche ifplugd(eth0)[744]: Link beat lost. Dec 25 06:15:47 kueche ntpd[23982]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 25 06:15:48 kueche ntpd[23982]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 25 06:15:49 kueche ifplugd(eth0)[744]: Link beat detected. Dec 25 06:15:49 kueche dhcpcd[873]: eth0: carrier acquired Dec 25 06:15:49 kueche kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 25 06:15:49 kueche dhcpcd[873]: eth0: IAID eb:b1:b2:d6 Dec 25 06:15:49 kueche ntpd[23982]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 25 06:15:49 kueche dhcpcd[873]: eth0: rebinding lease of 192.168.178.73 Dec 25 06:15:50 kueche dhcpcd[873]: eth0: probing address 192.168.178.73/24 Dec 25 06:15:50 kueche dhcpcd[873]: eth0: soliciting an IPv6 router Dec 25 06:15:50 kueche ntpd[23982]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 25 06:15:51 kueche volumio[23312]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 06:15:51 kueche volumio[23312]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 25 06:15:51 kueche volumio[23312]: at doSend (dgram.js:692:16) Dec 25 06:15:51 kueche volumio[23312]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 25 06:15:51 kueche volumio[23312]: at afterDns (dgram.js:638:5) Dec 25 06:15:51 kueche volumio[23312]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 25 06:15:51 kueche volumio[23312]: errno: -101, Dec 25 06:15:51 kueche volumio[23312]: code: 'ENETUNREACH', Dec 25 06:15:51 kueche volumio[23312]: syscall: 'send', Dec 25 06:15:51 kueche volumio[23312]: address: '255.255.255.255', Dec 25 06:15:51 kueche volumio[23312]: port: 3483 Dec 25 06:15:51 kueche volumio[23312]: } Dec 25 06:15:51 kueche volumio[23312]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 06:15:51 kueche sudo[23995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-25 06:14 Dec 25 06:15:51 kueche sudo[23995]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:15:51 kueche sudo[23995]: pam_unix(sudo:session): session closed for user root Dec 25 06:15:55 kueche dhcpcd[873]: eth0: leased 192.168.178.73 for 864000 seconds Dec 25 06:15:55 kueche dhcpcd[873]: eth0: adding route to 192.168.178.0/24 Dec 25 06:15:55 kueche avahi-daemon[539]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.73. Dec 25 06:15:55 kueche dhcpcd[873]: eth0: adding default route via 192.168.178.1 Dec 25 06:15:55 kueche avahi-daemon[539]: New relevant interface eth0.IPv4 for mDNS. Dec 25 06:15:55 kueche avahi-daemon[539]: Registering new address record for 192.168.178.73 on eth0.IPv4. Dec 25 06:15:55 kueche ntpd[23982]: ntpd exiting on signal 15 (Terminated) Dec 25 06:15:55 kueche systemd[1]: Stopping Network Time Service... Dec 25 06:15:55 kueche systemd[1]: ntp.service: Succeeded. Dec 25 06:15:55 kueche systemd[1]: Stopped Network Time Service. Dec 25 06:15:55 kueche systemd[1]: Starting Network Time Service... Dec 25 06:15:55 kueche ntpd[24038]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 25 06:15:55 kueche systemd[1]: Started Network Time Service. Dec 25 06:15:55 kueche ntpd[24038]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Dec 25 06:15:55 kueche ntpd[24044]: proto: precision = 0.573 usec (-21) Dec 25 06:15:55 kueche ntpd[24044]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 25 06:15:55 kueche ntpd[24044]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 25 06:15:55 kueche ntpd[24044]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 363 days ago Dec 25 06:15:55 kueche ntpd[24044]: Listen and drop on 0 v6wildcard [::]:123 Dec 25 06:15:55 kueche ntpd[24044]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 25 06:15:55 kueche ntpd[24044]: Listen normally on 2 lo 127.0.0.1:123 Dec 25 06:15:55 kueche ntpd[24044]: Listen normally on 3 eth0 192.168.178.73:123 Dec 25 06:15:55 kueche ntpd[24044]: Listening on routing socket on fd #20 for interface updates Dec 25 06:15:55 kueche ntpd[24044]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 25 06:15:55 kueche ntpd[24044]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 25 06:15:55 kueche volumio-remote-updater[552]: [2025-12-25 06:15:55] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 25 06:15:55 kueche volumio-remote-updater[552]: [2025-12-25 06:15:55] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 25 06:15:55 kueche systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 25 06:15:55 kueche systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 25 06:15:55 kueche systemd[1]: Started dynamicswap service. Dec 25 06:15:55 kueche systemd[1]: dynamicswap.service: Succeeded. Dec 25 06:15:56 kueche systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 25 06:15:56 kueche systemd[1]: volumio.service: Scheduled restart job, restart counter is at 60. Dec 25 06:15:56 kueche systemd[1]: Stopped Volumio Backend Module. Dec 25 06:15:56 kueche systemd[1]: Started Volumio Backend Module. Dec 25 06:15:56 kueche systemd[1]: Started dynamicswap service. Dec 25 06:15:56 kueche systemd[1]: dynamicswap.service: Succeeded. Dec 25 06:15:56 kueche ntpd[24044]: Soliciting pool server 212.132.108.186 Dec 25 06:15:57 kueche ntpd[24044]: Soliciting pool server 185.228.139.165 Dec 25 06:15:57 kueche ntpd[24044]: Soliciting pool server 176.9.42.71 Dec 25 06:15:57 kueche volumio[24052]: info: ------------------------------------------- Dec 25 06:15:57 kueche volumio[24052]: info: ----- Volumio3 ---- Dec 25 06:15:57 kueche volumio[24052]: info: ------------------------------------------- Dec 25 06:15:57 kueche volumio[24052]: info: ----- System startup ---- Dec 25 06:15:57 kueche volumio[24052]: info: ------------------------------------------- Dec 25 06:15:58 kueche volumio[24052]: info: MYVOLUMIO Environment detected Dec 25 06:15:58 kueche volumio[24052]: info: Plugin folders cleanup Dec 25 06:15:58 kueche volumio[24052]: info: Scanning into folder /volumio/app/plugins/ Dec 25 06:15:58 kueche volumio[24052]: info: Scanning category audio_interface Dec 25 06:15:58 kueche volumio[24052]: info: Scanning category miscellanea Dec 25 06:15:58 kueche volumio[24052]: info: Scanning category music_service Dec 25 06:15:58 kueche ntpd[24044]: Soliciting pool server 78.47.118.0 Dec 25 06:15:58 kueche ntpd[24044]: Soliciting pool server 45.145.40.190 Dec 25 06:15:58 kueche volumio[24052]: info: Scanning category plugins.json Dec 25 06:15:58 kueche volumio[24052]: info: Scanning category system_controller Dec 25 06:15:58 kueche volumio[24052]: info: Scanning category user_interface Dec 25 06:15:58 kueche volumio[24052]: info: Scanning into folder /data/plugins/ Dec 25 06:15:58 kueche volumio[24052]: info: Scanning category music_service Dec 25 06:15:58 kueche volumio[24052]: info: Plugin folders cleanup completed Dec 25 06:15:58 kueche volumio[24052]: info: ------------------------------------------- Dec 25 06:15:58 kueche volumio[24052]: info: ----- Core plugins startup ---- Dec 25 06:15:58 kueche volumio[24052]: info: ------------------------------------------- Dec 25 06:15:58 kueche volumio[24052]: info: Loading plugins from folder /volumio/app/plugins/ Dec 25 06:15:58 kueche volumio[24052]: info: Adding plugin upnp to MyMusic Plugins Dec 25 06:15:58 kueche volumio[24052]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 25 06:15:58 kueche volumio[24052]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 25 06:15:58 kueche volumio[24052]: info: Loading plugins from folder /data/plugins/ Dec 25 06:15:58 kueche volumio[24052]: info: Loading plugin "system"... Dec 25 06:15:58 kueche volumio[24052]: info: Loading plugin "appearance"... Dec 25 06:15:58 kueche ntpd[24044]: Soliciting pool server 213.239.234.28 Dec 25 06:15:59 kueche volumio[24052]: info: Loading plugin "network"... Dec 25 06:15:59 kueche volumio[24052]: info: Refreshing Cached IP Addresses Dec 25 06:15:59 kueche sudo[24079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 25 06:15:59 kueche sudo[24079]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:15:59 kueche ntpd[24044]: Soliciting pool server 129.70.132.34 Dec 25 06:15:59 kueche ntpd[24044]: Soliciting pool server 79.133.44.139 Dec 25 06:15:59 kueche ntpd[24044]: Soliciting pool server 90.187.112.137 Dec 25 06:15:59 kueche sudo[24079]: pam_unix(sudo:session): session closed for user root Dec 25 06:15:59 kueche sudo[24081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 25 06:15:59 kueche volumio[24052]: info: Loading plugin "services"... Dec 25 06:15:59 kueche sudo[24081]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:15:59 kueche sudo[24081]: pam_unix(sudo:session): session closed for user root Dec 25 06:15:59 kueche volumio[24052]: info: Loading plugin "alsa_controller"... Dec 25 06:15:59 kueche ntpd[24044]: Soliciting pool server 194.59.205.229 Dec 25 06:15:59 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 06:15:59 kueche volumio[24052]: info: Loading plugin "wizard"... Dec 25 06:15:59 kueche volumio[24052]: info: Loading plugin "networkfs"... Dec 25 06:15:59 kueche volumio[24052]: info: Starting Udev Watcher for removable devices Dec 25 06:15:59 kueche sudo[24104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://fritz.box/nas/http:/fritz.box/nas?sid=45d19a7b4c37a0a3 /mnt/NAS/Fritz Dec 25 06:15:59 kueche sudo[24104]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:15:59 kueche volumio[24052]: info: Ignoring mount for partition: boot Dec 25 06:15:59 kueche volumio[24052]: info: Ignoring mount for partition: volumio Dec 25 06:15:59 kueche volumio[24052]: info: Ignoring mount for partition: volumio_data Dec 25 06:15:59 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 25 06:15:59 kueche volumio[24052]: info: Loading plugin "volumio_command_line_client"... Dec 25 06:15:59 kueche sudo[24104]: pam_unix(sudo:session): session closed for user root Dec 25 06:15:59 kueche volumio[24052]: info: Loading plugin "upnp"... Dec 25 06:15:59 kueche volumio[24052]: info: [1766639759602] Starting Upmpd Daemon Dec 25 06:15:59 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 25 06:15:59 kueche volumio[24052]: info: Loading plugin "my_music"... Dec 25 06:15:59 kueche volumio[24052]: info: Loading plugin "mpd"... Dec 25 06:15:59 kueche volumio[24052]: info: Loading plugin "upnp_browser"... Dec 25 06:16:00 kueche volumio[24052]: info: Loading plugin "alarm-clock"... Dec 25 06:16:00 kueche volumio[24052]: info: Loading plugin "airplay_emulation"... Dec 25 06:16:00 kueche volumio[24052]: info: Starting Shairport Sync Dec 25 06:16:00 kueche volumio[24052]: info: Loading plugin "last_100"... Dec 25 06:16:00 kueche volumio[24052]: info: Loading plugin "webradio"... Dec 25 06:16:00 kueche ntpd[24044]: Soliciting pool server 217.91.44.17 Dec 25 06:16:00 kueche ntpd[24044]: Soliciting pool server 51.75.67.47 Dec 25 06:16:00 kueche ntpd[24044]: Soliciting pool server 78.46.53.2 Dec 25 06:16:00 kueche volumio[24052]: info: Loading plugin "i2s_dacs"... Dec 25 06:16:00 kueche volumio[24052]: info: Loading plugin "volumiodiscovery"... Dec 25 06:16:00 kueche volumio[24052]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 25 06:16:00 kueche volumio[24052]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 06:16:00 kueche volumio[24052]: *** WARNING *** For more information see Dec 25 06:16:00 kueche node[24052]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 25 06:16:00 kueche volumio[24052]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 25 06:16:00 kueche volumio[24052]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 06:16:00 kueche volumio[24052]: *** WARNING *** For more information see Dec 25 06:16:00 kueche node[24052]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 06:16:00 kueche node[24052]: *** WARNING *** For more information see Dec 25 06:16:00 kueche node[24052]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 25 06:16:00 kueche node[24052]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 06:16:00 kueche node[24052]: *** WARNING *** For more information see Dec 25 06:16:00 kueche volumio[24052]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 25 06:16:00 kueche volumio[24052]: info: Discovery: Started advertising with name: kueche Dec 25 06:16:00 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 25 06:16:00 kueche volumio[24052]: info: Loading plugin "squeezelite_mc"... Dec 25 06:16:00 kueche volumio-remote-updater[552]: [2025-12-25 06:16:00] [connect] Successful connection Dec 25 06:16:01 kueche volumio[24052]: info: Loading plugin "outputs"... Dec 25 06:16:01 kueche volumio[24052]: info: Loading plugin "albumart"... Dec 25 06:16:01 kueche volumio[24052]: info: Plugin example_plugin is not enabled Dec 25 06:16:01 kueche volumio[24052]: info: Loading plugin "inputs"... Dec 25 06:16:01 kueche volumio[24052]: info: Loading plugin "updater_comm"... Dec 25 06:16:01 kueche ntpd[24044]: Soliciting pool server 134.60.111.110 Dec 25 06:16:01 kueche ntpd[24044]: Soliciting pool server 85.215.189.120 Dec 25 06:16:01 kueche volumio[24052]: info: Plugin mpdemulation is not enabled Dec 25 06:16:01 kueche volumio[24052]: info: Loading plugin "rest_api"... Dec 25 06:16:01 kueche volumio[24052]: info: Loading plugin "websocket"... Dec 25 06:16:01 kueche volumio[24052]: info: Starting Socket.io Server version 2.3.0 Dec 25 06:16:01 kueche volumio[24052]: info: Loading i18n strings for locale de Dec 25 06:16:01 kueche volumio[24052]: Updating browse sources language Dec 25 06:16:01 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 06:16:01 kueche volumio[24052]: Forking 3 albumart workers Dec 25 06:16:01 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 06:16:01 kueche volumio[24052]: info: CoreCommandRouter::initPlayerControls Dec 25 06:16:01 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:01 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:01 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:01 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:01 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:01 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:01 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:01 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:01 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 06:16:01 kueche volumio[24052]: Express server listening on port 3000 Dec 25 06:16:01 kueche volumio[24052]: [Metrics] WebUI: 4s 569.53ms Dec 25 06:16:01 kueche volumio[24052]: info: CoreStateMachine::resetVolumioState Dec 25 06:16:01 kueche volumio[24052]: info: CoreStateMachine::getcurrentVolume Dec 25 06:16:01 kueche volumio[24052]: info: CoreCommandRouter::volumioRetrievevolume Dec 25 06:16:01 kueche volumio[24052]: info: Cannot read play queue from file Dec 25 06:16:01 kueche volumio[24052]: info: Volumio Network Manager: Network status updated: 1 Dec 25 06:16:01 kueche volumio[24052]: info: Cannot mount NAS Fritz at system boot, trial number 1 ,retrying in 5 seconds Dec 25 06:16:01 kueche volumio[24052]: verbose: New Socket.io Connection to 192.168.178.73:3000 from 192.168.178.39 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 25 06:16:01 kueche volumio-remote-updater[552]: [2025-12-25 06:16:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766639760 101 Dec 25 06:16:01 kueche volumio[24052]: 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: 2 Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:02 kueche volumio[24052]: info: Executing start script for DAC IQaudIO DAC Plus Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::volumioGetState Dec 25 06:16:02 kueche volumio[24052]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:02 kueche volumio[24052]: info: VolumeController:: Volume=100 Mute =false Dec 25 06:16:02 kueche volumio[24052]: info: CoreStateMachine::pushState Dec 25 06:16:02 kueche volumio[24052]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::volumioPushState Dec 25 06:16:02 kueche volumio[24052]: info: CoreStateMachine::updateTrackBlock Dec 25 06:16:02 kueche volumio[24052]: info: CorePlayQueue::getTrackBlock Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::volumioRetrievevolume Dec 25 06:16:02 kueche volumio[24052]: info: CoreStateMachine::setRepeat null single undefined Dec 25 06:16:02 kueche volumio[24052]: info: CoreStateMachine::pushState Dec 25 06:16:02 kueche volumio[24052]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::volumioPushState Dec 25 06:16:02 kueche volumio[24052]: info: CoreStateMachine::setRandom null Dec 25 06:16:02 kueche volumio[24052]: info: CoreStateMachine::pushState Dec 25 06:16:02 kueche volumio[24052]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::volumioPushState Dec 25 06:16:02 kueche volumio[24052]: info: Setting Device type: Raspberry PI Dec 25 06:16:02 kueche volumio[24052]: info: DAC script executed Dec 25 06:16:02 kueche volumio[24052]: info: Completed loading Core Plugins Dec 25 06:16:02 kueche volumio[24052]: info: Preparing to generate the ALSA configuration file Dec 25 06:16:02 kueche ntpd[24044]: Soliciting pool server 78.47.249.55 Dec 25 06:16:02 kueche ntpd[24044]: Soliciting pool server 2a01:7e01::f03c:94ff:fee2:cb39 Dec 25 06:16:02 kueche volumio[24052]: info: Discovery: adding a0d54c44-6986-4ca3-a5a9-17977cbd4fa7 Dec 25 06:16:02 kueche volumio[24052]: info: Discovery: Found device wohnen Dec 25 06:16:02 kueche volumio[24052]: info: Discovery: Connecting to remote: 192.168.178.39 Dec 25 06:16:02 kueche volumio[24052]: info: Asound.conf file unchanged, so no further update is needed Dec 25 06:16:02 kueche volumio[24052]: info: Output device has changed, restarting MPD Dec 25 06:16:02 kueche sudo[24156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 06:16:02 kueche sudo[24156]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:02 kueche volumio[24052]: info: Output device has changed, restarting Shairport Sync Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:02 kueche sudo[24156]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:02 kueche sudo[24158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 06:16:02 kueche sudo[24158]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:02 kueche volumio[24052]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 06:16:02 kueche volumio[24052]: info: ___________ START PLUGINS ___________ Dec 25 06:16:02 kueche volumio[24052]: info: ControllerMpd::onStart: Initializing MPD Dec 25 06:16:02 kueche volumio[24052]: info: Creating MPD Configuration file Dec 25 06:16:02 kueche systemd[1]: Stopping Music Player Daemon... Dec 25 06:16:02 kueche volumio[24052]: Starting albumart workers Dec 25 06:16:02 kueche systemd[1]: mpd.service: Succeeded. Dec 25 06:16:02 kueche systemd[1]: Stopped Music Player Daemon. Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 25 06:16:02 kueche volumio[24052]: info: [1766639762675] CoreMusicLibrary::Adding element Medienserver Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 06:16:02 kueche sudo[24165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 06:16:02 kueche sudo[24168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 06:16:02 kueche volumio[24052]: Starting albumart workers Dec 25 06:16:02 kueche systemd[1]: Starting Music Player Daemon... Dec 25 06:16:02 kueche sudo[24165]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:02 kueche sudo[24168]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:02 kueche volumio[24052]: Starting albumart workers Dec 25 06:16:02 kueche sudo[24165]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:02 kueche systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 25 06:16:02 kueche systemd[1]: mpd.service: Succeeded. Dec 25 06:16:02 kueche systemd[1]: Stopped Music Player Daemon. Dec 25 06:16:02 kueche systemd[1]: Starting Music Player Daemon... Dec 25 06:16:02 kueche volumio[24052]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:02 kueche volumio[24052]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 25 06:16:02 kueche volumio[24052]: info: [1766639762866] CoreMusicLibrary::Adding element Last_100 Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 25 06:16:02 kueche volumio[24052]: info: [1766639762873] CoreMusicLibrary::Adding element Webradio Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 06:16:02 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 06:16:02 kueche sudo[24174]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 06:16:02 kueche sudo[24174]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:02 kueche volumio[24052]: info: Initializing BBC Radios Dec 25 06:16:02 kueche sudo[24174]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: [squeezelite_mc] Starting proxy server... Dec 25 06:16:03 kueche volumio[24052]: info: Volumio Calling Home Dec 25 06:16:03 kueche volumio[24052]: info: [squeezelite_mc] Proxy server started on port 34281 Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::volumioRetrievevolume Dec 25 06:16:03 kueche volumio[24052]: info: VolumeController:: Volume=100 Mute =false Dec 25 06:16:03 kueche volumio[24052]: info: CoreStateMachine::pushState Dec 25 06:16:03 kueche volumio[24052]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::volumioPushState Dec 25 06:16:03 kueche volumio[24052]: info: Discovery: adding 0dd56c15-94b0-48be-b72c-b626c1510ee3 Dec 25 06:16:03 kueche volumio[24052]: info: Discovery: Found device kueche Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::volumioGetState Dec 25 06:16:03 kueche volumio[24052]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:03 kueche volumio[24052]: info: MPD Permissions set Dec 25 06:16:03 kueche volumio[24052]: info: MPD Permissions set Dec 25 06:16:03 kueche volumio[24052]: info: Volumio called home Dec 25 06:16:03 kueche volumio[24052]: info: Discovery: Connected to remote: 192.168.178.39 Dec 25 06:16:03 kueche volumio[24052]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:03 kueche volumio[24052]: info: VolumeController:: Volume=100 Mute =false Dec 25 06:16:04 kueche volumio[24052]: info: CoreStateMachine::pushState Dec 25 06:16:04 kueche volumio[24052]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:04 kueche volumio[24052]: info: CoreCommandRouter::volumioPushState Dec 25 06:16:04 kueche volumio[24052]: info: [squeezelite_mc] Server discovery started Dec 25 06:16:04 kueche volumio[24052]: info: [squeezelite_mc] Player finder started Dec 25 06:16:04 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:04 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:04 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:04 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 06:16:04 kueche volumio[24052]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 25 06:16:04 kueche volumio[24052]: info: Starting Shairport Sync Dec 25 06:16:04 kueche volumio[24052]: info: Starting Shairport Sync Dec 25 06:16:04 kueche volumio[24052]: info: Starting Shairport Sync Dec 25 06:16:04 kueche sudo[24230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 25 06:16:04 kueche sudo[24230]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:04 kueche sudo[24232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 25 06:16:04 kueche sudo[24232]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:04 kueche volumio[24052]: info: CoreCommandRouter::volumioGetState Dec 25 06:16:04 kueche volumio[24052]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:04 kueche sudo[24235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 25 06:16:04 kueche sudo[24235]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:04 kueche volumio[24052]: info: [squeezelite_mc] Card 2 supports the following ALSA formats: ["S16_LE","S24_LE","S32_LE"] Dec 25 06:16:04 kueche volumio[24052]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"kueche","dsdFormat":null,"card":"2","mixerType":"Hardware","mixer":"Digital"} Dec 25 06:16:04 kueche volumio[24052]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Dec 25 06:16:04 kueche volumio[24052]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.178.39","name":"wohnen","ver":"7.9.1","uuid":"7e93b266-dc04-408a-8f9b-46ec781f5644","jsonPort":"9000","cliPort":"9090"} Dec 25 06:16:04 kueche systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 25 06:16:04 kueche systemd[1]: shairport-sync.service: Succeeded. Dec 25 06:16:04 kueche systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 25 06:16:04 kueche sudo[24240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Dec 25 06:16:04 kueche sudo[24240]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:04 kueche sudo[24240]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:04 kueche systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 25 06:16:04 kueche volumio[24052]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Dec 25 06:16:04 kueche sudo[24230]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:04 kueche sudo[24232]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:04 kueche sudo[24235]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:04 kueche volumio[24052]: info: Shairport-Sync Started Dec 25 06:16:04 kueche volumio[24052]: Error adding Membership: Error: addMembership EINVAL Dec 25 06:16:04 kueche volumio[24052]: info: Shairport-Sync Started Dec 25 06:16:04 kueche volumio[24052]: info: Shairport-Sync Started Dec 25 06:16:04 kueche sudo[24246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Dec 25 06:16:04 kueche sudo[24246]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:04 kueche sudo[24246]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:04 kueche volumio[24052]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Dec 25 06:16:04 kueche sudo[24252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Dec 25 06:16:04 kueche sudo[24252]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:04 kueche sudo[24252]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:04 kueche volumio[24052]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Dec 25 06:16:04 kueche sudo[24255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 25 06:16:04 kueche sudo[24255]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:04 kueche systemd[1]: Reloading. Dec 25 06:16:04 kueche mpd[24179]: Dec 25 06:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 25 06:16:04 kueche systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 25 06:16:05 kueche volumio[24052]: info: [squeezelite_mc] Notification listener started Dec 25 06:16:05 kueche volumio[24052]: info: [squeezelite_mc] Getting players connected to wohnen (192.168.178.39) Dec 25 06:16:05 kueche systemd[1]: Started Music Player Daemon. Dec 25 06:16:05 kueche sudo[24158]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:05 kueche sudo[24168]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:05 kueche sudo[24255]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:05 kueche volumio[24052]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 25 06:16:05 kueche sudo[24275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 25 06:16:05 kueche sudo[24275]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:05 kueche sudo[24275]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:05 kueche volumio[24052]: error: MPD error: The expression evaluated to a falsy value: Dec 25 06:16:05 kueche volumio[24052]: assert.ok(self.idling) Dec 25 06:16:05 kueche volumio[24052]: error: The expression evaluated to a falsy value: Dec 25 06:16:05 kueche volumio[24052]: assert.ok(self.idling) Dec 25 06:16:05 kueche volumio[24052]: info: MPD running with PID24179 Dec 25 06:16:05 kueche volumio[24052]: ,establishing connection Dec 25 06:16:05 kueche volumio[24052]: error: updateQueue error: null Dec 25 06:16:05 kueche volumio[24052]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Dec 25 06:16:05 kueche volumio[24052]: info: [squeezelite_mc] Players connected to wohnen (192.168.178.39): [{"id":"b8:27:eb:b1:b2:d6","uuid":null,"ip":"192.168.178.73","name":"kueche","server":{"ip":"192.168.178.39","name":"wohnen","ver":"7.9.1","uuid":"7e93b266-dc04-408a-8f9b-46ec781f5644","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:05:40:64","uuid":null,"ip":"192.168.178.39","name":"Wohnen","server":{"ip":"192.168.178.39","name":"wohnen","ver":"7.9.1","uuid":"7e93b266-dc04-408a-8f9b-46ec781f5644","jsonPort":"9000","cliPort":"9090"}}] Dec 25 06:16:05 kueche volumio[24052]: info: [squeezelite_mc] Player found: {"id":"b8:27:eb:b1:b2:d6","uuid":null,"ip":"192.168.178.73","name":"kueche","server":{"ip":"192.168.178.39","name":"wohnen","ver":"7.9.1","uuid":"7e93b266-dc04-408a-8f9b-46ec781f5644","jsonPort":"9000","cliPort":"9090"}} Dec 25 06:16:05 kueche volumio[24052]: error: updateQueue error: null Dec 25 06:16:05 kueche sudo[24278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Dec 25 06:16:05 kueche sudo[24278]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:05 kueche systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Dec 25 06:16:05 kueche volumio[24052]: info: [squeezelite_mc] 'client' notification received from wohnen (192.168.178.39); type is 'disconnect' Dec 25 06:16:05 kueche volumio[24052]: info: CoreCommandRouter::volumioGetState Dec 25 06:16:05 kueche volumio[24052]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:05 kueche systemd[1]: squeezelite.service: Succeeded. Dec 25 06:16:05 kueche systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Dec 25 06:16:05 kueche sudo[24278]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:06 kueche volumio[24052]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 25 06:16:06 kueche sudo[24281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 25 06:16:06 kueche sudo[24281]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:06 kueche sudo[24281]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:06 kueche volumio[24052]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Dec 25 06:16:06 kueche sudo[24285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Dec 25 06:16:06 kueche sudo[24285]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:06 kueche sudo[24285]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:06 kueche volumio[24052]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Dec 25 06:16:06 kueche sudo[24288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Dec 25 06:16:06 kueche sudo[24288]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:06 kueche systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Dec 25 06:16:06 kueche systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Dec 25 06:16:06 kueche sudo[24288]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:06 kueche volumio[24052]: info: [squeezelite_mc] Squeezelite in sync group with sync master b8:27:eb:05:40:64. Dec 25 06:16:06 kueche volumio[24052]: info: [squeezelite_mc] 'client' notification received from wohnen (192.168.178.39); type is 'connect' Dec 25 06:16:06 kueche volumio[24052]: info: [squeezelite_mc] Getting players connected to wohnen (192.168.178.39) Dec 25 06:16:06 kueche volumio[24052]: (node:24052) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined Dec 25 06:16:06 kueche volumio[24052]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37) Dec 25 06:16:06 kueche volumio[24052]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129) Dec 25 06:16:06 kueche volumio[24052]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 25 06:16:06 kueche volumio[24052]: at async PlayerStatusMonitor.start (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:47:9) Dec 25 06:16:06 kueche volumio[24052]: at async PlayerFinder. (/data/plugins/music_service/squeezelite_mc/dist/index.js:686:13) Dec 25 06:16:06 kueche volumio[24052]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 25 06:16:06 kueche volumio[24052]: (node:24052) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Dec 25 06:16:06 kueche volumio[24052]: (node:24052) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Dec 25 06:16:06 kueche volumio[24052]: info: [squeezelite_mc] Players connected to wohnen (192.168.178.39): [{"id":"b8:27:eb:b1:b2:d6","uuid":null,"ip":"192.168.178.73","name":"kueche","server":{"ip":"192.168.178.39","name":"wohnen","ver":"7.9.1","uuid":"7e93b266-dc04-408a-8f9b-46ec781f5644","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:05:40:64","uuid":null,"ip":"192.168.178.39","name":"Wohnen","server":{"ip":"192.168.178.39","name":"wohnen","ver":"7.9.1","uuid":"7e93b266-dc04-408a-8f9b-46ec781f5644","jsonPort":"9000","cliPort":"9090"}}] Dec 25 06:16:06 kueche volumio[24052]: info: [squeezelite_mc] Player found: {"id":"b8:27:eb:b1:b2:d6","uuid":null,"ip":"192.168.178.73","name":"kueche","server":{"ip":"192.168.178.39","name":"wohnen","ver":"7.9.1","uuid":"7e93b266-dc04-408a-8f9b-46ec781f5644","jsonPort":"9000","cliPort":"9090"}} Dec 25 06:16:06 kueche sudo[24295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://fritz.box/nas/http:/fritz.box/nas?sid=45d19a7b4c37a0a3 /mnt/NAS/Fritz Dec 25 06:16:06 kueche sudo[24295]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:06 kueche sudo[24295]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:06 kueche volumio[24052]: info: Cannot mount NAS Fritz at system boot, trial number 2 ,retrying in 5 seconds Dec 25 06:16:07 kueche volumio[24052]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 25 06:16:07 kueche sudo[24300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 25 06:16:07 kueche sudo[24300]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:07 kueche sudo[24300]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:07 kueche volumio[24052]: (node:24052) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined Dec 25 06:16:07 kueche volumio[24052]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37) Dec 25 06:16:07 kueche volumio[24052]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129) Dec 25 06:16:07 kueche volumio[24052]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 25 06:16:07 kueche volumio[24052]: (node:24052) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Dec 25 06:16:07 kueche volumio[24052]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 25 06:16:07 kueche sudo[24303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 25 06:16:07 kueche sudo[24303]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:07 kueche sudo[24303]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:08 kueche volumio[24052]: info: [squeezelite_mc] Squeezelite in sync group with sync master b8:27:eb:05:40:64. Dec 25 06:16:08 kueche volumio[24052]: (node:24052) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined Dec 25 06:16:08 kueche volumio[24052]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37) Dec 25 06:16:08 kueche volumio[24052]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129) Dec 25 06:16:08 kueche volumio[24052]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 25 06:16:08 kueche volumio[24052]: at async PlayerStatusMonitor.start (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:47:9) Dec 25 06:16:08 kueche volumio[24052]: at async PlayerFinder. (/data/plugins/music_service/squeezelite_mc/dist/index.js:686:13) Dec 25 06:16:08 kueche volumio[24052]: (node:24052) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 3) Dec 25 06:16:08 kueche volumio[24052]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 25 06:16:08 kueche sudo[24306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 25 06:16:08 kueche sudo[24306]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:08 kueche sudo[24306]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:08 kueche volumio[24052]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 25 06:16:08 kueche sudo[24309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 25 06:16:08 kueche sudo[24309]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:08 kueche sudo[24309]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:09 kueche volumio[24052]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 25 06:16:09 kueche sudo[24343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 25 06:16:09 kueche sudo[24343]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:09 kueche sudo[24343]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:09 kueche volumio[24052]: info: Completed starting Core Plugins Dec 25 06:16:09 kueche volumio[24052]: info: ------------------------------------------- Dec 25 06:16:09 kueche volumio[24052]: info: ----- MyVolumio plugins startup ---- Dec 25 06:16:09 kueche volumio[24052]: info: ------------------------------------------- Dec 25 06:16:09 kueche volumio[24052]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 25 06:16:09 kueche sudo[24367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 25 06:16:09 kueche sudo[24367]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:09 kueche sudo[24367]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:09 kueche sudo[24369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 25 06:16:09 kueche sudo[24369]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:09 kueche sudo[24369]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:09 kueche sudo[24373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 25 06:16:09 kueche sudo[24373]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:09 kueche sudo[24373]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:09 kueche volumio[24052]: info: Upmpdcli Daemon Started Dec 25 06:16:10 kueche volumio[24052]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 25 06:16:11 kueche sudo[24376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://fritz.box/nas/http:/fritz.box/nas?sid=45d19a7b4c37a0a3 /mnt/NAS/Fritz Dec 25 06:16:11 kueche sudo[24376]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:11 kueche sudo[24376]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:11 kueche volumio[24052]: info: Cannot mount NAS Fritz at system boot, trial number 3 ,retrying in 5 seconds Dec 25 06:16:17 kueche sudo[24385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://fritz.box/nas/http:/fritz.box/nas?sid=45d19a7b4c37a0a3 /mnt/NAS/Fritz Dec 25 06:16:17 kueche sudo[24385]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:17 kueche sudo[24385]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:17 kueche volumio[24052]: info: Cannot mount NAS Fritz at system boot, trial number 4 ,retrying in 5 seconds Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 25 06:16:17 kueche volumio[24052]: info: Adding plugin bluetooth to MyMusic Plugins Dec 25 06:16:17 kueche volumio[24052]: info: Adding plugin multiroom to MyMusic Plugins Dec 25 06:16:17 kueche volumio[24052]: info: Adding plugin metavolumio to MyMusic Plugins Dec 25 06:16:17 kueche volumio[24052]: info: Adding plugin cd_controller to MyMusic Plugins Dec 25 06:16:17 kueche volumio[24052]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 25 06:16:17 kueche volumio[24052]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 25 06:16:17 kueche volumio[24052]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 25 06:16:17 kueche volumio[24052]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 25 06:16:18 kueche volumio[24052]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 25 06:16:18 kueche volumio[24052]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 25 06:16:18 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:18 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:18 kueche volumio[24052]: info: Starting MyVolumio Remote Streaming Endpoints Dec 25 06:16:18 kueche volumio[24052]: info: MyVolumio login type: Token Dec 25 06:16:18 kueche volumio[24052]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 25 06:16:18 kueche volumio[24052]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 25 06:16:19 kueche volumio[24052]: info: Starting Streaming Service Transparent Proxy Dec 25 06:16:19 kueche volumio[24052]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 25 06:16:19 kueche volumio[24052]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 25 06:16:19 kueche volumio[24052]: info: Streaming services startup Dec 25 06:16:19 kueche volumio[24052]: info: Starting Streaming Daemon Dec 25 06:16:19 kueche sudo[24410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 25 06:16:19 kueche sudo[24410]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:19 kueche sudo[24410]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:19 kueche volumio[24052]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 25 06:16:20 kueche volumio[24052]: error: Cannot start Volumio Streaming Daemon Dec 25 06:16:20 kueche volumio[24052]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 25 06:16:20 kueche volumio[24052]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 25 06:16:20 kueche volumio[24052]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 25 06:16:21 kueche volumio[24052]: STREAMING PROXY: Starting server on port 3245 Dec 25 06:16:21 kueche volumio[24052]: Node JS runtime: 14 Dec 25 06:16:22 kueche sudo[24418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://fritz.box/nas/http:/fritz.box/nas?sid=45d19a7b4c37a0a3 /mnt/NAS/Fritz Dec 25 06:16:22 kueche sudo[24418]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:22 kueche sudo[24418]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:22 kueche volumio[24052]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Dec 25 06:16:26 kueche volumio[24052]: info: MyVolumio token set successfully Dec 25 06:16:26 kueche volumio[24052]: info: MYVOLUMIO: Adding device Dec 25 06:16:26 kueche volumio[24052]: info: MYVOLUMIO: Evaluating Server Dec 25 06:16:26 kueche volumio[24052]: info: MyVolumio status changed Dec 25 06:16:26 kueche volumio[24052]: info: Streaming services startup Dec 25 06:16:26 kueche volumio[24052]: info: Starting Streaming Daemon Dec 25 06:16:26 kueche volumio[24052]: info: Removing browser output: myVolumio user plan is not superstar Dec 25 06:16:26 kueche volumio[24052]: info: Removing audio output: Dec 25 06:16:26 kueche volumio[24052]: info: Stoppping Tunnel 1 Dec 25 06:16:26 kueche sudo[24443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 25 06:16:26 kueche sudo[24443]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:26 kueche sudo[24446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 25 06:16:26 kueche sudo[24443]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:26 kueche sudo[24446]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:26 kueche volumio[24052]: error: Cannot start Volumio Streaming Daemon Dec 25 06:16:26 kueche volumio[24052]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 25 06:16:26 kueche volumio[24052]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 25 06:16:26 kueche sudo[24446]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:26 kueche volumio[24052]: info: Remote SSH Stopped Dec 25 06:16:26 kueche volumio[24052]: info: Setting Geolocation for MyVolumio to eu7 Dec 25 06:16:26 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:26 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:26 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:27 kueche volumio[24052]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 25 06:16:27 kueche volumio[24052]: info: Updating MyVolumio device info Dec 25 06:16:27 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:27 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:27 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:28 kueche volumio[24052]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 25 06:16:31 kueche volumio[24052]: info: MYVOLUMIO: Adding device Dec 25 06:16:31 kueche volumio[24052]: info: MYVOLUMIO: Evaluating Server Dec 25 06:16:31 kueche volumio[24052]: info: Setting Geolocation for MyVolumio to eu11 Dec 25 06:16:31 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:31 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:31 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:32 kueche volumio[24052]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 25 06:16:32 kueche volumio[24052]: info: Updating MyVolumio device info Dec 25 06:16:33 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:33 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:33 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:33 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 06:16:33 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:33 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 25 06:16:33 kueche ifplugd(eth0)[744]: Link beat lost. Dec 25 06:16:33 kueche dhcpcd[873]: eth0: carrier lost Dec 25 06:16:33 kueche kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Dec 25 06:16:33 kueche avahi-daemon[539]: Withdrawing address record for 192.168.178.73 on eth0. Dec 25 06:16:33 kueche dhcpcd[873]: eth0: deleting route to 192.168.178.0/24 Dec 25 06:16:33 kueche dhcpcd[873]: eth0: deleting default route via 192.168.178.1 Dec 25 06:16:33 kueche avahi-daemon[539]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.178.73. Dec 25 06:16:33 kueche avahi-daemon[539]: Interface eth0.IPv4 no longer relevant for mDNS. Dec 25 06:16:33 kueche volumio[24052]: info: Discovery: A device disappeared from network Dec 25 06:16:33 kueche volumio[24052]: info: Discovery: Device kueche disappeared from network Dec 25 06:16:33 kueche volumio[24052]: info: Discovery: A device disappeared from network Dec 25 06:16:33 kueche volumio[24052]: info: Discovery: Device wohnen disappeared from network Dec 25 06:16:33 kueche volumio[24052]: info: Discovery: Disconnected from remote: 192.168.178.39 Dec 25 06:16:33 kueche ntpd[24044]: ntpd exiting on signal 15 (Terminated) Dec 25 06:16:33 kueche ntpd[24044]: 192.168.178.1 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche systemd[1]: Stopping Network Time Service... Dec 25 06:16:33 kueche ntpd[24044]: 212.132.108.186 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche ntpd[24044]: 185.228.139.165 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche volumio[24052]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 25 06:16:33 kueche ntpd[24044]: 176.9.42.71 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche ntpd[24044]: 45.145.40.190 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche ntpd[24044]: 78.47.118.0 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche ntpd[24044]: 213.239.234.28 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche ntpd[24044]: 79.133.44.139 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche ntpd[24044]: 129.70.132.34 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche ntpd[24044]: 90.187.112.137 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche ntpd[24044]: 194.59.205.229 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche ntpd[24044]: 51.75.67.47 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche ntpd[24044]: 78.46.53.2 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche ntpd[24044]: 217.91.44.17 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche ntpd[24044]: 134.60.111.110 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche ntpd[24044]: 85.215.189.120 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche ntpd[24044]: 78.47.249.55 local addr 192.168.178.73 -> Dec 25 06:16:33 kueche systemd[1]: ntp.service: Succeeded. Dec 25 06:16:33 kueche systemd[1]: Stopped Network Time Service. Dec 25 06:16:33 kueche systemd[1]: Starting Network Time Service... Dec 25 06:16:33 kueche ntpd[24526]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 25 06:16:33 kueche ntpd[24526]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Dec 25 06:16:33 kueche systemd[1]: Started Network Time Service. Dec 25 06:16:33 kueche ntpd[24532]: proto: precision = 0.573 usec (-21) Dec 25 06:16:33 kueche ntpd[24532]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 25 06:16:33 kueche ntpd[24532]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 25 06:16:33 kueche ntpd[24532]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 363 days ago Dec 25 06:16:34 kueche ntpd[24532]: Listen and drop on 0 v6wildcard [::]:123 Dec 25 06:16:34 kueche ntpd[24532]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 25 06:16:34 kueche ntpd[24532]: Listen normally on 2 lo 127.0.0.1:123 Dec 25 06:16:34 kueche ntpd[24532]: Listening on routing socket on fd #19 for interface updates Dec 25 06:16:34 kueche ntpd[24532]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 25 06:16:34 kueche ntpd[24532]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 25 06:16:34 kueche volumio[24052]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 06:16:34 kueche volumio[24052]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 25 06:16:34 kueche volumio[24052]: at doSend (dgram.js:692:16) Dec 25 06:16:34 kueche volumio[24052]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 25 06:16:34 kueche volumio[24052]: at afterDns (dgram.js:638:5) Dec 25 06:16:34 kueche volumio[24052]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 25 06:16:34 kueche volumio[24052]: errno: -101, Dec 25 06:16:34 kueche volumio[24052]: code: 'ENETUNREACH', Dec 25 06:16:34 kueche volumio[24052]: syscall: 'send', Dec 25 06:16:34 kueche volumio[24052]: address: '255.255.255.255', Dec 25 06:16:34 kueche volumio[24052]: port: 3483 Dec 25 06:16:34 kueche volumio[24052]: } Dec 25 06:16:34 kueche volumio[24052]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 06:16:34 kueche sudo[24546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-25 06:15 Dec 25 06:16:34 kueche sudo[24546]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:34 kueche sudo[24546]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:34 kueche ntpd[24532]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 25 06:16:35 kueche ifplugd(eth0)[744]: Link beat detected. Dec 25 06:16:35 kueche dhcpcd[873]: eth0: carrier acquired Dec 25 06:16:35 kueche kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 25 06:16:35 kueche dhcpcd[873]: eth0: IAID eb:b1:b2:d6 Dec 25 06:16:35 kueche ntpd[24532]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 25 06:16:36 kueche dhcpcd[873]: eth0: rebinding lease of 192.168.178.73 Dec 25 06:16:36 kueche ifplugd(eth0)[744]: Link beat lost. Dec 25 06:16:36 kueche dhcpcd[873]: eth0: soliciting an IPv6 router Dec 25 06:16:36 kueche dhcpcd[873]: eth0: carrier lost Dec 25 06:16:36 kueche kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Dec 25 06:16:36 kueche ntpd[24532]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 25 06:16:37 kueche ntpd[24532]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 25 06:16:38 kueche volumio-remote-updater[552]: [2025-12-25 06:16:38] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 25 06:16:38 kueche volumio-remote-updater[552]: [2025-12-25 06:16:38] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 25 06:16:38 kueche systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 25 06:16:38 kueche systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 25 06:16:38 kueche systemd[1]: Started dynamicswap service. Dec 25 06:16:38 kueche systemd[1]: dynamicswap.service: Succeeded. Dec 25 06:16:38 kueche systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 25 06:16:38 kueche systemd[1]: volumio.service: Scheduled restart job, restart counter is at 61. Dec 25 06:16:38 kueche systemd[1]: Started dynamicswap service. Dec 25 06:16:38 kueche systemd[1]: Stopped Volumio Backend Module. Dec 25 06:16:38 kueche systemd[1]: Started Volumio Backend Module. Dec 25 06:16:38 kueche systemd[1]: dynamicswap.service: Succeeded. Dec 25 06:16:38 kueche dhcpcd[873]: eth0: carrier acquired Dec 25 06:16:38 kueche dhcpcd[873]: eth0: IAID eb:b1:b2:d6 Dec 25 06:16:38 kueche kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 25 06:16:39 kueche dhcpcd[873]: eth0: rebinding lease of 192.168.178.73 Dec 25 06:16:39 kueche dhcpcd[873]: eth0: probing address 192.168.178.73/24 Dec 25 06:16:39 kueche dhcpcd[873]: eth0: soliciting an IPv6 router Dec 25 06:16:39 kueche ifplugd(eth0)[744]: Link beat detected. Dec 25 06:16:39 kueche volumio[24583]: info: ------------------------------------------- Dec 25 06:16:39 kueche volumio[24583]: info: ----- Volumio3 ---- Dec 25 06:16:39 kueche volumio[24583]: info: ------------------------------------------- Dec 25 06:16:39 kueche volumio[24583]: info: ----- System startup ---- Dec 25 06:16:39 kueche volumio[24583]: info: ------------------------------------------- Dec 25 06:16:40 kueche volumio[24583]: info: MYVOLUMIO Environment detected Dec 25 06:16:40 kueche volumio[24583]: info: Plugin folders cleanup Dec 25 06:16:40 kueche volumio[24583]: info: Scanning into folder /volumio/app/plugins/ Dec 25 06:16:40 kueche volumio[24583]: info: Scanning category audio_interface Dec 25 06:16:40 kueche volumio[24583]: info: Scanning category miscellanea Dec 25 06:16:40 kueche volumio[24583]: info: Scanning category music_service Dec 25 06:16:40 kueche volumio[24583]: info: Scanning category plugins.json Dec 25 06:16:40 kueche volumio[24583]: info: Scanning category system_controller Dec 25 06:16:40 kueche volumio[24583]: info: Scanning category user_interface Dec 25 06:16:40 kueche volumio[24583]: info: Scanning into folder /data/plugins/ Dec 25 06:16:40 kueche volumio[24583]: info: Scanning category music_service Dec 25 06:16:40 kueche volumio[24583]: info: Plugin folders cleanup completed Dec 25 06:16:40 kueche volumio[24583]: info: ------------------------------------------- Dec 25 06:16:40 kueche volumio[24583]: info: ----- Core plugins startup ---- Dec 25 06:16:40 kueche volumio[24583]: info: ------------------------------------------- Dec 25 06:16:40 kueche volumio[24583]: info: Loading plugins from folder /volumio/app/plugins/ Dec 25 06:16:40 kueche volumio[24583]: info: Adding plugin upnp to MyMusic Plugins Dec 25 06:16:40 kueche volumio[24583]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 25 06:16:40 kueche volumio[24583]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 25 06:16:40 kueche volumio[24583]: info: Loading plugins from folder /data/plugins/ Dec 25 06:16:40 kueche volumio[24583]: info: Loading plugin "system"... Dec 25 06:16:40 kueche volumio[24583]: info: Loading plugin "appearance"... Dec 25 06:16:41 kueche volumio[24583]: info: Loading plugin "network"... Dec 25 06:16:41 kueche volumio[24583]: info: Refreshing Cached IP Addresses Dec 25 06:16:41 kueche sudo[24610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 25 06:16:41 kueche sudo[24610]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:41 kueche sudo[24610]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:41 kueche sudo[24612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 25 06:16:41 kueche sudo[24612]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:41 kueche volumio[24583]: info: Loading plugin "services"... Dec 25 06:16:41 kueche volumio[24583]: info: Loading plugin "alsa_controller"... Dec 25 06:16:41 kueche sudo[24612]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:41 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 06:16:41 kueche volumio[24583]: info: Loading plugin "wizard"... Dec 25 06:16:41 kueche volumio[24583]: info: Loading plugin "networkfs"... Dec 25 06:16:41 kueche volumio[24583]: info: Starting Udev Watcher for removable devices Dec 25 06:16:41 kueche sudo[24635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://fritz.box/nas/http:/fritz.box/nas?sid=45d19a7b4c37a0a3 /mnt/NAS/Fritz Dec 25 06:16:41 kueche sudo[24635]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:41 kueche volumio[24583]: info: Ignoring mount for partition: boot Dec 25 06:16:41 kueche volumio[24583]: info: Ignoring mount for partition: volumio Dec 25 06:16:41 kueche volumio[24583]: info: Ignoring mount for partition: volumio_data Dec 25 06:16:41 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 25 06:16:41 kueche volumio[24583]: info: Loading plugin "volumio_command_line_client"... Dec 25 06:16:41 kueche sudo[24635]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:41 kueche volumio[24583]: info: Loading plugin "upnp"... Dec 25 06:16:41 kueche volumio[24583]: info: [1766639801820] Starting Upmpd Daemon Dec 25 06:16:41 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 25 06:16:41 kueche volumio[24583]: info: Loading plugin "my_music"... Dec 25 06:16:41 kueche volumio[24583]: info: Loading plugin "mpd"... Dec 25 06:16:42 kueche volumio[24583]: info: Loading plugin "upnp_browser"... Dec 25 06:16:42 kueche volumio[24583]: info: Loading plugin "alarm-clock"... Dec 25 06:16:42 kueche volumio[24583]: info: Loading plugin "airplay_emulation"... Dec 25 06:16:42 kueche volumio[24583]: info: Starting Shairport Sync Dec 25 06:16:42 kueche volumio[24583]: info: Loading plugin "last_100"... Dec 25 06:16:42 kueche volumio[24583]: info: Loading plugin "webradio"... Dec 25 06:16:42 kueche volumio[24583]: info: Loading plugin "i2s_dacs"... Dec 25 06:16:42 kueche volumio[24583]: info: Loading plugin "volumiodiscovery"... Dec 25 06:16:42 kueche volumio[24583]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 25 06:16:42 kueche volumio[24583]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 06:16:42 kueche volumio[24583]: *** WARNING *** For more information see Dec 25 06:16:42 kueche node[24583]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 25 06:16:42 kueche volumio[24583]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 25 06:16:42 kueche volumio[24583]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 06:16:42 kueche volumio[24583]: *** WARNING *** For more information see Dec 25 06:16:42 kueche node[24583]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 06:16:42 kueche node[24583]: *** WARNING *** For more information see Dec 25 06:16:42 kueche node[24583]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 25 06:16:42 kueche node[24583]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 06:16:42 kueche node[24583]: *** WARNING *** For more information see Dec 25 06:16:42 kueche volumio[24583]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 25 06:16:42 kueche volumio[24583]: info: Discovery: Started advertising with name: kueche Dec 25 06:16:42 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 25 06:16:42 kueche volumio[24583]: info: Loading plugin "squeezelite_mc"... Dec 25 06:16:42 kueche ifplugd(eth0)[744]: Link beat lost. Dec 25 06:16:42 kueche dhcpcd[873]: eth0: carrier lost Dec 25 06:16:42 kueche kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Dec 25 06:16:43 kueche volumio-remote-updater[552]: [2025-12-25 06:16:43] [connect] Successful connection Dec 25 06:16:43 kueche volumio[24583]: info: Loading plugin "outputs"... Dec 25 06:16:43 kueche volumio[24583]: info: Loading plugin "albumart"... Dec 25 06:16:43 kueche volumio[24583]: info: Plugin example_plugin is not enabled Dec 25 06:16:43 kueche volumio[24583]: info: Loading plugin "inputs"... Dec 25 06:16:43 kueche volumio[24583]: info: Loading plugin "updater_comm"... Dec 25 06:16:43 kueche volumio[24583]: info: Plugin mpdemulation is not enabled Dec 25 06:16:43 kueche volumio[24583]: info: Loading plugin "rest_api"... Dec 25 06:16:43 kueche volumio[24583]: info: Loading plugin "websocket"... Dec 25 06:16:43 kueche volumio[24583]: info: Starting Socket.io Server version 2.3.0 Dec 25 06:16:43 kueche volumio[24583]: info: Loading i18n strings for locale de Dec 25 06:16:43 kueche volumio[24583]: Updating browse sources language Dec 25 06:16:43 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 06:16:43 kueche volumio[24583]: Forking 3 albumart workers Dec 25 06:16:43 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 06:16:43 kueche volumio[24583]: info: CoreCommandRouter::initPlayerControls Dec 25 06:16:43 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:43 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:43 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 06:16:43 kueche volumio[24583]: Express server listening on port 3000 Dec 25 06:16:43 kueche volumio[24583]: [Metrics] WebUI: 4s 600.73ms Dec 25 06:16:44 kueche volumio[24583]: info: CoreStateMachine::resetVolumioState Dec 25 06:16:44 kueche volumio[24583]: info: CoreStateMachine::getcurrentVolume Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::volumioRetrievevolume Dec 25 06:16:44 kueche volumio[24583]: info: Cannot read play queue from file Dec 25 06:16:44 kueche volumio[24583]: info: Volumio Network Manager: Network status updated: 0 Dec 25 06:16:44 kueche volumio[24583]: info: Cannot mount NAS Fritz at system boot, trial number 1 ,retrying in 5 seconds Dec 25 06:16:44 kueche volumio-remote-updater[552]: [2025-12-25 06:16:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766639803 101 Dec 25 06:16:44 kueche volumio[24583]: 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: 1 Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:44 kueche volumio[24583]: info: Executing start script for DAC IQaudIO DAC Plus Dec 25 06:16:44 kueche volumio[24583]: info: CoreStateMachine::setRepeat null single undefined Dec 25 06:16:44 kueche volumio[24583]: info: CoreStateMachine::pushState Dec 25 06:16:44 kueche volumio[24583]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::volumioPushState Dec 25 06:16:44 kueche volumio[24583]: info: CoreStateMachine::setRandom null Dec 25 06:16:44 kueche volumio[24583]: info: CoreStateMachine::pushState Dec 25 06:16:44 kueche volumio[24583]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::volumioPushState Dec 25 06:16:44 kueche volumio[24583]: info: Setting Device type: Raspberry PI Dec 25 06:16:44 kueche volumio[24583]: info: VolumeController:: Volume=100 Mute =false Dec 25 06:16:44 kueche volumio[24583]: info: CoreStateMachine::pushState Dec 25 06:16:44 kueche volumio[24583]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::volumioPushState Dec 25 06:16:44 kueche volumio[24583]: info: CoreStateMachine::updateTrackBlock Dec 25 06:16:44 kueche volumio[24583]: info: CorePlayQueue::getTrackBlock Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::volumioRetrievevolume Dec 25 06:16:44 kueche volumio[24583]: info: DAC script executed Dec 25 06:16:44 kueche volumio[24583]: info: Completed loading Core Plugins Dec 25 06:16:44 kueche volumio[24583]: info: Preparing to generate the ALSA configuration file Dec 25 06:16:44 kueche volumio[24583]: info: Asound.conf file unchanged, so no further update is needed Dec 25 06:16:44 kueche volumio[24583]: info: Output device has changed, restarting MPD Dec 25 06:16:44 kueche volumio[24583]: info: Output device has changed, restarting Shairport Sync Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:44 kueche sudo[24706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 06:16:44 kueche sudo[24706]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:44 kueche sudo[24706]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:44 kueche sudo[24708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 06:16:44 kueche sudo[24708]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:44 kueche systemd[1]: Stopping Music Player Daemon... Dec 25 06:16:44 kueche volumio[24583]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 06:16:44 kueche volumio[24583]: info: ___________ START PLUGINS ___________ Dec 25 06:16:44 kueche volumio[24583]: info: ControllerMpd::onStart: Initializing MPD Dec 25 06:16:44 kueche volumio[24583]: info: Creating MPD Configuration file Dec 25 06:16:44 kueche systemd[1]: mpd.service: Succeeded. Dec 25 06:16:44 kueche systemd[1]: Stopped Music Player Daemon. Dec 25 06:16:44 kueche sudo[24716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 06:16:44 kueche sudo[24716]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:44 kueche systemd[1]: Starting Music Player Daemon... Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 25 06:16:44 kueche volumio[24583]: info: [1766639804693] CoreMusicLibrary::Adding element Medienserver Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 06:16:44 kueche sudo[24716]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:44 kueche sudo[24718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 06:16:44 kueche sudo[24718]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:44 kueche systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 25 06:16:44 kueche systemd[1]: mpd.service: Succeeded. Dec 25 06:16:44 kueche systemd[1]: Stopped Music Player Daemon. Dec 25 06:16:44 kueche systemd[1]: Starting Music Player Daemon... Dec 25 06:16:44 kueche volumio[24583]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:44 kueche volumio[24583]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 25 06:16:44 kueche volumio[24583]: info: [1766639804867] CoreMusicLibrary::Adding element Last_100 Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 25 06:16:44 kueche volumio[24583]: info: [1766639804877] CoreMusicLibrary::Adding element Webradio Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 06:16:44 kueche volumio[24583]: info: Initializing BBC Radios Dec 25 06:16:44 kueche sudo[24724]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 06:16:44 kueche sudo[24724]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:44 kueche dhcpcd[873]: eth0: carrier acquired Dec 25 06:16:44 kueche kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 25 06:16:44 kueche ifplugd(eth0)[744]: Link beat detected. Dec 25 06:16:44 kueche sudo[24724]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:44 kueche dhcpcd[873]: eth0: IAID eb:b1:b2:d6 Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 06:16:44 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:45 kueche volumio[24583]: Starting albumart workers Dec 25 06:16:45 kueche volumio[24583]: info: [squeezelite_mc] Starting proxy server... Dec 25 06:16:45 kueche volumio[24583]: info: Volumio Calling Home Dec 25 06:16:45 kueche volumio[24583]: Starting albumart workers Dec 25 06:16:45 kueche volumio[24583]: Starting albumart workers Dec 25 06:16:45 kueche volumio[24583]: info: [squeezelite_mc] Proxy server started on port 34125 Dec 25 06:16:45 kueche volumio[24583]: info: CoreCommandRouter::volumioRetrievevolume Dec 25 06:16:45 kueche volumio[24583]: Unhandled rejection Error: No sockets available, cannot start. Dec 25 06:16:45 kueche volumio[24583]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 25 06:16:45 kueche volumio[24583]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 25 06:16:45 kueche volumio[24583]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 25 06:16:45 kueche volumio[24583]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 25 06:16:45 kueche volumio[24583]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 25 06:16:45 kueche volumio[24583]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 25 06:16:45 kueche volumio[24583]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 25 06:16:45 kueche volumio[24583]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 25 06:16:45 kueche volumio[24583]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 25 06:16:45 kueche volumio[24583]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 25 06:16:45 kueche volumio[24583]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 25 06:16:45 kueche volumio[24583]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 25 06:16:45 kueche volumio[24583]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 25 06:16:45 kueche volumio[24583]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 25 06:16:45 kueche volumio[24583]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 25 06:16:45 kueche volumio[24583]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 25 06:16:45 kueche volumio[24583]: info: MPD Permissions set Dec 25 06:16:45 kueche volumio[24583]: info: MPD Permissions set Dec 25 06:16:45 kueche dhcpcd[873]: eth0: rebinding lease of 192.168.178.73 Dec 25 06:16:45 kueche volumio[24583]: info: VolumeController:: Volume=100 Mute =false Dec 25 06:16:45 kueche volumio[24583]: info: CoreStateMachine::pushState Dec 25 06:16:45 kueche volumio[24583]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:45 kueche volumio[24583]: info: CoreCommandRouter::volumioPushState Dec 25 06:16:45 kueche volumio[24583]: info: [squeezelite_mc] Server discovery started Dec 25 06:16:45 kueche volumio[24583]: info: [squeezelite_mc] Player finder started Dec 25 06:16:45 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:45 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:45 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:45 kueche volumio[24583]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 06:16:45 kueche volumio[24583]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 25 06:16:45 kueche volumio[24583]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 06:16:45 kueche volumio[24583]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 25 06:16:45 kueche volumio[24583]: at doSend (dgram.js:692:16) Dec 25 06:16:45 kueche volumio[24583]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 25 06:16:45 kueche volumio[24583]: at afterDns (dgram.js:638:5) Dec 25 06:16:45 kueche volumio[24583]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 25 06:16:45 kueche volumio[24583]: errno: -101, Dec 25 06:16:45 kueche volumio[24583]: code: 'ENETUNREACH', Dec 25 06:16:45 kueche volumio[24583]: syscall: 'send', Dec 25 06:16:45 kueche volumio[24583]: address: '255.255.255.255', Dec 25 06:16:45 kueche volumio[24583]: port: 3483 Dec 25 06:16:45 kueche volumio[24583]: } Dec 25 06:16:45 kueche volumio[24583]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 06:16:45 kueche dhcpcd[873]: eth0: soliciting an IPv6 router Dec 25 06:16:45 kueche dhcpcd[873]: eth0: carrier lost Dec 25 06:16:45 kueche kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Dec 25 06:16:46 kueche ifplugd(eth0)[744]: Link beat lost. Dec 25 06:16:46 kueche sudo[24804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-25 06:15 Dec 25 06:16:46 kueche sudo[24804]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:46 kueche sudo[24804]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:46 kueche volumio-remote-updater[552]: [2025-12-25 06:16:46] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 25 06:16:46 kueche volumio-remote-updater[552]: [2025-12-25 06:16:46] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 25 06:16:46 kueche systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 25 06:16:46 kueche sudo[24718]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:46 kueche sudo[24708]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:46 kueche systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 25 06:16:46 kueche systemd[1]: Started dynamicswap service. Dec 25 06:16:46 kueche systemd[1]: dynamicswap.service: Succeeded. Dec 25 06:16:46 kueche systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 25 06:16:46 kueche systemd[1]: volumio.service: Scheduled restart job, restart counter is at 62. Dec 25 06:16:46 kueche systemd[1]: Started dynamicswap service. Dec 25 06:16:46 kueche systemd[1]: Stopped Volumio Backend Module. Dec 25 06:16:46 kueche systemd[1]: Started Volumio Backend Module. Dec 25 06:16:46 kueche systemd[1]: dynamicswap.service: Succeeded. Dec 25 06:16:46 kueche mpd[24736]: Dec 25 06:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 25 06:16:46 kueche systemd[1]: Started Music Player Daemon. Dec 25 06:16:48 kueche volumio[24818]: info: ------------------------------------------- Dec 25 06:16:48 kueche volumio[24818]: info: ----- Volumio3 ---- Dec 25 06:16:48 kueche volumio[24818]: info: ------------------------------------------- Dec 25 06:16:48 kueche volumio[24818]: info: ----- System startup ---- Dec 25 06:16:48 kueche volumio[24818]: info: ------------------------------------------- Dec 25 06:16:49 kueche dhcpcd[873]: eth0: carrier acquired Dec 25 06:16:49 kueche kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 25 06:16:49 kueche dhcpcd[873]: eth0: IAID eb:b1:b2:d6 Dec 25 06:16:49 kueche volumio[24818]: info: MYVOLUMIO Environment detected Dec 25 06:16:49 kueche volumio[24818]: info: Plugin folders cleanup Dec 25 06:16:49 kueche volumio[24818]: info: Scanning into folder /volumio/app/plugins/ Dec 25 06:16:49 kueche volumio[24818]: info: Scanning category audio_interface Dec 25 06:16:49 kueche volumio[24818]: info: Scanning category miscellanea Dec 25 06:16:49 kueche volumio[24818]: info: Scanning category music_service Dec 25 06:16:49 kueche volumio[24818]: info: Scanning category plugins.json Dec 25 06:16:49 kueche volumio[24818]: info: Scanning category system_controller Dec 25 06:16:49 kueche volumio[24818]: info: Scanning category user_interface Dec 25 06:16:49 kueche volumio[24818]: info: Scanning into folder /data/plugins/ Dec 25 06:16:49 kueche volumio[24818]: info: Scanning category music_service Dec 25 06:16:49 kueche volumio[24818]: info: Plugin folders cleanup completed Dec 25 06:16:49 kueche volumio[24818]: info: ------------------------------------------- Dec 25 06:16:49 kueche volumio[24818]: info: ----- Core plugins startup ---- Dec 25 06:16:49 kueche volumio[24818]: info: ------------------------------------------- Dec 25 06:16:49 kueche volumio[24818]: info: Loading plugins from folder /volumio/app/plugins/ Dec 25 06:16:49 kueche volumio[24818]: info: Adding plugin upnp to MyMusic Plugins Dec 25 06:16:49 kueche volumio[24818]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 25 06:16:49 kueche volumio[24818]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 25 06:16:49 kueche volumio[24818]: info: Loading plugins from folder /data/plugins/ Dec 25 06:16:49 kueche volumio[24818]: info: Loading plugin "system"... Dec 25 06:16:49 kueche ifplugd(eth0)[744]: Link beat detected. Dec 25 06:16:49 kueche volumio[24818]: info: Loading plugin "appearance"... Dec 25 06:16:49 kueche dhcpcd[873]: eth0: rebinding lease of 192.168.178.73 Dec 25 06:16:49 kueche dhcpcd[873]: eth0: probing address 192.168.178.73/24 Dec 25 06:16:49 kueche dhcpcd[873]: eth0: soliciting an IPv6 router Dec 25 06:16:50 kueche volumio[24818]: info: Loading plugin "network"... Dec 25 06:16:50 kueche volumio[24818]: info: Refreshing Cached IP Addresses Dec 25 06:16:50 kueche sudo[24847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 25 06:16:50 kueche sudo[24847]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:50 kueche sudo[24847]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:50 kueche sudo[24849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 25 06:16:50 kueche volumio[24818]: info: Loading plugin "services"... Dec 25 06:16:50 kueche sudo[24849]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:50 kueche volumio[24818]: info: Loading plugin "alsa_controller"... Dec 25 06:16:50 kueche sudo[24849]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:50 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 06:16:50 kueche volumio[24818]: info: Loading plugin "wizard"... Dec 25 06:16:50 kueche volumio[24818]: info: Loading plugin "networkfs"... Dec 25 06:16:50 kueche volumio[24818]: info: Starting Udev Watcher for removable devices Dec 25 06:16:50 kueche sudo[24872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://fritz.box/nas/http:/fritz.box/nas?sid=45d19a7b4c37a0a3 /mnt/NAS/Fritz Dec 25 06:16:50 kueche sudo[24872]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:50 kueche volumio[24818]: info: Ignoring mount for partition: boot Dec 25 06:16:50 kueche volumio[24818]: info: Ignoring mount for partition: volumio Dec 25 06:16:50 kueche volumio[24818]: info: Ignoring mount for partition: volumio_data Dec 25 06:16:50 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 25 06:16:50 kueche volumio[24818]: info: Loading plugin "volumio_command_line_client"... Dec 25 06:16:50 kueche sudo[24872]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:50 kueche volumio[24818]: info: Loading plugin "upnp"... Dec 25 06:16:50 kueche volumio[24818]: info: [1766639810316] Starting Upmpd Daemon Dec 25 06:16:50 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 25 06:16:50 kueche volumio[24818]: info: Loading plugin "my_music"... Dec 25 06:16:50 kueche volumio[24818]: info: Loading plugin "mpd"... Dec 25 06:16:50 kueche volumio[24818]: info: Loading plugin "upnp_browser"... Dec 25 06:16:50 kueche volumio[24818]: info: Loading plugin "alarm-clock"... Dec 25 06:16:51 kueche volumio[24818]: info: Loading plugin "airplay_emulation"... Dec 25 06:16:51 kueche volumio[24818]: info: Starting Shairport Sync Dec 25 06:16:51 kueche volumio[24818]: info: Loading plugin "last_100"... Dec 25 06:16:51 kueche volumio[24818]: info: Loading plugin "webradio"... Dec 25 06:16:51 kueche volumio[24818]: info: Loading plugin "i2s_dacs"... Dec 25 06:16:51 kueche volumio[24818]: info: Loading plugin "volumiodiscovery"... Dec 25 06:16:51 kueche volumio[24818]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 25 06:16:51 kueche volumio[24818]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 06:16:51 kueche volumio[24818]: *** WARNING *** For more information see Dec 25 06:16:51 kueche volumio[24818]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 25 06:16:51 kueche node[24818]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 25 06:16:51 kueche volumio[24818]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 06:16:51 kueche volumio[24818]: *** WARNING *** For more information see Dec 25 06:16:51 kueche node[24818]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 06:16:51 kueche node[24818]: *** WARNING *** For more information see Dec 25 06:16:51 kueche node[24818]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 25 06:16:51 kueche node[24818]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 06:16:51 kueche node[24818]: *** WARNING *** For more information see Dec 25 06:16:51 kueche volumio[24818]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 25 06:16:51 kueche volumio[24818]: info: Discovery: Started advertising with name: kueche Dec 25 06:16:51 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 25 06:16:51 kueche volumio[24818]: info: Loading plugin "squeezelite_mc"... Dec 25 06:16:51 kueche volumio-remote-updater[552]: [2025-12-25 06:16:51] [connect] Successful connection Dec 25 06:16:52 kueche volumio[24818]: info: Loading plugin "outputs"... Dec 25 06:16:52 kueche volumio[24818]: info: Loading plugin "albumart"... Dec 25 06:16:52 kueche volumio[24818]: info: Plugin example_plugin is not enabled Dec 25 06:16:52 kueche volumio[24818]: info: Loading plugin "inputs"... Dec 25 06:16:52 kueche volumio[24818]: info: Loading plugin "updater_comm"... Dec 25 06:16:52 kueche volumio[24818]: info: Plugin mpdemulation is not enabled Dec 25 06:16:52 kueche volumio[24818]: info: Loading plugin "rest_api"... Dec 25 06:16:52 kueche volumio[24818]: info: Loading plugin "websocket"... Dec 25 06:16:52 kueche volumio[24818]: info: Starting Socket.io Server version 2.3.0 Dec 25 06:16:52 kueche volumio[24818]: info: Loading i18n strings for locale de Dec 25 06:16:52 kueche volumio[24818]: Updating browse sources language Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 06:16:52 kueche volumio[24818]: Forking 3 albumart workers Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::initPlayerControls Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 06:16:52 kueche volumio[24818]: Express server listening on port 3000 Dec 25 06:16:52 kueche volumio[24818]: [Metrics] WebUI: 4s 585.80ms Dec 25 06:16:52 kueche volumio[24818]: info: CoreStateMachine::resetVolumioState Dec 25 06:16:52 kueche volumio[24818]: info: CoreStateMachine::getcurrentVolume Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::volumioRetrievevolume Dec 25 06:16:52 kueche volumio[24818]: info: Cannot read play queue from file Dec 25 06:16:52 kueche volumio[24818]: info: Volumio Network Manager: Network status updated: 0 Dec 25 06:16:52 kueche volumio[24818]: info: Cannot mount NAS Fritz at system boot, trial number 1 ,retrying in 5 seconds Dec 25 06:16:52 kueche volumio-remote-updater[552]: [2025-12-25 06:16:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766639811 101 Dec 25 06:16:52 kueche volumio[24818]: 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: 1 Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:52 kueche volumio[24818]: info: Executing start script for DAC IQaudIO DAC Plus Dec 25 06:16:52 kueche volumio[24818]: info: CoreStateMachine::setRepeat null single undefined Dec 25 06:16:52 kueche volumio[24818]: info: CoreStateMachine::pushState Dec 25 06:16:52 kueche volumio[24818]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::volumioPushState Dec 25 06:16:52 kueche volumio[24818]: info: CoreStateMachine::setRandom null Dec 25 06:16:52 kueche volumio[24818]: info: CoreStateMachine::pushState Dec 25 06:16:52 kueche volumio[24818]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::volumioPushState Dec 25 06:16:52 kueche volumio[24818]: info: VolumeController:: Volume=100 Mute =false Dec 25 06:16:52 kueche volumio[24818]: info: CoreStateMachine::pushState Dec 25 06:16:52 kueche volumio[24818]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::volumioPushState Dec 25 06:16:52 kueche volumio[24818]: info: CoreStateMachine::updateTrackBlock Dec 25 06:16:52 kueche volumio[24818]: info: CorePlayQueue::getTrackBlock Dec 25 06:16:52 kueche volumio[24818]: info: CoreCommandRouter::volumioRetrievevolume Dec 25 06:16:52 kueche volumio[24818]: info: Setting Device type: Raspberry PI Dec 25 06:16:52 kueche volumio[24818]: info: DAC script executed Dec 25 06:16:52 kueche volumio[24818]: info: Completed loading Core Plugins Dec 25 06:16:52 kueche volumio[24818]: info: Preparing to generate the ALSA configuration file Dec 25 06:16:52 kueche volumio[24818]: info: Asound.conf file unchanged, so no further update is needed Dec 25 06:16:52 kueche volumio[24818]: info: Output device has changed, restarting MPD Dec 25 06:16:53 kueche volumio[24818]: info: Output device has changed, restarting Shairport Sync Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:53 kueche sudo[24922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 06:16:53 kueche sudo[24922]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:53 kueche sudo[24922]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:53 kueche sudo[24924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 06:16:53 kueche sudo[24924]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:53 kueche volumio[24818]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 06:16:53 kueche volumio[24818]: info: ___________ START PLUGINS ___________ Dec 25 06:16:53 kueche volumio[24818]: info: ControllerMpd::onStart: Initializing MPD Dec 25 06:16:53 kueche volumio[24818]: info: Creating MPD Configuration file Dec 25 06:16:53 kueche systemd[1]: Stopping Music Player Daemon... Dec 25 06:16:53 kueche systemd[1]: mpd.service: Succeeded. Dec 25 06:16:53 kueche systemd[1]: Stopped Music Player Daemon. Dec 25 06:16:53 kueche sudo[24931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 25 06:16:53 kueche volumio[24818]: info: [1766639813205] CoreMusicLibrary::Adding element Medienserver Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 06:16:53 kueche sudo[24931]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:53 kueche sudo[24931]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:53 kueche sudo[24933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 06:16:53 kueche systemd[1]: Starting Music Player Daemon... Dec 25 06:16:53 kueche sudo[24933]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:53 kueche systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 25 06:16:53 kueche systemd[1]: mpd.service: Succeeded. Dec 25 06:16:53 kueche systemd[1]: Stopped Music Player Daemon. Dec 25 06:16:53 kueche systemd[1]: Starting Music Player Daemon... Dec 25 06:16:53 kueche volumio[24818]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:53 kueche volumio[24818]: Starting albumart workers Dec 25 06:16:53 kueche volumio[24818]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 25 06:16:53 kueche volumio[24818]: info: [1766639813438] CoreMusicLibrary::Adding element Last_100 Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 25 06:16:53 kueche volumio[24818]: info: [1766639813443] CoreMusicLibrary::Adding element Webradio Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 06:16:53 kueche sudo[24940]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 06:16:53 kueche sudo[24940]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 06:16:53 kueche volumio[24818]: info: Initializing BBC Radios Dec 25 06:16:53 kueche sudo[24940]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:53 kueche volumio[24818]: Starting albumart workers Dec 25 06:16:53 kueche volumio[24818]: Starting albumart workers Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 06:16:53 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:53 kueche volumio[24818]: info: [squeezelite_mc] Starting proxy server... Dec 25 06:16:53 kueche volumio[24818]: info: Volumio Calling Home Dec 25 06:16:53 kueche volumio[24818]: info: [squeezelite_mc] Proxy server started on port 44059 Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::volumioRetrievevolume Dec 25 06:16:54 kueche volumio[24818]: Unhandled rejection Error: No sockets available, cannot start. Dec 25 06:16:54 kueche volumio[24818]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 25 06:16:54 kueche volumio[24818]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 25 06:16:54 kueche volumio[24818]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 25 06:16:54 kueche volumio[24818]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 25 06:16:54 kueche volumio[24818]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 25 06:16:54 kueche volumio[24818]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 25 06:16:54 kueche volumio[24818]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 25 06:16:54 kueche volumio[24818]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 25 06:16:54 kueche volumio[24818]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 25 06:16:54 kueche volumio[24818]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 25 06:16:54 kueche volumio[24818]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 25 06:16:54 kueche volumio[24818]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 25 06:16:54 kueche volumio[24818]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 25 06:16:54 kueche volumio[24818]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 25 06:16:54 kueche volumio[24818]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 25 06:16:54 kueche volumio[24818]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 25 06:16:54 kueche volumio[24818]: info: MPD Permissions set Dec 25 06:16:54 kueche volumio[24818]: info: MPD Permissions set Dec 25 06:16:54 kueche volumio[24818]: info: VolumeController:: Volume=100 Mute =false Dec 25 06:16:54 kueche volumio[24818]: info: CoreStateMachine::pushState Dec 25 06:16:54 kueche volumio[24818]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::volumioPushState Dec 25 06:16:54 kueche volumio[24818]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: VolumeController:: Volume=100 Mute =false Dec 25 06:16:54 kueche volumio[24818]: info: CoreStateMachine::pushState Dec 25 06:16:54 kueche volumio[24818]: info: CorePlayQueue::getTrack 0 Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::volumioPushState Dec 25 06:16:54 kueche volumio[24818]: info: [squeezelite_mc] Server discovery started Dec 25 06:16:54 kueche volumio[24818]: info: [squeezelite_mc] Player finder started Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 06:16:54 kueche volumio[24818]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 25 06:16:54 kueche volumio[24818]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 06:16:54 kueche volumio[24818]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 25 06:16:54 kueche volumio[24818]: at doSend (dgram.js:692:16) Dec 25 06:16:54 kueche volumio[24818]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 25 06:16:54 kueche volumio[24818]: at afterDns (dgram.js:638:5) Dec 25 06:16:54 kueche volumio[24818]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 25 06:16:54 kueche volumio[24818]: errno: -101, Dec 25 06:16:54 kueche volumio[24818]: code: 'ENETUNREACH', Dec 25 06:16:54 kueche volumio[24818]: syscall: 'send', Dec 25 06:16:54 kueche volumio[24818]: address: '255.255.255.255', Dec 25 06:16:54 kueche volumio[24818]: port: 3483 Dec 25 06:16:54 kueche volumio[24818]: } Dec 25 06:16:54 kueche volumio[24818]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 06:16:55 kueche dhcpcd[873]: eth0: leased 192.168.178.73 for 864000 seconds Dec 25 06:16:55 kueche dhcpcd[873]: eth0: adding route to 192.168.178.0/24 Dec 25 06:16:55 kueche dhcpcd[873]: eth0: adding default route via 192.168.178.1 Dec 25 06:16:55 kueche avahi-daemon[539]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.73. Dec 25 06:16:55 kueche avahi-daemon[539]: New relevant interface eth0.IPv4 for mDNS. Dec 25 06:16:55 kueche avahi-daemon[539]: Registering new address record for 192.168.178.73 on eth0.IPv4. Dec 25 06:16:55 kueche ntpd[24532]: ntpd exiting on signal 15 (Terminated) Dec 25 06:16:55 kueche systemd[1]: Stopping Network Time Service... Dec 25 06:16:55 kueche systemd[1]: ntp.service: Succeeded. Dec 25 06:16:55 kueche systemd[1]: Stopped Network Time Service. Dec 25 06:16:55 kueche sudo[25040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-25 06:15 Dec 25 06:16:55 kueche sudo[25040]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 06:16:55 kueche systemd[1]: Starting Network Time Service... Dec 25 06:16:55 kueche ntpd[25038]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 25 06:16:55 kueche systemd[1]: Started Network Time Service. Dec 25 06:16:55 kueche ntpd[25038]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Dec 25 06:16:55 kueche ntpd[25047]: proto: precision = 0.573 usec (-21) Dec 25 06:16:55 kueche ntpd[25047]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 25 06:16:55 kueche ntpd[25047]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 25 06:16:55 kueche ntpd[25047]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 363 days ago Dec 25 06:16:55 kueche ntpd[25047]: Listen and drop on 0 v6wildcard [::]:123 Dec 25 06:16:55 kueche ntpd[25047]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 25 06:16:55 kueche ntpd[25047]: Listen normally on 2 lo 127.0.0.1:123 Dec 25 06:16:55 kueche ntpd[25047]: Listen normally on 3 eth0 192.168.178.73:123 Dec 25 06:16:55 kueche ntpd[25047]: Listening on routing socket on fd #20 for interface updates Dec 25 06:16:55 kueche ntpd[25047]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 25 06:16:55 kueche ntpd[25047]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 25 06:16:55 kueche mpd[24946]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 25 06:16:55 kueche mpd[24946]: output: No 'audio_output' defined in config file Dec 25 06:16:55 kueche mpd[24946]: output: Successfully detected a sndio audio device Dec 25 06:16:55 kueche mpd[24946]: zeroconf: No global port, disabling zeroconf Dec 25 06:16:55 kueche systemd[1]: Started Music Player Daemon. Dec 25 06:16:55 kueche sudo[24924]: pam_unix(sudo:session): session closed for user root Dec 25 06:16:55 kueche sudo[24933]: pam_unix(sudo:session): session closed for user root 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"