-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Sat 2025-12-20 20:51:38 +07. -- Dec 20 20:50:30 volumio-livingroom kernel: hub 2-0:1.0: USB hub found Dec 20 20:50:30 volumio-livingroom kernel: hub 2-0:1.0: 4 ports detected Dec 20 20:50:30 volumio-livingroom kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Dec 20 20:50:30 volumio-livingroom kernel: dwc_otg: FIQ enabled Dec 20 20:50:30 volumio-livingroom kernel: dwc_otg: NAK holdoff enabled Dec 20 20:50:30 volumio-livingroom kernel: dwc_otg: FIQ split-transaction FSM enabled Dec 20 20:50:30 volumio-livingroom kernel: Module dwc_common_port init Dec 20 20:50:30 volumio-livingroom kernel: usbcore: registered new interface driver uas Dec 20 20:50:30 volumio-livingroom kernel: usbcore: registered new interface driver usb-storage Dec 20 20:50:30 volumio-livingroom kernel: mousedev: PS/2 mouse device common for all mice Dec 20 20:50:30 volumio-livingroom kernel: sdhci: Secure Digital Host Controller Interface driver Dec 20 20:50:30 volumio-livingroom kernel: sdhci: Copyright(c) Pierre Ossman Dec 20 20:50:30 volumio-livingroom kernel: sdhci-pltfm: SDHCI platform and OF driver helper Dec 20 20:50:30 volumio-livingroom kernel: ledtrig-cpu: registered to indicate activity on CPUs Dec 20 20:50:30 volumio-livingroom kernel: hid: raw HID events driver (C) Jiri Kosina Dec 20 20:50:30 volumio-livingroom kernel: usbcore: registered new interface driver usbhid Dec 20 20:50:30 volumio-livingroom kernel: usbhid: USB HID core driver Dec 20 20:50:30 volumio-livingroom kernel: Initializing XFRM netlink socket Dec 20 20:50:30 volumio-livingroom kernel: NET: Registered PF_PACKET protocol family Dec 20 20:50:30 volumio-livingroom kernel: Key type dns_resolver registered Dec 20 20:50:30 volumio-livingroom kernel: Registering SWP/SWPB emulation handler Dec 20 20:50:30 volumio-livingroom kernel: registered taskstats version 1 Dec 20 20:50:30 volumio-livingroom kernel: Loading compiled-in X.509 certificates Dec 20 20:50:30 volumio-livingroom kernel: Key type .fscrypt registered Dec 20 20:50:30 volumio-livingroom kernel: Key type fscrypt-provisioning registered Dec 20 20:50:30 volumio-livingroom kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Dec 20 20:50:30 volumio-livingroom kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Dec 20 20:50:30 volumio-livingroom kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Dec 20 20:50:30 volumio-livingroom kernel: serial serial0: tty port ttyAMA1 registered Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Dec 20 20:50:30 volumio-livingroom kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Dec 20 20:50:30 volumio-livingroom kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Dec 20 20:50:30 volumio-livingroom kernel: of_cfs_init Dec 20 20:50:30 volumio-livingroom kernel: of_cfs_init: OK Dec 20 20:50:30 volumio-livingroom kernel: clk: Disabling unused clocks Dec 20 20:50:30 volumio-livingroom kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Dec 20 20:50:30 volumio-livingroom kernel: Freeing unused kernel image (initmem) memory: 2048K Dec 20 20:50:30 volumio-livingroom kernel: Run /init as init process Dec 20 20:50:30 volumio-livingroom kernel: with arguments: Dec 20 20:50:30 volumio-livingroom kernel: /init Dec 20 20:50:30 volumio-livingroom kernel: splash Dec 20 20:50:30 volumio-livingroom kernel: nodebug Dec 20 20:50:30 volumio-livingroom kernel: with environment: Dec 20 20:50:30 volumio-livingroom kernel: HOME=/ Dec 20 20:50:30 volumio-livingroom kernel: TERM=linux Dec 20 20:50:30 volumio-livingroom kernel: numa_policy=interleave Dec 20 20:50:30 volumio-livingroom kernel: imgpart=UUID=af3faec2-9654-4fec-8791-d5638a5837ad Dec 20 20:50:30 volumio-livingroom kernel: imgfile=/volumio_current.sqsh Dec 20 20:50:30 volumio-livingroom kernel: bootpart=UUID=2E0A-AE55 Dec 20 20:50:30 volumio-livingroom kernel: datapart=UUID=660717eb-e471-4727-aac1-2c263938c365 Dec 20 20:50:30 volumio-livingroom kernel: uuidconfig=cmdline.txt Dec 20 20:50:30 volumio-livingroom kernel: pcie_aspm=off Dec 20 20:50:30 volumio-livingroom kernel: bootdelay=7 Dec 20 20:50:30 volumio-livingroom kernel: use_kmsg=no Dec 20 20:50:30 volumio-livingroom kernel: mmc1: new high speed SDIO card at address 0001 Dec 20 20:50:30 volumio-livingroom kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Dec 20 20:50:30 volumio-livingroom kernel: mmcblk0: mmc0:aaaa SB16G 14.8 GiB Dec 20 20:50:30 volumio-livingroom kernel: mmcblk0: p1 p2 p3 Dec 20 20:50:30 volumio-livingroom kernel: mmcblk0: mmc0:aaaa SB16G 14.8 GiB (quirks 0x0000c000) Dec 20 20:50:30 volumio-livingroom kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Dec 20 20:50:30 volumio-livingroom kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Dec 20 20:50:30 volumio-livingroom kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Dec 20 20:50:30 volumio-livingroom kernel: usb 1-1: Product: USB2.0 Hub Dec 20 20:50:30 volumio-livingroom kernel: hub 1-1:1.0: USB hub found Dec 20 20:50:30 volumio-livingroom kernel: hub 1-1:1.0: 4 ports detected Dec 20 20:50:30 volumio-livingroom kernel: fuse: init (API version 7.39) Dec 20 20:50:30 volumio-livingroom kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 20 20:50:30 volumio-livingroom kernel: EXT4-fs (mmcblk0p2): recovery complete Dec 20 20:50:30 volumio-livingroom kernel: EXT4-fs (mmcblk0p2): mounted filesystem af3faec2-9654-4fec-8791-d5638a5837ad r/w with ordered data mode. Quota mode: none. Dec 20 20:50:30 volumio-livingroom kernel: loop0: detected capacity change from 0 to 1005568 Dec 20 20:50:30 volumio-livingroom kernel: EXT4-fs (mmcblk0p3): recovery complete Dec 20 20:50:30 volumio-livingroom kernel: EXT4-fs (mmcblk0p3): mounted filesystem 660717eb-e471-4727-aac1-2c263938c365 r/w with ordered data mode. Quota mode: none. Dec 20 20:50:30 volumio-livingroom systemd[1]: System time before build time, advancing clock. Dec 20 20:50:30 volumio-livingroom kernel: NET: Registered PF_INET6 protocol family Dec 20 20:50:30 volumio-livingroom kernel: Segment Routing with IPv6 Dec 20 20:50:30 volumio-livingroom kernel: In-situ OAM (IOAM) with IPv6 Dec 20 20:50:30 volumio-livingroom systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Dec 20 20:50:30 volumio-livingroom systemd[1]: Detected architecture arm. Dec 20 20:50:30 volumio-livingroom systemd[1]: Set hostname to . Dec 20 20:50:30 volumio-livingroom systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 20 20:50:30 volumio-livingroom systemd[1]: Listening on udev Control Socket. Dec 20 20:50:30 volumio-livingroom systemd[1]: Listening on initctl Compatibility Named Pipe. Dec 20 20:50:30 volumio-livingroom systemd[1]: Listening on fsck to fsckd communication Socket. Dec 20 20:50:30 volumio-livingroom systemd[1]: Listening on Journal Socket (/dev/log). Dec 20 20:50:30 volumio-livingroom systemd[1]: Reached target System Time Synchronized. Dec 20 20:50:30 volumio-livingroom systemd[1]: Started Forward Password Requests to Wall Directory Watch. Dec 20 20:50:30 volumio-livingroom kernel: i2c_dev: i2c /dev entries driver Dec 20 20:50:30 volumio-livingroom systemd-journald[308]: Journal started Dec 20 20:50:30 volumio-livingroom systemd-journald[308]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 7.5M, max 30.0M, 22.5M free. Feb 14 17:11:58 volumio-livingroom systemd-modules-load[302]: Inserted module 'i2c_dev' Dec 20 20:50:30 volumio-livingroom fake-hwclock[303]: Sat Dec 20 13:50:30 UTC 2025 Dec 20 20:50:30 volumio-livingroom systemd[1]: Started Load/Save Random Seed. Dec 20 20:50:30 volumio-livingroom systemd[1]: Started Apply Kernel Variables. Dec 20 20:50:30 volumio-livingroom systemd[1]: dynamicswap.service: Succeeded. Dec 20 20:50:30 volumio-livingroom systemd[1]: Started Create System Users. Dec 20 20:50:30 volumio-livingroom systemd[1]: Starting Create Static Device Nodes in /dev... Dec 20 20:50:30 volumio-livingroom systemd[1]: Started udev Coldplug all Devices. Dec 20 20:50:30 volumio-livingroom systemd[1]: Starting Helper to synchronize boot up for ifupdown... Dec 20 20:50:30 volumio-livingroom systemd[1]: Started Helper to synchronize boot up for ifupdown. Dec 20 20:50:30 volumio-livingroom systemd[1]: Started Create Static Device Nodes in /dev. Dec 20 20:50:30 volumio-livingroom systemd[1]: Starting udev Kernel Device Manager... Dec 20 20:50:30 volumio-livingroom systemd[1]: Reached target Local File Systems (Pre). Dec 20 20:50:30 volumio-livingroom systemd[1]: Mounting /var/spool/cups... Dec 20 20:50:30 volumio-livingroom systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Dec 20 20:50:30 volumio-livingroom systemd[1]: Mounting /var/log... Dec 20 20:50:30 volumio-livingroom systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Dec 20 20:50:30 volumio-livingroom systemd[1]: Mounting /tmp... Dec 20 20:50:30 volumio-livingroom systemd[1]: Mounted /var/spool/cups. Dec 20 20:50:30 volumio-livingroom systemd[1]: Mounted /var/log. Dec 20 20:50:30 volumio-livingroom systemd[1]: Starting Flush Journal to Persistent Storage... Dec 20 20:50:30 volumio-livingroom systemd[1]: Mounting /var/spool/cups/tmp... Dec 20 20:50:30 volumio-livingroom systemd[1]: Mounted /tmp. Dec 20 20:50:30 volumio-livingroom systemd[1]: Mounted /var/spool/cups/tmp. Dec 20 20:50:30 volumio-livingroom systemd-udevd[330]: Network interface NamePolicy= disabled on kernel command line, ignoring. Dec 20 20:50:30 volumio-livingroom systemd-journald[308]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 7.5M, max 30.0M, 22.5M free. Dec 20 20:50:30 volumio-livingroom systemd[1]: Started udev Kernel Device Manager. Dec 20 20:50:30 volumio-livingroom systemd[1]: Starting Show Plymouth Boot Screen... Dec 20 20:50:30 volumio-livingroom systemd[1]: Started Flush Journal to Persistent Storage. Dec 20 20:50:30 volumio-livingroom systemd[1]: plymouth-start.service: Succeeded. Dec 20 20:50:30 volumio-livingroom systemd[1]: Started Show Plymouth Boot Screen. Dec 20 20:50:30 volumio-livingroom systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Dec 20 20:50:30 volumio-livingroom systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 20 20:50:30 volumio-livingroom systemd[1]: Reached target Paths. Dec 20 20:50:30 volumio-livingroom systemd[1]: Reached target Local Encrypted Volumes. Dec 20 20:50:30 volumio-livingroom systemd-udevd[346]: Using default interface naming scheme 'v240'. Dec 20 20:50:30 volumio-livingroom kernel: mc: Linux media interface: v0.10 Dec 20 20:50:30 volumio-livingroom kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Dec 20 20:50:30 volumio-livingroom kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Dec 20 20:50:30 volumio-livingroom kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 20:50:30 volumio-livingroom kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Dec 20 20:50:30 volumio-livingroom kernel: [vc_sm_connected_init]: start Dec 20 20:50:30 volumio-livingroom kernel: [vc_sm_connected_init]: installed successfully Dec 20 20:50:30 volumio-livingroom kernel: videodev: Linux video capture interface: v2.00 Dec 20 20:50:30 volumio-livingroom systemd-udevd[346]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 20 20:50:30 volumio-livingroom kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 20:50:30 volumio-livingroom kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Dec 20 20:50:30 volumio-livingroom kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Dec 20 20:50:30 volumio-livingroom kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Dec 20 20:50:30 volumio-livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Dec 20 20:50:30 volumio-livingroom kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 20:50:30 volumio-livingroom kernel: rpivid feb10000.codec: Device registered as /dev/video19 Dec 20 20:50:30 volumio-livingroom kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 20:50:30 volumio-livingroom kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Dec 20 20:50:30 volumio-livingroom kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 20 20:50:30 volumio-livingroom kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 20 20:50:30 volumio-livingroom systemd[1]: Found device /dev/disk/by-uuid/2E0A-AE55. Dec 20 20:50:30 volumio-livingroom kernel: Bluetooth: Core ver 2.22 Dec 20 20:50:30 volumio-livingroom kernel: NET: Registered PF_BLUETOOTH protocol family Dec 20 20:50:30 volumio-livingroom kernel: Bluetooth: HCI device and connection manager initialized Dec 20 20:50:30 volumio-livingroom kernel: Bluetooth: HCI socket layer initialized Dec 20 20:50:30 volumio-livingroom kernel: Bluetooth: L2CAP socket layer initialized Dec 20 20:50:30 volumio-livingroom kernel: Bluetooth: SCO socket layer initialized Dec 20 20:50:30 volumio-livingroom kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Dec 20 20:50:30 volumio-livingroom kernel: Bluetooth: HCI UART driver ver 2.3 Dec 20 20:50:30 volumio-livingroom kernel: Bluetooth: HCI UART protocol H4 registered Dec 20 20:50:30 volumio-livingroom kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Dec 20 20:50:30 volumio-livingroom kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Dec 20 20:50:30 volumio-livingroom kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Dec 20 20:50:30 volumio-livingroom kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Dec 20 20:50:30 volumio-livingroom kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Dec 20 20:50:30 volumio-livingroom kernel: Bluetooth: HCI UART protocol Broadcom registered Dec 20 20:50:30 volumio-livingroom kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Dec 20 20:50:31 volumio-livingroom kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Dec 20 20:50:31 volumio-livingroom kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Dec 20 20:50:31 volumio-livingroom kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Dec 20 20:50:31 volumio-livingroom kernel: usbcore: registered new interface driver brcmfmac Dec 20 20:50:31 volumio-livingroom kernel: uart-pl011 fe201000.serial: no DMA platform data Dec 20 20:50:30 volumio-livingroom systemd-udevd[336]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 20 20:50:31 volumio-livingroom kernel: wm8804 1-003b: revision E Dec 20 20:50:31 volumio-livingroom kernel: Bluetooth: hci0: BCM: chip id 107 Dec 20 20:50:31 volumio-livingroom kernel: Bluetooth: hci0: BCM: features 0x2f Dec 20 20:50:31 volumio-livingroom kernel: Bluetooth: hci0: BCM4345C0 Dec 20 20:50:31 volumio-livingroom kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Dec 20 20:50:31 volumio-livingroom kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Dec 20 20:50:31 volumio-livingroom kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Dec 20 20:50:31 volumio-livingroom kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Dec 20 20:50:31 volumio-livingroom kernel: alsactl[467]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Dec 20 20:50:31 volumio-livingroom systemd-udevd[343]: Using default interface naming scheme 'v240'. Dec 20 20:50:31 volumio-livingroom systemd-udevd[343]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 20 20:50:31 volumio-livingroom systemd-udevd[347]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 1' failed with exit code 17. Dec 20 20:50:31 volumio-livingroom systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Dec 20 20:50:31 volumio-livingroom systemd[1]: Created slice system-bthelper.slice. Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting Show Plymouth Boot Screen... Dec 20 20:50:31 volumio-livingroom systemd[1]: Condition check resulted in Huge Pages File System being skipped. Dec 20 20:50:31 volumio-livingroom systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Dec 20 20:50:31 volumio-livingroom systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Dec 20 20:50:31 volumio-livingroom systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting File System Check on /dev/disk/by-uuid/2E0A-AE55... Dec 20 20:50:31 volumio-livingroom systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Dec 20 20:50:31 volumio-livingroom systemd[1]: Started File System Check Daemon to report status. Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting Load/Save RF Kill Switch Status... Dec 20 20:50:31 volumio-livingroom systemd[1]: plymouth-start.service: Succeeded. Dec 20 20:50:31 volumio-livingroom systemd[1]: Started Show Plymouth Boot Screen. Dec 20 20:50:31 volumio-livingroom systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 20 20:50:31 volumio-livingroom systemd[1]: Started Load/Save RF Kill Switch Status. Dec 20 20:50:31 volumio-livingroom systemd-fsck[489]: fsck.fat 4.1 (2017-01-24) Dec 20 20:50:31 volumio-livingroom systemd-fsck[489]: /dev/mmcblk0p1: 407 files, 41769/46774 clusters Dec 20 20:50:31 volumio-livingroom systemd[1]: Started File System Check on /dev/disk/by-uuid/2E0A-AE55. Dec 20 20:50:31 volumio-livingroom systemd[1]: Mounting /boot... Dec 20 20:50:31 volumio-livingroom systemd[1]: Mounted /boot. Dec 20 20:50:31 volumio-livingroom systemd[1]: Reached target Local File Systems. Dec 20 20:50:31 volumio-livingroom systemd[1]: Started ifup for wlan0. Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting Preprocess NFS configuration... Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Dec 20 20:50:31 volumio-livingroom systemd[1]: Started ifup for eth0. Dec 20 20:50:31 volumio-livingroom systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting Raise network interfaces... Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting Create Volatile Files and Directories... Dec 20 20:50:31 volumio-livingroom systemd[1]: Received SIGRTMIN+20 from PID 192 (plymouthd). Dec 20 20:50:31 volumio-livingroom systemd[1]: plymouth-read-write.service: Succeeded. Dec 20 20:50:31 volumio-livingroom systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Dec 20 20:50:31 volumio-livingroom systemd[1]: nfs-config.service: Succeeded. Dec 20 20:50:31 volumio-livingroom systemd[1]: Started Preprocess NFS configuration. Dec 20 20:50:31 volumio-livingroom systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Dec 20 20:50:31 volumio-livingroom systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Dec 20 20:50:31 volumio-livingroom systemd[1]: Reached target NFS client services. Dec 20 20:50:31 volumio-livingroom kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Dec 20 20:50:31 volumio-livingroom systemd[1]: Started Create Volatile Files and Directories. Dec 20 20:50:31 volumio-livingroom systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting Update UTMP about System Boot/Shutdown... Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting RPC bind portmap service... Dec 20 20:50:31 volumio-livingroom systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Dec 20 20:50:31 volumio-livingroom systemd[1]: Started Update UTMP about System Boot/Shutdown. Dec 20 20:50:31 volumio-livingroom systemd[1]: Reached target System Initialization. Dec 20 20:50:31 volumio-livingroom systemd[1]: Started Daily apt download activities. Dec 20 20:50:31 volumio-livingroom systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 20 20:50:31 volumio-livingroom systemd[1]: Started Daily apt upgrade and clean activities. Dec 20 20:50:31 volumio-livingroom systemd[1]: Reached target Timers. Dec 20 20:50:31 volumio-livingroom systemd[1]: Listening on triggerhappy.socket. Dec 20 20:50:31 volumio-livingroom systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 20 20:50:31 volumio-livingroom systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 20 20:50:31 volumio-livingroom systemd[1]: Reached target Sockets. Dec 20 20:50:31 volumio-livingroom systemd[1]: Reached target Basic System. Dec 20 20:50:31 volumio-livingroom systemd[1]: Started Manage Sound Card State (restore and store). Dec 20 20:50:31 volumio-livingroom systemd[1]: Started UPnP Renderer front-end to MPD. Dec 20 20:50:31 volumio-livingroom alsactl[568]: alsactl 1.1.8 daemon started Dec 20 20:50:31 volumio-livingroom systemd[1]: Started volumio-remote-updater.service. Dec 20 20:50:31 volumio-livingroom systemd[1]: Started D-Bus System Message Bus. Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting WPA supplicant... Dec 20 20:50:31 volumio-livingroom systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 20 20:50:31 volumio-livingroom systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting dhcpcd on all interfaces... Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting Login Service... Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting Volumio Time Update Utility... Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting triggerhappy global hotkey daemon... Dec 20 20:50:31 volumio-livingroom systemd[1]: Started Volumio Iptables Module. Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting Save/Restore Sound Card State... Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting Configure Bluetooth Modems connected by UART... Dec 20 20:50:31 volumio-livingroom systemd[1]: Starting Wireless Services... Dec 20 20:50:31 volumio-livingroom systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Dec 20 20:50:31 volumio-livingroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 20 20:50:31 volumio-livingroom systemd[1]: Started Volumio Log Rotation Service. Dec 20 20:50:32 volumio-livingroom kernel: Bluetooth: hci0: BCM: features 0x2f Dec 20 20:50:32 volumio-livingroom kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Dec 20 20:50:32 volumio-livingroom kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Dec 20 20:50:32 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: Fetching time from Volumio... Dec 20 20:50:32 volumio-livingroom kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 20 20:50:32 volumio-livingroom dhcpcd[574]: Not running dhcpcd because /etc/network/interfaces Dec 20 20:50:32 volumio-livingroom dhcpcd[574]: defines some interfaces that will use a Dec 20 20:50:32 volumio-livingroom dhcpcd[574]: DHCP client or static address Dec 20 20:50:32 volumio-livingroom thd[577]: Unable to parse trigger line: Dec 20 20:50:32 volumio-livingroom thd[577]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Dec 20 20:50:32 volumio-livingroom thd[577]: Unable to parse trigger line: Dec 20 20:50:32 volumio-livingroom thd[577]: Unable to parse trigger line: Dec 20 20:50:32 volumio-livingroom thd[577]: Found socket passed from systemd Dec 20 20:50:32 volumio-livingroom systemd-logind[575]: New seat seat0. Dec 20 20:50:32 volumio-livingroom avahi-daemon[573]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Dec 20 20:50:32 volumio-livingroom avahi-daemon[573]: Successfully dropped root privileges. Dec 20 20:50:32 volumio-livingroom avahi-daemon[573]: avahi-daemon 0.7 starting up. Dec 20 20:50:32 volumio-livingroom wpa_supplicant[572]: Successfully initialized wpa_supplicant Dec 20 20:50:32 volumio-livingroom systemd[1]: Started WPA supplicant. Dec 20 20:50:32 volumio-livingroom systemd[1]: Started triggerhappy global hotkey daemon. Dec 20 20:50:32 volumio-livingroom systemd[1]: Started RPC bind portmap service. Dec 20 20:50:32 volumio-livingroom avahi-daemon[573]: Successfully called chroot(). Dec 20 20:50:32 volumio-livingroom systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Dec 20 20:50:32 volumio-livingroom avahi-daemon[573]: Successfully dropped remaining capabilities. Dec 20 20:50:32 volumio-livingroom systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Dec 20 20:50:32 volumio-livingroom systemd[1]: Failed to start dhcpcd on all interfaces. Dec 20 20:50:32 volumio-livingroom systemd[1]: Started Save/Restore Sound Card State. Dec 20 20:50:32 volumio-livingroom systemd[1]: hciuart.service: Succeeded. Dec 20 20:50:32 volumio-livingroom systemd[1]: Started Configure Bluetooth Modems connected by UART. Dec 20 20:50:32 volumio-livingroom avahi-daemon[573]: Loading service file /services/volumio.service. Dec 20 20:50:32 volumio-livingroom systemd[1]: Started Login Service. Dec 20 20:50:32 volumio-livingroom systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 20 20:50:32 volumio-livingroom avahi-daemon[573]: Network interface enumeration completed. Dec 20 20:50:32 volumio-livingroom avahi-daemon[573]: Server startup complete. Host name is volumio-livingroom.local. Local service cookie is 883447250. Dec 20 20:50:32 volumio-livingroom avahi-daemon[573]: Service "Volumio-Livingroom" (/services/volumio.service) successfully established. Dec 20 20:50:32 volumio-livingroom sh[497]: wlan0=wlan0 Dec 20 20:50:32 volumio-livingroom systemd[1]: Starting Raspberry Pi bluetooth helper... Dec 20 20:50:32 volumio-livingroom systemd[1]: Reached target Sound Card. Dec 20 20:50:32 volumio-livingroom systemd[1]: Reached target RPC Port Mapper. Dec 20 20:50:32 volumio-livingroom systemd[1]: Reached target Remote File Systems (Pre). Dec 20 20:50:32 volumio-livingroom systemd[1]: Reached target Remote File Systems. Dec 20 20:50:32 volumio-livingroom systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 20 20:50:32 volumio-livingroom sh[502]: eth0: waiting for carrier Dec 20 20:50:32 volumio-livingroom dhcpcd[546]: eth0: waiting for carrier Dec 20 20:50:32 volumio-livingroom kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Dec 20 20:50:32 volumio-livingroom kernel: bcmgenet fd580000.ethernet eth0: Link is Down Dec 20 20:50:32 volumio-livingroom systemd[1]: Started Raise network interfaces. Dec 20 20:50:32 volumio-livingroom systemd[1]: Reached target Network. Dec 20 20:50:32 volumio-livingroom systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Dec 20 20:50:32 volumio-livingroom volumio-remote-updater[570]: Error: No active session Dec 20 20:50:32 volumio-livingroom volumio-remote-updater[570]: [2025-12-20 20:50:32] [info] asio async_connect error: system:111 (Connection refused) Dec 20 20:50:32 volumio-livingroom volumio-remote-updater[570]: [2025-12-20 20:50:32] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 20 20:50:32 volumio-livingroom volumio-remote-updater[570]: [2025-12-20 20:50:32] [error] handle_connect error: Underlying Transport Error Dec 20 20:50:32 volumio-livingroom bthelper[650]: Raspberry Pi BDADDR already set Dec 20 20:50:32 volumio-livingroom systemd[1]: Starting Network Time Service... Dec 20 20:50:32 volumio-livingroom systemd[1]: Starting Permit User Sessions... Dec 20 20:50:32 volumio-livingroom systemd[1]: Reached target Network is Online. Dec 20 20:50:32 volumio-livingroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 20 20:50:32 volumio-livingroom systemd[1]: Starting /etc/rc.local Compatibility... Dec 20 20:50:32 volumio-livingroom volumio[569]: Could not open config: /tmp/upmpdcli.conf Dec 20 20:50:32 volumio-livingroom systemd[1]: Starting LSB: Brings up/down network automatically... Dec 20 20:50:32 volumio-livingroom systemd[1]: Starting Samba NMB Daemon... Dec 20 20:50:32 volumio-livingroom systemd[1]: Started Raspberry Pi bluetooth helper. Dec 20 20:50:32 volumio-livingroom systemd[1]: Started /etc/rc.local Compatibility. Dec 20 20:50:32 volumio-livingroom systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 20 20:50:32 volumio-livingroom systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 20 20:50:32 volumio-livingroom systemd[1]: iptables.service: Succeeded. Dec 20 20:50:32 volumio-livingroom systemd[1]: Starting Bluetooth service... Dec 20 20:50:32 volumio-livingroom systemd[1]: Started Permit User Sessions. Dec 20 20:50:32 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: Date not found in response Dec 20 20:50:32 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 20:50:32 volumio-livingroom systemd[1]: Starting Hold until boot process finishes up... Dec 20 20:50:32 volumio-livingroom systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 20 20:50:33 volumio-livingroom systemd[1]: Received SIGRTMIN+21 from PID 192 (plymouthd). Dec 20 20:50:33 volumio-livingroom systemd[1]: Received SIGRTMIN+21 from PID 192 (plymouthd). Dec 20 20:50:33 volumio-livingroom systemd[1]: plymouth-quit.service: Succeeded. Dec 20 20:50:33 volumio-livingroom systemd[1]: Started Terminate Plymouth Boot Screen. Dec 20 20:50:33 volumio-livingroom loadcpufreq[661]: Loading cpufreq kernel modules...error: unexpectedly disconnected from boot status daemon Dec 20 20:50:33 volumio-livingroom systemd[1]: plymouth-quit-wait.service: Succeeded. Dec 20 20:50:33 volumio-livingroom systemd[1]: Started Hold until boot process finishes up. Dec 20 20:50:33 volumio-livingroom systemd[1]: Started Getty on tty1. Dec 20 20:50:33 volumio-livingroom systemd[1]: Reached target Login Prompts. Dec 20 20:50:33 volumio-livingroom ifplugd(eth0)[726]: ifplugd 0.28 initializing. Dec 20 20:50:33 volumio-livingroom ifplugd(eth0)[726]: Using interface eth0/DC:A6:32:0D:B7:A8 with driver (version: 6.6.62-v7l+) Dec 20 20:50:33 volumio-livingroom ifplugd(eth0)[726]: Using detection mode: SIOCETHTOOL Dec 20 20:50:33 volumio-livingroom ifplugd(eth0)[726]: Initialization complete, link beat not detected. Dec 20 20:50:33 volumio-livingroom ntpd[690]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 20 20:50:33 volumio-livingroom ntpd[690]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Dec 20 20:50:33 volumio-livingroom systemd[1]: Started Network Time Service. Dec 20 20:50:33 volumio-livingroom ntpd[742]: proto: precision = 0.834 usec (-20) Dec 20 20:50:33 volumio-livingroom ifplugd[697]: Network Interface Plugging Daemon...start eth0...done. Dec 20 20:50:33 volumio-livingroom systemd[1]: Started LSB: Brings up/down network automatically. Dec 20 20:50:33 volumio-livingroom bluetoothd[703]: Bluetooth daemon 5.83 Dec 20 20:50:33 volumio-livingroom haveged[543]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Dec 20 20:50:33 volumio-livingroom haveged[543]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Dec 20 20:50:33 volumio-livingroom haveged[543]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00042 Dec 20 20:50:33 volumio-livingroom haveged[543]: haveged: fills: 0, generated: 0 Dec 20 20:50:33 volumio-livingroom ntpd[742]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 20 20:50:33 volumio-livingroom ntpd[742]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 20 20:50:33 volumio-livingroom ntpd[742]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 358 days ago Dec 20 20:50:33 volumio-livingroom ntpd[742]: Listen and drop on 0 v6wildcard [::]:123 Dec 20 20:50:33 volumio-livingroom ntpd[742]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 20 20:50:33 volumio-livingroom ntpd[742]: Listen normally on 2 lo 127.0.0.1:123 Dec 20 20:50:33 volumio-livingroom ntpd[742]: Listening on routing socket on fd #19 for interface updates Dec 20 20:50:33 volumio-livingroom ntpd[742]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 20 20:50:33 volumio-livingroom ntpd[742]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 20 20:50:33 volumio-livingroom loadcpufreq[661]: done (none). Dec 20 20:50:33 volumio-livingroom systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 20 20:50:33 volumio-livingroom bluetoothd[703]: src/main.c:check_options() Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Dec 20 20:50:33 volumio-livingroom bluetoothd[703]: Starting SDP server Dec 20 20:50:33 volumio-livingroom bluetoothd[703]: Excluding (cli) hostname Dec 20 20:50:33 volumio-livingroom bluetoothd[703]: Excluding (cli) scanparam Dec 20 20:50:33 volumio-livingroom bluetoothd[703]: Excluding (cli) battery Dec 20 20:50:33 volumio-livingroom bluetoothd[703]: Bluetooth management interface 1.22 initialized Dec 20 20:50:33 volumio-livingroom bluetoothd[703]: Battery Provider Manager created Dec 20 20:50:33 volumio-livingroom kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 20 20:50:33 volumio-livingroom kernel: Bluetooth: BNEP filters: protocol multicast Dec 20 20:50:33 volumio-livingroom kernel: Bluetooth: BNEP socket layer initialized Dec 20 20:50:33 volumio-livingroom kernel: Bluetooth: MGMT ver 1.22 Dec 20 20:50:33 volumio-livingroom systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 20 20:50:33 volumio-livingroom systemd[1]: Started Bluetooth service. Dec 20 20:50:33 volumio-livingroom systemd[1]: Reached target Bluetooth. Dec 20 20:50:33 volumio-livingroom kernel: NET: Registered PF_ALG protocol family Dec 20 20:50:33 volumio-livingroom kernel: cryptd: max_cpu_qlen set to 1000 Dec 20 20:50:33 volumio-livingroom cpufrequtils[752]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 20 20:50:33 volumio-livingroom systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 20 20:50:33 volumio-livingroom bluetoothd[703]: Failed to set privacy: Rejected (0x0b) Dec 20 20:50:33 volumio-livingroom nmbd[702]: [2025/12/20 20:50:33.828442, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 20 20:50:33 volumio-livingroom nmbd[702]: started asyncdns process 789 Dec 20 20:50:33 volumio-livingroom nmbd[702]: [2025/12/20 20:50:33.830101, 0] ../lib/util/become_daemon.c:149(daemon_status) Dec 20 20:50:33 volumio-livingroom nmbd[702]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 20 20:50:33 volumio-livingroom nmbd[702]: [2025/12/20 20:50:33.830238, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Dec 20 20:50:33 volumio-livingroom nmbd[702]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 20 20:50:34 volumio-livingroom wireless.js[581]: Cleaning previous... Dec 20 20:50:34 volumio-livingroom sudo[797]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 20 20:50:34 volumio-livingroom sudo[797]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:34 volumio-livingroom sudo[797]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:34 volumio-livingroom sudo[799]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 20 20:50:34 volumio-livingroom sudo[799]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:34 volumio-livingroom ntpd[742]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 20 20:50:34 volumio-livingroom sudo[799]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:34 volumio-livingroom wireless.js[581]: Stopped aP Dec 20 20:50:34 volumio-livingroom sudo[806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 20 20:50:34 volumio-livingroom sudo[806]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:34 volumio-livingroom sudo[806]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:34 volumio-livingroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 20 20:50:34 volumio-livingroom sudo[808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 20 20:50:34 volumio-livingroom sudo[808]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:34 volumio-livingroom sudo[808]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:34 volumio-livingroom sudo[815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 20 20:50:34 volumio-livingroom sudo[815]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:34 volumio-livingroom sudo[815]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:34 volumio-livingroom sudo[817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 20 20:50:34 volumio-livingroom sudo[817]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:35 volumio-livingroom ntpd[742]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 20 20:50:36 volumio-livingroom ntpd[742]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 20 20:50:36 volumio-livingroom systemd[1]: systemd-rfkill.service: Succeeded. Dec 20 20:50:37 volumio-livingroom ntpd[742]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 20 20:50:37 volumio-livingroom sudo[817]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:37 volumio-livingroom wireless.js[581]: SETTING APPROPRIATE REG DOMAIN: US Dec 20 20:50:37 volumio-livingroom sudo[822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 20 20:50:37 volumio-livingroom sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:37 volumio-livingroom sudo[822]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:37 volumio-livingroom sudo[824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Dec 20 20:50:37 volumio-livingroom sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:37 volumio-livingroom sudo[824]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:37 volumio-livingroom wireless.js[581]: SUCCESSFULLY SET NEW REGDOMAIN: US Dec 20 20:50:37 volumio-livingroom wireless.js[581]: Start wireless flow Dec 20 20:50:37 volumio-livingroom wireless.js[581]: Stopped hotspot (if there).. Dec 20 20:50:37 volumio-livingroom sudo[829]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 20 20:50:37 volumio-livingroom sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:37 volumio-livingroom sudo[829]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:37 volumio-livingroom sudo[831]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 20 20:50:37 volumio-livingroom sudo[831]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:37 volumio-livingroom bthelper[650]: [NEW] Media /org/bluez/hci0 Dec 20 20:50:37 volumio-livingroom bthelper[650]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 20 20:50:37 volumio-livingroom bthelper[650]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 20 20:50:37 volumio-livingroom bluetoothd[703]: src/adv_monitor.c:btd_adv_monitor_power_down() Unexpected NULL btd_adv_monitor_manager object upon power down Dec 20 20:50:37 volumio-livingroom bthelper[650]: hci0 class of device changed: 0x000000 Dec 20 20:50:37 volumio-livingroom bthelper[650]: hci0 new_settings: ssp br/edr le secure-conn Dec 20 20:50:37 volumio-livingroom bthelper[650]: [CHG] Controller DC:A6:32:0D:B7:AA PowerState: on-disabling Dec 20 20:50:37 volumio-livingroom bthelper[650]: Changing power off succeeded Dec 20 20:50:37 volumio-livingroom bthelper[650]: [CHG] Controller DC:A6:32:0D:B7:AA PowerState: off Dec 20 20:50:37 volumio-livingroom bthelper[650]: [CHG] Controller DC:A6:32:0D:B7:AA Powered: no Dec 20 20:50:37 volumio-livingroom bthelper[650]: [CHG] Controller DC:A6:32:0D:B7:AA Discovering: no Dec 20 20:50:37 volumio-livingroom bthelper[650]: [CHG] Controller DC:A6:32:0D:B7:AA Class: 0x00000000 (0) Dec 20 20:50:37 volumio-livingroom bthelper[650]: [NEW] Media /org/bluez/hci0 Dec 20 20:50:37 volumio-livingroom bthelper[650]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 20 20:50:37 volumio-livingroom bthelper[650]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 20 20:50:37 volumio-livingroom bthelper[650]: [CHG] Controller DC:A6:32:0D:B7:AA PowerState: off-enabling Dec 20 20:50:37 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: Fetching time from Volumio... Dec 20 20:50:37 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: Date not found in response Dec 20 20:50:37 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 20:50:38 volumio-livingroom bthelper[650]: hci0 class of device changed: 0x00041c Dec 20 20:50:38 volumio-livingroom bthelper[650]: [CHG] Controller DC:A6:32:0D:B7:AA Class: 0x0000041c (1052) Dec 20 20:50:38 volumio-livingroom bthelper[650]: hci0 new_settings: powered ssp br/edr le secure-conn Dec 20 20:50:38 volumio-livingroom bthelper[650]: Changing power on succeeded Dec 20 20:50:38 volumio-livingroom volumio-remote-updater[570]: [2025-12-20 20:50:38] [info] asio async_connect error: system:111 (Connection refused) Dec 20 20:50:38 volumio-livingroom volumio-remote-updater[570]: [2025-12-20 20:50:38] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 20 20:50:38 volumio-livingroom volumio-remote-updater[570]: [2025-12-20 20:50:38] [error] handle_connect error: Underlying Transport Error Dec 20 20:50:38 volumio-livingroom wireless.js[581]: DHCP IP Dec 20 20:50:38 volumio-livingroom sudo[831]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:38 volumio-livingroom wireless.js[581]: Start ap Dec 20 20:50:38 volumio-livingroom wpa_supplicant[839]: Successfully initialized wpa_supplicant Dec 20 20:50:38 volumio-livingroom sudo[840]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 20 20:50:38 volumio-livingroom sudo[840]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:38 volumio-livingroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 20 20:50:38 volumio-livingroom dhcpcd[841]: dev: loaded udev Dec 20 20:50:38 volumio-livingroom dhcpcd-run-hooks[859]: wlan0: starting wpa_supplicant Dec 20 20:50:38 volumio-livingroom dhcpcd-run-hooks[864]: wlan0: failed to start wpa_supplicant Dec 20 20:50:38 volumio-livingroom dhcpcd-run-hooks[865]: 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 Dec 20 20:50:38 volumio-livingroom dhcpcd[841]: wlan0: connected to Access Point `' Dec 20 20:50:38 volumio-livingroom dhcpcd[841]: no interfaces have a carrier Dec 20 20:50:38 volumio-livingroom dhcpcd[841]: forked to background, child pid 875 Dec 20 20:50:38 volumio-livingroom dhcpcd[875]: eth0: waiting for carrier Dec 20 20:50:38 volumio-livingroom dhcpcd[875]: wlan0: waiting for carrier Dec 20 20:50:38 volumio-livingroom sudo[840]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:39 volumio-livingroom wireless.js[581]: trying... Dec 20 20:50:39 volumio-livingroom sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 20 20:50:39 volumio-livingroom sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:39 volumio-livingroom sudo[877]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:40 volumio-livingroom wireless.js[581]: trying... Dec 20 20:50:40 volumio-livingroom sudo[880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 20 20:50:40 volumio-livingroom sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:40 volumio-livingroom sudo[880]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:41 volumio-livingroom wireless.js[581]: trying... Dec 20 20:50:41 volumio-livingroom sudo[883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 20 20:50:41 volumio-livingroom sudo[883]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:41 volumio-livingroom sudo[883]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:42 volumio-livingroom wireless.js[581]: trying... Dec 20 20:50:42 volumio-livingroom sudo[886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 20 20:50:42 volumio-livingroom sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:42 volumio-livingroom sudo[886]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:42 volumio-livingroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 20 20:50:42 volumio-livingroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 20 20:50:42 volumio-livingroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 20 20:50:42 volumio-livingroom kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 20 20:50:42 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: Fetching time from Volumio... Dec 20 20:50:42 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: Date not found in response Dec 20 20:50:42 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 20:50:43 volumio-livingroom wireless.js[581]: trying... Dec 20 20:50:43 volumio-livingroom sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 20 20:50:43 volumio-livingroom sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:43 volumio-livingroom sudo[894]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:44 volumio-livingroom wireless.js[581]: trying... Dec 20 20:50:44 volumio-livingroom sudo[897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 20 20:50:44 volumio-livingroom sudo[897]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:44 volumio-livingroom sudo[897]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:45 volumio-livingroom wpa_supplicant[863]: wlan0: Trying to associate with SSID 'ASUS.5G' Dec 20 20:50:45 volumio-livingroom wireless.js[581]: trying... Dec 20 20:50:45 volumio-livingroom sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 20 20:50:45 volumio-livingroom sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:45 volumio-livingroom sudo[900]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:45 volumio-livingroom dhcpcd[875]: wlan0: carrier acquired Dec 20 20:50:45 volumio-livingroom wpa_supplicant[863]: wlan0: Associated with 04:d4:c4:3f:a2:34 Dec 20 20:50:45 volumio-livingroom wpa_supplicant[863]: wlan0: CTRL-EVENT-CONNECTED - Connection to 04:d4:c4:3f:a2:34 completed [id=0 id_str=] Dec 20 20:50:45 volumio-livingroom wpa_supplicant[863]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 20 20:50:45 volumio-livingroom dhcpcd[875]: DUID 00:01:00:01:2c:c5:40:e8:dc:a6:32:0d:b7:a8 Dec 20 20:50:45 volumio-livingroom dhcpcd[875]: wlan0: IAID 32:0d:b7:a9 Dec 20 20:50:45 volumio-livingroom dhcpcd[875]: wlan0: adding address fe80::937b:59b1:265:c0cf Dec 20 20:50:45 volumio-livingroom dhcpcd[875]: ipv6_addaddr1: Permission denied Dec 20 20:50:45 volumio-livingroom dhcpcd[875]: wlan0: carrier lost Dec 20 20:50:45 volumio-livingroom dhcpcd[875]: wlan0: carrier acquired Dec 20 20:50:45 volumio-livingroom dhcpcd[875]: wlan0: connected to Access Point `ASUS.5G' Dec 20 20:50:45 volumio-livingroom dhcpcd[875]: wlan0: IAID 32:0d:b7:a9 Dec 20 20:50:45 volumio-livingroom dhcpcd[875]: wlan0: soliciting an IPv6 router Dec 20 20:50:45 volumio-livingroom dhcpcd[875]: wlan0: rebinding lease of 192.168.2.12 Dec 20 20:50:45 volumio-livingroom dhcpcd[875]: wlan0: probing address 192.168.2.12/24 Dec 20 20:50:45 volumio-livingroom volumio-remote-updater[570]: [2025-12-20 20:50:45] [info] asio async_connect error: system:111 (Connection refused) Dec 20 20:50:45 volumio-livingroom volumio-remote-updater[570]: [2025-12-20 20:50:45] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 20 20:50:45 volumio-livingroom volumio-remote-updater[570]: [2025-12-20 20:50:45] [error] handle_connect error: Underlying Transport Error Dec 20 20:50:46 volumio-livingroom wireless.js[581]: trying... Dec 20 20:50:46 volumio-livingroom sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 20 20:50:46 volumio-livingroom sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:46 volumio-livingroom sudo[916]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:47 volumio-livingroom wireless.js[581]: trying... Dec 20 20:50:47 volumio-livingroom sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 20 20:50:47 volumio-livingroom sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:47 volumio-livingroom sudo[919]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:47 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: Fetching time from Volumio... Dec 20 20:50:48 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: Date not found in response Dec 20 20:50:48 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 20:50:48 volumio-livingroom wireless.js[581]: trying... Dec 20 20:50:48 volumio-livingroom sudo[927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 20 20:50:48 volumio-livingroom sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:48 volumio-livingroom sudo[927]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:49 volumio-livingroom wireless.js[581]: trying... Dec 20 20:50:49 volumio-livingroom sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 20 20:50:49 volumio-livingroom sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:49 volumio-livingroom sudo[930]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:50 volumio-livingroom wireless.js[581]: trying... Dec 20 20:50:50 volumio-livingroom sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 20 20:50:50 volumio-livingroom sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:50 volumio-livingroom sudo[933]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:50 volumio-livingroom dhcpcd[875]: wlan0: leased 192.168.2.12 for 86400 seconds Dec 20 20:50:50 volumio-livingroom avahi-daemon[573]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.12. Dec 20 20:50:50 volumio-livingroom dhcpcd[875]: wlan0: adding route to 192.168.2.0/24 Dec 20 20:50:50 volumio-livingroom avahi-daemon[573]: New relevant interface wlan0.IPv4 for mDNS. Dec 20 20:50:50 volumio-livingroom avahi-daemon[573]: Registering new address record for 192.168.2.12 on wlan0.IPv4. Dec 20 20:50:50 volumio-livingroom dhcpcd[875]: wlan0: adding default route via 192.168.2.1 Dec 20 20:50:51 volumio-livingroom wireless.js[581]: trying... Dec 20 20:50:51 volumio-livingroom sudo[956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 20 20:50:51 volumio-livingroom sudo[956]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:51 volumio-livingroom sudo[956]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:51 volumio-livingroom wireless.js[581]: Connected to: ----ASUS.5G Dec 20 20:50:51 volumio-livingroom wireless.js[581]: ---- Dec 20 20:50:51 volumio-livingroom sudo[959]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 20:50:51 volumio-livingroom sudo[959]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:51 volumio-livingroom sudo[959]: pam_unix(sudo:session): session closed for user root Dec 20 20:50:51 volumio-livingroom wireless.js[581]: ... joined AP, wlan0 IPv4 is 192.168.2.12, ipV6 is undefined Dec 20 20:50:51 volumio-livingroom wireless.js[581]: It's done! AP Dec 20 20:50:51 volumio-livingroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 20 20:50:51 volumio-livingroom systemd[1]: Started Wireless Services. Dec 20 20:50:51 volumio-livingroom systemd[1]: Started Volumio Backend Module. Dec 20 20:50:51 volumio-livingroom systemd[1]: Started Volumio Cpu Tweaker. Dec 20 20:50:51 volumio-livingroom volumio-cpu-tweak[965]: Setting RT Priority for mpd Dec 20 20:50:51 volumio-livingroom volumio-cpu-tweak[965]: pid 35's current scheduling policy: SCHED_OTHER Dec 20 20:50:51 volumio-livingroom volumio-cpu-tweak[965]: pid 35's current scheduling priority: 0 Dec 20 20:50:51 volumio-livingroom volumio-cpu-tweak[965]: Setting MPD Affinity Dec 20 20:50:51 volumio-livingroom volumio-cpu-tweak[965]: pid 3's current affinity mask: f Dec 20 20:50:51 volumio-livingroom volumio-cpu-tweak[965]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 20 20:50:51 volumio-livingroom volumio-cpu-tweak[965]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 20 20:50:51 volumio-livingroom systemd[1]: volumio_cpu_tweak.service: Succeeded. Dec 20 20:50:52 volumio-livingroom ntpd[742]: Listen normally on 3 wlan0 192.168.2.12:123 Dec 20 20:50:52 volumio-livingroom ntpd[742]: new interface(s) found: waking up resolver Dec 20 20:50:52 volumio-livingroom volumio[964]: info: ------------------------------------------- Dec 20 20:50:52 volumio-livingroom volumio[964]: info: ----- Volumio3 ---- Dec 20 20:50:52 volumio-livingroom volumio[964]: info: ------------------------------------------- Dec 20 20:50:52 volumio-livingroom volumio[964]: info: ----- System startup ---- Dec 20 20:50:52 volumio-livingroom volumio[964]: info: ------------------------------------------- Dec 20 20:50:53 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: Fetching time from Volumio... Dec 20 20:50:53 volumio-livingroom volumio[964]: info: MYVOLUMIO Environment detected Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Plugin folders cleanup Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Scanning into folder /volumio/app/plugins/ Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Scanning category audio_interface Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Scanning category miscellanea Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Scanning category music_service Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Scanning category plugins.json Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Scanning category system_controller Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Scanning category user_interface Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Scanning into folder /data/plugins/ Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Scanning category music_service Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Plugin folders cleanup completed Dec 20 20:50:53 volumio-livingroom volumio[964]: info: ------------------------------------------- Dec 20 20:50:53 volumio-livingroom volumio[964]: info: ----- Core plugins startup ---- Dec 20 20:50:53 volumio-livingroom volumio[964]: info: ------------------------------------------- Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Adding plugin upnp to MyMusic Plugins Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Loading plugins from folder /data/plugins/ Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Loading plugin "system"... Dec 20 20:50:53 volumio-livingroom volumio[964]: info: Loading plugin "appearance"... Dec 20 20:50:53 volumio-livingroom nmbd[702]: [2025/12/20 20:50:53.835673, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 20 20:50:53 volumio-livingroom nmbd[702]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 20 20:50:53 volumio-livingroom systemd[1]: Started Samba NMB Daemon. Dec 20 20:50:53 volumio-livingroom systemd[1]: Starting Samba Winbind Daemon... Dec 20 20:50:54 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: Setting system time to: 2025-12-20 20:51:22 Dec 20 20:50:54 volumio-livingroom sudo[1001]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-12-20 20:51:22 Dec 20 20:50:54 volumio-livingroom sudo[1001]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:50:54 volumio-livingroom winbindd[998]: [2025/12/20 20:50:54.163716, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 20 20:50:54 volumio-livingroom winbindd[998]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 20 20:50:54 volumio-livingroom dbus-daemon[571]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.8' (uid=0 pid=1002 comm="timedatectl set-time 2025-12-20 20:51:22 ") Dec 20 20:50:54 volumio-livingroom winbindd[998]: [2025/12/20 20:50:54.189883, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 20 20:50:54 volumio-livingroom winbindd[998]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 20 20:50:54 volumio-livingroom systemd[1]: Started Samba Winbind Daemon. Dec 20 20:50:54 volumio-livingroom systemd[1]: Starting Samba SMB Daemon... Dec 20 20:50:54 volumio-livingroom systemd[1]: Starting Time & Date Service... Dec 20 20:50:54 volumio-livingroom dbus-daemon[571]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 20 20:50:54 volumio-livingroom systemd[1]: Started Time & Date Service. Dec 20 20:51:22 volumio-livingroom systemd-timedated[1006]: Changed local time to Sat Dec 20 20:51:22 2025 Dec 20 20:51:22 volumio-livingroom sudo[1001]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:22 volumio-livingroom systemd[1]: Started Volumio Time Update Utility. Dec 20 20:51:22 volumio-livingroom volumio-time-update[576]: volumio-time-update-util: System time updated successfully. Dec 20 20:51:22 volumio-livingroom smbd[1007]: [2025/12/20 20:51:22.300477, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 20 20:51:22 volumio-livingroom systemd[1]: Started Samba SMB Daemon. Dec 20 20:51:22 volumio-livingroom systemd[1]: Reached target Multi-User System. Dec 20 20:51:22 volumio-livingroom systemd[1]: Reached target Graphical Interface. Dec 20 20:51:22 volumio-livingroom smbd[1007]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 20 20:51:22 volumio-livingroom systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 20 20:51:22 volumio-livingroom systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 20 20:51:22 volumio-livingroom systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 20 20:51:22 volumio-livingroom systemd[1]: Startup finished in 11.884s (kernel) + 25.712s (userspace) = 37.596s. Dec 20 20:51:22 volumio-livingroom volumio[964]: info: Loading plugin "network"... Dec 20 20:51:22 volumio-livingroom volumio[964]: info: Refreshing Cached IP Addresses Dec 20 20:51:22 volumio-livingroom sudo[1017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 20:51:22 volumio-livingroom sudo[1017]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:22 volumio-livingroom sudo[1017]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:22 volumio-livingroom sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 20:51:22 volumio-livingroom sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:22 volumio-livingroom sudo[1019]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:22 volumio-livingroom volumio[964]: info: Loading plugin "services"... Dec 20 20:51:22 volumio-livingroom volumio[964]: info: Loading plugin "alsa_controller"... Dec 20 20:51:22 volumio-livingroom sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 20:51:22 volumio-livingroom sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:22 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 20:51:22 volumio-livingroom volumio[964]: info: Loading plugin "wizard"... Dec 20 20:51:22 volumio-livingroom volumio[964]: info: Loading plugin "networkfs"... Dec 20 20:51:22 volumio-livingroom volumio[964]: info: Starting Udev Watcher for removable devices Dec 20 20:51:22 volumio-livingroom sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=csabvarg,password=Kokszos84!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.200/music /mnt/NAS/Synology_DS918+ Dec 20 20:51:22 volumio-livingroom sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:22 volumio-livingroom volumio[964]: info: Ignoring mount for partition: boot Dec 20 20:51:22 volumio-livingroom volumio[964]: info: Ignoring mount for partition: volumio Dec 20 20:51:22 volumio-livingroom volumio[964]: info: Ignoring mount for partition: volumio_data Dec 20 20:51:22 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 20:51:22 volumio-livingroom volumio[964]: info: Loading plugin "volumio_command_line_client"... Dec 20 20:51:22 volumio-livingroom volumio[964]: info: Loading plugin "upnp"... Dec 20 20:51:22 volumio-livingroom volumio[964]: info: [1766238682801] Starting Upmpd Daemon Dec 20 20:51:22 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 20:51:22 volumio-livingroom volumio[964]: info: Loading plugin "my_music"... Dec 20 20:51:22 volumio-livingroom volumio[964]: info: Loading plugin "mpd"... Dec 20 20:51:22 volumio-livingroom kernel: Key type cifs.spnego registered Dec 20 20:51:22 volumio-livingroom kernel: Key type cifs.idmap registered Dec 20 20:51:22 volumio-livingroom kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Dec 20 20:51:22 volumio-livingroom kernel: CIFS: Attempting to mount //192.168.2.200/music Dec 20 20:51:23 volumio-livingroom volumio[964]: info: Loading plugin "upnp_browser"... Dec 20 20:51:23 volumio-livingroom volumio-remote-updater[570]: [2025-12-20 20:51:23] [connect] Successful connection Dec 20 20:51:23 volumio-livingroom sudo[1043]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:23 volumio-livingroom volumio[964]: info: Starting UPNP Browser Dec 20 20:51:23 volumio-livingroom volumio[964]: info: Loading plugin "alarm-clock"... Dec 20 20:51:23 volumio-livingroom volumio[964]: info: Loading plugin "airplay_emulation"... Dec 20 20:51:23 volumio-livingroom volumio[964]: info: Starting Shairport Sync Dec 20 20:51:23 volumio-livingroom volumio[964]: info: Loading plugin "last_100"... Dec 20 20:51:23 volumio-livingroom volumio[964]: info: Loading plugin "webradio"... Dec 20 20:51:23 volumio-livingroom volumio[964]: info: Loading plugin "i2s_dacs"... Dec 20 20:51:23 volumio-livingroom volumio[964]: info: Loading plugin "volumiodiscovery"... Dec 20 20:51:23 volumio-livingroom volumio[964]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 20:51:23 volumio-livingroom volumio[964]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 20:51:23 volumio-livingroom volumio[964]: *** WARNING *** For more information see Dec 20 20:51:23 volumio-livingroom node[964]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 20:51:23 volumio-livingroom volumio[964]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 20:51:23 volumio-livingroom volumio[964]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 20:51:23 volumio-livingroom volumio[964]: *** WARNING *** For more information see Dec 20 20:51:23 volumio-livingroom node[964]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 20:51:23 volumio-livingroom node[964]: *** WARNING *** For more information see Dec 20 20:51:23 volumio-livingroom node[964]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 20:51:23 volumio-livingroom node[964]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 20:51:23 volumio-livingroom node[964]: *** WARNING *** For more information see Dec 20 20:51:23 volumio-livingroom volumio[964]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 20 20:51:23 volumio-livingroom volumio[964]: info: Discovery: Started advertising with name: Volumio-Livingroom Dec 20 20:51:23 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 20:51:23 volumio-livingroom volumio[964]: info: Loading plugin "spop"... Dec 20 20:51:24 volumio-livingroom volumio[964]: info: Loading plugin "ytcr"... Dec 20 20:51:26 volumio-livingroom sudo[1028]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:26 volumio-livingroom volumio[964]: info: Loading plugin "outputs"... Dec 20 20:51:26 volumio-livingroom volumio[964]: info: Loading plugin "albumart"... Dec 20 20:51:26 volumio-livingroom volumio[964]: info: Plugin example_plugin is not enabled Dec 20 20:51:26 volumio-livingroom volumio[964]: info: Loading plugin "inputs"... Dec 20 20:51:26 volumio-livingroom volumio[964]: info: Loading plugin "updater_comm"... Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Plugin mpdemulation is not enabled Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Loading plugin "rest_api"... Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Loading plugin "websocket"... Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Starting Socket.io Server version 2.3.0 Dec 20 20:51:27 volumio-livingroom volumio[964]: Forking 3 albumart workers Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Loading i18n strings for locale en Dec 20 20:51:27 volumio-livingroom volumio[964]: Updating browse sources language Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::initPlayerControls Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 20:51:27 volumio-livingroom volumio[964]: Express server listening on port 3000 Dec 20 20:51:27 volumio-livingroom volumio[964]: [Metrics] WebUI: 7s 405.29ms Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreStateMachine::resetVolumioState Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreStateMachine::getcurrentVolume Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreStateMachine::pushState Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioPushState Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Volumio Network Manager: Network status updated: 2 Dec 20 20:51:27 volumio-livingroom volumio[964]: verbose: New Socket.io Connection to 192.168.2.12:3000 from 192.168.2.131 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Dec 20 20:51:27 volumio-livingroom volumio-remote-updater[570]: [2025-12-20 20:51:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766238683 101 Dec 20 20:51:27 volumio-livingroom volumio[964]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Dec 20 20:51:27 volumio-livingroom volumio[964]: verbose: New Socket.io Connection to 192.168.2.12:3000 from 192.168.2.11 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 20 20:51:27 volumio-livingroom volumio[964]: verbose: New Socket.io Connection to 192.168.2.12:3000 from 192.168.2.131 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Discovery: Getting this device information Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 20:51:27 volumio-livingroom volumio[964]: Starting albumart workers Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Discovery: Getting this device information Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 20:51:27 volumio-livingroom volumio[964]: Starting albumart workers Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Reloading queue from file Dec 20 20:51:27 volumio-livingroom volumio[964]: Starting albumart workers Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Setting Device type: Raspberry PI Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreStateMachine::setRepeat null single undefined Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreStateMachine::pushState Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioPushState Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreStateMachine::setRandom null Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreStateMachine::pushState Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioPushState Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 20 20:51:27 volumio-livingroom volumio[964]: verbose: New Socket.io Connection to 192.168.2.12:3000 from 192.168.2.131 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 20 20:51:27 volumio-livingroom volumio[964]: verbose: New Socket.io Connection to 192.168.2.12:3000 from 192.168.2.131 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Completed loading Core Plugins Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Preparing to generate the ALSA configuration file Dec 20 20:51:27 volumio-livingroom volumio[964]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 20 20:51:27 volumio-livingroom volumio[964]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Dec 20 20:51:27 volumio-livingroom volumio[964]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Discovery: Getting this device information Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Discovery: adding a7286d2f-7184-4191-b9c3-f20bcdee8e3a Dec 20 20:51:27 volumio-livingroom volumio[964]: info: Discovery: Found device Volumio-Livingroom Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:27 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Asound.conf file unchanged, so no further update is needed Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Output device has changed, restarting MPD Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Output device has changed, restarting Shairport Sync Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 20:51:28 volumio-livingroom sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 20:51:28 volumio-livingroom sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 20:51:28 volumio-livingroom sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:28 volumio-livingroom sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:28 volumio-livingroom sudo[1118]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 20:51:28 volumio-livingroom volumio[964]: info: ___________ START PLUGINS ___________ Dec 20 20:51:28 volumio-livingroom volumio[964]: info: ControllerMpd::onStart: Initializing MPD Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Creating MPD Configuration file Dec 20 20:51:28 volumio-livingroom systemd[1]: Listening on mpd.socket. Dec 20 20:51:28 volumio-livingroom systemd[1]: Starting Music Player Daemon... Dec 20 20:51:28 volumio-livingroom sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 20:51:28 volumio-livingroom sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 20:51:28 volumio-livingroom volumio[964]: info: [1766238688182] CoreMusicLibrary::Adding element Media Servers Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 20:51:28 volumio-livingroom sudo[1128]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:28 volumio-livingroom sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 20:51:28 volumio-livingroom sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:28 volumio-livingroom volumio[964]: info: UPNP Browser: Client initialized successfully Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 20:51:28 volumio-livingroom systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 20 20:51:28 volumio-livingroom systemd[1]: mpd.service: Succeeded. Dec 20 20:51:28 volumio-livingroom systemd[1]: Stopped Music Player Daemon. Dec 20 20:51:28 volumio-livingroom systemd[1]: Starting Music Player Daemon... Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 20:51:28 volumio-livingroom sudo[1143]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 20:51:28 volumio-livingroom sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 20:51:28 volumio-livingroom volumio[964]: info: [1766238688321] CoreMusicLibrary::Adding element Last_100 Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 20:51:28 volumio-livingroom volumio[964]: info: [1766238688324] CoreMusicLibrary::Adding element Webradio Dec 20 20:51:28 volumio-livingroom sudo[1143]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 20:51:28 volumio-livingroom sudo[1143]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Initializing BBC Radios Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Creating Spotify config file Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Volumio Calling Home Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Discovery: Getting this device information Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Discovery: adding f07f740a-0747-49c5-84c9-2bf98041f393 Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Discovery: Found device Volumio-Bedroom Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Discovery: Connecting to remote: 192.168.2.11 Dec 20 20:51:28 volumio-livingroom volumio[964]: info: MPD Permissions set Dec 20 20:51:28 volumio-livingroom volumio[964]: info: MPD Permissions set Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Spotify config file written Dec 20 20:51:28 volumio-livingroom sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 20 20:51:28 volumio-livingroom volumio[964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 20 20:51:28 volumio-livingroom sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 20:51:28 volumio-livingroom volumio[964]: info: No need to fix Spotify hosts Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Discovery: Connected to remote: 192.168.2.11 Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 20 20:51:28 volumio-livingroom systemd[1]: Started go-librespot Daemon. Dec 20 20:51:28 volumio-livingroom go-librespot[1187]: go-librespot daemon starting... Dec 20 20:51:28 volumio-livingroom sudo[1181]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Starting Shairport Sync Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Starting Shairport Sync Dec 20 20:51:28 volumio-livingroom volumio[964]: info: Starting Shairport Sync Dec 20 20:51:28 volumio-livingroom sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 20:51:28 volumio-livingroom sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 20:51:28 volumio-livingroom sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:28 volumio-livingroom sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 20 20:51:28 volumio-livingroom sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 20:51:28 volumio-livingroom sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:28 volumio-livingroom systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 20 20:51:28 volumio-livingroom systemd[1]: shairport-sync.service: Succeeded. Dec 20 20:51:28 volumio-livingroom systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 20 20:51:28 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 20 20:51:29 volumio-livingroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 20 20:51:29 volumio-livingroom sudo[1196]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:29 volumio-livingroom sudo[1194]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:29 volumio-livingroom sudo[1198]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:29 volumio-livingroom sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 20 20:51:29 volumio-livingroom sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:29 volumio-livingroom sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 20 20:51:29 volumio-livingroom sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 20 20:51:29 volumio-livingroom sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:29 volumio-livingroom sudo[1215]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:29 volumio-livingroom sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:29 volumio-livingroom sudo[1221]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:29 volumio-livingroom sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 20 20:51:29 volumio-livingroom sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:29 volumio-livingroom sudo[1228]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:29 volumio-livingroom sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 20:51:29 volumio-livingroom sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:29 volumio-livingroom sudo[1210]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:29 volumio-livingroom sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 20:51:29 volumio-livingroom sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:29 volumio-livingroom sudo[1236]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:29 volumio-livingroom sudo[1239]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:29 volumio-livingroom volumio[964]: info: Shairport-Sync Started Dec 20 20:51:29 volumio-livingroom volumio[964]: Error adding Membership: Error: addMembership EINVAL Dec 20 20:51:29 volumio-livingroom volumio[964]: info: Shairport-Sync Started Dec 20 20:51:29 volumio-livingroom volumio[964]: info: Shairport-Sync Started Dec 20 20:51:29 volumio-livingroom volumio[964]: info: Volumio called home Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 20:51:29 volumio-livingroom volumio[964]: info: Discovery: Getting this device information Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 20:51:29 volumio-livingroom volumio[964]: info: Discovery: Getting this device information Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:29 volumio-livingroom volumio[964]: verbose: New Socket.io Connection to 192.168.2.12:3000 from 192.168.2.131 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 20 20:51:29 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:29+07:00" level=info msg="running go-librespot 0.4.0" Dec 20 20:51:29 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:29+07:00" level=debug msg="app state loaded" Dec 20 20:51:29 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:29+07:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 20:51:29 volumio-livingroom systemd[1]: systemd-fsckd.service: Succeeded. Dec 20 20:51:29 volumio-livingroom volumio[964]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 20 20:51:29 volumio-livingroom volumio[964]: SPOTIFY: BQCZB8XMchdrz0jyIFvgNSy0qP8ty83LeMV387mnIXZ0gW2cWLBW6ywaC8yHoDT__QZhmc5zYoU2HHhVIDYQm4ZaGmvXSkrZukjNywpLSUh4VeXe2ajVrQ9vfxQ-Y_uHtEo3u-zaLpdSd_UjFHNoylar3lJYxUL4U0ZsMwMTz4HZdYjbBSK4fbUFuPcAFZxUdlY63UfuPjBnzAF7SQwsZ99dJxkTXIMJGj03Awq6Xh3M7kESZu_4zd4gWTqhJ08fA5BjiZ1J9gHxhvpQZN5NKUgI0phDyEf1PdxfsUT9h4nXqH2IeaKTMUGl Dec 20 20:51:29 volumio-livingroom volumio[964]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 20 20:51:29 volumio-livingroom volumio[964]: info: New Spotify access token = BQCZB8XMchdrz0jyIFvgNSy0qP8ty83LeMV387mnIXZ0gW2cWLBW6ywaC8yHoDT__QZhmc5zYoU2HHhVIDYQm4ZaGmvXSkrZukjNywpLSUh4VeXe2ajVrQ9vfxQ-Y_uHtEo3u-zaLpdSd_UjFHNoylar3lJYxUL4U0ZsMwMTz4HZdYjbBSK4fbUFuPcAFZxUdlY63UfuPjBnzAF7SQwsZ99dJxkTXIMJGj03Awq6Xh3M7kESZu_4zd4gWTqhJ08fA5BjiZ1J9gHxhvpQZN5NKUgI0phDyEf1PdxfsUT9h4nXqH2IeaKTMUGl Dec 20 20:51:29 volumio-livingroom volumio[964]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 20 20:51:29 volumio-livingroom volumio[964]: SPOTIFY: User informations: {"country":"VN","display_name":"Csaba","email":"csabvarg@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/ml41vxnjulnyo8p6fb26cer9v"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/ml41vxnjulnyo8p6fb26cer9v","id":"ml41vxnjulnyo8p6fb26cer9v","images":[],"product":"premium","type":"user","uri":"spotify:user:ml41vxnjulnyo8p6fb26cer9v"} Dec 20 20:51:29 volumio-livingroom volumio[964]: info: Spotify Successfully logged in Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 20:51:29 volumio-livingroom volumio[964]: info: [1766238689659] CoreMusicLibrary::Adding element Spotify Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 20:51:29 volumio-livingroom volumio[964]: Cannot find translation for source Spotify Dec 20 20:51:29 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:29+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 20 20:51:29 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:29+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 20 20:51:29 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:29+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 20 20:51:29 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:29+07:00" level=info msg="zeroconf server listening on port 40253" Dec 20 20:51:29 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:29+07:00" level=debug msg="obtained new client token: AADNTllJdX0WaPiX3K1XWljV2P9dm0w1/qde06hmuTuPOJBld3HIcR3fHcqQUDhyoRLeb/oSX8gGVUI2j4W0qtDJior4MIpBxbA1uPPNGT7r7QTTzhBPZ1JOWl5EAwFUQz5Q4KL/k0qjYRvoDpIaiXW2TSA/ERMr27MkflbBLtp+KYcfL5Fju2ENvxR59YFrH/QGf1N8sAY5hL8dWEEVi6EOvHEcfNQ7FsYFAzpPjtzv4uQQN+3Hvz2XVQ==" Dec 20 20:51:29 volumio-livingroom sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 20:51:29 volumio-livingroom sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:29 volumio-livingroom sudo[1246]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:29 volumio-livingroom sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 20:51:29 volumio-livingroom sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:29 volumio-livingroom sudo[1248]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:29 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:29+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Dec 20 20:51:29 volumio-livingroom volumio[964]: verbose: New Socket.io Connection to 192.168.2.12 from 192.168.2.131 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Dec 20 20:51:29 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:29+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 20:51:29 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 20:51:30 volumio-livingroom volumio[964]: info: Received Get System Info Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 20:51:30 volumio-livingroom volumio[964]: info: Discovery: Getting this device information Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:30 volumio-livingroom volumio[964]: info: Listing playlists Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 20:51:30 volumio-livingroom sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 20:51:30 volumio-livingroom sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:30 volumio-livingroom sudo[1252]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:30 volumio-livingroom sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 20:51:30 volumio-livingroom sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:30 volumio-livingroom sudo[1254]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:30 volumio-livingroom volumio[964]: verbose: New Socket.io Connection to 192.168.2.12 from 192.168.2.131 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Dec 20 20:51:30 volumio-livingroom volumio[964]: info: [yt-cast-receiver] DIAL server listening on port 8098 Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreStateMachine::pushState Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioPushState Dec 20 20:51:30 volumio-livingroom volumio[964]: error: [ytcr] VolumeControl failed to obtain volume from Volumio: Dec 20 20:51:30 volumio-livingroom volumio[964]: (TypeError) Cannot read property 'vol' of undefined Dec 20 20:51:30 volumio-livingroom volumio[964]: TypeError: Cannot read property 'vol' of undefined Dec 20 20:51:30 volumio-livingroom volumio[964]: at VolumeControl.getVolume (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:56:42) Dec 20 20:51:30 volumio-livingroom volumio[964]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 20 20:51:30 volumio-livingroom volumio[964]: at async VolumeControl.init (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:28:68) Dec 20 20:51:30 volumio-livingroom volumio[964]: at async /data/plugins/music_service/ytcr/dist/index.js:310:13 Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 20:51:30 volumio-livingroom volumio[964]: info: Received Get System Info Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 20:51:30 volumio-livingroom volumio[964]: info: Discovery: Getting this device information Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:30 volumio-livingroom volumio[964]: info: Listing playlists Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 20 20:51:30 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 20:51:30 volumio-livingroom sh[502]: timed out Dec 20 20:51:30 volumio-livingroom dhcpcd[546]: timed out Dec 20 20:51:30 volumio-livingroom sh[502]: dhcpcd exited Dec 20 20:51:30 volumio-livingroom dhcpcd[546]: dhcpcd exited Dec 20 20:51:30 volumio-livingroom sh[502]: ifup: failed to bring up eth0 Dec 20 20:51:30 volumio-livingroom systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 20 20:51:30 volumio-livingroom systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 20 20:51:30 volumio-livingroom mpd[1166]: Dec 20 20:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 20:51:30 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:30+07:00" level=debug msg="completed keyexchange" Dec 20 20:51:30 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:30+07:00" level=debug msg="completed challenge" Dec 20 20:51:30 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:30+07:00" level=info msg="authenticated AP" username="ml*********************9v" Dec 20 20:51:30 volumio-livingroom systemd[1]: Started Music Player Daemon. Dec 20 20:51:30 volumio-livingroom sudo[1135]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:30 volumio-livingroom sudo[1120]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:30 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:30+07:00" level=info msg="authenticated Login5" username="ml*********************9v" Dec 20 20:51:30 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:30+07:00" level=debug msg="initializing zeroconf session" username="ml*********************9v" Dec 20 20:51:30 volumio-livingroom volumio[964]: info: Completed starting Core Plugins Dec 20 20:51:30 volumio-livingroom volumio[964]: info: ------------------------------------------- Dec 20 20:51:30 volumio-livingroom volumio[964]: info: ----- MyVolumio plugins startup ---- Dec 20 20:51:30 volumio-livingroom volumio[964]: info: ------------------------------------------- Dec 20 20:51:30 volumio-livingroom volumio[964]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 20 20:51:30 volumio-livingroom volumio[964]: error: MPD error: The expression evaluated to a falsy value: Dec 20 20:51:30 volumio-livingroom volumio[964]: assert.ok(self.idling) Dec 20 20:51:30 volumio-livingroom volumio[964]: error: The expression evaluated to a falsy value: Dec 20 20:51:30 volumio-livingroom volumio[964]: assert.ok(self.idling) Dec 20 20:51:30 volumio-livingroom volumio[964]: info: MPD running with PID1166 Dec 20 20:51:30 volumio-livingroom volumio[964]: ,establishing connection Dec 20 20:51:30 volumio-livingroom volumio[964]: error: MPD error: The expression evaluated to a falsy value: Dec 20 20:51:30 volumio-livingroom volumio[964]: assert.ok(self.idling) Dec 20 20:51:30 volumio-livingroom volumio[964]: error: The expression evaluated to a falsy value: Dec 20 20:51:30 volumio-livingroom volumio[964]: assert.ok(self.idling) Dec 20 20:51:30 volumio-livingroom volumio[964]: error: updateQueue error: null Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=debug msg="dealer connection opened" Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=trace msg="starting accesspoint recv loop" Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=trace msg="starting dealer recv loop" Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=trace msg="received accesspoint ping" Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=debug msg="received connection id: NzIzYjBlNWQtMTA3...M0M0QjJGMkUzNQ==" Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=trace msg="received accesspoint pong ack" Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=debug msg="put connect state because NEW_DEVICE" Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=debug msg="update volume requested to 65535/65535" Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 20 20:51:31 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:31+07:00" level=trace msg="emitting websocket event: volume" Dec 20 20:51:31 volumio-livingroom volumio[964]: info: go-librespot daemon successfully initialized Dec 20 20:51:31 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 20 20:51:32 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 20:51:32 volumio-livingroom volumio[964]: info: Received Get System Info Dec 20 20:51:32 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 20:51:32 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 20:51:32 volumio-livingroom volumio[964]: info: Discovery: Getting this device information Dec 20 20:51:32 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioGetState Dec 20 20:51:32 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:32 volumio-livingroom volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 20:51:32 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:32+07:00" level=debug msg="handling transfer player command from 9eddd26d539edf4b9b2fef41d2a4b7ddf419bed7" Dec 20 20:51:32 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:32+07:00" level=debug msg="resolved context of track" uri="spotify:search:en+meg+te" Dec 20 20:51:32 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:32+07:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:search:en+meg+te" Dec 20 20:51:32 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:32+07:00" level=debug msg="loading track (paused: true, position: 48490ms)" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:32 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:32+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 20 20:51:32 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:32+07:00" level=trace msg="emitting websocket event: will_play" Dec 20 20:51:32 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:32+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Dec 20 20:51:32 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:32+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1135" Dec 20 20:51:32 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:32+07:00" level=debug msg="selected format OGG_VORBIS_320 (d90278b941cd65ff80fb97969d73cb574fd1786d)" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:32 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:32+07:00" level=debug msg="requested aes key for file d90278b941cd65ff80fb97969d73cb574fd1786d, gid: 3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:32 volumio-livingroom sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 20:51:32 volumio-livingroom sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:32 volumio-livingroom sudo[1277]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:32 volumio-livingroom sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 20:51:32 volumio-livingroom sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:32 volumio-livingroom sudo[1279]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:32 volumio-livingroom sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 20 20:51:32 volumio-livingroom sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:32 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:32+07:00" level=trace msg="found 2 cdn urls" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:32 volumio-livingroom systemd[1]: Started UPnP Renderer front-end to MPD. Dec 20 20:51:32 volumio-livingroom sudo[1283]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:32 volumio-livingroom volumio[964]: info: Upmpdcli Daemon Started Dec 20 20:51:33 volumio-livingroom volumio[1285]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 20 20:51:33 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:33+07:00" level=debug msg="fetched first chunk of 20, total size is 10455132 bytes" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:33 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:33+07:00" level=debug msg="fetched chunk 2/19, size: 524288" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:33 volumio-livingroom sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 20:51:33 volumio-livingroom sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:33 volumio-livingroom sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 20:51:33 volumio-livingroom sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 20:51:33 volumio-livingroom systemd[1]: Started MPD Monitor Service. Dec 20 20:51:34 volumio-livingroom mpd_monitor.sh[1306]: MPD Monitor Service: Starting MPD Monitor Service Dec 20 20:51:34 volumio-livingroom sudo[1302]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:34 volumio-livingroom volumio[964]: info: Successfully started MPD Monitor Dec 20 20:51:34 volumio-livingroom systemd[1]: Stopping MPD Monitor Service... Dec 20 20:51:34 volumio-livingroom systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 20 20:51:34 volumio-livingroom systemd[1]: mpd_monitor.service: Succeeded. Dec 20 20:51:34 volumio-livingroom systemd[1]: Stopped MPD Monitor Service. Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=debug msg="fetched chunk 1/19, size: 524288" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:34 volumio-livingroom systemd[1]: Started MPD Monitor Service. Dec 20 20:51:34 volumio-livingroom sudo[1305]: pam_unix(sudo:session): session closed for user root Dec 20 20:51:34 volumio-livingroom volumio[964]: info: Successfully started MPD Monitor Dec 20 20:51:34 volumio-livingroom mpd_monitor.sh[1309]: MPD Monitor Service: Starting MPD Monitor Service Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=debug msg="fetched chunk 6/19, size: 524288" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=debug msg="fetched chunk 5/19, size: 524288" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=debug msg="fetched chunk 3/19, size: 524288" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=debug msg="fetched chunk 4/19, size: 524288" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=trace msg="seek to 48490ms (diff: 91ms, samples: 2138409, bytes: 2100939)" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=debug msg="created new output device" Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=info msg="loaded track \"Együtt én meg te - Együtt kezdtük filmzene\" (paused: true, position: 48490ms, duration: 237000ms, prefetched: false)" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=debug msg="fetched chunk 7/19, size: 524288" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=trace msg="emitting websocket event: metadata" Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=trace msg="emitting websocket event: active" Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=debug msg="sending successful reply for dealer request" Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=trace msg="emitting websocket event: paused" Dec 20 20:51:34 volumio-livingroom volumio[964]: info: Initializing connection to go-librespot Websocket Dec 20 20:51:34 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:34+07:00" level=debug msg="new websocket client" Dec 20 20:51:34 volumio-livingroom volumio[964]: info: Connection to go-librespot Websocket established Dec 20 20:51:36 volumio-livingroom volumio[964]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 20 20:51:36 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:36+07:00" level=debug msg="handling seek_to player command from 9eddd26d539edf4b9b2fef41d2a4b7ddf419bed7" Dec 20 20:51:36 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:36+07:00" level=debug msg="seek track to 0ms" Dec 20 20:51:36 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:36+07:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:36 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:36+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 20 20:51:36 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:36+07:00" level=trace msg="emitting websocket event: seek" Dec 20 20:51:36 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:36+07:00" level=debug msg="sending successful reply for dealer request" Dec 20 20:51:36 volumio-livingroom volumio[964]: SPOTIFY: received: {"type":"seek","data":{"context_uri":"spotify:search:en+meg+te","uri":"spotify:track:3vPz4qCW4rHGuw818DNiq6","position":0,"duration":237000,"play_origin":"search"}} Dec 20 20:51:36 volumio-livingroom volumio[964]: SPOTIFY: PUSH STATE SPOTIFY Dec 20 20:51:36 volumio-livingroom volumio[964]: SPOTIFY: {"status":"stop","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 20 20:51:36 volumio-livingroom volumio[964]: info: CoreCommandRouter::servicePushState Dec 20 20:51:36 volumio-livingroom volumio[964]: info: CoreStateMachine::pushState Dec 20 20:51:36 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:36 volumio-livingroom volumio[964]: info: CoreCommandRouter::volumioPushState Dec 20 20:51:36 volumio-livingroom volumio[964]: info: CorePlayQueue::getTrack 0 Dec 20 20:51:36 volumio-livingroom volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received spop Dec 20 20:51:37 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:37+07:00" level=debug msg="handling resume player command from 9eddd26d539edf4b9b2fef41d2a4b7ddf419bed7" Dec 20 20:51:37 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:37+07:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:37 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:37+07:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:3vPz4qCW4rHGuw818DNiq6" Dec 20 20:51:37 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:37+07:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames, PCM format = FLOAT_LE" Dec 20 20:51:37 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:37+07:00" level=debug msg="resume track at 0ms" Dec 20 20:51:37 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:37+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 20 20:51:37 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:37+07:00" level=trace msg="scheduling prefetch in 207s" Dec 20 20:51:37 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:37+07:00" level=debug msg="sending successful reply for dealer request" Dec 20 20:51:37 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:37+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 20 20:51:37 volumio-livingroom go-librespot[1187]: time="2025-12-20T20:51:37+07:00" level=trace msg="emitting websocket event: playing" Dec 20 20:51:37 volumio-livingroom volumio[964]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:search:en+meg+te","uri":"spotify:track:3vPz4qCW4rHGuw818DNiq6","resume":true,"play_origin":"search"}} Dec 20 20:51:37 volumio-livingroom volumio[964]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 20:51:37 volumio-livingroom volumio[964]: TypeError: Cannot read property 'service' of undefined Dec 20 20:51:37 volumio-livingroom volumio[964]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Dec 20 20:51:37 volumio-livingroom volumio[964]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:272:18) Dec 20 20:51:37 volumio-livingroom volumio[964]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Dec 20 20:51:37 volumio-livingroom volumio[964]: at WebSocket.emit (events.js:315:20) Dec 20 20:51:37 volumio-livingroom volumio[964]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Dec 20 20:51:37 volumio-livingroom volumio[964]: at Receiver.emit (events.js:315:20) Dec 20 20:51:37 volumio-livingroom volumio[964]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Dec 20 20:51:37 volumio-livingroom volumio[964]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Dec 20 20:51:37 volumio-livingroom volumio[964]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Dec 20 20:51:37 volumio-livingroom volumio[964]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Dec 20 20:51:37 volumio-livingroom volumio[964]: at writeOrBuffer (internal/streams/writable.js:358:12) Dec 20 20:51:37 volumio-livingroom volumio[964]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Dec 20 20:51:37 volumio-livingroom volumio[964]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Dec 20 20:51:37 volumio-livingroom volumio[964]: at Socket.emit (events.js:315:20) Dec 20 20:51:37 volumio-livingroom volumio[964]: at addChunk (internal/streams/readable.js:309:12) Dec 20 20:51:37 volumio-livingroom volumio[964]: at readableAddChunk (internal/streams/readable.js:284:9) Dec 20 20:51:37 volumio-livingroom volumio[964]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 20:51:38 volumio-livingroom sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-20 20:50 Dec 20 20:51:38 volumio-livingroom sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"