-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Mon 2025-05-12 08:58:52 CEST. -- May 12 08:57:50 volumio fake-hwclock[276]: Mon May 12 06:57:50 UTC 2025 May 12 08:57:50 volumio systemd[1]: dynamicswap.service: Succeeded. May 12 08:57:50 volumio systemd[1]: Started Restore / save the current clock. May 12 08:57:50 volumio systemd[1]: Mounted Kernel Configuration File System. May 12 08:57:50 volumio systemd[1]: Started Apply Kernel Variables. May 12 08:57:50 volumio systemd[1]: Mounted FUSE Control File System. May 12 08:57:50 volumio systemd[1]: Started Load/Save Random Seed. May 12 08:57:50 volumio systemd[1]: Started Create System Users. May 12 08:57:50 volumio systemd[1]: Starting Create Static Device Nodes in /dev... May 12 08:57:50 volumio systemd[1]: Started udev Coldplug all Devices. May 12 08:57:50 volumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... May 12 08:57:50 volumio systemd[1]: Started Create Static Device Nodes in /dev. May 12 08:57:50 volumio systemd[1]: Starting udev Kernel Device Manager... May 12 08:57:50 volumio systemd[1]: Reached target Local File Systems (Pre). May 12 08:57:50 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 12 08:57:50 volumio systemd[1]: Mounting /tmp... May 12 08:57:50 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 12 08:57:50 volumio systemd[1]: Mounting /var/log... May 12 08:57:50 volumio systemd[1]: Mounting /var/spool/cups... May 12 08:57:50 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. May 12 08:57:50 volumio systemd[1]: Mounted /tmp. May 12 08:57:50 volumio systemd[1]: Mounted /var/log. May 12 08:57:50 volumio systemd[1]: Mounted /var/spool/cups. May 12 08:57:50 volumio systemd[1]: Mounting /var/spool/cups/tmp... May 12 08:57:50 volumio systemd-udevd[303]: Network interface NamePolicy= disabled on kernel command line, ignoring. May 12 08:57:50 volumio systemd[1]: Starting Flush Journal to Persistent Storage... May 12 08:57:50 volumio systemd[1]: Mounted /var/spool/cups/tmp. May 12 08:57:50 volumio systemd[1]: Started udev Kernel Device Manager. May 12 08:57:50 volumio systemd-journald[277]: Runtime journal (/run/log/journal/a89b7be63bc86b985c184d84675fe488) is 7.5M, max 30.0M, 22.5M free. May 12 08:57:50 volumio systemd[1]: Starting Show Plymouth Boot Screen... May 12 08:57:50 volumio systemd[1]: Started Flush Journal to Persistent Storage. May 12 08:57:50 volumio systemd[1]: plymouth-start.service: Succeeded. May 12 08:57:50 volumio systemd[1]: Started Show Plymouth Boot Screen. May 12 08:57:50 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. May 12 08:57:50 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 12 08:57:50 volumio systemd[1]: Reached target Paths. May 12 08:57:50 volumio systemd[1]: Reached target Local Encrypted Volumes. May 12 08:57:50 volumio kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 May 12 08:57:50 volumio kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem May 12 08:57:50 volumio kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. May 12 08:57:50 volumio kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver May 12 08:57:50 volumio kernel: [vc_sm_connected_init]: start May 12 08:57:50 volumio kernel: [vc_sm_connected_init]: installed successfully May 12 08:57:51 volumio systemd[1]: Found device /dev/ttyAMA0. May 12 08:57:51 volumio kernel: mc: Linux media interface: v0.10 May 12 08:57:51 volumio kernel: videodev: Linux video capture interface: v2.00 May 12 08:57:51 volumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 12 08:57:51 volumio kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 12 08:57:51 volumio kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp May 12 08:57:51 volumio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. May 12 08:57:51 volumio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. May 12 08:57:51 volumio systemd-udevd[319]: Using default interface naming scheme 'v240'. May 12 08:57:51 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 May 12 08:57:51 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode May 12 08:57:51 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 May 12 08:57:51 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode May 12 08:57:51 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 May 12 08:57:51 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp May 12 08:57:51 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 May 12 08:57:51 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx May 12 08:57:51 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 May 12 08:57:51 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image May 12 08:57:51 volumio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. May 12 08:57:51 volumio kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored May 12 08:57:51 volumio systemd[1]: Found device /dev/disk/by-uuid/51B1-040D. May 12 08:57:51 volumio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels May 12 08:57:51 volumio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels May 12 08:57:51 volumio systemd-udevd[319]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 12 08:57:52 volumio systemd[1]: Starting File System Check on /dev/disk/by-uuid/51B1-040D... May 12 08:57:52 volumio systemd-udevd[309]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 12 08:57:52 volumio systemd-fsck[380]: fsck.fat 4.1 (2017-01-24) May 12 08:57:52 volumio systemd-fsck[380]: /dev/mmcblk0p1: 402 files, 166319/184582 clusters May 12 08:57:52 volumio systemd[1]: Started File System Check on /dev/disk/by-uuid/51B1-040D. May 12 08:57:53 volumio kernel: alsactl[412]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set May 12 08:57:53 volumio kernel: alsactl[405]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set May 12 08:57:53 volumio systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. May 12 08:57:53 volumio systemd[1]: Starting Show Plymouth Boot Screen... May 12 08:57:53 volumio systemd[1]: Condition check resulted in Huge Pages File System being skipped. May 12 08:57:53 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 12 08:57:53 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 12 08:57:53 volumio systemd[1]: Mounting /boot... May 12 08:57:53 volumio systemd[1]: Started File System Check Daemon to report status. May 12 08:57:53 volumio systemd[1]: plymouth-start.service: Succeeded. May 12 08:57:53 volumio systemd[1]: Started Show Plymouth Boot Screen. May 12 08:57:53 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 12 08:57:53 volumio systemd[1]: Mounted /boot. May 12 08:57:53 volumio systemd[1]: Reached target Local File Systems. May 12 08:57:53 volumio systemd[1]: Starting Raise network interfaces... May 12 08:57:53 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. May 12 08:57:53 volumio systemd[1]: Started ifup for eth0. May 12 08:57:53 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 12 08:57:53 volumio systemd[1]: Starting Preprocess NFS configuration... May 12 08:57:53 volumio systemd[1]: Starting Create Volatile Files and Directories... May 12 08:57:53 volumio systemd[1]: Received SIGRTMIN+20 from PID 165 (plymouthd). May 12 08:57:53 volumio systemd[1]: plymouth-read-write.service: Succeeded. May 12 08:57:53 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 12 08:57:53 volumio systemd[1]: nfs-config.service: Succeeded. May 12 08:57:53 volumio systemd[1]: Started Preprocess NFS configuration. May 12 08:57:53 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. May 12 08:57:53 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. May 12 08:57:53 volumio systemd[1]: Reached target NFS client services. May 12 08:57:53 volumio systemd[1]: Started Create Volatile Files and Directories. May 12 08:57:53 volumio systemd[1]: Starting RPC bind portmap service... May 12 08:57:53 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 12 08:57:53 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. May 12 08:57:54 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 12 08:57:54 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. May 12 08:57:54 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database May 12 08:57:54 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. May 12 08:57:54 volumio systemd[1]: Reached target System Initialization. May 12 08:57:54 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. May 12 08:57:54 volumio kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' May 12 08:57:54 volumio kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' May 12 08:57:54 volumio kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' May 12 08:57:54 volumio kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' May 12 08:57:54 volumio systemd[1]: Started Daily apt download activities. May 12 08:57:54 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. May 12 08:57:54 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 12 08:57:54 volumio systemd[1]: Listening on triggerhappy.socket. May 12 08:57:54 volumio systemd[1]: Reached target Sockets. May 12 08:57:54 volumio systemd[1]: Reached target Basic System. May 12 08:57:54 volumio systemd[1]: Started D-Bus System Message Bus. May 12 08:57:54 volumio sh[419]: command failed: No such device (-19) May 12 08:57:54 volumio ifup[418]: command failed: No such device (-19) May 12 08:57:54 volumio systemd[1]: Starting Login Service... May 12 08:57:54 volumio systemd[1]: Starting WPA supplicant... May 12 08:57:54 volumio systemd[1]: Started MPD Monitor Service. May 12 08:57:54 volumio systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. May 12 08:57:54 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. May 12 08:57:54 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. May 12 08:57:54 volumio systemd[1]: Started Daily apt upgrade and clean activities. May 12 08:57:54 volumio systemd[1]: Reached target Timers. May 12 08:57:54 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 12 08:57:54 volumio systemd[1]: Starting triggerhappy global hotkey daemon... May 12 08:57:54 volumio mpd_monitor.sh[462]: MPD Monitor Service: Starting MPD Monitor Service May 12 08:57:54 volumio systemd[1]: Started Volumio Log Rotation Service. May 12 08:57:54 volumio systemd[1]: Starting Volumio Time Update Utility... May 12 08:57:54 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 12 08:57:54 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. May 12 08:57:54 volumio systemd[1]: Starting dhcpcd on all interfaces... May 12 08:57:54 volumio systemd[1]: Started volumio-remote-updater.service. May 12 08:57:54 volumio thd[466]: Unable to parse trigger line: May 12 08:57:54 volumio thd[466]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle May 12 08:57:54 volumio thd[466]: Unable to parse trigger line: May 12 08:57:54 volumio thd[466]: Found socket passed from systemd May 12 08:57:54 volumio systemd[1]: Started Manage Sound Card State (restore and store). May 12 08:57:54 volumio volumio-time-update[469]: volumio-time-update-util: Fetching time from Volumio... May 12 08:57:54 volumio systemd[1]: Starting Save/Restore Sound Card State... May 12 08:57:54 volumio systemd[1]: Started Volumio Iptables Module. May 12 08:57:54 volumio systemd[1]: Starting Wireless Services... May 12 08:57:54 volumio alsactl[478]: alsactl 1.1.8 daemon started May 12 08:57:54 volumio systemd[1]: Started RPC bind portmap service. May 12 08:57:54 volumio systemd[1]: Started triggerhappy global hotkey daemon. May 12 08:57:54 volumio dhcpcd[473]: Not running dhcpcd because /etc/network/interfaces May 12 08:57:54 volumio dhcpcd[473]: defines some interfaces that will use a May 12 08:57:54 volumio dhcpcd[473]: DHCP client or static address May 12 08:57:54 volumio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED May 12 08:57:54 volumio systemd[1]: dhcpcd.service: Failed with result 'exit-code'. May 12 08:57:54 volumio systemd[1]: Failed to start dhcpcd on all interfaces. May 12 08:57:54 volumio systemd[1]: Reached target RPC Port Mapper. May 12 08:57:54 volumio systemd[1]: Reached target Remote File Systems (Pre). May 12 08:57:54 volumio systemd[1]: Reached target Remote File Systems. May 12 08:57:54 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 12 08:57:54 volumio systemd-logind[458]: New seat seat0. May 12 08:57:55 volumio avahi-daemon[463]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). May 12 08:57:55 volumio avahi-daemon[463]: Successfully dropped root privileges. May 12 08:57:55 volumio avahi-daemon[463]: avahi-daemon 0.7 starting up. May 12 08:57:55 volumio ifup[418]: command failed: No such device (-19) May 12 08:57:55 volumio mpd_monitor.sh[462]: MPD error: Cannot assign requested address May 12 08:57:55 volumio mpd_monitor.sh[462]: MPD Monitor Service: MPD Appears to be inactive, restarting May 12 08:57:55 volumio mpd_monitor.sh[462]: mpd: no process found May 12 08:57:55 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 May 12 08:57:56 volumio dbus-daemon[455]: [system] Successfully activated service 'org.freedesktop.systemd1' May 12 08:57:56 volumio avahi-daemon[463]: Successfully called chroot(). May 12 08:57:56 volumio avahi-daemon[463]: Successfully dropped remaining capabilities. May 12 08:57:56 volumio systemd[1]: Started Login Service. May 12 08:57:56 volumio wpa_supplicant[459]: Successfully initialized wpa_supplicant May 12 08:57:56 volumio systemd[1]: Started Save/Restore Sound Card State. May 12 08:57:56 volumio avahi-daemon[463]: No service file found in /etc/avahi/services. May 12 08:57:56 volumio avahi-daemon[463]: Network interface enumeration completed. May 12 08:57:56 volumio avahi-daemon[463]: Server startup complete. Host name is volumio.local. Local service cookie is 2826655460. May 12 08:57:56 volumio sh[419]: eth0: waiting for carrier May 12 08:57:56 volumio sh[419]: eth0: carrier acquired May 12 08:57:56 volumio dhcpcd[499]: eth0: waiting for carrier May 12 08:57:56 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 12 08:57:56 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off May 12 08:57:56 volumio dhcpcd[499]: eth0: carrier acquired May 12 08:57:56 volumio volumio-remote-updater[475]: Error: No active session May 12 08:57:56 volumio volumio-remote-updater[475]: [2025-05-12 08:57:56] [info] asio async_connect error: system:111 (Connection refused) May 12 08:57:56 volumio volumio-remote-updater[475]: [2025-05-12 08:57:56] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 12 08:57:56 volumio volumio-remote-updater[475]: [2025-05-12 08:57:56] [error] handle_connect error: Underlying Transport Error May 12 08:57:56 volumio sh[419]: DUID 00:01:00:01:2e:f2:a2:db:b8:27:eb:bc:ee:ac May 12 08:57:56 volumio sh[419]: eth0: IAID eb:bc:ee:ac May 12 08:57:56 volumio sh[419]: eth0: adding address fe80::b91e:37b6:f29c:323f May 12 08:57:56 volumio sh[419]: ipv6_addaddr1: Permission denied May 12 08:57:56 volumio dhcpcd[499]: DUID 00:01:00:01:2e:f2:a2:db:b8:27:eb:bc:ee:ac May 12 08:57:56 volumio dhcpcd[499]: eth0: IAID eb:bc:ee:ac May 12 08:57:56 volumio dhcpcd[499]: eth0: adding address fe80::b91e:37b6:f29c:323f May 12 08:57:56 volumio dhcpcd[499]: ipv6_addaddr1: Permission denied May 12 08:57:56 volumio haveged[443]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K May 12 08:57:56 volumio haveged[443]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 May 12 08:57:56 volumio haveged[443]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00357 May 12 08:57:56 volumio haveged[443]: haveged: fills: 0, generated: 0 May 12 08:57:56 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 12 08:57:56 volumio systemd[1]: Started WPA supplicant. May 12 08:57:56 volumio volumio[470]: Could not open config: /tmp/upmpdcli.conf May 12 08:57:56 volumio systemd[1]: Listening on mpd.socket. May 12 08:57:56 volumio systemd[1]: Reached target Sound Card. May 12 08:57:56 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 12 08:57:56 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 12 08:57:56 volumio volumio-time-update[469]: volumio-time-update-util: Date not found in response May 12 08:57:56 volumio volumio-time-update[469]: volumio-time-update-util: Retrying in 5 seconds... May 12 08:57:56 volumio systemd[1]: iptables.service: Succeeded. May 12 08:57:56 volumio systemd[1]: Started Raise network interfaces. May 12 08:57:56 volumio systemd[1]: Reached target Network. May 12 08:57:56 volumio systemd[1]: Starting Permit User Sessions... May 12 08:57:56 volumio systemd[1]: Starting Network Time Service... May 12 08:57:56 volumio systemd[1]: Reached target Network is Online. May 12 08:57:56 volumio systemd[1]: Starting LSB: Brings up/down network automatically... May 12 08:57:57 volumio systemd[1]: Starting /etc/rc.local Compatibility... May 12 08:57:57 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. May 12 08:57:57 volumio systemd[1]: Starting Daily apt download activities... May 12 08:57:57 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:57:57 volumio systemd[1]: Starting Music Player Daemon... May 12 08:57:57 volumio loadcpufreq[489]: Loading cpufreq kernel modules...done (none). May 12 08:57:57 volumio dhcpcd[499]: eth0: soliciting a DHCP lease May 12 08:57:57 volumio sh[419]: eth0: soliciting a DHCP lease May 12 08:57:57 volumio systemd[1]: Starting Samba NMB Daemon... May 12 08:57:57 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 12 08:57:57 volumio sh[419]: eth0: offered 192.168.1.40 from 192.168.1.1 May 12 08:57:57 volumio dhcpcd[499]: eth0: offered 192.168.1.40 from 192.168.1.1 May 12 08:57:57 volumio systemd[1]: Started Permit User Sessions. May 12 08:57:57 volumio sh[419]: eth0: probing address 192.168.1.40/23 May 12 08:57:57 volumio dhcpcd[499]: eth0: probing address 192.168.1.40/23 May 12 08:57:57 volumio systemd[1]: Started /etc/rc.local Compatibility. May 12 08:57:57 volumio sh[419]: eth0: soliciting an IPv6 router May 12 08:57:57 volumio dhcpcd[499]: eth0: soliciting an IPv6 router May 12 08:57:57 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... May 12 08:57:57 volumio systemd[1]: Starting Hold until boot process finishes up... May 12 08:57:57 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 12 08:57:57 volumio systemd[1]: Received SIGRTMIN+21 from PID 165 (plymouthd). May 12 08:57:57 volumio ntpd[608]: ntpd 4.2.8p12@1.3728-o (1): Starting May 12 08:57:57 volumio ntpd[608]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 May 12 08:57:57 volumio ntpd[637]: proto: precision = 1.094 usec (-20) May 12 08:57:57 volumio systemd[1]: Started Network Time Service. May 12 08:57:57 volumio systemd[1]: plymouth-quit.service: Succeeded. May 12 08:57:57 volumio ntpd[637]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 12 08:57:57 volumio systemd[1]: Started Terminate Plymouth Boot Screen. May 12 08:57:57 volumio ntpd[637]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 12 08:57:57 volumio ntpd[637]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 136 days ago May 12 08:57:57 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. May 12 08:57:57 volumio systemd[1]: Started Hold until boot process finishes up. May 12 08:57:57 volumio systemd[1]: Received SIGRTMIN+21 from PID 165 (n/a). May 12 08:57:57 volumio ntpd[637]: Listen and drop on 0 v6wildcard [::]:123 May 12 08:57:57 volumio ntpd[637]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 12 08:57:57 volumio ntpd[637]: Listen normally on 2 lo 127.0.0.1:123 May 12 08:57:57 volumio ntpd[637]: Listening on routing socket on fd #19 for interface updates May 12 08:57:57 volumio ntpd[637]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 12 08:57:57 volumio ntpd[637]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 12 08:57:57 volumio systemd[1]: Started Serial Getty on ttyAMA0. May 12 08:57:57 volumio systemd[1]: Started Getty on tty1. May 12 08:57:57 volumio systemd[1]: Reached target Login Prompts. May 12 08:57:57 volumio ifplugd(eth0)[649]: ifplugd 0.28 initializing. May 12 08:57:57 volumio ifplugd(eth0)[649]: Using interface eth0/B8:27:EB:BC:EE:AC with driver (version: 6.6.62-v7+) May 12 08:57:57 volumio ifplugd(eth0)[649]: Using detection mode: SIOCETHTOOL May 12 08:57:57 volumio ifplugd(eth0)[649]: Initialization complete, link beat detected. May 12 08:57:57 volumio ifplugd(eth0)[649]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 12 08:57:57 volumio cpufrequtils[634]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. May 12 08:57:57 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. May 12 08:57:58 volumio ifplugd(eth0)[649]: client: ifup: waiting for lock on /run/network/ifstate.eth0 May 12 08:57:58 volumio sudo[618]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 08:57:58 volumio sudo[618]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:57:58 volumio sudo[618]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 12 08:57:58 volumio sudo[618]: pam_unix(sudo:session): session closed for user root May 12 08:57:58 volumio ntpd[637]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 12 08:57:59 volumio ntpd[637]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 12 08:57:59 volumio nmbd[624]: [2025/05/12 08:57:59.762921, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) May 12 08:57:59 volumio nmbd[624]: started asyncdns process 702 May 12 08:57:59 volumio nmbd[624]: [2025/05/12 08:57:59.767803, 0] ../lib/util/become_daemon.c:149(daemon_status) May 12 08:57:59 volumio nmbd[624]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... May 12 08:57:59 volumio nmbd[624]: [2025/05/12 08:57:59.772315, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) May 12 08:57:59 volumio nmbd[624]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 12 08:58:00 volumio systemd[1]: apt-daily.service: Succeeded. May 12 08:58:00 volumio systemd[1]: Started Daily apt download activities. May 12 08:58:00 volumio ntpd[637]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 12 08:58:00 volumio wireless.js[487]: WIRELESS: No wireless interface, exiting May 12 08:58:00 volumio iw[721]: command failed: No such device (-19) May 12 08:58:00 volumio iwconfig[724]: Error for wireless request "Set Power Management" (8B2C) : May 12 08:58:00 volumio iwconfig[724]: SET failed on device wlan0 ; No such device. May 12 08:58:00 volumio systemd[1]: wireless.service: Succeeded. May 12 08:58:00 volumio systemd[1]: Started Wireless Services. May 12 08:58:00 volumio systemd[1]: Started Volumio Backend Module. May 12 08:58:00 volumio systemd[1]: Started Volumio Cpu Tweaker. May 12 08:58:00 volumio volumio-cpu-tweak[728]: Setting RT Priority for mpd May 12 08:58:00 volumio volumio-cpu-tweak[728]: Setting MPD Affinity May 12 08:58:00 volumio volumio-cpu-tweak[728]: pid 671's current affinity mask: f May 12 08:58:00 volumio volumio-cpu-tweak[728]: pid 671's new affinity mask: 3 May 12 08:58:00 volumio volumio-cpu-tweak[728]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 12 08:58:00 volumio volumio-cpu-tweak[728]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 12 08:58:01 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. May 12 08:58:01 volumio volumio-remote-updater[475]: [2025-05-12 08:58:01] [info] asio async_connect error: system:111 (Connection refused) May 12 08:58:01 volumio volumio-remote-updater[475]: [2025-05-12 08:58:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 12 08:58:01 volumio volumio-remote-updater[475]: [2025-05-12 08:58:01] [error] handle_connect error: Underlying Transport Error May 12 08:58:01 volumio ntpd[637]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 12 08:58:01 volumio volumio-time-update[469]: volumio-time-update-util: Fetching time from Volumio... May 12 08:58:01 volumio volumio-time-update[469]: volumio-time-update-util: Date not found in response May 12 08:58:01 volumio volumio-time-update[469]: volumio-time-update-util: Retrying in 5 seconds... May 12 08:58:02 volumio sh[419]: eth0: leased 192.168.1.40 for 7200 seconds May 12 08:58:02 volumio sh[419]: eth0: adding route to 192.168.0.0/23 May 12 08:58:02 volumio sh[419]: eth0: adding default route via 192.168.1.1 May 12 08:58:02 volumio dhcpcd[499]: eth0: leased 192.168.1.40 for 7200 seconds May 12 08:58:02 volumio dhcpcd[499]: eth0: adding route to 192.168.0.0/23 May 12 08:58:02 volumio dhcpcd[499]: eth0: adding default route via 192.168.1.1 May 12 08:58:02 volumio avahi-daemon[463]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.40. May 12 08:58:02 volumio avahi-daemon[463]: New relevant interface eth0.IPv4 for mDNS. May 12 08:58:02 volumio avahi-daemon[463]: Registering new address record for 192.168.1.40 on eth0.IPv4. May 12 08:58:02 volumio kernel: sched: RT throttling activated May 12 08:58:02 volumio sh[419]: forked to background, child pid 770 May 12 08:58:02 volumio dhcpcd[499]: forked to background, child pid 770 May 12 08:58:03 volumio ifplugd(eth0)[649]: client: ifup: interface eth0 already configured May 12 08:58:03 volumio sh[419]: eth0=eth0 May 12 08:58:03 volumio ifplugd(eth0)[649]: Program executed successfully. May 12 08:58:03 volumio ifplugd[609]: Network Interface Plugging Daemon...start eth0...done. May 12 08:58:03 volumio systemd[1]: Started LSB: Brings up/down network automatically. May 12 08:58:04 volumio ntpd[637]: Listen normally on 3 eth0 192.168.1.40:123 May 12 08:58:04 volumio ntpd[637]: new interface(s) found: waking up resolver May 12 08:58:04 volumio mpd[671]: May 12 08:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 08:58:04 volumio systemd[1]: Started Music Player Daemon. May 12 08:58:04 volumio mpd_monitor.sh[462]: MPD Monitor Service: MPD restarted due to no mpc output. May 12 08:58:04 volumio systemd[1]: Started Samba NMB Daemon. May 12 08:58:04 volumio nmbd[624]: [2025/05/12 08:58:04.787118, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 12 08:58:04 volumio nmbd[624]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 12 08:58:04 volumio systemd[1]: Starting Samba Winbind Daemon... May 12 08:58:05 volumio winbindd[799]: [2025/05/12 08:58:05.443763, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 12 08:58:05 volumio winbindd[799]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 12 08:58:05 volumio winbindd[799]: [2025/05/12 08:58:05.482643, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 12 08:58:05 volumio systemd[1]: Started Samba Winbind Daemon. May 12 08:58:05 volumio winbindd[799]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 12 08:58:05 volumio systemd[1]: Starting Samba SMB Daemon... May 12 08:58:06 volumio systemd[1]: Started Samba SMB Daemon. May 12 08:58:06 volumio smbd[804]: [2025/05/12 08:58:06.572016, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 12 08:58:06 volumio smbd[804]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 12 08:58:06 volumio volumio[727]: info: ------------------------------------------- May 12 08:58:06 volumio volumio[727]: info: ----- Volumio3 ---- May 12 08:58:06 volumio volumio[727]: info: ------------------------------------------- May 12 08:58:06 volumio volumio[727]: info: ----- System startup ---- May 12 08:58:06 volumio volumio[727]: info: ------------------------------------------- May 12 08:58:06 volumio volumio-time-update[469]: volumio-time-update-util: Fetching time from Volumio... May 12 08:58:07 volumio volumio-time-update[469]: volumio-time-update-util: Setting system time to: 2025-05-12 08:58:31 May 12 08:58:07 volumio sudo[825]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-12 08:58:31 May 12 08:58:07 volumio sudo[825]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:07 volumio dbus-daemon[455]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.6' (uid=0 pid=826 comm="timedatectl set-time 2025-05-12 08:58:31 ") May 12 08:58:07 volumio systemd[1]: Starting Time & Date Service... May 12 08:58:07 volumio dbus-daemon[455]: [system] Successfully activated service 'org.freedesktop.timedate1' May 12 08:58:07 volumio systemd[1]: Started Time & Date Service. May 12 08:58:31 volumio systemd-timedated[827]: Changed local time to Mon May 12 08:58:31 2025 May 12 08:58:31 volumio sudo[825]: pam_unix(sudo:session): session closed for user root May 12 08:58:31 volumio volumio-time-update[469]: volumio-time-update-util: System time updated successfully. May 12 08:58:31 volumio systemd[1]: Started Volumio Time Update Utility. May 12 08:58:31 volumio systemd[1]: Reached target Multi-User System. May 12 08:58:31 volumio systemd[1]: Reached target Graphical Interface. May 12 08:58:31 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 12 08:58:31 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 12 08:58:31 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 12 08:58:31 volumio systemd[1]: Startup finished in 15.193s (kernel) + 20.130s (userspace) = 35.324s. May 12 08:58:32 volumio volumio-remote-updater[475]: [2025-05-12 08:58:32] [connect] Successful connection May 12 08:58:32 volumio volumio[727]: info: MYVOLUMIO Environment detected May 12 08:58:32 volumio volumio[727]: info: Plugin folders cleanup May 12 08:58:32 volumio volumio[727]: info: Scanning into folder /volumio/app/plugins/ May 12 08:58:32 volumio volumio[727]: info: Scanning category audio_interface May 12 08:58:32 volumio volumio[727]: info: Scanning category miscellanea May 12 08:58:32 volumio volumio[727]: info: Scanning category music_service May 12 08:58:32 volumio volumio[727]: info: Scanning category plugins.json May 12 08:58:32 volumio volumio[727]: info: Scanning category system_controller May 12 08:58:32 volumio volumio[727]: info: Scanning category user_interface May 12 08:58:32 volumio volumio[727]: info: Scanning into folder /data/plugins/ May 12 08:58:32 volumio volumio[727]: info: Scanning category music_service May 12 08:58:32 volumio volumio[727]: info: Plugin folders cleanup completed May 12 08:58:32 volumio volumio[727]: info: ------------------------------------------- May 12 08:58:32 volumio volumio[727]: info: ----- Core plugins startup ---- May 12 08:58:32 volumio volumio[727]: info: ------------------------------------------- May 12 08:58:32 volumio volumio[727]: info: Loading plugins from folder /volumio/app/plugins/ May 12 08:58:32 volumio volumio[727]: info: Adding plugin upnp to MyMusic Plugins May 12 08:58:32 volumio volumio[727]: info: Adding plugin airplay_emulation to MyMusic Plugins May 12 08:58:32 volumio volumio[727]: info: Adding plugin upnp_browser to MyMusic Plugins May 12 08:58:32 volumio volumio[727]: info: Loading plugins from folder /data/plugins/ May 12 08:58:32 volumio volumio[727]: info: Loading plugin "system"... May 12 08:58:32 volumio volumio[727]: info: Loading plugin "appearance"... May 12 08:58:35 volumio volumio[727]: error: !!!! WARNING !!!! May 12 08:58:35 volumio kernel: SQUASHFS error: zlib decompression failed, data probably corrupt May 12 08:58:35 volumio kernel: SQUASHFS error: Failed to read block 0x9546fbd: -5 May 12 08:58:35 volumio kernel: SQUASHFS error: Unable to read fragment cache entry [9546fbd] May 12 08:58:35 volumio kernel: SQUASHFS error: Unable to read fragment cache entry [9546fbd] May 12 08:58:35 volumio kernel: SQUASHFS error: Unable to read page, block 9546fbd, size 9101 May 12 08:58:35 volumio volumio[727]: error: The plugin miscellanea/appearance failed to load, setting it to stopped. Error: Error: EIO: i/o error, read May 12 08:58:35 volumio volumio[727]: error: Stack trace: Error: EIO: i/o error, read May 12 08:58:35 volumio volumio[727]: at Object.readSync (fs.js:592:3) May 12 08:58:35 volumio volumio[727]: at tryReadSync (fs.js:366:20) May 12 08:58:35 volumio volumio[727]: at Object.readFileSync (fs.js:395:19) May 12 08:58:35 volumio volumio[727]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1090:18) May 12 08:58:35 volumio volumio[727]: at Module.load (internal/modules/cjs/loader.js:928:32) May 12 08:58:35 volumio volumio[727]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) May 12 08:58:35 volumio volumio[727]: at Module.require (internal/modules/cjs/loader.js:952:19) May 12 08:58:35 volumio volumio[727]: at require (internal/modules/cjs/helpers.js:88:18) May 12 08:58:35 volumio volumio[727]: at Object. (/volumio/node_modules/xml2js/lib/builder.js:7:13) May 12 08:58:35 volumio volumio[727]: at Object. (/volumio/node_modules/xml2js/lib/builder.js:127:4) May 12 08:58:35 volumio volumio[727]: error: !!!! WARNING !!!! May 12 08:58:35 volumio volumio[727]: info: Loading plugin "network"... May 12 08:58:35 volumio volumio[727]: info: Refreshing Cached IP Addresses May 12 08:58:35 volumio sudo[835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 08:58:35 volumio sudo[835]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:35 volumio sudo[837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 08:58:35 volumio sudo[837]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:35 volumio sudo[835]: pam_unix(sudo:session): session closed for user root May 12 08:58:35 volumio volumio[727]: info: Loading plugin "services"... May 12 08:58:35 volumio volumio[727]: info: Loading plugin "alsa_controller"... May 12 08:58:35 volumio sudo[844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 12 08:58:35 volumio sudo[844]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:35 volumio sudo[837]: pam_unix(sudo:session): session closed for user root May 12 08:58:35 volumio sudo[844]: pam_unix(sudo:session): session closed for user root May 12 08:58:35 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:58:35 volumio volumio[727]: info: Loading plugin "wizard"... May 12 08:58:35 volumio volumio[727]: info: Loading plugin "networkfs"... May 12 08:58:35 volumio volumio[727]: info: Starting Udev Watcher for removable devices May 12 08:58:35 volumio volumio[727]: info: Ignoring mount for partition: boot May 12 08:58:35 volumio volumio[727]: info: Ignoring mount for partition: volumio May 12 08:58:35 volumio volumio[727]: info: Ignoring mount for partition: volumio_data May 12 08:58:35 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:35 volumio volumio[727]: info: Loading plugin "volumio_command_line_client"... May 12 08:58:35 volumio volumio[727]: info: Loading plugin "upnp"... May 12 08:58:35 volumio volumio[727]: info: [1747033115883] Starting Upmpd Daemon May 12 08:58:35 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:35 volumio volumio[727]: info: Loading plugin "my_music"... May 12 08:58:35 volumio volumio[727]: info: Loading plugin "mpd"... May 12 08:58:36 volumio volumio[727]: info: Loading plugin "upnp_browser"... May 12 08:58:37 volumio volumio[727]: error: !!!! WARNING !!!! May 12 08:58:37 volumio volumio[727]: error: The plugin music_service/upnp_browser failed to load, setting it to stopped. Error: Error: EIO: i/o error, read May 12 08:58:37 volumio volumio[727]: error: Stack trace: Error: EIO: i/o error, read May 12 08:58:37 volumio volumio[727]: at Object.readSync (fs.js:592:3) May 12 08:58:37 volumio volumio[727]: at tryReadSync (fs.js:366:20) May 12 08:58:37 volumio volumio[727]: at Object.readFileSync (fs.js:395:19) May 12 08:58:37 volumio volumio[727]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1090:18) May 12 08:58:37 volumio volumio[727]: at Module.load (internal/modules/cjs/loader.js:928:32) May 12 08:58:37 volumio volumio[727]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) May 12 08:58:37 volumio volumio[727]: at Module.require (internal/modules/cjs/loader.js:952:19) May 12 08:58:37 volumio volumio[727]: at require (internal/modules/cjs/helpers.js:88:18) May 12 08:58:37 volumio volumio[727]: at Object. (/volumio/node_modules/xml2js/lib/builder.js:7:13) May 12 08:58:37 volumio volumio[727]: at Object. (/volumio/node_modules/xml2js/lib/builder.js:127:4) May 12 08:58:37 volumio volumio[727]: at Module._compile (internal/modules/cjs/loader.js:1063:30) May 12 08:58:37 volumio volumio[727]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) May 12 08:58:37 volumio volumio[727]: at Module.load (internal/modules/cjs/loader.js:928:32) May 12 08:58:37 volumio volumio[727]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) May 12 08:58:37 volumio volumio[727]: at Module.require (internal/modules/cjs/loader.js:952:19) May 12 08:58:37 volumio volumio[727]: at require (internal/modules/cjs/helpers.js:88:18) May 12 08:58:37 volumio kernel: SQUASHFS error: zlib decompression failed, data probably corrupt May 12 08:58:37 volumio kernel: SQUASHFS error: Failed to read block 0x9546fbd: -5 May 12 08:58:37 volumio kernel: SQUASHFS error: Unable to read fragment cache entry [9546fbd] May 12 08:58:37 volumio kernel: SQUASHFS error: Unable to read page, block 9546fbd, size 9101 May 12 08:58:37 volumio volumio[727]: error: !!!! WARNING !!!! May 12 08:58:37 volumio volumio[727]: info: Loading plugin "alarm-clock"... May 12 08:58:37 volumio volumio[727]: info: Loading plugin "airplay_emulation"... May 12 08:58:37 volumio volumio[727]: info: Starting Shairport Sync May 12 08:58:37 volumio volumio[727]: info: Loading plugin "last_100"... May 12 08:58:37 volumio volumio[727]: info: Loading plugin "webradio"... May 12 08:58:38 volumio volumio[727]: info: Loading plugin "i2s_dacs"... May 12 08:58:38 volumio volumio[727]: info: Loading plugin "volumiodiscovery"... May 12 08:58:38 volumio volumio[727]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 08:58:38 volumio volumio[727]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:38 volumio volumio[727]: *** WARNING *** For more information see May 12 08:58:38 volumio volumio[727]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 08:58:38 volumio volumio[727]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:38 volumio volumio[727]: *** WARNING *** For more information see May 12 08:58:38 volumio node[727]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 08:58:38 volumio node[727]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:38 volumio node[727]: *** WARNING *** For more information see May 12 08:58:38 volumio node[727]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 08:58:38 volumio node[727]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:38 volumio node[727]: *** WARNING *** For more information see May 12 08:58:38 volumio volumio[727]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 08:58:38 volumio volumio[727]: info: Discovery: Started advertising with name: Volumio May 12 08:58:38 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:38 volumio volumio[727]: info: Loading plugin "spop"... May 12 08:58:41 volumio volumio[727]: info: Loading plugin "outputs"... May 12 08:58:41 volumio volumio[727]: info: Loading plugin "albumart"... May 12 08:58:41 volumio volumio[727]: info: Plugin example_plugin is not enabled May 12 08:58:41 volumio volumio[727]: info: Loading plugin "inputs"... May 12 08:58:41 volumio volumio[727]: info: Loading plugin "updater_comm"... May 12 08:58:42 volumio volumio[727]: info: Plugin mpdemulation is not enabled May 12 08:58:42 volumio volumio[727]: info: Loading plugin "rest_api"... May 12 08:58:42 volumio volumio[727]: info: Loading plugin "websocket"... May 12 08:58:42 volumio volumio[727]: info: Starting Socket.io Server version 2.3.0 May 12 08:58:42 volumio volumio[727]: info: Loading i18n strings for locale undefined May 12 08:58:42 volumio volumio[727]: error: Failed to load i18n strings for locale undefined: Error: /volumio/app/i18n/strings_undefined.json: ENOENT: no such file or directory, open '/volumio/app/i18n/strings_undefined.json' May 12 08:58:42 volumio volumio[727]: Updating browse sources language May 12 08:58:42 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:42 volumio volumio[727]: Forking 3 albumart workers May 12 08:58:43 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:43 volumio volumio[727]: info: CoreCommandRouter::initPlayerControls May 12 08:58:43 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:43 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:43 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:43 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:43 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:43 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:43 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:43 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:43 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:43 volumio volumio[727]: Express server listening on port 3000 May 12 08:58:43 volumio volumio[727]: [Metrics] WebUI: 15s 5.25ms May 12 08:58:43 volumio volumio[727]: info: CoreStateMachine::resetVolumioState May 12 08:58:43 volumio volumio[727]: info: CoreStateMachine::getcurrentVolume May 12 08:58:43 volumio volumio[727]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:58:43 volumio volumio[727]: info: Volumio Network Manager: Network status updated: 1 May 12 08:58:43 volumio volumio[727]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 12 08:58:43 volumio volumio[727]: wlan0 Interface doesn't support scanning. May 12 08:58:43 volumio volumio[727]: info: Cannot use regular scanning, forcing with ap-force May 12 08:58:43 volumio sudo[901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 12 08:58:43 volumio sudo[901]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:43 volumio sudo[901]: pam_unix(sudo:session): session closed for user root May 12 08:58:43 volumio volumio[727]: command failed: No such device (-19) May 12 08:58:43 volumio volumio[727]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 12 08:58:43 volumio volumio[727]: command failed: No such device (-19) May 12 08:58:43 volumio volumio[727]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.41 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 12 08:58:43 volumio volumio[727]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.41 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 12 08:58:43 volumio volumio[727]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.41 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 12 08:58:43 volumio volumio[727]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.41 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 12 08:58:43 volumio volumio[727]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 12 08:58:43 volumio volumio[727]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.41 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 12 08:58:43 volumio volumio[727]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.41 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 12 08:58:44 volumio volumio[727]: Unable to parse: May 12 08:58:44 volumio volumio[727]: Simple mixer control 'Master',0 May 12 08:58:44 volumio volumio[727]: Capabilities: volume volume-joined May 12 08:58:44 volumio volumio[727]: Playback channels: Mono May 12 08:58:44 volumio volumio[727]: Capture channels: Mono May 12 08:58:44 volumio volumio[727]: Limits: 0 - 248 May 12 08:58:44 volumio volumio[727]: Mono: 109 [44%] May 12 08:58:44 volumio volumio[727]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 12 08:58:44 volumio volumio[727]: info: CoreCommandRouter::volumioGetState May 12 08:58:44 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 12 08:58:44 volumio volumio[727]: info: CoreCommandRouter::volumioGetState May 12 08:58:44 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 12 08:58:44 volumio volumio[727]: info: CoreCommandRouter::volumioGetState May 12 08:58:44 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 12 08:58:44 volumio volumio[727]: info: Reloading queue from file May 12 08:58:44 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:44 volumio volumio[727]: Unable to parse: May 12 08:58:44 volumio volumio[727]: Simple mixer control 'Master',0 May 12 08:58:44 volumio volumio[727]: Capabilities: volume volume-joined May 12 08:58:44 volumio volumio[727]: Playback channels: Mono May 12 08:58:44 volumio volumio[727]: Capture channels: Mono May 12 08:58:44 volumio volumio[727]: Limits: 0 - 248 May 12 08:58:44 volumio volumio[727]: Mono: 109 [44%] May 12 08:58:44 volumio volumio[727]: info: VolumeController:: Volume=undefined Mute =false May 12 08:58:44 volumio volumio[727]: info: CoreStateMachine::pushState May 12 08:58:44 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 12 08:58:44 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:44 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 12 08:58:44 volumio volumio[727]: info: CoreStateMachine::updateTrackBlock May 12 08:58:44 volumio volumio[727]: info: CorePlayQueue::getTrackBlock May 12 08:58:44 volumio volumio[727]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:58:44 volumio volumio[727]: info: CoreStateMachine::setRepeat null single undefined May 12 08:58:44 volumio volumio[727]: info: CoreStateMachine::pushState May 12 08:58:44 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 12 08:58:44 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 12 08:58:44 volumio volumio[727]: info: CoreStateMachine::setRandom null May 12 08:58:44 volumio volumio[727]: info: CoreStateMachine::pushState May 12 08:58:44 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 12 08:58:44 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 12 08:58:44 volumio volumio[727]: info: Setting Device type: Raspberry PI May 12 08:58:44 volumio volumio[727]: info: Listing playlists May 12 08:58:44 volumio volumio[727]: info: Listing playlists May 12 08:58:44 volumio volumio[727]: info: Listing playlists May 12 08:58:44 volumio volumio[727]: info: Listing playlists May 12 08:58:44 volumio volumio[727]: info: Listing playlists May 12 08:58:44 volumio volumio[727]: info: Listing playlists May 12 08:58:44 volumio volumio[727]: info: Completed loading Core Plugins May 12 08:58:44 volumio volumio[727]: info: Preparing to generate the ALSA configuration file May 12 08:58:44 volumio volumio[727]: Unable to parse: May 12 08:58:44 volumio volumio[727]: Simple mixer control 'Master',0 May 12 08:58:44 volumio volumio[727]: Capabilities: volume volume-joined May 12 08:58:44 volumio volumio[727]: Playback channels: Mono May 12 08:58:44 volumio volumio[727]: Capture channels: Mono May 12 08:58:44 volumio volumio[727]: Limits: 0 - 248 May 12 08:58:44 volumio volumio[727]: Mono: 109 [44%] May 12 08:58:44 volumio volumio[727]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 12 08:58:44 volumio volumio[727]: Starting albumart workers May 12 08:58:44 volumio volumio[727]: Starting albumart workers May 12 08:58:44 volumio volumio[727]: info: Asound.conf file unchanged, so no further update is needed May 12 08:58:44 volumio volumio[727]: info: Output device has changed, restarting MPD May 12 08:58:44 volumio volumio[727]: Starting albumart workers May 12 08:58:44 volumio volumio[727]: info: Output device has changed, restarting Shairport Sync May 12 08:58:44 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:44 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:44 volumio sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:44 volumio sudo[914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:44 volumio sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:44 volumio sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:44 volumio sudo[914]: pam_unix(sudo:session): session closed for user root May 12 08:58:44 volumio volumio[727]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:44 volumio systemd[1]: Stopping Music Player Daemon... May 12 08:58:44 volumio volumio[727]: info: ___________ START PLUGINS ___________ May 12 08:58:44 volumio volumio[727]: info: ControllerMpd::onStart: Initializing MPD May 12 08:58:44 volumio volumio[727]: info: Creating MPD Configuration file May 12 08:58:45 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:45 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:45 volumio sudo[923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:45 volumio sudo[923]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:45 volumio sudo[923]: pam_unix(sudo:session): session closed for user root May 12 08:58:45 volumio systemd[1]: mpd.service: Succeeded. May 12 08:58:45 volumio systemd[1]: Stopped Music Player Daemon. May 12 08:58:45 volumio sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:45 volumio sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:45 volumio systemd[1]: Starting Music Player Daemon... May 12 08:58:45 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 12 08:58:45 volumio systemd[1]: mpd.service: Succeeded. May 12 08:58:45 volumio systemd[1]: Stopped Music Player Daemon. May 12 08:58:45 volumio volumio[727]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:45 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:45 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:45 volumio systemd[1]: Starting Music Player Daemon... May 12 08:58:45 volumio volumio[727]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:45 volumio volumio[727]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:45 volumio volumio[727]: info: [1747033125503] CoreMusicLibrary::Adding element Last_100 May 12 08:58:45 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:45 volumio volumio[727]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:45 volumio volumio[727]: info: [1747033125525] CoreMusicLibrary::Adding element Webradio May 12 08:58:45 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:45 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:58:45 volumio volumio[727]: info: Initializing BBC Radios May 12 08:58:45 volumio sudo[932]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 08:58:45 volumio sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:45 volumio sudo[932]: pam_unix(sudo:session): session closed for user root May 12 08:58:45 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:58:45 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:45 volumio volumio[727]: info: Creating Spotify config file May 12 08:58:45 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:45 volumio volumio[727]: info: Volumio Calling Home May 12 08:58:46 volumio systemd[1]: systemd-fsckd.service: Succeeded. May 12 08:58:46 volumio sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 08:58:46 volumio volumio[727]: info: Discovery: adding f95fd0b8-5d81-419d-9b5b-96d9cc2b12e1 May 12 08:58:46 volumio volumio[727]: info: Discovery: Found device Volumio May 12 08:58:46 volumio volumio[727]: info: CoreCommandRouter::volumioGetState May 12 08:58:46 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 12 08:58:46 volumio sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:46 volumio sudo[990]: pam_unix(sudo:session): session closed for user root May 12 08:58:47 volumio sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 08:58:47 volumio sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:47 volumio volumio[727]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.41 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 12 08:58:47 volumio volumio[727]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 12 08:58:47 volumio volumio[727]: info: MPD Permissions set May 12 08:58:47 volumio volumio[727]: info: MPD Permissions set May 12 08:58:47 volumio volumio[727]: Unable to parse: May 12 08:58:47 volumio volumio[727]: Simple mixer control 'Master',0 May 12 08:58:47 volumio volumio[727]: Capabilities: volume volume-joined May 12 08:58:47 volumio volumio[727]: Playback channels: Mono May 12 08:58:47 volumio volumio[727]: Capture channels: Mono May 12 08:58:47 volumio volumio[727]: Limits: 0 - 248 May 12 08:58:47 volumio volumio[727]: Mono: 109 [44%] May 12 08:58:47 volumio volumio[727]: info: VolumeController:: Volume=undefined Mute =false May 12 08:58:47 volumio volumio[727]: info: CoreStateMachine::pushState May 12 08:58:47 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 12 08:58:47 volumio sudo[992]: pam_unix(sudo:session): session closed for user root May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 12 08:58:47 volumio volumio[727]: info: Volumio called home May 12 08:58:47 volumio volumio[727]: info: Spotify config file written May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::volumioGetVisibleSources May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::volumioGetState May 12 08:58:47 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::volumioGetState May 12 08:58:47 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 12 08:58:47 volumio sudo[998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 12 08:58:47 volumio sudo[998]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:47 volumio volumio[727]: info: Received Get System Info May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 08:58:47 volumio volumio[727]: info: Discovery: Getting this device information May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::volumioGetState May 12 08:58:47 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::volumioGetState May 12 08:58:47 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 12 08:58:47 volumio volumio[727]: info: Listing playlists May 12 08:58:47 volumio volumio[727]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 12 08:58:47 volumio systemd[1]: Started go-librespot Daemon. May 12 08:58:47 volumio go-librespot[1000]: go-librespot daemon starting... May 12 08:58:47 volumio sudo[998]: pam_unix(sudo:session): session closed for user root May 12 08:58:47 volumio volumio-remote-updater[475]: [2025-05-12 08:58:47] [connect] Successful connection May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:47 volumio volumio[727]: info: No need to fix Spotify hosts May 12 08:58:47 volumio volumio-remote-updater[475]: [2025-05-12 08:58:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747033127 101 May 12 08:58:47 volumio volumio[727]: 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: 11 May 12 08:58:47 volumio volumio[727]: info: Starting Shairport Sync May 12 08:58:47 volumio volumio[727]: info: Starting Shairport Sync May 12 08:58:47 volumio volumio[727]: info: Starting Shairport Sync May 12 08:58:47 volumio sudo[1017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:47 volumio sudo[1017]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:47 volumio sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:47 volumio sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:47 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:48 volumio sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 12 08:58:48 volumio sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetState May 12 08:58:48 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 12 08:58:48 volumio sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:48 volumio sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:48 volumio sudo[1017]: pam_unix(sudo:session): session closed for user root May 12 08:58:48 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 12 08:58:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:48 volumio volumio[727]: info: Shairport-Sync Started May 12 08:58:48 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:48 volumio volumio[727]: Error adding Membership: Error: addMembership EINVAL May 12 08:58:48 volumio sudo[1024]: pam_unix(sudo:session): session closed for user root May 12 08:58:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:48 volumio volumio[727]: info: Upmpdcli Daemon Started May 12 08:58:48 volumio sudo[1019]: pam_unix(sudo:session): session closed for user root May 12 08:58:48 volumio sudo[1022]: pam_unix(sudo:session): session closed for user root May 12 08:58:48 volumio volumio[727]: info: Shairport-Sync Started May 12 08:58:48 volumio volumio[727]: info: Shairport-Sync Started May 12 08:58:48 volumio go-librespot[1000]: time="2025-05-12T08:58:48+02:00" level=info msg="running go-librespot 0.2.0" May 12 08:58:48 volumio go-librespot[1000]: time="2025-05-12T08:58:48+02:00" level=debug msg="app state loaded" May 12 08:58:48 volumio go-librespot[1000]: time="2025-05-12T08:58:48+02:00" level=debug msg="stored credentials not found" May 12 08:58:48 volumio go-librespot[1000]: runtime: g50: frame.sp=0x1eaab91 top=0x1eaafec May 12 08:58:48 volumio go-librespot[1000]: stack=[0x1eaa000-0x1eab000 May 12 08:58:48 volumio go-librespot[1000]: fatal error: traceback did not unwind completely May 12 08:58:48 volumio go-librespot[1000]: runtime stack: May 12 08:58:48 volumio go-librespot[1000]: runtime.throw({0x71f141, 0x23}) May 12 08:58:48 volumio go-librespot[1000]: [92B blob data] May 12 08:58:48 volumio go-librespot[1000]: runtime.(*unwinder).finishInternal(0x7ef9bae0) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/traceback.go:565 +0x164 fp=0x7ef9ba74 sp=0x7ef9ba54 pc=0x852cc May 12 08:58:48 volumio go-librespot[1000]: runtime.(*unwinder).next(0x7ef9bae0) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/traceback.go:446 +0x34c fp=0x7ef9bac0 sp=0x7ef9ba74 pc=0x85080 May 12 08:58:48 volumio go-librespot[1000]: runtime.copystack(0x1db7328, 0x1000) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/stack.go:930 +0x2bc fp=0x7ef9bb34 sp=0x7ef9bac0 pc=0x77ba8 May 12 08:58:48 volumio go-librespot[1000]: runtime.newstack() May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/stack.go:1112 +0x480 fp=0x7ef9bc0c sp=0x7ef9bb34 pc=0x78184 May 12 08:58:48 volumio go-librespot[1000]: runtime.morestack() May 12 08:58:48 volumio go-librespot[1000]: [92B blob data] May 12 08:58:48 volumio go-librespot[1000]: goroutine 50 gp=0x1db7328 m=0 mp=0xbcc020 [copystack]: May 12 08:58:48 volumio go-librespot[1000]: sync.(*Map).Load(0x0, {0x0, 0x0}) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/sync/map.go:120 +0x1ac fp=0x1eaab91 sp=0x1eaab70 pc=0x9d7bc May 12 08:58:48 volumio go-librespot[1000]: internal/godebug.(*Setting).Value.func1() May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/internal/godebug/godebug.go:139 +0x54 fp=0x1eaabb5 sp=0x1eaab91 pc=0x159ec0 May 12 08:58:48 volumio go-librespot[1000]: created by internal/singleflight.(*Group).DoChan in goroutine 1 May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x2fc May 12 08:58:48 volumio go-librespot[1000]: goroutine 1 gp=0x1c02128 m=nil [select]: May 12 08:58:48 volumio go-librespot[1000]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1d65af4 sp=0x1d65ae0 pc=0x5cfc8 May 12 08:58:48 volumio go-librespot[1000]: fatal error: index out of range May 12 08:58:48 volumio go-librespot[1000]: panic during panic May 12 08:58:48 volumio go-librespot[1000]: runtime stack: May 12 08:58:48 volumio go-librespot[1000]: runtime.throw({0x7111a8, 0x12}) May 12 08:58:48 volumio go-librespot[1000]: [92B blob data] May 12 08:58:48 volumio go-librespot[1000]: runtime.panicCheck1(0x7d55c, {0x7111a8, 0x12}) May 12 08:58:48 volumio go-librespot[1000]: [90B blob data] May 12 08:58:48 volumio go-librespot[1000]: runtime.goPanicIndexU(0xfbece04b, 0x6908) May 12 08:58:48 volumio go-librespot[1000]: [91B blob data] May 12 08:58:48 volumio go-librespot[1000]: runtime.funcfile({0xa47124, 0xb8dbe0}, 0xfbece02e) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/symtab.go:1005 +0xbc fp=0x7ef9b6a4 sp=0x7ef9b68c pc=0x7d55c May 12 08:58:48 volumio go-librespot[1000]: runtime.funcline1({0xa47124, 0xb8dbe0}, 0x70d7f, 0x0) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/symtab.go:1023 +0xdc fp=0x7ef9b6cc sp=0x7ef9b6a4 pc=0x7d650 May 12 08:58:48 volumio go-librespot[1000]: runtime.traceback2.func1(...) May 12 08:58:48 volumio go-librespot[1000]: ?:1011 May 12 08:58:48 volumio go-librespot[1000]: runtime.traceback2(0x7ef9b880, 0x0, 0x0, 0x30) May 12 08:58:48 volumio go-librespot[1000]: ?:1011 +0x390 fp=0x7ef9b804 sp=0x7ef9b6cc pc=0x86a6c May 12 08:58:48 volumio go-librespot[1000]: runtime.traceback1.func1(0x0) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/traceback.go:917 +0x6c fp=0x7ef9b864 sp=0x7ef9b804 pc=0x865b8 May 12 08:58:48 volumio go-librespot[1000]: runtime.traceback1(0xffffffff, 0xffffffff, 0x0, 0x1c02128, 0x0) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/traceback.go:940 +0x1a4 fp=0x7ef9b96c sp=0x7ef9b864 pc=0x864a8 May 12 08:58:48 volumio go-librespot[1000]: internal/reflectlite.name.readVarint(...) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/traceback.go:-2841 May 12 08:58:48 volumio go-librespot[1000]: runtime.tracebackothers.func1(0x1c02128) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/traceback.go:1246 +0x118 fp=0x7ef9b988 sp=0x7ef9b96c pc=0x87eb0 May 12 08:58:48 volumio go-librespot[1000]: runtime.forEachGRace(0x7ef9b9c0) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/proc.go:677 +0x4c fp=0x7ef9b9a0 sp=0x7ef9b988 pc=0x5db14 May 12 08:58:48 volumio go-librespot[1000]: runtime.tracebackothers(0xbcb778) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/runtime2.go:1242 +0xcc fp=0x7ef9b9d0 sp=0x7ef9b9a0 pc=0x87d80 May 12 08:58:48 volumio go-librespot[1000]: runtime.dopanic_m(0xbcb778, 0x59624, 0x7ef9ba40) May 12 08:58:48 volumio go-librespot[1000]: [93B blob data] May 12 08:58:48 volumio go-librespot[1000]: runtime.fatalthrow.func1() May 12 08:58:48 volumio go-librespot[1000]: [92B blob data] May 12 08:58:48 volumio go-librespot[1000]: runtime.fatalthrow(0x2) May 12 08:58:48 volumio go-librespot[1000]: [92B blob data] May 12 08:58:48 volumio go-librespot[1000]: runtime.throw({0x71f141, 0x23}) May 12 08:58:48 volumio go-librespot[1000]: [92B blob data] May 12 08:58:48 volumio go-librespot[1000]: runtime.(*unwinder).finishInternal(0x7ef9bae0) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/traceback.go:565 +0x164 fp=0x7ef9ba74 sp=0x7ef9ba54 pc=0x852cc May 12 08:58:48 volumio go-librespot[1000]: runtime.(*unwinder).next(0x7ef9bae0) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/traceback.go:446 +0x34c fp=0x7ef9bac0 sp=0x7ef9ba74 pc=0x85080 May 12 08:58:48 volumio go-librespot[1000]: runtime.copystack(0x1db7328, 0x1000) May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/stack.go:930 +0x2bc fp=0x7ef9bb34 sp=0x7ef9bac0 pc=0x77ba8 May 12 08:58:48 volumio go-librespot[1000]: runtime.newstack() May 12 08:58:48 volumio go-librespot[1000]: /usr/local/go/src/runtime/stack.go:1112 +0x480 fp=0x7ef9bc0c sp=0x7ef9bb34 pc=0x78184 May 12 08:58:48 volumio go-librespot[1000]: runtime.morestack() May 12 08:58:48 volumio go-librespot[1000]: [92B blob data] May 12 08:58:48 volumio go-librespot[1000]: Aborted May 12 08:58:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a May 12 08:58:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 12 08:58:49 volumio mpd[952]: May 12 08:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 08:58:49 volumio systemd[1]: Started Music Player Daemon. May 12 08:58:49 volumio sudo[916]: pam_unix(sudo:session): session closed for user root May 12 08:58:49 volumio sudo[925]: pam_unix(sudo:session): session closed for user root May 12 08:58:49 volumio volumio[727]: info: Completed starting Core Plugins May 12 08:58:49 volumio volumio[727]: info: ------------------------------------------- May 12 08:58:49 volumio volumio[727]: info: ----- MyVolumio plugins startup ---- May 12 08:58:49 volumio volumio[727]: info: ------------------------------------------- May 12 08:58:49 volumio volumio[727]: info: [MyVolumio PluginManager] Fetching plans data.... May 12 08:58:49 volumio volumio[727]: error: MPD error: The expression evaluated to a falsy value: May 12 08:58:49 volumio volumio[727]: assert.ok(self.idling) May 12 08:58:49 volumio volumio[727]: error: The expression evaluated to a falsy value: May 12 08:58:49 volumio volumio[727]: assert.ok(self.idling) May 12 08:58:49 volumio volumio[727]: info: MPD running with PID952 May 12 08:58:49 volumio volumio[727]: ,establishing connection May 12 08:58:49 volumio volumio[727]: error: updateQueue error: null May 12 08:58:49 volumio volumio[727]: error: updateQueue error: null May 12 08:58:49 volumio volumio[1033]: Generating RSA private key, 4096 bit long modulus (2 primes) May 12 08:58:50 volumio volumio[727]: info: go-librespot daemon successfully initialized May 12 08:58:51 volumio volumio[727]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.41 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 12 08:58:51 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 12 08:58:51 volumio volumio[727]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:58:51 volumio volumio[727]: TypeError: Cannot read property 'then' of undefined May 12 08:58:51 volumio volumio[727]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:2005:20) May 12 08:58:51 volumio volumio[727]: at Socket.emit (events.js:315:20) May 12 08:58:51 volumio volumio[727]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 12 08:58:51 volumio volumio[727]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 12 08:58:51 volumio volumio[727]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:58:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 12 08:58:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. May 12 08:58:51 volumio systemd[1]: Stopped go-librespot Daemon. May 12 08:58:51 volumio systemd[1]: Started go-librespot Daemon. May 12 08:58:52 volumio go-librespot[1066]: go-librespot daemon starting... May 12 08:58:52 volumio go-librespot[1066]: time="2025-05-12T08:58:52+02:00" level=info msg="running go-librespot 0.2.0" May 12 08:58:52 volumio go-librespot[1066]: time="2025-05-12T08:58:52+02:00" level=debug msg="app state loaded" May 12 08:58:52 volumio go-librespot[1066]: time="2025-05-12T08:58:52+02:00" level=debug msg="stored credentials not found" May 12 08:58:52 volumio go-librespot[1066]: runtime: g34: frame.sp=0x2859b91 top=0x2859fec May 12 08:58:52 volumio go-librespot[1066]: stack=[0x2859000-0x285a000 May 12 08:58:52 volumio go-librespot[1066]: fatal error: traceback did not unwind completely May 12 08:58:52 volumio go-librespot[1066]: runtime stack: May 12 08:58:52 volumio go-librespot[1066]: runtime.throw({0x71f141, 0x23}) May 12 08:58:52 volumio go-librespot[1066]: [92B blob data] May 12 08:58:52 volumio go-librespot[1066]: runtime.(*unwinder).finishInternal(0x7ea32ae0) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/traceback.go:565 +0x164 fp=0x7ea32a74 sp=0x7ea32a54 pc=0x852cc May 12 08:58:52 volumio go-librespot[1066]: runtime.(*unwinder).next(0x7ea32ae0) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/traceback.go:446 +0x34c fp=0x7ea32ac0 sp=0x7ea32a74 pc=0x85080 May 12 08:58:52 volumio go-librespot[1066]: runtime.copystack(0x29e2fc8, 0x1000) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/stack.go:930 +0x2bc fp=0x7ea32b34 sp=0x7ea32ac0 pc=0x77ba8 May 12 08:58:52 volumio go-librespot[1066]: runtime.newstack() May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/stack.go:1112 +0x480 fp=0x7ea32c0c sp=0x7ea32b34 pc=0x78184 May 12 08:58:52 volumio go-librespot[1066]: runtime.morestack() May 12 08:58:52 volumio go-librespot[1066]: [92B blob data] May 12 08:58:52 volumio go-librespot[1066]: goroutine 34 gp=0x29e2fc8 m=0 mp=0xbcc020 [copystack]: May 12 08:58:52 volumio go-librespot[1066]: sync.(*Map).Load(0x0, {0x0, 0x0}) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/sync/map.go:120 +0x1ac fp=0x2859b91 sp=0x2859b70 pc=0x9d7bc May 12 08:58:52 volumio go-librespot[1066]: internal/godebug.(*Setting).Value.func1() May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/internal/godebug/godebug.go:139 +0x54 fp=0x2859bb5 sp=0x2859b91 pc=0x159ec0 May 12 08:58:52 volumio go-librespot[1066]: created by internal/singleflight.(*Group).DoChan in goroutine 1 May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x2fc May 12 08:58:52 volumio go-librespot[1066]: goroutine 1 gp=0x2802128 m=nil [select]: May 12 08:58:52 volumio go-librespot[1066]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x29cfaf4 sp=0x29cfae0 pc=0x5cfc8 May 12 08:58:52 volumio go-librespot[1066]: fatal error: index out of range May 12 08:58:52 volumio go-librespot[1066]: panic during panic May 12 08:58:52 volumio go-librespot[1066]: runtime stack: May 12 08:58:52 volumio go-librespot[1066]: runtime.throw({0x7111a8, 0x12}) May 12 08:58:52 volumio go-librespot[1066]: [92B blob data] May 12 08:58:52 volumio go-librespot[1066]: runtime.panicCheck1(0x7d55c, {0x7111a8, 0x12}) May 12 08:58:52 volumio go-librespot[1066]: [90B blob data] May 12 08:58:52 volumio go-librespot[1066]: runtime.goPanicIndexU(0xfbece04b, 0x6908) May 12 08:58:52 volumio go-librespot[1066]: [91B blob data] May 12 08:58:52 volumio go-librespot[1066]: runtime.funcfile({0xa47124, 0xb8dbe0}, 0xfbece02e) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/symtab.go:1005 +0xbc fp=0x7ea326a4 sp=0x7ea3268c pc=0x7d55c May 12 08:58:52 volumio go-librespot[1066]: runtime.funcline1({0xa47124, 0xb8dbe0}, 0x70d7f, 0x0) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/symtab.go:1023 +0xdc fp=0x7ea326cc sp=0x7ea326a4 pc=0x7d650 May 12 08:58:52 volumio go-librespot[1066]: runtime.traceback2.func1(...) May 12 08:58:52 volumio go-librespot[1066]: ?:1011 May 12 08:58:52 volumio go-librespot[1066]: runtime.traceback2(0x7ea32880, 0x0, 0x0, 0x30) May 12 08:58:52 volumio go-librespot[1066]: ?:1011 +0x390 fp=0x7ea32804 sp=0x7ea326cc pc=0x86a6c May 12 08:58:52 volumio go-librespot[1066]: runtime.traceback1.func1(0x0) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/traceback.go:917 +0x6c fp=0x7ea32864 sp=0x7ea32804 pc=0x865b8 May 12 08:58:52 volumio go-librespot[1066]: runtime.traceback1(0xffffffff, 0xffffffff, 0x0, 0x2802128, 0x0) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/traceback.go:940 +0x1a4 fp=0x7ea3296c sp=0x7ea32864 pc=0x864a8 May 12 08:58:52 volumio go-librespot[1066]: internal/reflectlite.name.readVarint(...) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/traceback.go:-2841 May 12 08:58:52 volumio go-librespot[1066]: runtime.tracebackothers.func1(0x2802128) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/traceback.go:1246 +0x118 fp=0x7ea32988 sp=0x7ea3296c pc=0x87eb0 May 12 08:58:52 volumio go-librespot[1066]: runtime.forEachGRace(0x7ea329c0) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/proc.go:677 +0x4c fp=0x7ea329a0 sp=0x7ea32988 pc=0x5db14 May 12 08:58:52 volumio go-librespot[1066]: runtime.tracebackothers(0xbcb778) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/runtime2.go:1242 +0xcc fp=0x7ea329d0 sp=0x7ea329a0 pc=0x87d80 May 12 08:58:52 volumio go-librespot[1066]: runtime.dopanic_m(0xbcb778, 0x59624, 0x7ea32a40) May 12 08:58:52 volumio go-librespot[1066]: [93B blob data] May 12 08:58:52 volumio go-librespot[1066]: runtime.fatalthrow.func1() May 12 08:58:52 volumio go-librespot[1066]: [92B blob data] May 12 08:58:52 volumio go-librespot[1066]: runtime.fatalthrow(0x2) May 12 08:58:52 volumio go-librespot[1066]: [92B blob data] May 12 08:58:52 volumio go-librespot[1066]: runtime.throw({0x71f141, 0x23}) May 12 08:58:52 volumio go-librespot[1066]: [92B blob data] May 12 08:58:52 volumio go-librespot[1066]: runtime.(*unwinder).finishInternal(0x7ea32ae0) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/traceback.go:565 +0x164 fp=0x7ea32a74 sp=0x7ea32a54 pc=0x852cc May 12 08:58:52 volumio go-librespot[1066]: runtime.(*unwinder).next(0x7ea32ae0) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/traceback.go:446 +0x34c fp=0x7ea32ac0 sp=0x7ea32a74 pc=0x85080 May 12 08:58:52 volumio go-librespot[1066]: runtime.copystack(0x29e2fc8, 0x1000) May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/stack.go:930 +0x2bc fp=0x7ea32b34 sp=0x7ea32ac0 pc=0x77ba8 May 12 08:58:52 volumio go-librespot[1066]: runtime.newstack() May 12 08:58:52 volumio go-librespot[1066]: /usr/local/go/src/runtime/stack.go:1112 +0x480 fp=0x7ea32c0c sp=0x7ea32b34 pc=0x78184 May 12 08:58:52 volumio go-librespot[1066]: runtime.morestack() May 12 08:58:52 volumio go-librespot[1066]: [92B blob data] May 12 08:58:52 volumio go-librespot[1066]: Aborted May 12 08:58:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a May 12 08:58:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 12 08:58:52 volumio sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-12 08:57 May 12 08:58:52 volumio sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"