-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-03-16 10:08:06 CET. -- Mar 16 10:07:04 framboosje fake-hwclock[304]: Sun Mar 16 09:07:04 UTC 2025 Mar 16 10:07:04 framboosje systemd[1]: Mounted Kernel Debug File System. Mar 16 10:07:04 framboosje systemd[1]: Started Create list of required static device nodes for the current kernel. Mar 16 10:07:04 framboosje systemd[1]: Started Remount Root and Kernel File Systems. Mar 16 10:07:04 framboosje systemd[1]: Started Restore / save the current clock. Mar 16 10:07:04 framboosje systemd[1]: Mounted RPC Pipe File System. Mar 16 10:07:04 framboosje systemd[1]: Started Load Kernel Modules. Mar 16 10:07:04 framboosje systemd[1]: Mounted POSIX Message Queue File System. Mar 16 10:07:04 framboosje systemd[1]: Starting Apply Kernel Variables... Mar 16 10:07:04 framboosje systemd[1]: Mounting FUSE Control File System... Mar 16 10:07:04 framboosje systemd[1]: Mounting Kernel Configuration File System... Mar 16 10:07:04 framboosje systemd[1]: Starting Load/Save Random Seed... Mar 16 10:07:04 framboosje systemd[1]: Starting Create System Users... Mar 16 10:07:04 framboosje systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 16 10:07:04 framboosje systemd[1]: Mounted FUSE Control File System. Mar 16 10:07:04 framboosje systemd[1]: Mounted Kernel Configuration File System. Mar 16 10:07:04 framboosje systemd[1]: dynamicswap.service: Succeeded. Mar 16 10:07:04 framboosje systemd[1]: Started Apply Kernel Variables. Mar 16 10:07:04 framboosje systemd[1]: Started Load/Save Random Seed. Mar 16 10:07:04 framboosje systemd[1]: Started Create System Users. Mar 16 10:07:04 framboosje systemd[1]: Starting Create Static Device Nodes in /dev... Mar 16 10:07:04 framboosje systemd[1]: Started udev Coldplug all Devices. Mar 16 10:07:04 framboosje systemd[1]: Starting Helper to synchronize boot up for ifupdown... Mar 16 10:07:04 framboosje systemd[1]: Started Helper to synchronize boot up for ifupdown. Mar 16 10:07:04 framboosje systemd[1]: Started Create Static Device Nodes in /dev. Mar 16 10:07:04 framboosje systemd[1]: Reached target Local File Systems (Pre). Mar 16 10:07:04 framboosje systemd[1]: Mounting /var/spool/cups... Mar 16 10:07:04 framboosje systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Mar 16 10:07:04 framboosje systemd[1]: Mounting /var/log... Mar 16 10:07:04 framboosje systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Mar 16 10:07:04 framboosje systemd[1]: Mounting /tmp... Mar 16 10:07:04 framboosje systemd[1]: Starting udev Kernel Device Manager... Mar 16 10:07:04 framboosje systemd[1]: Mounted /var/spool/cups. Mar 16 10:07:04 framboosje systemd[1]: Mounted /var/log. Mar 16 10:07:04 framboosje systemd[1]: Mounted /tmp. Mar 16 10:07:04 framboosje systemd[1]: Starting Flush Journal to Persistent Storage... Mar 16 10:07:04 framboosje systemd[1]: Mounting /var/spool/cups/tmp... Mar 16 10:07:04 framboosje systemd[1]: Mounted /var/spool/cups/tmp. Mar 16 10:07:04 framboosje systemd-udevd[341]: Network interface NamePolicy= disabled on kernel command line, ignoring. Mar 16 10:07:04 framboosje systemd-journald[307]: Runtime journal (/run/log/journal/d9a3e155a633914cdbe1662066e99934) is 11.2M, max 30.0M, 18.7M free. Mar 16 10:07:04 framboosje systemd[1]: Started Flush Journal to Persistent Storage. Mar 16 10:07:04 framboosje systemd[1]: Started udev Kernel Device Manager. Mar 16 10:07:04 framboosje systemd[1]: Starting Show Plymouth Boot Screen... Mar 16 10:07:04 framboosje systemd[1]: plymouth-start.service: Succeeded. Mar 16 10:07:04 framboosje systemd[1]: Started Show Plymouth Boot Screen. Mar 16 10:07:04 framboosje systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Mar 16 10:07:04 framboosje systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 16 10:07:04 framboosje systemd[1]: Reached target Local Encrypted Volumes. Mar 16 10:07:04 framboosje systemd[1]: Reached target Paths. Mar 16 10:07:04 framboosje kernel: mc: Linux media interface: v0.10 Mar 16 10:07:04 framboosje kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Mar 16 10:07:04 framboosje kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Mar 16 10:07:04 framboosje kernel: [vc_sm_connected_init]: start Mar 16 10:07:04 framboosje kernel: [vc_sm_connected_init]: installed successfully Mar 16 10:07:04 framboosje kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Mar 16 10:07:04 framboosje kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Mar 16 10:07:04 framboosje kernel: videodev: Linux video capture interface: v2.00 Mar 16 10:07:04 framboosje kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Mar 16 10:07:04 framboosje kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Mar 16 10:07:05 framboosje kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Mar 16 10:07:05 framboosje kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 16 10:07:05 framboosje kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Mar 16 10:07:05 framboosje kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Mar 16 10:07:05 framboosje systemd-udevd[353]: Using default interface naming scheme 'v240'. Mar 16 10:07:05 framboosje kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Mar 16 10:07:05 framboosje kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Mar 16 10:07:05 framboosje kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Mar 16 10:07:05 framboosje kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 16 10:07:05 framboosje kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Mar 16 10:07:05 framboosje systemd-udevd[353]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 16 10:07:05 framboosje kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Mar 16 10:07:05 framboosje kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Mar 16 10:07:05 framboosje kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Mar 16 10:07:05 framboosje kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Mar 16 10:07:05 framboosje systemd[1]: Found device /dev/disk/by-uuid/9C48-E5A3. Mar 16 10:07:05 framboosje kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Mar 16 10:07:05 framboosje kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Mar 16 10:07:05 framboosje kernel: Bluetooth: Core ver 2.22 Mar 16 10:07:05 framboosje kernel: NET: Registered PF_BLUETOOTH protocol family Mar 16 10:07:05 framboosje kernel: Bluetooth: HCI device and connection manager initialized Mar 16 10:07:05 framboosje kernel: Bluetooth: HCI socket layer initialized Mar 16 10:07:05 framboosje kernel: Bluetooth: L2CAP socket layer initialized Mar 16 10:07:05 framboosje kernel: Bluetooth: SCO socket layer initialized Mar 16 10:07:05 framboosje kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Mar 16 10:07:05 framboosje kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Mar 16 10:07:05 framboosje kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Mar 16 10:07:05 framboosje kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Mar 16 10:07:05 framboosje kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Mar 16 10:07:05 framboosje systemd[1]: Starting File System Check on /dev/disk/by-uuid/9C48-E5A3... Mar 16 10:07:05 framboosje kernel: Bluetooth: HCI UART driver ver 2.3 Mar 16 10:07:05 framboosje kernel: Bluetooth: HCI UART protocol H4 registered Mar 16 10:07:05 framboosje kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Mar 16 10:07:05 framboosje kernel: Bluetooth: HCI UART protocol Broadcom registered Mar 16 10:07:05 framboosje kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Mar 16 10:07:05 framboosje kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Mar 16 10:07:05 framboosje kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Mar 16 10:07:05 framboosje kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Mar 16 10:07:05 framboosje kernel: uart-pl011 3f201000.serial: no DMA platform data Mar 16 10:07:05 framboosje systemd[1]: Started File System Check Daemon to report status. Mar 16 10:07:05 framboosje systemd-udevd[351]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 16 10:07:05 framboosje systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Mar 16 10:07:05 framboosje kernel: Bluetooth: hci0: BCM: chip id 94 Mar 16 10:07:05 framboosje kernel: Bluetooth: hci0: BCM: features 0x2e Mar 16 10:07:05 framboosje kernel: Bluetooth: hci0: BCM43430A1 Mar 16 10:07:05 framboosje kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000 Mar 16 10:07:05 framboosje kernel: Bluetooth: hci0: BCM43430A1 'brcm/BCM43430A1.hcd' Patch Mar 16 10:07:05 framboosje kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Mar 16 10:07:05 framboosje kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Mar 16 10:07:05 framboosje kernel: usbcore: registered new interface driver brcmfmac Mar 16 10:07:05 framboosje systemd-fsck[410]: fsck.fat 4.1 (2017-01-24) Mar 16 10:07:05 framboosje systemd-fsck[410]: Unexpected long filename sequence number (2 vs. expected 1). Mar 16 10:07:05 framboosje systemd-fsck[410]: It could be that just the number is wrong Mar 16 10:07:05 framboosje systemd-fsck[410]: if "network-confi" seems to match "g". Mar 16 10:07:05 framboosje systemd-fsck[410]: Not auto-correcting this. Mar 16 10:07:05 framboosje systemd-fsck[410]: /dev/mmcblk0p1: 485 files, 167635/184582 clusters Mar 16 10:07:05 framboosje systemd[1]: Started File System Check on /dev/disk/by-uuid/9C48-E5A3. Mar 16 10:07:06 framboosje kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Mar 16 10:07:06 framboosje kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897 Mar 16 10:07:06 framboosje systemd-udevd[347]: Using default interface naming scheme 'v240'. Mar 16 10:07:06 framboosje systemd-udevd[347]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 16 10:07:06 framboosje kernel: alsactl[484]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Mar 16 10:07:06 framboosje systemd[1]: Created slice system-bthelper.slice. Mar 16 10:07:06 framboosje systemd[1]: Condition check resulted in Huge Pages File System being skipped. Mar 16 10:07:06 framboosje systemd[1]: Starting Show Plymouth Boot Screen... Mar 16 10:07:06 framboosje systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 16 10:07:06 framboosje systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 16 10:07:06 framboosje systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Mar 16 10:07:06 framboosje systemd[1]: Mounting /boot... Mar 16 10:07:06 framboosje systemd[1]: Starting Load/Save RF Kill Switch Status... Mar 16 10:07:06 framboosje systemd[1]: Mounted /boot. Mar 16 10:07:06 framboosje systemd[1]: plymouth-start.service: Succeeded. Mar 16 10:07:06 framboosje systemd[1]: Started Show Plymouth Boot Screen. Mar 16 10:07:06 framboosje systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 16 10:07:06 framboosje systemd[1]: Reached target Local File Systems. Mar 16 10:07:06 framboosje systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Mar 16 10:07:06 framboosje systemd[1]: Starting Raise network interfaces... Mar 16 10:07:06 framboosje systemd[1]: Starting Create Volatile Files and Directories... Mar 16 10:07:06 framboosje systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Mar 16 10:07:06 framboosje systemd[1]: Starting Preprocess NFS configuration... Mar 16 10:07:06 framboosje systemd[1]: Started ifup for eth0. Mar 16 10:07:06 framboosje systemd[1]: Started Load/Save RF Kill Switch Status. Mar 16 10:07:06 framboosje systemd[1]: plymouth-read-write.service: Succeeded. Mar 16 10:07:06 framboosje systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Mar 16 10:07:06 framboosje systemd[1]: Received SIGRTMIN+20 from PID 197 (plymouthd). Mar 16 10:07:06 framboosje systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Mar 16 10:07:06 framboosje systemd[1]: Started Create Volatile Files and Directories. Mar 16 10:07:06 framboosje systemd[1]: nfs-config.service: Succeeded. Mar 16 10:07:06 framboosje systemd[1]: Started Preprocess NFS configuration. Mar 16 10:07:06 framboosje systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Mar 16 10:07:06 framboosje systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Mar 16 10:07:06 framboosje systemd[1]: Reached target NFS client services. Mar 16 10:07:06 framboosje systemd[1]: Starting RPC bind portmap service... Mar 16 10:07:06 framboosje systemd[1]: Starting Update UTMP about System Boot/Shutdown... Mar 16 10:07:06 framboosje systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Mar 16 10:07:06 framboosje systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Mar 16 10:07:06 framboosje systemd[1]: Started ifup for wlan0. Mar 16 10:07:06 framboosje kernel: Bluetooth: hci0: BCM: features 0x2e Mar 16 10:07:06 framboosje kernel: Bluetooth: hci0: BCM43438A1 37.4MHz Raspberry Pi 3-0141 Mar 16 10:07:06 framboosje kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0508 Mar 16 10:07:06 framboosje kernel: 8021q: 802.1Q VLAN Support v1.8 Mar 16 10:07:06 framboosje systemd[1]: Started Update UTMP about System Boot/Shutdown. Mar 16 10:07:06 framboosje systemd[1]: Reached target System Initialization. Mar 16 10:07:06 framboosje systemd[1]: Listening on triggerhappy.socket. Mar 16 10:07:06 framboosje systemd[1]: Started Daily apt download activities. Mar 16 10:07:06 framboosje kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Mar 16 10:07:06 framboosje systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 16 10:07:06 framboosje systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 16 10:07:06 framboosje systemd[1]: Started Daily apt upgrade and clean activities. Mar 16 10:07:06 framboosje systemd[1]: Reached target Timers. Mar 16 10:07:06 framboosje systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Mar 16 10:07:06 framboosje systemd[1]: Reached target Sockets. Mar 16 10:07:06 framboosje systemd[1]: Reached target Basic System. Mar 16 10:07:06 framboosje kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 16 10:07:06 framboosje systemd[1]: Starting triggerhappy global hotkey daemon... Mar 16 10:07:06 framboosje systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Mar 16 10:07:06 framboosje systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Mar 16 10:07:06 framboosje systemd[1]: Started MPD Monitor Service. Mar 16 10:07:06 framboosje systemd[1]: Started D-Bus System Message Bus. Mar 16 10:07:06 framboosje systemd[1]: Starting WPA supplicant... Mar 16 10:07:06 framboosje systemd[1]: Started volumio-remote-updater.service. Mar 16 10:07:06 framboosje systemd[1]: Started Volumio Log Rotation Service. Mar 16 10:07:07 framboosje systemd[1]: Starting Login Service... Mar 16 10:07:07 framboosje systemd[1]: Starting Volumio Time Update Utility... Mar 16 10:07:07 framboosje systemd[1]: Starting Wireless Services... Mar 16 10:07:07 framboosje systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Mar 16 10:07:07 framboosje systemd[1]: Starting dhcpcd on all interfaces... Mar 16 10:07:07 framboosje systemd[1]: Started Manage Sound Card State (restore and store). Mar 16 10:07:07 framboosje systemd[1]: Starting Save/Restore Sound Card State... Mar 16 10:07:07 framboosje systemd[1]: Started UPnP Renderer front-end to MPD. Mar 16 10:07:07 framboosje alsactl[610]: alsactl 1.1.8 daemon started Mar 16 10:07:07 framboosje systemd[1]: Starting Configure Bluetooth Modems connected by UART... Mar 16 10:07:07 framboosje systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Mar 16 10:07:07 framboosje systemd[1]: Started Volumio Iptables Module. Mar 16 10:07:07 framboosje systemd[1]: Started RPC bind portmap service. Mar 16 10:07:07 framboosje systemd[1]: Reached target RPC Port Mapper. Mar 16 10:07:07 framboosje systemd[1]: Reached target Remote File Systems (Pre). Mar 16 10:07:07 framboosje systemd[1]: Reached target Remote File Systems. Mar 16 10:07:07 framboosje systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Mar 16 10:07:07 framboosje volumio-time-update[607]: volumio-time-update-util: Fetching time from Volumio... Mar 16 10:07:07 framboosje thd[577]: Unable to parse trigger line: Mar 16 10:07:07 framboosje thd[577]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Mar 16 10:07:07 framboosje thd[577]: Unable to parse trigger line: Mar 16 10:07:07 framboosje thd[577]: Found socket passed from systemd Mar 16 10:07:07 framboosje dhcpcd[609]: Not running dhcpcd because /etc/network/interfaces Mar 16 10:07:07 framboosje dhcpcd[609]: defines some interfaces that will use a Mar 16 10:07:07 framboosje dhcpcd[609]: DHCP client or static address Mar 16 10:07:07 framboosje dhcpcd[537]: eth0: waiting for carrier Mar 16 10:07:07 framboosje sh[510]: eth0: waiting for carrier Mar 16 10:07:07 framboosje kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Mar 16 10:07:07 framboosje kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Mar 16 10:07:07 framboosje systemd-logind[606]: New seat seat0. Mar 16 10:07:07 framboosje mpd_monitor.sh[594]: MPD error: Connection refused Mar 16 10:07:07 framboosje avahi-daemon[592]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Mar 16 10:07:07 framboosje avahi-daemon[592]: Successfully dropped root privileges. Mar 16 10:07:07 framboosje avahi-daemon[592]: avahi-daemon 0.7 starting up. Mar 16 10:07:07 framboosje mpd_monitor.sh[594]: mpd: no process found Mar 16 10:07:08 framboosje wpa_supplicant[599]: Successfully initialized wpa_supplicant Mar 16 10:07:08 framboosje systemd[1]: Started WPA supplicant. Mar 16 10:07:08 framboosje avahi-daemon[592]: Successfully called chroot(). Mar 16 10:07:08 framboosje systemd[1]: Started Login Service. Mar 16 10:07:08 framboosje avahi-daemon[592]: Successfully dropped remaining capabilities. Mar 16 10:07:08 framboosje systemd[1]: Started triggerhappy global hotkey daemon. Mar 16 10:07:08 framboosje systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Mar 16 10:07:08 framboosje systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Mar 16 10:07:08 framboosje systemd[1]: Failed to start dhcpcd on all interfaces. Mar 16 10:07:08 framboosje systemd[1]: Started Save/Restore Sound Card State. Mar 16 10:07:08 framboosje systemd[1]: hciuart.service: Succeeded. Mar 16 10:07:08 framboosje systemd[1]: Started Configure Bluetooth Modems connected by UART. Mar 16 10:07:08 framboosje haveged[555]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Mar 16 10:07:08 framboosje haveged[555]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Mar 16 10:07:08 framboosje haveged[555]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99816 Mar 16 10:07:08 framboosje haveged[555]: haveged: fills: 0, generated: 0 Mar 16 10:07:08 framboosje avahi-daemon[592]: Loading service file /services/volumio.service. Mar 16 10:07:08 framboosje avahi-daemon[592]: Network interface enumeration completed. Mar 16 10:07:08 framboosje avahi-daemon[592]: Server startup complete. Host name is framboosje.local. Local service cookie is 2598164344. Mar 16 10:07:08 framboosje avahi-daemon[592]: Service "Framboosje" (/services/volumio.service) successfully established. Mar 16 10:07:08 framboosje sh[558]: wlan0=wlan0 Mar 16 10:07:08 framboosje systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 16 10:07:08 framboosje volumio-remote-updater[603]: Error: No active session Mar 16 10:07:08 framboosje volumio-remote-updater[603]: [2025-03-16 10:07:08] [info] asio async_connect error: system:111 (Connection refused) Mar 16 10:07:08 framboosje volumio-remote-updater[603]: [2025-03-16 10:07:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 16 10:07:08 framboosje volumio-remote-updater[603]: [2025-03-16 10:07:08] [error] handle_connect error: Underlying Transport Error Mar 16 10:07:08 framboosje systemd[1]: Listening on mpd.socket. Mar 16 10:07:08 framboosje systemd[1]: Starting Raspberry Pi bluetooth helper... Mar 16 10:07:08 framboosje systemd[1]: Reached target Sound Card. Mar 16 10:07:08 framboosje volumio[612]: Could not open config: /tmp/upmpdcli.conf Mar 16 10:07:08 framboosje systemd[1]: Started Raise network interfaces. Mar 16 10:07:08 framboosje systemd[1]: Reached target Network. Mar 16 10:07:08 framboosje systemd[1]: Starting Permit User Sessions... Mar 16 10:07:08 framboosje systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Mar 16 10:07:08 framboosje volumio-time-update[607]: volumio-time-update-util: Date not found in response Mar 16 10:07:08 framboosje volumio-time-update[607]: volumio-time-update-util: Retrying in 5 seconds... Mar 16 10:07:08 framboosje systemd[1]: Starting Music Player Daemon... Mar 16 10:07:08 framboosje systemd[1]: Starting Network Time Service... Mar 16 10:07:08 framboosje systemd[1]: Reached target Network is Online. Mar 16 10:07:08 framboosje systemd[1]: Starting /etc/rc.local Compatibility... Mar 16 10:07:08 framboosje systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 10:07:08 framboosje systemd[1]: Starting Samba NMB Daemon... Mar 16 10:07:08 framboosje systemd[1]: Starting LSB: Brings up/down network automatically... Mar 16 10:07:08 framboosje systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 16 10:07:08 framboosje systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 16 10:07:08 framboosje bthelper[710]: Raspberry Pi BDADDR already set Mar 16 10:07:08 framboosje systemd[1]: Started Permit User Sessions. Mar 16 10:07:08 framboosje systemd[1]: Started Raspberry Pi bluetooth helper. Mar 16 10:07:08 framboosje systemd[1]: Started /etc/rc.local Compatibility. Mar 16 10:07:08 framboosje systemd[1]: iptables.service: Succeeded. Mar 16 10:07:08 framboosje systemd[1]: Starting Bluetooth service... Mar 16 10:07:08 framboosje systemd[1]: Starting Hold until boot process finishes up... Mar 16 10:07:08 framboosje systemd[1]: Starting Terminate Plymouth Boot Screen... Mar 16 10:07:08 framboosje loadcpufreq[618]: Loading cpufreq kernel modules...done (none). Mar 16 10:07:08 framboosje systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Mar 16 10:07:08 framboosje systemd[1]: Received SIGRTMIN+21 from PID 197 (plymouthd). Mar 16 10:07:08 framboosje systemd[1]: Starting LSB: set CPUFreq kernel parameters... Mar 16 10:07:08 framboosje systemd[1]: plymouth-quit-wait.service: Succeeded. Mar 16 10:07:08 framboosje systemd[1]: Started Hold until boot process finishes up. Mar 16 10:07:08 framboosje systemd[1]: plymouth-quit.service: Succeeded. Mar 16 10:07:08 framboosje systemd[1]: Started Terminate Plymouth Boot Screen. Mar 16 10:07:08 framboosje systemd[1]: Received SIGRTMIN+21 from PID 197 (n/a). Mar 16 10:07:08 framboosje systemd[1]: Started Getty on tty1. Mar 16 10:07:08 framboosje systemd[1]: Reached target Login Prompts. Mar 16 10:07:08 framboosje ifplugd(eth0)[778]: ifplugd 0.28 initializing. Mar 16 10:07:08 framboosje ifplugd(eth0)[778]: Using interface eth0/B8:27:EB:D0:72:37 with driver (version: 6.6.62-v7+) Mar 16 10:07:08 framboosje ifplugd(eth0)[778]: Using detection mode: SIOCETHTOOL Mar 16 10:07:08 framboosje ifplugd(eth0)[778]: Initialization complete, link beat not detected. Mar 16 10:07:08 framboosje ifplugd[738]: Network Interface Plugging Daemon...start eth0...done. Mar 16 10:07:08 framboosje systemd[1]: Started LSB: Brings up/down network automatically. Mar 16 10:07:08 framboosje ntpd[724]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 16 10:07:08 framboosje ntpd[724]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Mar 16 10:07:08 framboosje systemd[1]: Started Network Time Service. Mar 16 10:07:08 framboosje ntpd[784]: proto: precision = 0.729 usec (-20) Mar 16 10:07:09 framboosje ntpd[784]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 16 10:07:09 framboosje ntpd[784]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 16 10:07:09 framboosje ntpd[784]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 79 days ago Mar 16 10:07:09 framboosje ntpd[784]: Listen and drop on 0 v6wildcard [::]:123 Mar 16 10:07:09 framboosje ntpd[784]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 16 10:07:09 framboosje ntpd[784]: Listen normally on 2 lo 127.0.0.1:123 Mar 16 10:07:09 framboosje ntpd[784]: Listening on routing socket on fd #19 for interface updates Mar 16 10:07:09 framboosje ntpd[784]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 16 10:07:09 framboosje ntpd[784]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 16 10:07:09 framboosje cpufrequtils[765]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Mar 16 10:07:09 framboosje systemd[1]: Started LSB: set CPUFreq kernel parameters. Mar 16 10:07:09 framboosje bluetoothd[761]: Bluetooth daemon 5.50 Mar 16 10:07:09 framboosje bluetoothd[761]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Mar 16 10:07:09 framboosje systemd[1]: Started Bluetooth service. Mar 16 10:07:09 framboosje systemd[1]: Reached target Bluetooth. Mar 16 10:07:09 framboosje bluetoothd[761]: Starting SDP server Mar 16 10:07:09 framboosje bluetoothd[761]: Excluding (cli) sap Mar 16 10:07:09 framboosje kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 16 10:07:09 framboosje kernel: Bluetooth: BNEP filters: protocol multicast Mar 16 10:07:09 framboosje kernel: Bluetooth: BNEP socket layer initialized Mar 16 10:07:09 framboosje bluetoothd[761]: Bluetooth management interface 1.22 initialized Mar 16 10:07:09 framboosje dbus-daemon[596]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=761 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Mar 16 10:07:09 framboosje kernel: Bluetooth: MGMT ver 1.22 Mar 16 10:07:09 framboosje sudo[718]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 16 10:07:09 framboosje sudo[718]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:09 framboosje sudo[718]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 16 10:07:09 framboosje sudo[718]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:09 framboosje bluetoothd[761]: Failed to set privacy: Rejected (0x0b) Mar 16 10:07:09 framboosje systemd[1]: Starting Hostname Service... Mar 16 10:07:09 framboosje dbus-daemon[596]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 16 10:07:09 framboosje systemd[1]: Started Hostname Service. Mar 16 10:07:09 framboosje ntpd[784]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 16 10:07:10 framboosje nmbd[754]: [2025/03/16 10:07:10.140657, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 16 10:07:10 framboosje nmbd[754]: started asyncdns process 803 Mar 16 10:07:10 framboosje nmbd[754]: [2025/03/16 10:07:10.142941, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 16 10:07:10 framboosje nmbd[754]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 16 10:07:10 framboosje nmbd[754]: [2025/03/16 10:07:10.143107, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 16 10:07:10 framboosje nmbd[754]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 16 10:07:10 framboosje wireless.js[608]: Cleaning previous... Mar 16 10:07:10 framboosje sudo[811]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 16 10:07:10 framboosje ntpd[784]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 16 10:07:10 framboosje sudo[811]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:10 framboosje sudo[811]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:10 framboosje sudo[813]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 16 10:07:10 framboosje sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:11 framboosje sudo[813]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:11 framboosje systemd[1]: systemd-rfkill.service: Succeeded. Mar 16 10:07:11 framboosje wireless.js[608]: Stopped aP Mar 16 10:07:11 framboosje sudo[820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 16 10:07:11 framboosje sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:11 framboosje sudo[820]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:11 framboosje kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 16 10:07:11 framboosje sudo[822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 16 10:07:11 framboosje sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:11 framboosje sudo[822]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:11 framboosje sudo[829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 16 10:07:11 framboosje sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:11 framboosje sudo[829]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:11 framboosje sudo[831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 16 10:07:11 framboosje sudo[831]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:11 framboosje ntpd[784]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 16 10:07:12 framboosje sudo[831]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:12 framboosje wireless.js[608]: SETTING APPROPRIATE REG DOMAIN: NL Mar 16 10:07:12 framboosje sudo[836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 16 10:07:12 framboosje sudo[836]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:12 framboosje sudo[836]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:12 framboosje sudo[838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set NL Mar 16 10:07:12 framboosje sudo[838]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:12 framboosje sudo[838]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:12 framboosje wireless.js[608]: SUCCESSFULLY SET NEW REGDOMAIN: NL Mar 16 10:07:12 framboosje wireless.js[608]: Start wireless flow Mar 16 10:07:12 framboosje wireless.js[608]: Stopped hotspot (if there).. Mar 16 10:07:12 framboosje sudo[843]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 16 10:07:12 framboosje sudo[843]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:12 framboosje sudo[843]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:12 framboosje sudo[845]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 16 10:07:12 framboosje sudo[845]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:12 framboosje ntpd[784]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 16 10:07:13 framboosje sudo[845]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:13 framboosje mpd[800]: Mar 16 10:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 16 10:07:13 framboosje systemd[1]: Started Music Player Daemon. Mar 16 10:07:13 framboosje mpd_monitor.sh[594]: MPD restarted due to no mpc output. Mar 16 10:07:13 framboosje wireless.js[608]: DHCP IP Mar 16 10:07:13 framboosje wpa_supplicant[847]: Successfully initialized wpa_supplicant Mar 16 10:07:13 framboosje wireless.js[608]: Start ap Mar 16 10:07:13 framboosje sudo[851]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 16 10:07:13 framboosje sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:13 framboosje kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 16 10:07:13 framboosje dhcpcd[852]: dev: loaded udev Mar 16 10:07:13 framboosje dhcpcd-run-hooks[870]: wlan0: starting wpa_supplicant Mar 16 10:07:13 framboosje volumio-remote-updater[603]: [2025-03-16 10:07:13] [info] asio async_connect error: system:111 (Connection refused) Mar 16 10:07:13 framboosje volumio-remote-updater[603]: [2025-03-16 10:07:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 16 10:07:13 framboosje volumio-remote-updater[603]: [2025-03-16 10:07:13] [error] handle_connect error: Underlying Transport Error Mar 16 10:07:13 framboosje volumio-time-update[607]: volumio-time-update-util: Fetching time from Volumio... Mar 16 10:07:13 framboosje volumio-time-update[607]: volumio-time-update-util: Date not found in response Mar 16 10:07:13 framboosje volumio-time-update[607]: volumio-time-update-util: Retrying in 5 seconds... Mar 16 10:07:13 framboosje dhcpcd-run-hooks[880]: wlan0: failed to start wpa_supplicant Mar 16 10:07:13 framboosje dhcpcd-run-hooks[881]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Mar 16 10:07:13 framboosje dhcpcd[852]: wlan0: connected to Access Point `' Mar 16 10:07:13 framboosje dhcpcd[852]: no interfaces have a carrier Mar 16 10:07:13 framboosje dhcpcd[852]: forked to background, child pid 891 Mar 16 10:07:13 framboosje dhcpcd[891]: eth0: waiting for carrier Mar 16 10:07:13 framboosje dhcpcd[891]: wlan0: waiting for carrier Mar 16 10:07:13 framboosje sudo[851]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:13 framboosje bthelper[710]: Changing power off succeeded Mar 16 10:07:13 framboosje bthelper[710]: [CHG] Controller B8:27:EB:7A:D8:9D Class: 0x0000041c Mar 16 10:07:13 framboosje bthelper[710]: Changing power on succeeded Mar 16 10:07:13 framboosje wpa_supplicant[874]: wlan0: Trying to associate with SSID 'Gertruda' Mar 16 10:07:13 framboosje dhcpcd[891]: wlan0: carrier acquired Mar 16 10:07:13 framboosje wpa_supplicant[874]: wlan0: Associated with 20:9c:b4:dc:a2:82 Mar 16 10:07:13 framboosje wpa_supplicant[874]: wlan0: CTRL-EVENT-CONNECTED - Connection to 20:9c:b4:dc:a2:82 completed [id=0 id_str=] Mar 16 10:07:13 framboosje wpa_supplicant[874]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 16 10:07:13 framboosje wpa_supplicant[874]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=NL Mar 16 10:07:13 framboosje dhcpcd[891]: DUID 00:01:00:01:2e:7c:58:86:b8:27:eb:85:27:62 Mar 16 10:07:13 framboosje dhcpcd[891]: wlan0: IAID eb:85:27:62 Mar 16 10:07:13 framboosje dhcpcd[891]: wlan0: adding address fe80::388d:40dd:44a8:f8e2 Mar 16 10:07:13 framboosje dhcpcd[891]: ipv6_addaddr1: Permission denied Mar 16 10:07:13 framboosje dhcpcd[891]: wlan0: carrier lost Mar 16 10:07:14 framboosje dhcpcd[891]: wlan0: carrier acquired Mar 16 10:07:14 framboosje dhcpcd[891]: wlan0: connected to Access Point `Gertruda' Mar 16 10:07:14 framboosje dhcpcd[891]: wlan0: IAID eb:85:27:62 Mar 16 10:07:14 framboosje wireless.js[608]: trying... Mar 16 10:07:14 framboosje sudo[912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 10:07:14 framboosje sudo[912]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:14 framboosje sudo[912]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:14 framboosje dhcpcd[891]: wlan0: rebinding lease of 192.168.2.11 Mar 16 10:07:14 framboosje dhcpcd[891]: wlan0: soliciting an IPv6 router Mar 16 10:07:15 framboosje wireless.js[608]: trying... Mar 16 10:07:15 framboosje sudo[915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 10:07:15 framboosje sudo[915]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:15 framboosje sudo[915]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:16 framboosje wireless.js[608]: trying... Mar 16 10:07:16 framboosje sudo[918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 10:07:16 framboosje sudo[918]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:16 framboosje sudo[918]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:17 framboosje wireless.js[608]: trying... Mar 16 10:07:17 framboosje sudo[921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 10:07:17 framboosje sudo[921]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:17 framboosje sudo[921]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:18 framboosje wireless.js[608]: trying... Mar 16 10:07:18 framboosje sudo[924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 10:07:18 framboosje sudo[924]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:18 framboosje sudo[924]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:18 framboosje volumio-time-update[607]: volumio-time-update-util: Fetching time from Volumio... Mar 16 10:07:18 framboosje volumio-time-update[607]: volumio-time-update-util: Date not found in response Mar 16 10:07:18 framboosje volumio-time-update[607]: volumio-time-update-util: Retrying in 5 seconds... Mar 16 10:07:18 framboosje dhcpcd[891]: wlan0: probing address 192.168.2.11/24 Mar 16 10:07:19 framboosje wireless.js[608]: trying... Mar 16 10:07:19 framboosje sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 10:07:19 framboosje sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:19 framboosje sudo[932]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:20 framboosje wireless.js[608]: trying... Mar 16 10:07:20 framboosje sudo[935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 10:07:20 framboosje sudo[935]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:20 framboosje sudo[935]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:20 framboosje volumio-remote-updater[603]: [2025-03-16 10:07:20] [info] asio async_connect error: system:111 (Connection refused) Mar 16 10:07:20 framboosje volumio-remote-updater[603]: [2025-03-16 10:07:20] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 16 10:07:20 framboosje volumio-remote-updater[603]: [2025-03-16 10:07:20] [error] handle_connect error: Underlying Transport Error Mar 16 10:07:21 framboosje wireless.js[608]: trying... Mar 16 10:07:21 framboosje sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 10:07:21 framboosje sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:21 framboosje sudo[938]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:22 framboosje wireless.js[608]: trying... Mar 16 10:07:22 framboosje sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 10:07:22 framboosje sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:22 framboosje sudo[941]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:23 framboosje wireless.js[608]: trying... Mar 16 10:07:23 framboosje sudo[947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 10:07:23 framboosje sudo[947]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:23 framboosje sudo[947]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:23 framboosje dhcpcd[891]: wlan0: leased 192.168.2.11 for 86400 seconds Mar 16 10:07:23 framboosje avahi-daemon[592]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.11. Mar 16 10:07:23 framboosje dhcpcd[891]: wlan0: adding route to 192.168.2.0/24 Mar 16 10:07:23 framboosje dhcpcd[891]: wlan0: adding default route via 192.168.2.254 Mar 16 10:07:23 framboosje avahi-daemon[592]: New relevant interface wlan0.IPv4 for mDNS. Mar 16 10:07:23 framboosje avahi-daemon[592]: Registering new address record for 192.168.2.11 on wlan0.IPv4. Mar 16 10:07:23 framboosje volumio-time-update[607]: volumio-time-update-util: Fetching time from Volumio... Mar 16 10:07:23 framboosje volumio-time-update[607]: volumio-time-update-util: Setting system time to: 2025-03-16 10:07:44 Mar 16 10:07:23 framboosje sudo[975]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-03-16 10:07:44 Mar 16 10:07:23 framboosje sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:23 framboosje dbus-daemon[596]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=976 comm="timedatectl set-time 2025-03-16 10:07:44 ") Mar 16 10:07:23 framboosje systemd[1]: Starting Time & Date Service... Mar 16 10:07:23 framboosje dbus-daemon[596]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 16 10:07:23 framboosje systemd[1]: Started Time & Date Service. Mar 16 10:07:44 framboosje systemd-timedated[977]: Changed local time to Sun Mar 16 10:07:44 2025 Mar 16 10:07:44 framboosje sudo[975]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:44 framboosje volumio-time-update[607]: volumio-time-update-util: System time updated successfully. Mar 16 10:07:44 framboosje systemd[1]: Started Volumio Time Update Utility. Mar 16 10:07:44 framboosje wireless.js[608]: trying... Mar 16 10:07:44 framboosje sudo[979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 16 10:07:44 framboosje sudo[979]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:44 framboosje sudo[979]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:44 framboosje wireless.js[608]: Connected to: ----Gertruda Mar 16 10:07:44 framboosje wireless.js[608]: ---- Mar 16 10:07:44 framboosje sudo[982]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 10:07:44 framboosje sudo[982]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:44 framboosje sudo[982]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:44 framboosje wireless.js[608]: ... joined AP, wlan0 IPv4 is 192.168.2.11, ipV6 is undefined Mar 16 10:07:44 framboosje wireless.js[608]: It's done! AP Mar 16 10:07:44 framboosje systemd[1]: Started Wireless Services. Mar 16 10:07:44 framboosje kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 16 10:07:44 framboosje systemd[1]: Started Volumio Backend Module. Mar 16 10:07:44 framboosje systemd[1]: Started Volumio Cpu Tweaker. Mar 16 10:07:44 framboosje volumio-cpu-tweak[988]: Setting RT Priority for mpd Mar 16 10:07:44 framboosje volumio-cpu-tweak[988]: Setting MPD Affinity Mar 16 10:07:44 framboosje volumio-cpu-tweak[988]: pid 800's current affinity mask: f Mar 16 10:07:44 framboosje volumio-cpu-tweak[988]: pid 800's new affinity mask: 3 Mar 16 10:07:44 framboosje volumio-cpu-tweak[988]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 16 10:07:44 framboosje volumio-cpu-tweak[988]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 16 10:07:44 framboosje systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 16 10:07:45 framboosje ntpd[784]: Listen normally on 3 wlan0 192.168.2.11:123 Mar 16 10:07:45 framboosje ntpd[784]: new interface(s) found: waking up resolver Mar 16 10:07:45 framboosje nmbd[754]: [2025/03/16 10:07:45.319645, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 16 10:07:45 framboosje nmbd[754]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 16 10:07:45 framboosje systemd[1]: Started Samba NMB Daemon. Mar 16 10:07:45 framboosje systemd[1]: Starting Samba Winbind Daemon... Mar 16 10:07:45 framboosje winbindd[1004]: [2025/03/16 10:07:45.719876, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 16 10:07:45 framboosje winbindd[1004]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 16 10:07:45 framboosje winbindd[1004]: [2025/03/16 10:07:45.746282, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 16 10:07:45 framboosje winbindd[1004]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 16 10:07:45 framboosje systemd[1]: Started Samba Winbind Daemon. Mar 16 10:07:45 framboosje systemd[1]: Starting Samba SMB Daemon... Mar 16 10:07:46 framboosje smbd[1009]: [2025/03/16 10:07:46.375404, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 16 10:07:46 framboosje systemd[1]: Started Samba SMB Daemon. Mar 16 10:07:46 framboosje smbd[1009]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 16 10:07:46 framboosje systemd[1]: Reached target Multi-User System. Mar 16 10:07:46 framboosje systemd[1]: Reached target Graphical Interface. Mar 16 10:07:46 framboosje systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 16 10:07:46 framboosje systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 16 10:07:46 framboosje systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 16 10:07:46 framboosje systemd[1]: Startup finished in 13.455s (kernel) + 23.812s (userspace) = 37.268s. Mar 16 10:07:47 framboosje volumio[987]: info: ------------------------------------------- Mar 16 10:07:47 framboosje volumio[987]: info: ----- Volumio3 ---- Mar 16 10:07:47 framboosje volumio[987]: info: ------------------------------------------- Mar 16 10:07:47 framboosje volumio[987]: info: ----- System startup ---- Mar 16 10:07:47 framboosje volumio[987]: info: ------------------------------------------- Mar 16 10:07:48 framboosje volumio[987]: info: MYVOLUMIO Environment detected Mar 16 10:07:48 framboosje volumio[987]: info: Plugin folders cleanup Mar 16 10:07:48 framboosje volumio[987]: info: Scanning into folder /volumio/app/plugins/ Mar 16 10:07:48 framboosje volumio[987]: info: Scanning category audio_interface Mar 16 10:07:48 framboosje volumio[987]: info: Scanning category miscellanea Mar 16 10:07:48 framboosje volumio[987]: info: Scanning category music_service Mar 16 10:07:48 framboosje volumio[987]: info: Scanning category plugins.json Mar 16 10:07:48 framboosje volumio[987]: info: Scanning category system_controller Mar 16 10:07:48 framboosje volumio[987]: info: Scanning category user_interface Mar 16 10:07:48 framboosje volumio[987]: info: Scanning into folder /data/plugins/ Mar 16 10:07:48 framboosje volumio[987]: info: Scanning category music_service Mar 16 10:07:48 framboosje volumio[987]: info: Plugin folders cleanup completed Mar 16 10:07:48 framboosje volumio[987]: info: ------------------------------------------- Mar 16 10:07:48 framboosje volumio[987]: info: ----- Core plugins startup ---- Mar 16 10:07:48 framboosje volumio[987]: info: ------------------------------------------- Mar 16 10:07:48 framboosje volumio[987]: info: Loading plugins from folder /volumio/app/plugins/ Mar 16 10:07:48 framboosje volumio[987]: info: Adding plugin upnp to MyMusic Plugins Mar 16 10:07:48 framboosje volumio[987]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 16 10:07:48 framboosje volumio[987]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 16 10:07:48 framboosje volumio[987]: info: Loading plugins from folder /data/plugins/ Mar 16 10:07:48 framboosje volumio[987]: info: Loading plugin "system"... Mar 16 10:07:48 framboosje volumio[987]: info: Loading plugin "appearance"... Mar 16 10:07:50 framboosje volumio[987]: info: Loading plugin "network"... Mar 16 10:07:50 framboosje volumio[987]: info: Refreshing Cached IP Addresses Mar 16 10:07:50 framboosje sudo[1032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 16 10:07:50 framboosje sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:50 framboosje sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 10:07:50 framboosje sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:50 framboosje sudo[1032]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:50 framboosje sudo[1034]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:50 framboosje volumio[987]: info: Loading plugin "services"... Mar 16 10:07:50 framboosje volumio[987]: info: Loading plugin "alsa_controller"... Mar 16 10:07:50 framboosje sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 16 10:07:50 framboosje sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:50 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 10:07:50 framboosje volumio[987]: info: Loading plugin "wizard"... Mar 16 10:07:50 framboosje volumio[987]: info: Loading plugin "networkfs"... Mar 16 10:07:50 framboosje volumio[987]: info: Starting Udev Watcher for removable devices Mar 16 10:07:50 framboosje volumio[987]: info: Ignoring mount for partition: boot Mar 16 10:07:50 framboosje volumio[987]: info: Ignoring mount for partition: volumio Mar 16 10:07:50 framboosje volumio[987]: info: Ignoring mount for partition: volumio_data Mar 16 10:07:50 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 16 10:07:50 framboosje volumio[987]: info: Loading plugin "volumio_command_line_client"... Mar 16 10:07:50 framboosje volumio[987]: info: Loading plugin "upnp"... Mar 16 10:07:50 framboosje volumio[987]: info: [1742116070758] Starting Upmpd Daemon Mar 16 10:07:50 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 16 10:07:50 framboosje volumio[987]: info: Loading plugin "my_music"... Mar 16 10:07:50 framboosje volumio[987]: info: Loading plugin "mpd"... Mar 16 10:07:50 framboosje volumio-remote-updater[603]: [2025-03-16 10:07:50] [connect] Successful connection Mar 16 10:07:51 framboosje volumio[987]: info: Loading plugin "upnp_browser"... Mar 16 10:07:51 framboosje sudo[1043]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:51 framboosje volumio[987]: info: Loading plugin "alarm-clock"... Mar 16 10:07:51 framboosje volumio[987]: info: Loading plugin "airplay_emulation"... Mar 16 10:07:51 framboosje volumio[987]: info: Starting Shairport Sync Mar 16 10:07:51 framboosje volumio[987]: info: Loading plugin "last_100"... Mar 16 10:07:51 framboosje volumio[987]: info: Loading plugin "webradio"... Mar 16 10:07:52 framboosje volumio[987]: info: Loading plugin "i2s_dacs"... Mar 16 10:07:52 framboosje volumio[987]: info: I2S DAC not set, start Auto-detection Mar 16 10:07:52 framboosje volumio[987]: info: Loading plugin "volumiodiscovery"... Mar 16 10:07:52 framboosje volumio[987]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 16 10:07:52 framboosje volumio[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 16 10:07:52 framboosje volumio[987]: *** WARNING *** For more information see Mar 16 10:07:52 framboosje volumio[987]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 16 10:07:52 framboosje node[987]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 16 10:07:52 framboosje volumio[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 16 10:07:52 framboosje volumio[987]: *** WARNING *** For more information see Mar 16 10:07:52 framboosje node[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 16 10:07:52 framboosje node[987]: *** WARNING *** For more information see Mar 16 10:07:52 framboosje node[987]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 16 10:07:52 framboosje node[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 16 10:07:52 framboosje node[987]: *** WARNING *** For more information see Mar 16 10:07:52 framboosje volumio[987]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 16 10:07:52 framboosje volumio[987]: info: Discovery: Started advertising with name: Framboosje Mar 16 10:07:52 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 16 10:07:52 framboosje volumio[987]: info: Loading plugin "spop"... Mar 16 10:07:54 framboosje volumio[987]: info: Loading plugin "outputs"... Mar 16 10:07:54 framboosje volumio[987]: info: Loading plugin "albumart"... Mar 16 10:07:54 framboosje volumio[987]: info: Plugin example_plugin is not enabled Mar 16 10:07:54 framboosje volumio[987]: info: Loading plugin "inputs"... Mar 16 10:07:54 framboosje volumio[987]: info: Loading plugin "updater_comm"... Mar 16 10:07:54 framboosje volumio[987]: info: Plugin mpdemulation is not enabled Mar 16 10:07:54 framboosje volumio[987]: info: Loading plugin "rest_api"... Mar 16 10:07:54 framboosje volumio[987]: info: Loading plugin "websocket"... Mar 16 10:07:54 framboosje volumio[987]: info: Starting Socket.io Server version 2.3.0 Mar 16 10:07:54 framboosje volumio[987]: info: Loading i18n strings for locale nl Mar 16 10:07:54 framboosje volumio[987]: Updating browse sources language Mar 16 10:07:54 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 10:07:54 framboosje volumio[987]: Forking 3 albumart workers Mar 16 10:07:54 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 10:07:54 framboosje volumio[987]: info: CoreCommandRouter::initPlayerControls Mar 16 10:07:54 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:54 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:54 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:54 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:54 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:54 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:54 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 10:07:54 framboosje volumio[987]: Express server listening on port 3000 Mar 16 10:07:54 framboosje volumio[987]: [Metrics] WebUI: 8s 428.50ms Mar 16 10:07:54 framboosje volumio[987]: info: CoreStateMachine::resetVolumioState Mar 16 10:07:54 framboosje volumio[987]: info: CoreStateMachine::getcurrentVolume Mar 16 10:07:54 framboosje volumio[987]: info: CoreCommandRouter::volumioRetrievevolume Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 10:07:55 framboosje volumio[987]: info: Volumio Network Manager: Network status updated: 2 Mar 16 10:07:55 framboosje volumio-remote-updater[603]: [2025-03-16 10:07:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1742116070 101 Mar 16 10:07:55 framboosje volumio[987]: 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 Mar 16 10:07:55 framboosje volumio[987]: info: VolumeController:: Volume=49 Mute =false Mar 16 10:07:55 framboosje volumio[987]: info: CoreStateMachine::pushState Mar 16 10:07:55 framboosje volumio[987]: info: CorePlayQueue::getTrack 0 Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::volumioPushState Mar 16 10:07:55 framboosje volumio[987]: info: CoreStateMachine::updateTrackBlock Mar 16 10:07:55 framboosje volumio[987]: info: CorePlayQueue::getTrackBlock Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::volumioRetrievevolume Mar 16 10:07:55 framboosje volumio[987]: info: Reloading queue from file Mar 16 10:07:55 framboosje volumio[987]: info: CoreStateMachine::setRepeat null single undefined Mar 16 10:07:55 framboosje volumio[987]: info: CoreStateMachine::pushState Mar 16 10:07:55 framboosje volumio[987]: info: CorePlayQueue::getTrack 0 Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::volumioPushState Mar 16 10:07:55 framboosje volumio[987]: info: CoreStateMachine::setRandom null Mar 16 10:07:55 framboosje volumio[987]: info: CoreStateMachine::pushState Mar 16 10:07:55 framboosje volumio[987]: info: CorePlayQueue::getTrack 0 Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::volumioPushState Mar 16 10:07:55 framboosje volumio[987]: info: Setting Device type: Raspberry PI Mar 16 10:07:55 framboosje volumio[987]: info: Completed loading Core Plugins Mar 16 10:07:55 framboosje volumio[987]: info: Preparing to generate the ALSA configuration file Mar 16 10:07:55 framboosje volumio[987]: info: Discovery: adding f71c7ff4-e2d8-4204-83ec-95f2121ab27d Mar 16 10:07:55 framboosje volumio[987]: info: Discovery: Found device Framboosje Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::volumioGetState Mar 16 10:07:55 framboosje volumio[987]: info: CorePlayQueue::getTrack 0 Mar 16 10:07:55 framboosje volumio[987]: info: VolumeController:: Volume=49 Mute =false Mar 16 10:07:55 framboosje volumio[987]: info: CoreStateMachine::pushState Mar 16 10:07:55 framboosje volumio[987]: info: CorePlayQueue::getTrack 0 Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::volumioPushState Mar 16 10:07:55 framboosje volumio[987]: info: Asound.conf file unchanged, so no further update is needed Mar 16 10:07:55 framboosje volumio[987]: info: Output device has changed, restarting MPD Mar 16 10:07:55 framboosje volumio[987]: info: Output device has changed, restarting Shairport Sync Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 10:07:55 framboosje sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 16 10:07:55 framboosje sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:55 framboosje sudo[1105]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:55 framboosje sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 16 10:07:55 framboosje sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:55 framboosje volumio[987]: Starting albumart workers Mar 16 10:07:55 framboosje volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 16 10:07:55 framboosje volumio[987]: info: ___________ START PLUGINS ___________ Mar 16 10:07:55 framboosje volumio[987]: info: ControllerMpd::onStart: Initializing MPD Mar 16 10:07:55 framboosje volumio[987]: info: Creating MPD Configuration file Mar 16 10:07:55 framboosje systemd[1]: Stopping Music Player Daemon... Mar 16 10:07:55 framboosje volumio[987]: Starting albumart workers Mar 16 10:07:55 framboosje volumio[987]: Starting albumart workers Mar 16 10:07:55 framboosje sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 10:07:55 framboosje sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 10:07:55 framboosje volumio[987]: info: [1742116075740] CoreMusicLibrary::Adding element Media Servers Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 10:07:55 framboosje sudo[1114]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:55 framboosje sudo[1116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 16 10:07:55 framboosje sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 10:07:55 framboosje volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 10:07:55 framboosje systemd[1]: mpd.service: Succeeded. Mar 16 10:07:55 framboosje systemd[1]: Stopped Music Player Daemon. Mar 16 10:07:55 framboosje volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 10:07:55 framboosje volumio[987]: info: [1742116075915] CoreMusicLibrary::Adding element Last_100 Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 10:07:55 framboosje volumio[987]: info: [1742116075919] CoreMusicLibrary::Adding element Webradio Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 10:07:55 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 10:07:55 framboosje systemd[1]: Starting Music Player Daemon... Mar 16 10:07:55 framboosje volumio[987]: info: Initializing BBC Radios Mar 16 10:07:56 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 10:07:56 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 10:07:56 framboosje volumio[987]: info: Creating Spotify config file Mar 16 10:07:56 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:56 framboosje sudo[1122]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 16 10:07:56 framboosje sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:56 framboosje sudo[1122]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:56 framboosje systemd[1]: systemd-fsckd.service: Succeeded. Mar 16 10:07:56 framboosje volumio[987]: info: Volumio Calling Home Mar 16 10:07:56 framboosje volumio[987]: info: MPD Permissions set Mar 16 10:07:56 framboosje volumio[987]: info: MPD Permissions set Mar 16 10:07:56 framboosje volumio[987]: info: Spotify config file written Mar 16 10:07:56 framboosje sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 16 10:07:56 framboosje sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:56 framboosje systemd[1]: Started go-librespot Daemon. Mar 16 10:07:56 framboosje go-librespot[1152]: Librespot-go daemon starting... Mar 16 10:07:56 framboosje sudo[1150]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:56 framboosje volumio[987]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 16 10:07:56 framboosje volumio[987]: info: Volumio called home Mar 16 10:07:56 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:56 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:56 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:56 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:56 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:56 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:56 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:56 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:56 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 10:07:57 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:57 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:57 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:57 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:57 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:57 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:57 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:57 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:57 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 10:07:57 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 10:07:57 framboosje volumio[987]: info: No need to fix Spotify hosts Mar 16 10:07:57 framboosje volumio[987]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 16 10:07:57 framboosje volumio[987]: SPOTIFY: BQAk26V9OGHZx1clv3bvMjXXefLh7fWp4L3e7skHF4ELWyy-kvfX7Trhky6BxytBpvkqTD9RTxV7PhYY2hADff1tZDj34mbtF33nq_NLyNV0pmMku_MTcQ1RClJOIe26omqI2GchhmnFazQOXxSqnHxHMwBbGb5Ub9eI-iE0aJ-YOK8RvBL1r0DL9VYyRdbPbJ3P04uglD72KomE5eN_DGjhd3OeRy8qtC0eS9NJePAWhDG-OiUPFHSCl515HZlmASofpN1jOb5tMZEodN4 Mar 16 10:07:57 framboosje volumio[987]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 16 10:07:57 framboosje volumio[987]: info: New Spotify access token = BQAk26V9OGHZx1clv3bvMjXXefLh7fWp4L3e7skHF4ELWyy-kvfX7Trhky6BxytBpvkqTD9RTxV7PhYY2hADff1tZDj34mbtF33nq_NLyNV0pmMku_MTcQ1RClJOIe26omqI2GchhmnFazQOXxSqnHxHMwBbGb5Ub9eI-iE0aJ-YOK8RvBL1r0DL9VYyRdbPbJ3P04uglD72KomE5eN_DGjhd3OeRy8qtC0eS9NJePAWhDG-OiUPFHSCl515HZlmASofpN1jOb5tMZEodN4 Mar 16 10:07:57 framboosje volumio[987]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 16 10:07:57 framboosje volumio[987]: info: Starting Shairport Sync Mar 16 10:07:57 framboosje volumio[987]: info: Starting Shairport Sync Mar 16 10:07:57 framboosje volumio[987]: info: Starting Shairport Sync Mar 16 10:07:57 framboosje sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 10:07:57 framboosje sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:57 framboosje sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 10:07:57 framboosje sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:57 framboosje sh[510]: timed out Mar 16 10:07:57 framboosje dhcpcd[537]: timed out Mar 16 10:07:57 framboosje sh[510]: dhcpcd exited Mar 16 10:07:57 framboosje dhcpcd[537]: dhcpcd exited Mar 16 10:07:57 framboosje sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 10:07:57 framboosje sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:07:57 framboosje sh[510]: ifup: failed to bring up eth0 Mar 16 10:07:57 framboosje systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 16 10:07:57 framboosje systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 16 10:07:57 framboosje systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 16 10:07:57 framboosje go-librespot[1152]: time="2025-03-16T10:07:57+01:00" level=info msg="generated new device id: 4a849c423317a5ac82f27d828563a76a50995205" Mar 16 10:07:57 framboosje systemd[1]: shairport-sync.service: Succeeded. Mar 16 10:07:57 framboosje go-librespot[1152]: time="2025-03-16T10:07:57+01:00" level=debug msg="stored credentials found for rikmartens" Mar 16 10:07:57 framboosje volumio[987]: info: CoreCommandRouter::volumioGetState Mar 16 10:07:57 framboosje volumio[987]: info: CorePlayQueue::getTrack 0 Mar 16 10:07:57 framboosje systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 16 10:07:57 framboosje systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 10:07:57 framboosje sudo[1184]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:57 framboosje sudo[1186]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:57 framboosje systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 16 10:07:57 framboosje systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 16 10:07:57 framboosje systemd[1]: shairport-sync.service: Succeeded. Mar 16 10:07:57 framboosje systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 16 10:07:57 framboosje systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 10:07:57 framboosje sudo[1189]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:57 framboosje volumio[987]: info: Shairport-Sync Started Mar 16 10:07:57 framboosje volumio[987]: Error adding Membership: Error: addMembership EINVAL Mar 16 10:07:57 framboosje volumio[987]: info: Shairport-Sync Started Mar 16 10:07:57 framboosje volumio[987]: info: Shairport-Sync Started Mar 16 10:07:57 framboosje volumio[987]: SPOTIFY: User informations: {"country":"NL","display_name":"rikmartens","email":"rik@ik.me","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/rikmartens"},"followers":{"href":null,"total":8},"href":"https://api.spotify.com/v1/users/rikmartens","id":"rikmartens","images":[],"product":"premium","type":"user","uri":"spotify:user:rikmartens"} Mar 16 10:07:57 framboosje volumio[987]: info: Spotify Successfully logged in Mar 16 10:07:57 framboosje volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 16 10:07:57 framboosje volumio[987]: info: [1742116077660] CoreMusicLibrary::Adding element Spotify Mar 16 10:07:57 framboosje volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 10:07:57 framboosje volumio[987]: Cannot find translation for source Spotify Mar 16 10:07:57 framboosje go-librespot[1152]: time="2025-03-16T10:07:57+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 16 10:07:57 framboosje go-librespot[1152]: time="2025-03-16T10:07:57+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 16 10:07:57 framboosje go-librespot[1152]: time="2025-03-16T10:07:57+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 16 10:07:57 framboosje go-librespot[1152]: time="2025-03-16T10:07:57+01:00" level=debug msg="zeroconf server listening on port 42263" Mar 16 10:07:57 framboosje go-librespot[1152]: time="2025-03-16T10:07:57+01:00" level=debug msg="obtained new client token: AAAvAgMw02JRByNFWHBLpX3GoxXq5kAFt0RstOYdR7+nKymle624sTscOEEmmEg1MUAR0T7+KUUTxafjkwx1h7LuWkFTqx6n7cfJSKeC+emLsqKTjdeBkU3GJ6VTq43sZMaOzFKs3HLXj1drbGIjYj/cSOSvOPiR4zupvhKuGFjbXV3jU7MTy94XGtuFbPIUGZJW3NZWcXfdSJRMki4hu0m6rnAluhQp+eM/B5BIhZL8zM69eaelwTtcR34d" Mar 16 10:07:57 framboosje go-librespot[1152]: time="2025-03-16T10:07:57+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 16 10:07:57 framboosje go-librespot[1152]: time="2025-03-16T10:07:57+01:00" level=debug msg="completed keyexchange" Mar 16 10:07:58 framboosje mpd[1137]: Mar 16 10:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 16 10:07:58 framboosje systemd[1]: Started Music Player Daemon. Mar 16 10:07:58 framboosje sudo[1116]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:58 framboosje sudo[1107]: pam_unix(sudo:session): session closed for user root Mar 16 10:07:58 framboosje volumio[987]: info: Completed starting Core Plugins Mar 16 10:07:58 framboosje volumio[987]: info: ------------------------------------------- Mar 16 10:07:58 framboosje volumio[987]: info: ----- MyVolumio plugins startup ---- Mar 16 10:07:58 framboosje volumio[987]: info: ------------------------------------------- Mar 16 10:07:58 framboosje volumio[987]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 16 10:07:58 framboosje volumio[987]: error: MPD error: The expression evaluated to a falsy value: Mar 16 10:07:58 framboosje volumio[987]: assert.ok(self.idling) Mar 16 10:07:58 framboosje volumio[987]: error: The expression evaluated to a falsy value: Mar 16 10:07:58 framboosje volumio[987]: assert.ok(self.idling) Mar 16 10:07:58 framboosje volumio[987]: info: MPD running with PID1137 Mar 16 10:07:58 framboosje volumio[987]: ,establishing connection Mar 16 10:07:58 framboosje volumio[987]: error: MPD error: The expression evaluated to a falsy value: Mar 16 10:07:58 framboosje volumio[987]: assert.ok(self.idling) Mar 16 10:07:58 framboosje volumio[987]: error: The expression evaluated to a falsy value: Mar 16 10:07:58 framboosje volumio[987]: assert.ok(self.idling) Mar 16 10:07:58 framboosje volumio[987]: error: updateQueue error: null Mar 16 10:07:58 framboosje go-librespot[1152]: time="2025-03-16T10:07:58+01:00" level=debug msg="completed challenge" Mar 16 10:07:58 framboosje go-librespot[1152]: time="2025-03-16T10:07:58+01:00" level=debug msg="authenticated as rikmartens" Mar 16 10:07:58 framboosje go-librespot[1152]: time="2025-03-16T10:07:58+01:00" level=debug msg="authenticated as rikmartens" Mar 16 10:07:58 framboosje go-librespot[1152]: time="2025-03-16T10:07:58+01:00" level=debug msg="dealer connection opened" Mar 16 10:07:58 framboosje go-librespot[1152]: time="2025-03-16T10:07:58+01:00" level=debug msg="initializing zeroconf session, username: rikmartens" Mar 16 10:07:58 framboosje go-librespot[1152]: time="2025-03-16T10:07:58+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 16 10:07:58 framboosje go-librespot[1152]: time="2025-03-16T10:07:58+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 16 10:07:58 framboosje go-librespot[1152]: time="2025-03-16T10:07:58+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 16 10:07:58 framboosje go-librespot[1152]: time="2025-03-16T10:07:58+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 16 10:07:58 framboosje go-librespot[1152]: time="2025-03-16T10:07:58+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 16 10:07:58 framboosje go-librespot[1152]: time="2025-03-16T10:07:58+01:00" level=debug msg="autoplay enabled: false" Mar 16 10:07:58 framboosje go-librespot[1152]: time="2025-03-16T10:07:58+01:00" level=debug msg="received connection id: NmI5NTJkYmQtY2U4NS00M2ViLTg5NWYtOTQ0ZmIzNmQxOTg2K2RlYWxlcit0Y3A6Ly8wYWNhNWE1My5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArMTlERkMxQzBFOEEyMjk4NDEzODUzRTRDOTdCNTFFOTA5Q0QxRERGQkM5MzczQ0Q5NEExRUIyNEMzMEQ1NDlGQw==" Mar 16 10:07:58 framboosje go-librespot[1152]: time="2025-03-16T10:07:58+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 16 10:07:59 framboosje systemd[1]: systemd-hostnamed.service: Succeeded. Mar 16 10:08:00 framboosje volumio[987]: info: go-librespot daemon successfully initialized Mar 16 10:08:00 framboosje sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 16 10:08:00 framboosje sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:08:00 framboosje sudo[1222]: pam_unix(sudo:session): session closed for user root Mar 16 10:08:00 framboosje sudo[1224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 16 10:08:00 framboosje sudo[1224]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:08:00 framboosje sudo[1224]: pam_unix(sudo:session): session closed for user root Mar 16 10:08:00 framboosje sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 16 10:08:00 framboosje sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:08:00 framboosje systemd[1]: Started UPnP Renderer front-end to MPD. Mar 16 10:08:00 framboosje sudo[1228]: pam_unix(sudo:session): session closed for user root Mar 16 10:08:00 framboosje volumio[987]: info: Upmpdcli Daemon Started Mar 16 10:08:01 framboosje volumio[1230]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 16 10:08:03 framboosje volumio[987]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 16 10:08:03 framboosje volumio[987]: info: Initializing connection to go-librespot Websocket Mar 16 10:08:03 framboosje volumio[987]: info: Connection to go-librespot Websocket established Mar 16 10:08:03 framboosje go-librespot[1152]: time="2025-03-16T10:08:03+01:00" level=debug msg="new websocket client" Mar 16 10:08:04 framboosje go-librespot[1152]: time="2025-03-16T10:08:04+01:00" level=debug msg="handling transfer player command from 890211b363256689a20f62ab4b0a495a450ea0ba" Mar 16 10:08:04 framboosje go-librespot[1152]: time="2025-03-16T10:08:04+01:00" level=debug msg="resolved context of track" uri="spotify:artist:7x5rK9BClDQ8wmCkYAGsQp" Mar 16 10:08:04 framboosje go-librespot[1152]: time="2025-03-16T10:08:04+01:00" level=trace msg="fetched new page 0 with 33 items (list: 33)" uri="spotify:artist:7x5rK9BClDQ8wmCkYAGsQp" Mar 16 10:08:04 framboosje go-librespot[1152]: time="2025-03-16T10:08:04+01:00" level=debug msg="loading track (paused: false, position: 373894ms)" uri="spotify:track:4h5m56ExHLPUC9NGz7U1Qa" Mar 16 10:08:04 framboosje go-librespot[1152]: time="2025-03-16T10:08:04+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Mar 16 10:08:04 framboosje go-librespot[1152]: time="2025-03-16T10:08:04+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1117" Mar 16 10:08:04 framboosje go-librespot[1152]: time="2025-03-16T10:08:04+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 16 10:08:04 framboosje go-librespot[1152]: time="2025-03-16T10:08:04+01:00" level=trace msg="emitting websocket event: will_play" Mar 16 10:08:04 framboosje volumio[987]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4h5m56ExHLPUC9NGz7U1Qa","play_origin":"com.spotify.service.freetierartist"}} Mar 16 10:08:04 framboosje go-librespot[1152]: time="2025-03-16T10:08:04+01:00" level=debug msg="selected format OGG_VORBIS_320 (0130ddc9d6466403c6806bf8c815347487a375f7)" uri="spotify:track:4h5m56ExHLPUC9NGz7U1Qa" Mar 16 10:08:04 framboosje go-librespot[1152]: time="2025-03-16T10:08:04+01:00" level=debug msg="requested aes key for file 0130ddc9d6466403c6806bf8c815347487a375f7, gid: 4h5m56ExHLPUC9NGz7U1Qa" Mar 16 10:08:04 framboosje go-librespot[1152]: time="2025-03-16T10:08:04+01:00" level=debug msg="fetched first chunk of 25, total size is 12765656 bytes" uri="spotify:track:4h5m56ExHLPUC9NGz7U1Qa" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="fetched chunk 1/24, size: 524288" uri="spotify:track:4h5m56ExHLPUC9NGz7U1Qa" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="fetched chunk 24/24, size: 182744" uri="spotify:track:4h5m56ExHLPUC9NGz7U1Qa" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=trace msg="seek to 298693ms (diff: 887ms, samples: 13172361, bytes: 12744642)" uri="spotify:track:4h5m56ExHLPUC9NGz7U1Qa" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="fetched chunk 2/24, size: 524288" uri="spotify:track:4h5m56ExHLPUC9NGz7U1Qa" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 10068 us, period size = 444 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 49 frames" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="created new output device" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=info msg="loaded track \"Candy\" (paused: false, position: 373894ms, duration: 298693ms, prefetched: false)" uri="spotify:track:4h5m56ExHLPUC9NGz7U1Qa" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="fetched chunk 3/24, size: 524288" uri="spotify:track:4h5m56ExHLPUC9NGz7U1Qa" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="prefetching next track" uri="spotify:track:6xM8oBy40nK1rOd8WmoOPx" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=trace msg="emitting websocket event: metadata" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=trace msg="emitting websocket event: active" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="sending successful reply for dealer request" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 16 10:08:05 framboosje volumio[987]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4h5m56ExHLPUC9NGz7U1Qa","name":"Candy","artist_names":["Paolo Nutini"],"album_name":"Sunny Side Up","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026f95446c5dd8806464b836e6","position":373894,"duration":298693,"release_date":"year:2009 month:5 day:29","track_number":4,"disc_number":1}} Mar 16 10:08:05 framboosje volumio[987]: SPOTIFY: received: {"type":"active","data":null} Mar 16 10:08:05 framboosje volumio[987]: info: Aligning Spotify Volume to Volumio Volume Mar 16 10:08:05 framboosje volumio[987]: info: CoreCommandRouter::volumioGetState Mar 16 10:08:05 framboosje volumio[987]: info: CorePlayQueue::getTrack 0 Mar 16 10:08:05 framboosje volumio[987]: info: Setting Spotify Volume from Volumio: 49 Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="selected format OGG_VORBIS_320 (0270100864f049720c798d6bef859d70cc25232d)" uri="spotify:track:6xM8oBy40nK1rOd8WmoOPx" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="requested aes key for file 0270100864f049720c798d6bef859d70cc25232d, gid: 6xM8oBy40nK1rOd8WmoOPx" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=trace msg="emitting websocket event: playing" Mar 16 10:08:05 framboosje volumio[987]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4h5m56ExHLPUC9NGz7U1Qa","play_origin":"com.spotify.service.freetierartist"}} Mar 16 10:08:05 framboosje volumio[987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 10:08:05 framboosje volumio[987]: TypeError: Cannot read property 'service' of undefined Mar 16 10:08:05 framboosje volumio[987]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Mar 16 10:08:05 framboosje volumio[987]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Mar 16 10:08:05 framboosje volumio[987]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Mar 16 10:08:05 framboosje volumio[987]: at WebSocket.emit (events.js:315:20) Mar 16 10:08:05 framboosje volumio[987]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Mar 16 10:08:05 framboosje volumio[987]: at Receiver.emit (events.js:315:20) Mar 16 10:08:05 framboosje volumio[987]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Mar 16 10:08:05 framboosje volumio[987]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Mar 16 10:08:05 framboosje volumio[987]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Mar 16 10:08:05 framboosje volumio[987]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Mar 16 10:08:05 framboosje volumio[987]: at writeOrBuffer (internal/streams/writable.js:358:12) Mar 16 10:08:05 framboosje volumio[987]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Mar 16 10:08:05 framboosje volumio[987]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Mar 16 10:08:05 framboosje volumio[987]: at Socket.emit (events.js:315:20) Mar 16 10:08:05 framboosje volumio[987]: at addChunk (internal/streams/readable.js:309:12) Mar 16 10:08:05 framboosje volumio[987]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 16 10:08:05 framboosje volumio[987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=trace msg="emitting websocket event: not_playing" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="fetched first chunk of 19, total size is 9525476 bytes" uri="spotify:track:6xM8oBy40nK1rOd8WmoOPx" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6xM8oBy40nK1rOd8WmoOPx" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6xM8oBy40nK1rOd8WmoOPx" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=trace msg="emitting websocket event: will_play" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:6xM8oBy40nK1rOd8WmoOPx" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:6xM8oBy40nK1rOd8WmoOPx" Mar 16 10:08:05 framboosje go-librespot[1152]: time="2025-03-16T10:08:05+01:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:6xM8oBy40nK1rOd8WmoOPx" Mar 16 10:08:06 framboosje go-librespot[1152]: time="2025-03-16T10:08:06+01:00" level=trace msg="cleared closed output device" Mar 16 10:08:06 framboosje go-librespot[1152]: panic: runtime error: invalid memory address or nil pointer dereference Mar 16 10:08:06 framboosje go-librespot[1152]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x8 pc=0x57277c] Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 150 gp=0x208d0e8 m=0 mp=0xaa9b38 [running]: Mar 16 10:08:06 framboosje go-librespot[1152]: panic({0x5eab20, 0xa60098}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:779 +0x12c fp=0x20fbde8 sp=0x20fbd94 pc=0x58364 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.panicmem(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:261 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.sigpanic() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/signal_unix.go:881 +0x39c fp=0x20fbe18 sp=0x20fbde8 pc=0x74350 Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*AppPlayer).prefetchNext(0x1c9c320) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/controls.go:42 +0x4c4 fp=0x20fbfe4 sp=0x20fbe1c pc=0x57277c Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*AppPlayer).schedulePrefetchNext.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/controls.go:56 +0x28 fp=0x20fbfec sp=0x20fbfe4 pc=0x572d68 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x20fbfec sp=0x20fbfec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by main.(*AppPlayer).schedulePrefetchNext in goroutine 61 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/controls.go:56 +0x1c8 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 1 gp=0x1c02128 m=nil [select]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x20f8d0c sp=0x20f8cf8 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.selectgo(0x20f8e0c, 0x1cd3dd8, 0x0, 0x0, 0x2, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x20f8dac sp=0x20f8d0c pc=0x705e4 Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/zeroconf.(*Zeroconf).Serve(0x1c9c140, 0x20f8e88) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:268 +0x228 fp=0x20f8e50 sp=0x20f8dac pc=0x528c80 Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*App).withAppPlayer(0x1c9a210, 0x1c971c0) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:297 +0x4b0 fp=0x20f8ec0 sp=0x20f8e50 pc=0x578aac Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*App).withCredentials(0x1c9a210, {0x5f3170, 0x1c90290}) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:171 +0x3d4 fp=0x20f8f1c sp=0x20f8ec0 pc=0x578184 Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*App).SpotifyToken(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:131 Mar 16 10:08:06 framboosje go-librespot[1152]: main.main() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:466 +0x530 fp=0x20f8fa8 sp=0x20f8f1c pc=0x57a0cc Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.main() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:271 +0x2fc fp=0x20f8fec sp=0x20f8fa8 pc=0x5c29c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x20f8fec sp=0x20f8fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 18 gp=0x1c84248 m=nil [force gc (idle)]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f938, 0xaa84c0, 0x11, 0xa, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c3e7d4 sp=0x1c3e7c0 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goparkunlock(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.forcegchelper() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:326 +0xe4 fp=0x1c3e7ec sp=0x1c3e7d4 pc=0x5c668 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c3e7ec sp=0x1c3e7ec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by runtime.init.5 in goroutine 1 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:314 +0x1c Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 19 gp=0x1c84368 m=nil [GC sweep wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f938, 0xaa8a10, 0xc, 0x9, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c3efc4 sp=0x1c3efb0 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goparkunlock(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.bgsweep(0x1c92000) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcsweep.go:318 +0x11c fp=0x1c3efe4 sp=0x1c3efc4 pc=0x4506c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gcenable.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:203 +0x28 fp=0x1c3efec sp=0x1c3efe4 pc=0x3518c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c3efec sp=0x1c3efec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by runtime.gcenable in goroutine 1 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:203 +0x74 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 20 gp=0x1c84488 m=nil [GC scavenge wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f938, 0xaa9020, 0xd, 0xa, 0x2) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c3f7b4 sp=0x1c3f7a0 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goparkunlock(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.(*scavengerState).park(0xaa9020) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcscavenge.go:425 +0x68 fp=0x1c3f7c8 sp=0x1c3f7b4 pc=0x4238c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.bgscavenge(0x1c92000) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcscavenge.go:658 +0x60 fp=0x1c3f7e4 sp=0x1c3f7c8 pc=0x42ac4 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gcenable.gowrap2() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:204 +0x28 fp=0x1c3f7ec sp=0x1c3f7e4 pc=0x35138 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c3f7ec sp=0x1c3f7ec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by runtime.gcenable in goroutine 1 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:204 +0xbc Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 2 gp=0x1c02ea8 m=nil [finalizer wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f7e4, 0xad3bac, 0x10, 0xa, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c42f8c sp=0x1c42f78 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.runfinq() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mfinal.go:194 +0x110 fp=0x1c42fec sp=0x1c42f8c pc=0x33f78 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c42fec sp=0x1c42fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by runtime.createfing in goroutine 1 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mfinal.go:164 +0x5c Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 21 gp=0x1c84908 m=nil [IO wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f928, 0x74ec2f08, 0x2, 0x2, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c3fddc sp=0x1c3fdc8 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.netpollblock(0x74ec2ef8, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x1c3fdf4 sp=0x1c3fddc pc=0x540f0 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.runtime_pollWait(0x74ec2ef8, 0x72) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1c3fe08 sp=0x1c3fdf4 pc=0x8eba8 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).wait(0x1c9ce78, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c3fe1c sp=0x1c3fe08 pc=0x108108 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).waitRead(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*FD).Accept(0x1c9ce60) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1c3fe64 sp=0x1c3fe1c pc=0x10c974 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*netFD).accept(0x1c9ce60) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_unix.go:172 +0x20 fp=0x1c3fecc sp=0x1c3fe64 pc=0x2a2504 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*TCPListener).accept(0x1cbc2e8) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock_posix.go:159 +0x20 fp=0x1c3fee4 sp=0x1c3fecc pc=0x2ba604 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*TCPListener).Accept(0x1cbc2e8) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock.go:327 +0x30 fp=0x1c3ff00 sp=0x1c3fee4 pc=0x2b9670 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*onceCloseListener).Accept(0x1d02e00) Mar 16 10:08:06 framboosje go-librespot[1152]: :1 +0x34 fp=0x1c3ff18 sp=0x1c3ff00 pc=0x45e914 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*Server).Serve(0x1d46dc8, {0x72527c, 0x1cbc2e8}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3255 +0x314 fp=0x1c3ffb0 sp=0x1c3ff18 pc=0x43cfd0 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.Serve(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2794 Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*ApiServer).serve(0x1c996c0) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:470 +0x5ec fp=0x1c3ffe4 sp=0x1c3ffb0 pc=0x57084c Mar 16 10:08:06 framboosje go-librespot[1152]: main.NewApiServer.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:240 +0x28 fp=0x1c3ffec sp=0x1c3ffe4 pc=0x56ff20 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c3ffec sp=0x1c3ffec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by main.NewApiServer in goroutine 1 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:240 +0x224 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 103 gp=0x1d40488 m=nil [IO wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f928, 0x74ec28c8, 0x2, 0x2, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c558a4 sp=0x1c55890 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.netpollblock(0x74ec28b8, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x1c558bc sp=0x1c558a4 pc=0x540f0 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.runtime_pollWait(0x74ec28b8, 0x72) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1c558d0 sp=0x1c558bc pc=0x8eba8 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).wait(0x1dd21a8, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c558e4 sp=0x1c558d0 pc=0x108108 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).waitRead(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*FD).Read(0x1dd2190, {0x2073000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1c5592c sp=0x1c558e4 pc=0x1092f4 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*netFD).Read(0x1dd2190, {0x2073000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x1c55958 sp=0x1c5592c pc=0x2a0610 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*conn).Read(0x20525d0, {0x2073000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x1c55984 sp=0x1c55958 pc=0x2b07f0 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*TCPConn).Read(0x20525d0, {0x2073000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: :1 +0x44 fp=0x1c559a4 sp=0x1c55984 pc=0x2c3a44 Mar 16 10:08:06 framboosje go-librespot[1152]: io.(*multiReader).Read(0x1c0f330, {0x2073000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/multi.go:26 +0xb4 fp=0x1c559d0 sp=0x1c559a4 pc=0x103318 Mar 16 10:08:06 framboosje go-librespot[1152]: bufio.(*Reader).fill(0x20551a0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x1c559f4 sp=0x1c559d0 pc=0x15a4b8 Mar 16 10:08:06 framboosje go-librespot[1152]: bufio.(*Reader).ReadByte(0x20551a0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:269 +0x28 fp=0x1c55a00 sp=0x1c559f4 pc=0x15ad3c Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.readFrameHeader(0x20551a0, {0x1f1aa38, 0x8, 0x8}) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1c55a40 sp=0x1c55a00 pc=0x4771e0 Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x1f1aa08, {0x725688, 0xad3b20}) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1c55b00 sp=0x1c55a40 pc=0x479e44 Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.(*Conn).readLoop(0x1f1aa08, {0x725688, 0xad3b20}) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1c55bc4 sp=0x1c55b00 pc=0x47992c Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.(*Conn).reader(0x1f1aa08, {0x725688, 0xad3b20}) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1c55c50 sp=0x1c55bc4 pc=0x47ab40 Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.(*Conn).Reader(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.(*Conn).Read(0x1f1aa08, {0x725688, 0xad3b20}) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1c55c78 sp=0x1c55c50 pc=0x479210 Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*ApiServer).serve.func14({0x7253b0, 0x1c7e5a8}, 0x1c7e508) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:450 +0x22c fp=0x1c55cd0 sp=0x1c55c78 pc=0x570c88 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.HandlerFunc.ServeHTTP(0x1d00270, {0x7253b0, 0x1c7e5a8}, 0x1c7e508) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x1c55ce0 sp=0x1c55cd0 pc=0x4395b4 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*ServeMux).ServeHTTP(0x1d38400, {0x7253b0, 0x1c7e5a8}, 0x1c7e508) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x1c55d1c sp=0x1c55ce0 pc=0x43b414 Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x1c7e5a8}, 0x1c7e508) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x1c55d40 sp=0x1c55d1c pc=0x570a44 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.HandlerFunc.ServeHTTP(0x1d0c370, {0x7253b0, 0x1c7e5a8}, 0x1c7e508) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x1c55d50 sp=0x1c55d40 pc=0x4395b4 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.serverHandler.ServeHTTP({0x1d46dc8}, {0x7253b0, 0x1c7e5a8}, 0x1c7e508) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x1c55d6c sp=0x1c55d50 pc=0x43cba4 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*conn).serve(0x1da0300, {0x7256a8, 0x1d161b0}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x1c55fdc sp=0x1c55d6c pc=0x437df4 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*Server).Serve.gowrap3() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x1c55fec sp=0x1c55fdc pc=0x43d448 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c55fec sp=0x1c55fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by net/http.(*Server).Serve in goroutine 21 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 24 gp=0x1c84a28 m=nil [IO wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f928, 0x74ec2e68, 0x2, 0x2, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x20a8ad4 sp=0x20a8ac0 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.netpollblock(0x74ec2e58, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x20a8aec sp=0x20a8ad4 pc=0x540f0 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.runtime_pollWait(0x74ec2e58, 0x72) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x20a8b00 sp=0x20a8aec pc=0x8eba8 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).wait(0x1c26108, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20a8b14 sp=0x20a8b00 pc=0x108108 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).waitRead(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*FD).Read(0x1c260f0, {0x1e32000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x20a8b5c sp=0x20a8b14 pc=0x1092f4 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*netFD).Read(0x1c260f0, {0x1e32000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x20a8b88 sp=0x20a8b5c pc=0x2a0610 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*conn).Read(0x1d00330, {0x1e32000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x20a8bb4 sp=0x20a8b88 pc=0x2b07f0 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*TCPConn).Read(0x1d00330, {0x1e32000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: :1 +0x44 fp=0x20a8bd4 sp=0x20a8bb4 pc=0x2c3a44 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*atLeastReader).Read(0x1f7e010, {0x1e32000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x20a8c00 sp=0x20a8bd4 pc=0x386018 Mar 16 10:08:06 framboosje go-librespot[1152]: bytes.(*Buffer).ReadFrom(0x1d4260c, {0x723a98, 0x1f7e010}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x20a8c3c sp=0x20a8c00 pc=0x136d6c Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readFromUntil(0x1d42488, {0x722604, 0x1d00330}, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x20a8c64 sp=0x20a8c3c pc=0x386274 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readRecordOrCCS(0x1d42488, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x20a8dc8 sp=0x20a8c64 pc=0x383974 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readRecord(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).Read(0x1d42488, {0x2040000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x20a8df8 sp=0x20a8dc8 pc=0x3897e4 Mar 16 10:08:06 framboosje go-librespot[1152]: bufio.(*Reader).Read(0x1c9bb30, {0x1d881a4, 0x9, 0x9}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:241 +0x214 fp=0x20a8e1c sp=0x20a8df8 pc=0x15ab44 Mar 16 10:08:06 framboosje go-librespot[1152]: io.ReadAtLeast({0x7227f8, 0x1c9bb30}, {0x1d881a4, 0x9, 0x9}, 0x9) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:335 +0x90 fp=0x20a8e48 sp=0x20a8e1c pc=0x1020b0 Mar 16 10:08:06 framboosje go-librespot[1152]: io.ReadFull(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:354 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.http2readFrameHeader({0x1d881a4, 0x9, 0x9}, {0x7227f8, 0x1c9bb30}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1638 +0x54 fp=0x20a8e70 sp=0x20a8e48 pc=0x3f30fc Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2Framer).ReadFrame(0x1d88180) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1902 +0x88 fp=0x20a8eec sp=0x20a8e70 pc=0x3f3894 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2clientConnReadLoop).run(0x20a8fdc) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9334 +0x150 fp=0x20a8fa4 sp=0x20a8eec pc=0x4180b0 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2ClientConn).readLoop(0x1d27108) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9229 +0x68 fp=0x20a8fe4 sp=0x20a8fa4 pc=0x4175a4 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0x28 fp=0x20a8fec sp=0x20a8fe4 pc=0x4105f0 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x20a8fec sp=0x20a8fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by net/http.(*http2Transport).newClientConn in goroutine 23 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0xcb0 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 37 gp=0x1c02fc8 m=nil [GC worker (idle)]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f7f4, 0x1d14b88, 0x1a, 0xa, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c43790 sp=0x1c4377c pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gcBgMarkWorker() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x1c437ec sp=0x1c43790 pc=0x37db4 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c437ec sp=0x1c437ec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by runtime.gcBgMarkStartWorkers in goroutine 36 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 5 gp=0x1c030e8 m=nil [GC worker (idle)]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f7f4, 0x1ccdfc8, 0x1a, 0xa, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c43f90 sp=0x1c43f7c pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gcBgMarkWorker() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x1c43fec sp=0x1c43f90 pc=0x37db4 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c43fec sp=0x1c43fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by runtime.gcBgMarkStartWorkers in goroutine 36 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 38 gp=0x1d406c8 m=nil [GC worker (idle)]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f7f4, 0x1d14ba0, 0x1a, 0xa, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1e1e790 sp=0x1e1e77c pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gcBgMarkWorker() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x1e1e7ec sp=0x1e1e790 pc=0x37db4 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1e1e7ec sp=0x1e1e7ec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by runtime.gcBgMarkStartWorkers in goroutine 36 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 6 gp=0x1c03208 m=nil [GC worker (idle)]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f7f4, 0x1d14bb8, 0x1a, 0xa, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c44790 sp=0x1c4477c pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gcBgMarkWorker() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x1c447ec sp=0x1c44790 pc=0x37db4 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c447ec sp=0x1c447ec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by runtime.gcBgMarkStartWorkers in goroutine 36 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 39 gp=0x1c84b48 m=nil [IO wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f928, 0x74ec2d28, 0x2, 0x2, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x20a4e84 sp=0x20a4e70 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.netpollblock(0x74ec2d18, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x20a4e9c sp=0x20a4e84 pc=0x540f0 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.runtime_pollWait(0x74ec2d18, 0x72) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x20a4eb0 sp=0x20a4e9c pc=0x8eba8 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).wait(0x1c9c298, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20a4ec4 sp=0x20a4eb0 pc=0x108108 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).waitRead(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*FD).RawRead(0x1c9c280, 0x208a210) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:708 +0x164 fp=0x20a4ef0 sp=0x20a4ec4 pc=0x10d578 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*rawConn).Read(0x1fc8200, 0x208a210) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/rawconn.go:44 +0x38 fp=0x20a4f10 sp=0x20a4ef0 pc=0x2b4a2c Mar 16 10:08:06 framboosje go-librespot[1152]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1efe240, 0x208a1e0, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x154 fp=0x20a4f40 sp=0x20a4f10 pc=0x4c9804 Mar 16 10:08:06 framboosje go-librespot[1152]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247 Mar 16 10:08:06 framboosje go-librespot[1152]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x1c00188, {0x1dc0000, 0x10000, 0x10000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv4/payload_cmsg.go:32 +0x44c fp=0x20a4f9c sp=0x20a4f40 pc=0x4cd7ec Mar 16 10:08:06 framboosje go-librespot[1152]: github.com/grandcat/zeroconf.(*Server).recv4(0x1fbe4c0, 0x1c00180) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x20a4fe0 sp=0x20a4f9c pc=0x52407c Mar 16 10:08:06 framboosje go-librespot[1152]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x20a4fec sp=0x20a4fe0 pc=0x523d34 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x20a4fec sp=0x20a4fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 7 Mar 16 10:08:06 framboosje sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-16 10:07 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 40 gp=0x1c84ea8 m=nil [IO wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f928, 0x74ec2c88, 0x2, 0x2, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1dafe5c sp=0x1dafe48 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.netpollblock(0x74ec2c78, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x1dafe74 sp=0x1dafe5c pc=0x540f0 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.runtime_pollWait(0x74ec2c78, 0x72) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1dafe88 sp=0x1dafe74 pc=0x8eba8 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).wait(0x1c9c2e8, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1dafe9c sp=0x1dafe88 pc=0x108108 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).waitRead(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*FD).RawRead(0x1c9c2d0, 0x1c12030) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1dafec8 sp=0x1dafe9c pc=0x10d578 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*rawConn).Read(0x1fc8228, 0x1c12030) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/rawconn.go:44 +0x38 fp=0x1dafee8 sp=0x1dafec8 pc=0x2b4a2c Mar 16 10:08:06 framboosje go-librespot[1152]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1efe250, 0x1c12000, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x154 fp=0x1daff18 sp=0x1dafee8 pc=0x4c9804 Mar 16 10:08:06 framboosje go-librespot[1152]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247 Mar 16 10:08:06 framboosje go-librespot[1152]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x1c001b8, {0x1db0000, 0x10000, 0x10000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv6/payload_cmsg.go:32 +0x2ec fp=0x1daff9c sp=0x1daff18 pc=0x4d1174 Mar 16 10:08:06 framboosje go-librespot[1152]: github.com/grandcat/zeroconf.(*Server).recv6(0x1fbe4c0, 0x1c001b0) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x1daffe0 sp=0x1daff9c pc=0x524270 Mar 16 10:08:06 framboosje go-librespot[1152]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Mar 16 10:08:06 framboosje sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x1daffec sp=0x1daffe0 pc=0x523cd8 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1daffec sp=0x1daffec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 7 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 58 gp=0x1d410e8 m=nil [select]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1dac688 sp=0x1dac674 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.selectgo(0x1dac7d4, 0x1dac750, 0x0, 0x0, 0x2, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x1dac728 sp=0x1dac688 pc=0x705e4 Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/dealer.(*Dealer).pingTicker(0x1e7c288) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:107 +0x8c fp=0x1dac7e4 sp=0x1dac728 pc=0x4b85d0 Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/dealer.NewDealer.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:57 +0x28 fp=0x1dac7ec sp=0x1dac7e4 pc=0x4b80a0 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1dac7ec sp=0x1dac7ec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by go-librespot/dealer.NewDealer in goroutine 1 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:57 +0x180 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 48 gp=0x1d41208 m=nil [IO wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f928, 0x74ec2aa8, 0x2, 0x2, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x20fdad4 sp=0x20fdac0 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.netpollblock(0x74ec2a98, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x20fdaec sp=0x20fdad4 pc=0x540f0 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.runtime_pollWait(0x74ec2a98, 0x72) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x20fdb00 sp=0x20fdaec pc=0x8eba8 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).wait(0x1c26298, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20fdb14 sp=0x20fdb00 pc=0x108108 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).waitRead(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*FD).Read(0x1c26280, {0x1e9c000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x20fdb5c sp=0x20fdb14 pc=0x1092f4 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*netFD).Read(0x1c26280, {0x1e9c000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x20fdb88 sp=0x20fdb5c pc=0x2a0610 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*conn).Read(0x1c943c0, {0x1e9c000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x20fdbb4 sp=0x20fdb88 pc=0x2b07f0 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*TCPConn).Read(0x1c943c0, {0x1e9c000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: :1 +0x44 fp=0x20fdbd4 sp=0x20fdbb4 pc=0x2c3a44 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*atLeastReader).Read(0x1f7e640, {0x1e9c000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x20fdc00 sp=0x20fdbd4 pc=0x386018 Mar 16 10:08:06 framboosje go-librespot[1152]: bytes.(*Buffer).ReadFrom(0x1e8e18c, {0x723a98, 0x1f7e640}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x20fdc3c sp=0x20fdc00 pc=0x136d6c Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readFromUntil(0x1e8e008, {0x722604, 0x1c943c0}, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x20fdc64 sp=0x20fdc3c pc=0x386274 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readRecordOrCCS(0x1e8e008, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x20fddc8 sp=0x20fdc64 pc=0x383974 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readRecord(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).Read(0x1e8e008, {0x1de8000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x20fddf8 sp=0x20fddc8 pc=0x3897e4 Mar 16 10:08:06 framboosje go-librespot[1152]: bufio.(*Reader).Read(0x1c136b0, {0x1caa224, 0x9, 0x9}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:241 +0x214 fp=0x20fde1c sp=0x20fddf8 pc=0x15ab44 Mar 16 10:08:06 framboosje go-librespot[1152]: io.ReadAtLeast({0x7227f8, 0x1c136b0}, {0x1caa224, 0x9, 0x9}, 0x9) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:335 +0x90 fp=0x20fde48 sp=0x20fde1c pc=0x1020b0 Mar 16 10:08:06 framboosje go-librespot[1152]: io.ReadFull(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:354 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.http2readFrameHeader({0x1caa224, 0x9, 0x9}, {0x7227f8, 0x1c136b0}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1638 +0x54 fp=0x20fde70 sp=0x20fde48 pc=0x3f30fc Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2Framer).ReadFrame(0x1caa200) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1902 +0x88 fp=0x20fdeec sp=0x20fde70 pc=0x3f3894 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2clientConnReadLoop).run(0x20fdfdc) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9334 +0x150 fp=0x20fdfa4 sp=0x20fdeec pc=0x4180b0 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2ClientConn).readLoop(0x1ddc108) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9229 +0x68 fp=0x20fdfe4 sp=0x20fdfa4 pc=0x4175a4 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0x28 fp=0x20fdfec sp=0x20fdfe4 pc=0x4105f0 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x20fdfec sp=0x20fdfec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by net/http.(*http2Transport).newClientConn in goroutine 47 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0xcb0 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 43 gp=0x1d41328 m=nil [IO wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f928, 0x74ec2be8, 0x2, 0x2, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c59ad4 sp=0x1c59ac0 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.netpollblock(0x74ec2bd8, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x1c59aec sp=0x1c59ad4 pc=0x540f0 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.runtime_pollWait(0x74ec2bd8, 0x72) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1c59b00 sp=0x1c59aec pc=0x8eba8 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).wait(0x1c9deb8, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c59b14 sp=0x1c59b00 pc=0x108108 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).waitRead(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*FD).Read(0x1c9dea0, {0x1dd0000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1c59b5c sp=0x1c59b14 pc=0x1092f4 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*netFD).Read(0x1c9dea0, {0x1dd0000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x1c59b88 sp=0x1c59b5c pc=0x2a0610 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*conn).Read(0x2140110, {0x1dd0000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x1c59bb4 sp=0x1c59b88 pc=0x2b07f0 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*TCPConn).Read(0x2140110, {0x1dd0000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: :1 +0x44 fp=0x1c59bd4 sp=0x1c59bb4 pc=0x2c3a44 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*atLeastReader).Read(0x1efedc0, {0x1dd0000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x1c59c00 sp=0x1c59bd4 pc=0x386018 Mar 16 10:08:06 framboosje go-librespot[1152]: bytes.(*Buffer).ReadFrom(0x1cc018c, {0x723a98, 0x1efedc0}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x1c59c3c sp=0x1c59c00 pc=0x136d6c Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readFromUntil(0x1cc0008, {0x722604, 0x2140110}, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x1c59c64 sp=0x1c59c3c pc=0x386274 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readRecordOrCCS(0x1cc0008, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x1c59dc8 sp=0x1c59c64 pc=0x383974 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readRecord(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).Read(0x1cc0008, {0x1dde000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x1c59df8 sp=0x1c59dc8 pc=0x3897e4 Mar 16 10:08:06 framboosje go-librespot[1152]: bufio.(*Reader).Read(0x1c13200, {0x1caa124, 0x9, 0x9}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:241 +0x214 fp=0x1c59e1c sp=0x1c59df8 pc=0x15ab44 Mar 16 10:08:06 framboosje go-librespot[1152]: io.ReadAtLeast({0x7227f8, 0x1c13200}, {0x1caa124, 0x9, 0x9}, 0x9) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:335 +0x90 fp=0x1c59e48 sp=0x1c59e1c pc=0x1020b0 Mar 16 10:08:06 framboosje go-librespot[1152]: io.ReadFull(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:354 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.http2readFrameHeader({0x1caa124, 0x9, 0x9}, {0x7227f8, 0x1c13200}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1638 +0x54 fp=0x1c59e70 sp=0x1c59e48 pc=0x3f30fc Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2Framer).ReadFrame(0x1caa100) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1902 +0x88 fp=0x1c59eec sp=0x1c59e70 pc=0x3f3894 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2clientConnReadLoop).run(0x1c59fdc) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9334 +0x150 fp=0x1c59fa4 sp=0x1c59eec pc=0x4180b0 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2ClientConn).readLoop(0x1ddc008) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9229 +0x68 fp=0x1c59fe4 sp=0x1c59fa4 pc=0x4175a4 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0x28 fp=0x1c59fec sp=0x1c59fe4 pc=0x4105f0 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c59fec sp=0x1c59fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by net/http.(*http2Transport).newClientConn in goroutine 42 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0xcb0 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 16 gp=0x1d41688 m=nil [select]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1dab6a8 sp=0x1dab694 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.selectgo(0x1dab7d4, 0x1dab76c, 0x0, 0x0, 0x2, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x1dab748 sp=0x1dab6a8 pc=0x705e4 Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/ap.(*Accesspoint).pongAckTicker(0x1e7c0a8) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:319 +0x8c fp=0x1dab7e4 sp=0x1dab748 pc=0x48d514 Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/ap.NewAccesspoint.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:67 +0x28 fp=0x1dab7ec sp=0x1dab7e4 pc=0x48b71c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1dab7ec sp=0x1dab7ec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by go-librespot/ap.NewAccesspoint in goroutine 1 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:67 +0x188 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 69 gp=0x1d417a8 m=nil [IO wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f928, 0x74ec2b48, 0x2, 0x2, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x20a9d70 sp=0x20a9d5c pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.netpollblock(0x74ec2b38, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x20a9d88 sp=0x20a9d70 pc=0x540f0 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.runtime_pollWait(0x74ec2b38, 0x72) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x20a9d9c sp=0x20a9d88 pc=0x8eba8 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).wait(0x1e74a68, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20a9db0 sp=0x20a9d9c pc=0x108108 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).waitRead(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*FD).Read(0x1e74a50, {0x1efc248, 0x3, 0x3}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x20a9df8 sp=0x20a9db0 pc=0x1092f4 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*netFD).Read(0x1e74a50, {0x1efc248, 0x3, 0x3}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x20a9e24 sp=0x20a9df8 pc=0x2a0610 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*conn).Read(0x1fc8960, {0x1efc248, 0x3, 0x3}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x20a9e50 sp=0x20a9e24 pc=0x2b07f0 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*TCPConn).Read(0x1fc8960, {0x1efc248, 0x3, 0x3}) Mar 16 10:08:06 framboosje go-librespot[1152]: :1 +0x44 fp=0x20a9e70 sp=0x20a9e50 pc=0x2c3a44 Mar 16 10:08:06 framboosje go-librespot[1152]: io.ReadAtLeast({0x722604, 0x1fc8960}, {0x1efc248, 0x3, 0x3}, 0x3) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:335 +0x90 fp=0x20a9e9c sp=0x20a9e70 pc=0x1020b0 Mar 16 10:08:06 framboosje go-librespot[1152]: io.ReadFull(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:354 Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/ap.(*shannonConn).receivePacket(0x20de540) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/ap/shannon.go:80 +0x130 fp=0x20a9efc sp=0x20a9e9c pc=0x48fedc Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/ap.(*Accesspoint).recvLoop(0x1e7c0a8) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:251 +0x58 fp=0x20a9fe4 sp=0x20a9efc pc=0x48cda4 Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:240 +0x28 fp=0x20a9fec sp=0x20a9fe4 pc=0x48cd20 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x20a9fec sp=0x20a9fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 61 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:240 +0x60 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 57 gp=0x1e90128 m=nil [select]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1dabea8 sp=0x1dabe94 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.selectgo(0x1dabfa4, 0x1dabf6c, 0x0, 0x0, 0x5, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x1dabf48 sp=0x1dabea8 pc=0x705e4 Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x1ce4508) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x1dabfe4 sp=0x1dabf48 pc=0x47408c Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.newConn.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1dabfec sp=0x1dabfe4 pc=0x473c80 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1dabfec sp=0x1dabfec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by nhooyr.io/websocket.newConn in goroutine 1 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 59 gp=0x1e90248 m=nil [runnable]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f7c8, 0x1f9e1f0, 0xf, 0x6, 0x2) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x20a2ecc sp=0x20a2eb8 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.chansend(0x1f9e1c0, 0x720dac, 0x1, 0x4a7858) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:259 +0x378 fp=0x20a2f04 sp=0x20a2ecc pc=0x1ef54 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.chansend1(0x1f9e1c0, 0x720dac) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:145 +0x28 fp=0x20a2f1c sp=0x20a2f04 pc=0x1ebd8 Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/player.(*Player).manageLoop(0x1c99440) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/player/player.go:116 +0x72c fp=0x20a2fe4 sp=0x20a2f1c pc=0x4a7858 Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/player.NewPlayer.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/player/player.go:91 +0x28 fp=0x20a2fec sp=0x20a2fe4 pc=0x4a7040 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x20a2fec sp=0x20a2fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by go-librespot/player.NewPlayer in goroutine 1 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/player/player.go:91 +0x1e4 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 61 gp=0x1e90488 m=nil [chan send]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f7c8, 0x1e5d330, 0xf, 0x6, 0x2) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1ccf51c sp=0x1ccf508 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.chansend(0x1e5d300, 0x1ccf700, 0x1, 0x574084) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:259 +0x378 fp=0x1ccf554 sp=0x1ccf51c pc=0x1ef54 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.chansend1(0x1e5d300, 0x1ccf700) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:145 +0x28 fp=0x1ccf56c sp=0x1ccf554 pc=0x1ebd8 Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/player.(*Player).SetPrimaryStream(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/player/player.go:302 Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*AppPlayer).loadCurrentTrack(0x1c9c320, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/controls.go:228 +0x578 fp=0x1ccf770 sp=0x1ccf56c pc=0x574084 Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*AppPlayer).advanceNext(0x1c9c320, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/controls.go:519 +0x44c fp=0x1ccf860 sp=0x1ccf770 pc=0x57694c Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*AppPlayer).handlePlayerEvent(0x1c9c320, 0x1ccfa54) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/controls.go:100 +0x364 fp=0x1ccf8fc sp=0x1ccf860 pc=0x5730f8 Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*AppPlayer).Run(0x1c9c320, 0x1e5d400) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/player.go:453 +0x5a4 fp=0x1ccffe0 sp=0x1ccf8fc pc=0x57e2f8 Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*App).withAppPlayer.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:235 +0x30 fp=0x1ccffec sp=0x1ccffe0 pc=0x57944c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1ccffec sp=0x1ccffec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by main.(*App).withAppPlayer in goroutine 1 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:235 +0x348 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 62 gp=0x1e905a8 m=nil [chan receive]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f7c8, 0x1c921f0, 0xe, 0x7, 0x2) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1dae748 sp=0x1dae734 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.chanrecv(0x1c921c0, 0x1dae7d0, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x4b0 fp=0x1dae784 sp=0x1dae748 pc=0x1fdc4 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.chanrecv2(0x1c921c0, 0x1dae7d0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:447 +0x20 fp=0x1dae798 sp=0x1dae784 pc=0x1f908 Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*App).withAppPlayer.func1() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:245 +0x54 fp=0x1dae7ec sp=0x1dae798 pc=0x57937c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1dae7ec sp=0x1dae7ec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by main.(*App).withAppPlayer in goroutine 1 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:242 +0x3d4 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 63 gp=0x1e906c8 m=nil [chan receive]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f7c8, 0x1c92170, 0xe, 0x7, 0x2) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1daef3c sp=0x1daef28 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.chanrecv(0x1c92140, 0x1daefe0, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x4b0 fp=0x1daef78 sp=0x1daef3c pc=0x1fdc4 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.chanrecv2(0x1c92140, 0x1daefe0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:447 +0x20 fp=0x1daef8c sp=0x1daef78 pc=0x1f908 Mar 16 10:08:06 framboosje go-librespot[1152]: main.(*App).withAppPlayer.func2() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:261 +0x5c fp=0x1daefec sp=0x1daef8c pc=0x578f90 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1daefec sp=0x1daefec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by main.(*App).withAppPlayer in goroutine 1 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:258 +0x45c Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 70 gp=0x20e3448 m=nil [IO wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f928, 0x74ec2a08, 0x2, 0x2, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1eb1938 sp=0x1eb1924 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.netpollblock(0x74ec29f8, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x1eb1950 sp=0x1eb1938 pc=0x540f0 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.runtime_pollWait(0x74ec29f8, 0x72) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1eb1964 sp=0x1eb1950 pc=0x8eba8 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).wait(0x1e75878, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1eb1978 sp=0x1eb1964 pc=0x108108 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).waitRead(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*FD).Read(0x1e75860, {0x205c000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1eb19c0 sp=0x1eb1978 pc=0x1092f4 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*netFD).Read(0x1e75860, {0x205c000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x1eb19ec sp=0x1eb19c0 pc=0x2a0610 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*conn).Read(0x1d01ee0, {0x205c000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x1eb1a18 sp=0x1eb19ec pc=0x2b07f0 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*TCPConn).Read(0x1d01ee0, {0x205c000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: :1 +0x44 fp=0x1eb1a38 sp=0x1eb1a18 pc=0x2c3a44 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*atLeastReader).Read(0x1f7e2a0, {0x205c000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x1eb1a64 sp=0x1eb1a38 pc=0x386018 Mar 16 10:08:06 framboosje go-librespot[1152]: bytes.(*Buffer).ReadFrom(0x1d84a8c, {0x723a98, 0x1f7e2a0}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x1eb1aa0 sp=0x1eb1a64 pc=0x136d6c Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readFromUntil(0x1d84908, {0x722604, 0x1d01ee0}, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x1eb1ac8 sp=0x1eb1aa0 pc=0x386274 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readRecordOrCCS(0x1d84908, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x1eb1c2c sp=0x1eb1ac8 pc=0x383974 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readRecord(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).Read(0x1d84908, {0x1e9d000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x1eb1c5c sp=0x1eb1c2c pc=0x3897e4 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*readWriteCloserBody).Read(0x1c0e740, {0x1e9d000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2429 +0xec fp=0x1eb1c7c sp=0x1eb1c5c pc=0x4538c0 Mar 16 10:08:06 framboosje go-librespot[1152]: bufio.(*Reader).fill(0x1e55860) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x1eb1ca0 sp=0x1eb1c7c pc=0x15a4b8 Mar 16 10:08:06 framboosje go-librespot[1152]: bufio.(*Reader).ReadByte(0x1e55860) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:269 +0x28 fp=0x1eb1cac sp=0x1eb1ca0 pc=0x15ad3c Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.readFrameHeader(0x1e55860, {0x1ce4538, 0x8, 0x8}) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1eb1cec sp=0x1eb1cac pc=0x4771e0 Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x1ce4508, {0x725688, 0xad3b20}) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1eb1dac sp=0x1eb1cec pc=0x479e44 Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.(*Conn).readLoop(0x1ce4508, {0x725688, 0xad3b20}) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1eb1e70 sp=0x1eb1dac pc=0x47992c Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.(*Conn).reader(0x1ce4508, {0x725688, 0xad3b20}) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1eb1efc sp=0x1eb1e70 pc=0x47ab40 Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.(*Conn).Reader(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.(*Conn).Read(0x1ce4508, {0x725688, 0xad3b20}) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1eb1f24 sp=0x1eb1efc pc=0x479210 Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/dealer.(*Dealer).recvLoop(0x1e7c288) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:153 +0x58 fp=0x1eb1fe4 sp=0x1eb1f24 pc=0x4b8a14 Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:99 +0x28 fp=0x1eb1fec sp=0x1eb1fe4 pc=0x4ba26c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1eb1fec sp=0x1eb1fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 61 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:99 +0x60 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 64 gp=0x1e907e8 m=nil [IO wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f928, 0x74ec2dc8, 0x2, 0x2, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1f195e8 sp=0x1f195d4 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.netpollblock(0x74ec2db8, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x1f19600 sp=0x1f195e8 pc=0x540f0 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.runtime_pollWait(0x74ec2db8, 0x72) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1f19614 sp=0x1f19600 pc=0x8eba8 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).wait(0x1c9c248, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1f19628 sp=0x1f19614 pc=0x108108 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).waitRead(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*FD).Accept(0x1c9c230) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1f19670 sp=0x1f19628 pc=0x10c974 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*netFD).accept(0x1c9c230) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_unix.go:172 +0x20 fp=0x1f196d8 sp=0x1f19670 pc=0x2a2504 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*TCPListener).accept(0x20aa0d8) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock_posix.go:159 +0x20 fp=0x1f196f0 sp=0x1f196d8 pc=0x2ba604 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*TCPListener).Accept(0x20aa0d8) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock.go:327 +0x30 fp=0x1f1970c sp=0x1f196f0 pc=0x2b9670 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*onceCloseListener).Accept(0x1c97ee0) Mar 16 10:08:06 framboosje go-librespot[1152]: :1 +0x34 fp=0x1f19724 sp=0x1f1970c pc=0x45e914 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*Server).Serve(0x1e7c468, {0x72527c, 0x20aa0d8}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3255 +0x314 fp=0x1f197bc sp=0x1f19724 pc=0x43cfd0 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.Serve(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2794 Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/zeroconf.(*Zeroconf).Serve.func2() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:265 +0x8c fp=0x1f197ec sp=0x1f197bc pc=0x528d7c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1f197ec sp=0x1f197ec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:265 +0x160 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 87 gp=0x1e90908 m=nil [IO wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f928, 0x74ec2788, 0x2, 0x2, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x20f6afc sp=0x20f6ae8 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.netpollblock(0x74ec2778, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x20f6b14 sp=0x20f6afc pc=0x540f0 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.runtime_pollWait(0x74ec2778, 0x72) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x20f6b28 sp=0x20f6b14 pc=0x8eba8 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).wait(0x2096298, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20f6b3c sp=0x20f6b28 pc=0x108108 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).waitRead(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*FD).Read(0x2096280, {0x21da000, 0xa000, 0xa000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x20f6b84 sp=0x20f6b3c pc=0x1092f4 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*netFD).Read(0x2096280, {0x21da000, 0xa000, 0xa000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x20f6bb0 sp=0x20f6b84 pc=0x2a0610 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*conn).Read(0x20e46a8, {0x21da000, 0xa000, 0xa000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x20f6bdc sp=0x20f6bb0 pc=0x2b07f0 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*TCPConn).Read(0x20e46a8, {0x21da000, 0xa000, 0xa000}) Mar 16 10:08:06 framboosje go-librespot[1152]: :1 +0x44 fp=0x20f6bfc sp=0x20f6bdc pc=0x2c3a44 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*atLeastReader).Read(0x1c0e0a0, {0x21da000, 0xa000, 0xa000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x20f6c28 sp=0x20f6bfc pc=0x386018 Mar 16 10:08:06 framboosje go-librespot[1152]: bytes.(*Buffer).ReadFrom(0x1d43c8c, {0x723a98, 0x1c0e0a0}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x20f6c64 sp=0x20f6c28 pc=0x136d6c Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readFromUntil(0x1d43b08, {0x722604, 0x20e46a8}, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x20f6c8c sp=0x20f6c64 pc=0x386274 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readRecordOrCCS(0x1d43b08, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x20f6df0 sp=0x20f6c8c pc=0x383974 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readRecord(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).Read(0x1d43b08, {0x1e92000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x20f6e20 sp=0x20f6df0 pc=0x3897e4 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*persistConn).Read(0x1cee4d8, {0x1e92000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1977 +0x16c fp=0x20f6e54 sp=0x20f6e20 pc=0x45138c Mar 16 10:08:06 framboosje go-librespot[1152]: bufio.(*Reader).fill(0x20dfb90) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x20f6e78 sp=0x20f6e54 pc=0x15a4b8 Mar 16 10:08:06 framboosje go-librespot[1152]: bufio.(*Reader).Peek(0x20dfb90, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:148 +0x68 fp=0x20f6e84 sp=0x20f6e78 pc=0x15a63c Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*persistConn).readLoop(0x1cee4d8) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2141 +0x194 fp=0x20f6fe4 sp=0x20f6e84 pc=0x451f1c Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*Transport).dialConn.gowrap2() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1799 +0x28 fp=0x20f6fec sp=0x20f6fe4 pc=0x45083c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x20f6fec sp=0x20f6fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by net/http.(*Transport).dialConn in goroutine 77 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1799 +0x13f4 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 84 gp=0x1e90c68 m=nil [select]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1e21ea8 sp=0x1e21e94 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.selectgo(0x1e21fa4, 0x1e21f6c, 0x0, 0x0, 0x5, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x1e21f48 sp=0x1e21ea8 pc=0x705e4 Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x1f1aa08) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x1e21fe4 sp=0x1e21f48 pc=0x47408c Mar 16 10:08:06 framboosje go-librespot[1152]: nhooyr.io/websocket.newConn.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1e21fec sp=0x1e21fe4 pc=0x473c80 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1e21fec sp=0x1e21fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by nhooyr.io/websocket.newConn in goroutine 103 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 100 gp=0x1e90d88 m=nil [IO wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f928, 0x74ec2968, 0x2, 0x2, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c58ad4 sp=0x1c58ac0 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.netpollblock(0x74ec2958, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x1c58aec sp=0x1c58ad4 pc=0x540f0 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.runtime_pollWait(0x74ec2958, 0x72) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1c58b00 sp=0x1c58aec pc=0x8eba8 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).wait(0x20d95a8, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c58b14 sp=0x1c58b00 pc=0x108108 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).waitRead(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*FD).Read(0x20d9590, {0x20bf000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1c58b5c sp=0x1c58b14 pc=0x1092f4 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*netFD).Read(0x20d9590, {0x20bf000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x1c58b88 sp=0x1c58b5c pc=0x2a0610 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*conn).Read(0x2052000, {0x20bf000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x1c58bb4 sp=0x1c58b88 pc=0x2b07f0 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*TCPConn).Read(0x2052000, {0x20bf000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: :1 +0x44 fp=0x1c58bd4 sp=0x1c58bb4 pc=0x2c3a44 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*atLeastReader).Read(0x20e87d0, {0x20bf000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x1c58c00 sp=0x1c58bd4 pc=0x386018 Mar 16 10:08:06 framboosje go-librespot[1152]: bytes.(*Buffer).ReadFrom(0x1cc0ccc, {0x723a98, 0x20e87d0}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x1c58c3c sp=0x1c58c00 pc=0x136d6c Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readFromUntil(0x1cc0b48, {0x722604, 0x2052000}, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x1c58c64 sp=0x1c58c3c pc=0x386274 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readRecordOrCCS(0x1cc0b48, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x1c58dc8 sp=0x1c58c64 pc=0x383974 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readRecord(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).Read(0x1cc0b48, {0x2077000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x1c58df8 sp=0x1c58dc8 pc=0x3897e4 Mar 16 10:08:06 framboosje go-librespot[1152]: bufio.(*Reader).Read(0x1d5b4a0, {0x1caa0a4, 0x9, 0x9}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:241 +0x214 fp=0x1c58e1c sp=0x1c58df8 pc=0x15ab44 Mar 16 10:08:06 framboosje go-librespot[1152]: io.ReadAtLeast({0x7227f8, 0x1d5b4a0}, {0x1caa0a4, 0x9, 0x9}, 0x9) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:335 +0x90 fp=0x1c58e48 sp=0x1c58e1c pc=0x1020b0 Mar 16 10:08:06 framboosje go-librespot[1152]: io.ReadFull(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:354 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.http2readFrameHeader({0x1caa0a4, 0x9, 0x9}, {0x7227f8, 0x1d5b4a0}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1638 +0x54 fp=0x1c58e70 sp=0x1c58e48 pc=0x3f30fc Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2Framer).ReadFrame(0x1caa080) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1902 +0x88 fp=0x1c58eec sp=0x1c58e70 pc=0x3f3894 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2clientConnReadLoop).run(0x1c58fdc) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9334 +0x150 fp=0x1c58fa4 sp=0x1c58eec pc=0x4180b0 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2ClientConn).readLoop(0x1ca8208) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9229 +0x68 fp=0x1c58fe4 sp=0x1c58fa4 pc=0x4175a4 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0x28 fp=0x1c58fec sp=0x1c58fe4 pc=0x4105f0 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c58fec sp=0x1c58fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by net/http.(*http2Transport).newClientConn in goroutine 99 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0xcb0 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 108 gp=0x1de6d88 m=nil [select]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x20a7c9c sp=0x20a7c88 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.selectgo(0x20a7dfc, 0x20a7d68, 0x0, 0x0, 0x3, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x20a7d3c sp=0x20a7c9c pc=0x705e4 Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/audio.(*KeyProvider).recvLoop(0x1e4c660) Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/audio/provider.go:52 +0x118 fp=0x20a7fe4 sp=0x20a7d3c pc=0x49552c Mar 16 10:08:06 framboosje go-librespot[1152]: go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1.gowrap1() Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/audio/provider.go:42 +0x28 fp=0x20a7fec sp=0x20a7fe4 pc=0x4960c8 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x20a7fec sp=0x20a7fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1 in goroutine 61 Mar 16 10:08:06 framboosje go-librespot[1152]: /home/runner/work/go-librespot/go-librespot/audio/provider.go:42 +0x60 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 88 gp=0x1e91688 m=nil [select]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1da96b0 sp=0x1da969c pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.selectgo(0x1da97ac, 0x1da9778, 0x0, 0x0, 0x2, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x1da9750 sp=0x1da96b0 pc=0x705e4 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*persistConn).writeLoop(0x1cee4d8) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2444 +0xd0 fp=0x1da97e4 sp=0x1da9750 pc=0x4539dc Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*Transport).dialConn.gowrap3() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1800 +0x28 fp=0x1da97ec sp=0x1da97e4 pc=0x4507e8 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1da97ec sp=0x1da97ec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by net/http.(*Transport).dialConn in goroutine 77 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1800 +0x1444 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 261 gp=0x208cfc8 m=nil [select]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1fa7eb0 sp=0x1fa7e9c pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.selectgo(0x1fa7fac, 0x1fa7f78, 0x0, 0x0, 0x2, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x1fa7f50 sp=0x1fa7eb0 pc=0x705e4 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*persistConn).writeLoop(0x1d2e428) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2444 +0xd0 fp=0x1fa7fe4 sp=0x1fa7f50 pc=0x4539dc Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*Transport).dialConn.gowrap3() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1800 +0x28 fp=0x1fa7fec sp=0x1fa7fe4 pc=0x4507e8 Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1fa7fec sp=0x1fa7fec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by net/http.(*Transport).dialConn in goroutine 227 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1800 +0x1444 Mar 16 10:08:06 framboosje go-librespot[1152]: goroutine 260 gp=0x1f6b9e8 m=nil [IO wait]: Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.gopark(0x68f928, 0x74ec2648, 0x2, 0x2, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x20fcafc sp=0x20fcae8 pc=0x5c82c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.netpollblock(0x74ec2638, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x20fcb14 sp=0x20fcafc pc=0x540f0 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.runtime_pollWait(0x74ec2638, 0x72) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x20fcb28 sp=0x20fcb14 pc=0x8eba8 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).wait(0x1d79b98, 0x72, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20fcb3c sp=0x20fcb28 pc=0x108108 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*pollDesc).waitRead(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Mar 16 10:08:06 framboosje go-librespot[1152]: internal/poll.(*FD).Read(0x1d79b80, {0x20c1500, 0x1500, 0x1500}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x20fcb84 sp=0x20fcb3c pc=0x1092f4 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*netFD).Read(0x1d79b80, {0x20c1500, 0x1500, 0x1500}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x20fcbb0 sp=0x20fcb84 pc=0x2a0610 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*conn).Read(0x20e4260, {0x20c1500, 0x1500, 0x1500}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x20fcbdc sp=0x20fcbb0 pc=0x2b07f0 Mar 16 10:08:06 framboosje go-librespot[1152]: net.(*TCPConn).Read(0x20e4260, {0x20c1500, 0x1500, 0x1500}) Mar 16 10:08:06 framboosje go-librespot[1152]: :1 +0x44 fp=0x20fcbfc sp=0x20fcbdc pc=0x2c3a44 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*atLeastReader).Read(0x20e8510, {0x20c1500, 0x1500, 0x1500}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x20fcc28 sp=0x20fcbfc pc=0x386018 Mar 16 10:08:06 framboosje go-librespot[1152]: bytes.(*Buffer).ReadFrom(0x1cc084c, {0x723a98, 0x20e8510}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x20fcc64 sp=0x20fcc28 pc=0x136d6c Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readFromUntil(0x1cc06c8, {0x722604, 0x20e4260}, 0x5) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x20fcc8c sp=0x20fcc64 pc=0x386274 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readRecordOrCCS(0x1cc06c8, 0x0) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x20fcdf0 sp=0x20fcc8c pc=0x383974 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).readRecord(...) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Mar 16 10:08:06 framboosje go-librespot[1152]: crypto/tls.(*Conn).Read(0x1cc06c8, {0x2091000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x20fce20 sp=0x20fcdf0 pc=0x3897e4 Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*persistConn).Read(0x1d2e428, {0x2091000, 0x1000, 0x1000}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1977 +0x16c fp=0x20fce54 sp=0x20fce20 pc=0x45138c Mar 16 10:08:06 framboosje go-librespot[1152]: bufio.(*Reader).fill(0x1e54f00) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x20fce78 sp=0x20fce54 pc=0x15a4b8 Mar 16 10:08:06 framboosje go-librespot[1152]: bufio.(*Reader).Peek(0x1e54f00, 0x1) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:148 +0x68 fp=0x20fce84 sp=0x20fce78 pc=0x15a63c Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*persistConn).readLoop(0x1d2e428) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2141 +0x194 fp=0x20fcfe4 sp=0x20fce84 pc=0x451f1c Mar 16 10:08:06 framboosje go-librespot[1152]: net/http.(*Transport).dialConn.gowrap2() Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1799 +0x28 fp=0x20fcfec sp=0x20fcfe4 pc=0x45083c Mar 16 10:08:06 framboosje go-librespot[1152]: runtime.goexit({}) Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x20fcfec sp=0x20fcfec pc=0x9346c Mar 16 10:08:06 framboosje go-librespot[1152]: created by net/http.(*Transport).dialConn in goroutine 227 Mar 16 10:08:06 framboosje go-librespot[1152]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1799 +0x13f4 Mar 16 10:08:06 framboosje go-librespot[1152]: Aborted Mar 16 10:08:06 framboosje systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Mar 16 10:08:06 framboosje systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"