-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2025-01-20 08:46:51 CET. -- Jan 20 08:45:19 volumio-gra kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x067fffffff -> 0x0080000000 Jan 20 08:45:19 volumio-gra kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x003fffffff -> 0x0400000000 Jan 20 08:45:19 volumio-gra kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Jan 20 08:45:19 volumio-gra kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jan 20 08:45:19 volumio-gra kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x67fffffff] (bus address [0x80000000-0xffffffff]) Jan 20 08:45:19 volumio-gra kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Jan 20 08:45:19 volumio-gra kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Jan 20 08:45:19 volumio-gra kernel: PCI: bus0: Fast back to back transfers disabled Jan 20 08:45:19 volumio-gra kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Jan 20 08:45:19 volumio-gra kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Jan 20 08:45:19 volumio-gra kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Jan 20 08:45:19 volumio-gra kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Jan 20 08:45:19 volumio-gra kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Jan 20 08:45:19 volumio-gra kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Jan 20 08:45:19 volumio-gra kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Jan 20 08:45:19 volumio-gra kernel: pci 0000:01:00.0: PME# supported from D0 D3hot Jan 20 08:45:19 volumio-gra kernel: PCI: bus1: Fast back to back transfers disabled Jan 20 08:45:19 volumio-gra kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Jan 20 08:45:19 volumio-gra kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Jan 20 08:45:19 volumio-gra kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Jan 20 08:45:19 volumio-gra kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Jan 20 08:45:19 volumio-gra kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Jan 20 08:45:19 volumio-gra kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Jan 20 08:45:19 volumio-gra kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 256), Max Read Rq 512 Jan 20 08:45:19 volumio-gra kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Jan 20 08:45:19 volumio-gra kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 45 Jan 20 08:45:19 volumio-gra kernel: bcm2708_fb soc:fb: FB found 1 display(s) Jan 20 08:45:19 volumio-gra kernel: Console: switching to colour frame buffer device 80x30 Jan 20 08:45:19 volumio-gra kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Jan 20 08:45:19 volumio-gra kernel: iproc-rng200 fe104000.rng: hwrng registered Jan 20 08:45:19 volumio-gra kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Jan 20 08:45:19 volumio-gra kernel: brd: module loaded Jan 20 08:45:19 volumio-gra kernel: loop: module loaded Jan 20 08:45:19 volumio-gra kernel: Loading iSCSI transport class v2.0-870. Jan 20 08:45:19 volumio-gra kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Jan 20 08:45:19 volumio-gra kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Jan 20 08:45:19 volumio-gra kernel: usbcore: registered new device driver r8152-cfgselector Jan 20 08:45:19 volumio-gra kernel: usbcore: registered new interface driver r8152 Jan 20 08:45:19 volumio-gra kernel: usbcore: registered new interface driver lan78xx Jan 20 08:45:19 volumio-gra kernel: usbcore: registered new interface driver smsc95xx Jan 20 08:45:19 volumio-gra kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Jan 20 08:45:19 volumio-gra kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Jan 20 08:45:19 volumio-gra kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0300240000000890 Jan 20 08:45:19 volumio-gra kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Jan 20 08:45:19 volumio-gra kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Jan 20 08:45:19 volumio-gra kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Jan 20 08:45:19 volumio-gra kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Jan 20 08:45:19 volumio-gra kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 20 08:45:19 volumio-gra kernel: usb usb1: Product: xHCI Host Controller Jan 20 08:45:19 volumio-gra kernel: usb usb1: Manufacturer: Linux 6.6.62-v7l+ xhci-hcd Jan 20 08:45:19 volumio-gra kernel: usb usb1: SerialNumber: 0000:01:00.0 Jan 20 08:45:19 volumio-gra kernel: hub 1-0:1.0: USB hub found Jan 20 08:45:19 volumio-gra kernel: hub 1-0:1.0: 1 port detected Jan 20 08:45:19 volumio-gra kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Jan 20 08:45:19 volumio-gra kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 20 08:45:19 volumio-gra kernel: usb usb2: Product: xHCI Host Controller Jan 20 08:45:19 volumio-gra kernel: usb usb2: Manufacturer: Linux 6.6.62-v7l+ xhci-hcd Jan 20 08:45:19 volumio-gra kernel: usb usb2: SerialNumber: 0000:01:00.0 Jan 20 08:45:19 volumio-gra kernel: hub 2-0:1.0: USB hub found Jan 20 08:45:19 volumio-gra kernel: hub 2-0:1.0: 4 ports detected Jan 20 08:45:19 volumio-gra kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Jan 20 08:45:19 volumio-gra kernel: dwc_otg: FIQ enabled Jan 20 08:45:19 volumio-gra kernel: dwc_otg: NAK holdoff enabled Jan 20 08:45:19 volumio-gra kernel: dwc_otg: FIQ split-transaction FSM enabled Jan 20 08:45:19 volumio-gra kernel: Module dwc_common_port init Jan 20 08:45:19 volumio-gra kernel: usbcore: registered new interface driver uas Jan 20 08:45:19 volumio-gra kernel: usbcore: registered new interface driver usb-storage Jan 20 08:45:19 volumio-gra kernel: mousedev: PS/2 mouse device common for all mice Jan 20 08:45:19 volumio-gra kernel: sdhci: Secure Digital Host Controller Interface driver Jan 20 08:45:19 volumio-gra kernel: sdhci: Copyright(c) Pierre Ossman Jan 20 08:45:19 volumio-gra kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jan 20 08:45:19 volumio-gra kernel: ledtrig-cpu: registered to indicate activity on CPUs Jan 20 08:45:19 volumio-gra kernel: hid: raw HID events driver (C) Jiri Kosina Jan 20 08:45:19 volumio-gra kernel: usbcore: registered new interface driver usbhid Jan 20 08:45:19 volumio-gra kernel: usbhid: USB HID core driver Jan 20 08:45:19 volumio-gra kernel: Initializing XFRM netlink socket Jan 20 08:45:19 volumio-gra kernel: NET: Registered PF_PACKET protocol family Jan 20 08:45:19 volumio-gra kernel: Key type dns_resolver registered Jan 20 08:45:19 volumio-gra kernel: Registering SWP/SWPB emulation handler Jan 20 08:45:19 volumio-gra kernel: registered taskstats version 1 Jan 20 08:45:19 volumio-gra kernel: Loading compiled-in X.509 certificates Jan 20 08:45:19 volumio-gra kernel: Key type .fscrypt registered Jan 20 08:45:19 volumio-gra kernel: Key type fscrypt-provisioning registered Jan 20 08:45:19 volumio-gra kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Jan 20 08:45:19 volumio-gra kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Jan 20 08:45:19 volumio-gra kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Jan 20 08:45:19 volumio-gra kernel: serial serial0: tty port ttyAMA1 registered Jan 20 08:45:19 volumio-gra kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jan 20 08:45:19 volumio-gra kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Jan 20 08:45:19 volumio-gra kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Jan 20 08:45:19 volumio-gra kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Jan 20 08:45:19 volumio-gra kernel: of_cfs_init Jan 20 08:45:19 volumio-gra kernel: of_cfs_init: OK Jan 20 08:45:19 volumio-gra kernel: clk: Disabling unused clocks Jan 20 08:45:19 volumio-gra kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Jan 20 08:45:19 volumio-gra kernel: Freeing unused kernel image (initmem) memory: 2048K Jan 20 08:45:19 volumio-gra kernel: Run /init as init process Jan 20 08:45:19 volumio-gra kernel: with arguments: Jan 20 08:45:19 volumio-gra kernel: /init Jan 20 08:45:19 volumio-gra kernel: splash Jan 20 08:45:19 volumio-gra kernel: nodebug Jan 20 08:45:19 volumio-gra kernel: with environment: Jan 20 08:45:19 volumio-gra kernel: HOME=/ Jan 20 08:45:19 volumio-gra kernel: TERM=linux Jan 20 08:45:19 volumio-gra kernel: numa_policy=interleave Jan 20 08:45:19 volumio-gra kernel: imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb Jan 20 08:45:19 volumio-gra kernel: imgfile=/volumio_current.sqsh Jan 20 08:45:19 volumio-gra kernel: bootpart=UUID=51B1-040D Jan 20 08:45:19 volumio-gra kernel: datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a Jan 20 08:45:19 volumio-gra kernel: uuidconfig=cmdline.txt Jan 20 08:45:19 volumio-gra kernel: pcie_aspm=off Jan 20 08:45:19 volumio-gra kernel: bootdelay=7 Jan 20 08:45:19 volumio-gra kernel: use_kmsg=no Jan 20 08:45:19 volumio-gra kernel: mmc0: new high speed SDHC card at address 0001 Jan 20 08:45:19 volumio-gra kernel: mmcblk0: mmc0:0001 00000 3.79 GiB Jan 20 08:45:19 volumio-gra kernel: mmcblk0: p1 p2 p3 Jan 20 08:45:19 volumio-gra kernel: mmcblk0: mmc0:0001 00000 3.79 GiB Jan 20 08:45:19 volumio-gra kernel: mmc1: new high speed SDIO card at address 0001 Jan 20 08:45:19 volumio-gra kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Jan 20 08:45:19 volumio-gra kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Jan 20 08:45:19 volumio-gra kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Jan 20 08:45:19 volumio-gra kernel: usb 1-1: Product: USB2.0 Hub Jan 20 08:45:19 volumio-gra kernel: hub 1-1:1.0: USB hub found Jan 20 08:45:19 volumio-gra kernel: hub 1-1:1.0: 4 ports detected Jan 20 08:45:19 volumio-gra kernel: fuse: init (API version 7.39) Jan 20 08:45:19 volumio-gra kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 20 08:45:19 volumio-gra kernel: EXT4-fs (mmcblk0p2): recovery complete Jan 20 08:45:19 volumio-gra kernel: EXT4-fs (mmcblk0p2): mounted filesystem fb80cb44-b4d3-4b36-be35-613cba0a09fb r/w with ordered data mode. Quota mode: none. Jan 20 08:45:19 volumio-gra kernel: loop0: detected capacity change from 0 to 995064 Jan 20 08:45:19 volumio-gra kernel: EXT4-fs (mmcblk0p3): recovery complete Jan 20 08:45:19 volumio-gra kernel: EXT4-fs (mmcblk0p3): mounted filesystem 2899bccc-8620-4c7a-b56a-43fd2f7a836a r/w with ordered data mode. Quota mode: none. Jan 20 08:45:19 volumio-gra systemd[1]: System time before build time, advancing clock. Jan 20 08:45:19 volumio-gra kernel: NET: Registered PF_INET6 protocol family Jan 20 08:45:19 volumio-gra kernel: Segment Routing with IPv6 Jan 20 08:45:19 volumio-gra kernel: In-situ OAM (IOAM) with IPv6 Jan 20 08:45:19 volumio-gra 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) Jan 20 08:45:19 volumio-gra systemd[1]: Detected architecture arm. Jan 20 08:45:19 volumio-gra systemd[1]: Set hostname to . Jan 20 08:45:19 volumio-gra 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. Jan 20 08:45:19 volumio-gra systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Jan 20 08:45:19 volumio-gra systemd[1]: Created slice system-systemd\x2dfsck.slice. Jan 20 08:45:19 volumio-gra systemd[1]: Listening on fsck to fsckd communication Socket. Jan 20 08:45:19 volumio-gra systemd[1]: Reached target System Time Synchronized. Jan 20 08:45:19 volumio-gra systemd[1]: Listening on udev Control Socket. Jan 20 08:45:19 volumio-gra systemd[1]: Listening on Journal Audit Socket. Jan 20 08:45:19 volumio-gra kernel: i2c_dev: i2c /dev entries driver Jan 20 08:45:19 volumio-gra systemd-journald[304]: Journal started Jan 20 08:45:19 volumio-gra systemd-journald[304]: Runtime journal (/run/log/journal/a89b7be63bc86b985c184d84675fe488) is 7.5M, max 30.0M, 22.5M free. Jan 20 08:45:19 volumio-gra systemd-modules-load[292]: Inserted module 'i2c_dev' Jan 20 08:45:19 volumio-gra fake-hwclock[294]: Mon Jan 20 07:45:19 UTC 2025 Jan 20 08:45:19 volumio-gra systemd[1]: dynamicswap.service: Succeeded. Jan 20 08:45:19 volumio-gra systemd[1]: Started Load/Save Random Seed. Jan 20 08:45:19 volumio-gra systemd[1]: Started Create System Users. Jan 20 08:45:19 volumio-gra systemd[1]: Starting Create Static Device Nodes in /dev... Jan 20 08:45:19 volumio-gra systemd[1]: Started udev Coldplug all Devices. Jan 20 08:45:19 volumio-gra systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 20 08:45:19 volumio-gra systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 20 08:45:19 volumio-gra systemd[1]: Started Create Static Device Nodes in /dev. Jan 20 08:45:19 volumio-gra systemd[1]: Reached target Local File Systems (Pre). Jan 20 08:45:19 volumio-gra systemd[1]: Mounting /var/spool/cups... Jan 20 08:45:19 volumio-gra systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 20 08:45:19 volumio-gra systemd[1]: Mounting /var/log... Jan 20 08:45:19 volumio-gra systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 20 08:45:19 volumio-gra systemd[1]: Mounting /tmp... Jan 20 08:45:19 volumio-gra systemd[1]: Starting udev Kernel Device Manager... Jan 20 08:45:19 volumio-gra systemd[1]: Mounted /var/spool/cups. Jan 20 08:45:19 volumio-gra systemd[1]: Mounted /var/log. Jan 20 08:45:19 volumio-gra systemd[1]: Mounted /tmp. Jan 20 08:45:19 volumio-gra systemd[1]: Starting Flush Journal to Persistent Storage... Jan 20 08:45:19 volumio-gra systemd[1]: Mounting /var/spool/cups/tmp... Jan 20 08:45:19 volumio-gra systemd[1]: Mounted /var/spool/cups/tmp. Jan 20 08:45:19 volumio-gra systemd-udevd[330]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jan 20 08:45:19 volumio-gra systemd-journald[304]: Runtime journal (/run/log/journal/a89b7be63bc86b985c184d84675fe488) is 7.5M, max 30.0M, 22.5M free. Jan 20 08:45:19 volumio-gra systemd[1]: Started Flush Journal to Persistent Storage. Jan 20 08:45:19 volumio-gra systemd[1]: Started udev Kernel Device Manager. Jan 20 08:45:19 volumio-gra systemd[1]: Starting Show Plymouth Boot Screen... Jan 20 08:45:19 volumio-gra systemd[1]: plymouth-start.service: Succeeded. Jan 20 08:45:19 volumio-gra systemd[1]: Started Show Plymouth Boot Screen. Jan 20 08:45:19 volumio-gra systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 20 08:45:19 volumio-gra systemd[1]: Reached target Local Encrypted Volumes. Jan 20 08:45:19 volumio-gra systemd[1]: Reached target Paths. Jan 20 08:45:19 volumio-gra systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 20 08:45:19 volumio-gra systemd-udevd[341]: Using default interface naming scheme 'v240'. Jan 20 08:45:19 volumio-gra systemd-udevd[341]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 20 08:45:19 volumio-gra kernel: mc: Linux media interface: v0.10 Jan 20 08:45:19 volumio-gra kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 08:45:19 volumio-gra kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jan 20 08:45:19 volumio-gra kernel: [vc_sm_connected_init]: start Jan 20 08:45:19 volumio-gra kernel: [vc_sm_connected_init]: installed successfully Jan 20 08:45:20 volumio-gra kernel: videodev: Linux video capture interface: v2.00 Jan 20 08:45:20 volumio-gra kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 08:45:20 volumio-gra kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 08:45:20 volumio-gra kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 08:45:20 volumio-gra kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 08:45:20 volumio-gra kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 08:45:20 volumio-gra kernel: rpivid feb10000.codec: Device registered as /dev/video19 Jan 20 08:45:20 volumio-gra kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 20 08:45:20 volumio-gra kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jan 20 08:45:20 volumio-gra kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 20 08:45:20 volumio-gra kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 20 08:45:20 volumio-gra kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 20 08:45:20 volumio-gra kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 20 08:45:20 volumio-gra kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jan 20 08:45:20 volumio-gra kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 20 08:45:20 volumio-gra kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jan 20 08:45:20 volumio-gra kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jan 20 08:45:20 volumio-gra kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jan 20 08:45:20 volumio-gra kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Jan 20 08:45:20 volumio-gra kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Jan 20 08:45:20 volumio-gra kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 08:45:20 volumio-gra kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jan 20 08:45:20 volumio-gra kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jan 20 08:45:20 volumio-gra kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jan 20 08:45:20 volumio-gra systemd[1]: Found device /dev/disk/by-uuid/51B1-040D. Jan 20 08:45:21 volumio-gra kernel: Bluetooth: Core ver 2.22 Jan 20 08:45:21 volumio-gra kernel: NET: Registered PF_BLUETOOTH protocol family Jan 20 08:45:21 volumio-gra kernel: Bluetooth: HCI device and connection manager initialized Jan 20 08:45:21 volumio-gra kernel: Bluetooth: HCI socket layer initialized Jan 20 08:45:21 volumio-gra kernel: Bluetooth: L2CAP socket layer initialized Jan 20 08:45:21 volumio-gra kernel: Bluetooth: SCO socket layer initialized Jan 20 08:45:21 volumio-gra systemd[1]: Starting File System Check on /dev/disk/by-uuid/51B1-040D... Jan 20 08:45:21 volumio-gra kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 20 08:45:21 volumio-gra kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jan 20 08:45:21 volumio-gra kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jan 20 08:45:21 volumio-gra kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 20 08:45:21 volumio-gra kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jan 20 08:45:21 volumio-gra kernel: Bluetooth: HCI UART driver ver 2.3 Jan 20 08:45:21 volumio-gra kernel: Bluetooth: HCI UART protocol H4 registered Jan 20 08:45:21 volumio-gra kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 20 08:45:21 volumio-gra kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 20 08:45:21 volumio-gra kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jan 20 08:45:21 volumio-gra kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jan 20 08:45:21 volumio-gra kernel: uart-pl011 fe201000.serial: no DMA platform data Jan 20 08:45:21 volumio-gra systemd-udevd[338]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 20 08:45:21 volumio-gra kernel: Bluetooth: hci0: BCM: chip id 107 Jan 20 08:45:21 volumio-gra kernel: Bluetooth: hci0: BCM: features 0x2f Jan 20 08:45:21 volumio-gra kernel: Bluetooth: hci0: BCM4345C0 Jan 20 08:45:21 volumio-gra kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Jan 20 08:45:21 volumio-gra kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Jan 20 08:45:21 volumio-gra kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jan 20 08:45:21 volumio-gra kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jan 20 08:45:21 volumio-gra kernel: usbcore: registered new interface driver brcmfmac Jan 20 08:45:22 volumio-gra systemd-fsck[409]: fsck.fat 4.1 (2017-01-24) Jan 20 08:45:22 volumio-gra systemd-fsck[409]: /dev/mmcblk0p1: 402 files, 166317/184582 clusters Jan 20 08:45:22 volumio-gra systemd[1]: Started File System Check on /dev/disk/by-uuid/51B1-040D. Jan 20 08:45:22 volumio-gra kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Jan 20 08:45:22 volumio-gra 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 Jan 20 08:45:22 volumio-gra systemd-udevd[337]: Using default interface naming scheme 'v240'. Jan 20 08:45:22 volumio-gra systemd-udevd[337]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 20 08:45:22 volumio-gra kernel: Bluetooth: hci0: BCM: features 0x2f Jan 20 08:45:22 volumio-gra kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Jan 20 08:45:22 volumio-gra kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Jan 20 08:45:22 volumio-gra kernel: alsactl[453]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Jan 20 08:45:22 volumio-gra systemd-udevd[336]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 1' failed with exit code 17. Jan 20 08:45:22 volumio-gra systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Jan 20 08:45:22 volumio-gra kernel: hwmon hwmon1: Undervoltage detected! Jan 20 08:45:22 volumio-gra systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Jan 20 08:45:22 volumio-gra systemd[1]: Created slice system-bthelper.slice. Jan 20 08:45:22 volumio-gra systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 20 08:45:23 volumio-gra systemd[1]: Starting Show Plymouth Boot Screen... Jan 20 08:45:23 volumio-gra systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jan 20 08:45:23 volumio-gra systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 20 08:45:23 volumio-gra systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 20 08:45:23 volumio-gra systemd[1]: Mounting /boot... Jan 20 08:45:23 volumio-gra systemd[1]: Started File System Check Daemon to report status. Jan 20 08:45:23 volumio-gra systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 20 08:45:23 volumio-gra systemd[1]: Mounted /boot. Jan 20 08:45:23 volumio-gra systemd[1]: plymouth-start.service: Succeeded. Jan 20 08:45:23 volumio-gra systemd[1]: Started Show Plymouth Boot Screen. Jan 20 08:45:23 volumio-gra systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 20 08:45:23 volumio-gra systemd[1]: Reached target Local File Systems. Jan 20 08:45:23 volumio-gra systemd[1]: Starting Raise network interfaces... Jan 20 08:45:23 volumio-gra systemd[1]: Started ifup for eth0. Jan 20 08:45:23 volumio-gra systemd[1]: Starting Create Volatile Files and Directories... Jan 20 08:45:23 volumio-gra systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jan 20 08:45:23 volumio-gra systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 20 08:45:23 volumio-gra systemd[1]: Starting Preprocess NFS configuration... Jan 20 08:45:23 volumio-gra systemd[1]: Started ifup for wlan0. Jan 20 08:45:23 volumio-gra systemd[1]: Started Load/Save RF Kill Switch Status. Jan 20 08:45:23 volumio-gra systemd[1]: Received SIGRTMIN+20 from PID 190 (plymouthd). Jan 20 08:45:23 volumio-gra systemd[1]: plymouth-read-write.service: Succeeded. Jan 20 08:45:23 volumio-gra systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 20 08:45:23 volumio-gra systemd[1]: nfs-config.service: Succeeded. Jan 20 08:45:23 volumio-gra systemd[1]: Started Preprocess NFS configuration. Jan 20 08:45:23 volumio-gra systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 20 08:45:23 volumio-gra systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 20 08:45:23 volumio-gra systemd[1]: Reached target NFS client services. Jan 20 08:45:23 volumio-gra systemd[1]: Started Create Volatile Files and Directories. Jan 20 08:45:23 volumio-gra systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 20 08:45:23 volumio-gra kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jan 20 08:45:23 volumio-gra systemd[1]: Starting RPC bind portmap service... Jan 20 08:45:23 volumio-gra systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 20 08:45:23 volumio-gra systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 20 08:45:23 volumio-gra systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 20 08:45:23 volumio-gra systemd[1]: Reached target System Initialization. Jan 20 08:45:23 volumio-gra systemd[1]: Started Daily apt download activities. Jan 20 08:45:23 volumio-gra systemd[1]: Started Daily apt upgrade and clean activities. Jan 20 08:45:23 volumio-gra systemd[1]: Listening on triggerhappy.socket. Jan 20 08:45:23 volumio-gra systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 20 08:45:23 volumio-gra systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 20 08:45:23 volumio-gra systemd[1]: Reached target Timers. Jan 20 08:45:23 volumio-gra systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 20 08:45:23 volumio-gra systemd[1]: Reached target Sockets. Jan 20 08:45:23 volumio-gra systemd[1]: Reached target Basic System. Jan 20 08:45:23 volumio-gra systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 20 08:45:23 volumio-gra kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 20 08:45:23 volumio-gra kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 20 08:45:23 volumio-gra systemd[1]: Started Volumio Iptables Module. Jan 20 08:45:23 volumio-gra systemd[1]: Starting Wireless Services... Jan 20 08:45:23 volumio-gra systemd[1]: Started MPD Monitor Service. Jan 20 08:45:23 volumio-gra systemd[1]: Started Volumio Log Rotation Service. Jan 20 08:45:23 volumio-gra systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 20 08:45:23 volumio-gra systemd[1]: Starting dhcpcd on all interfaces... Jan 20 08:45:23 volumio-gra systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 20 08:45:23 volumio-gra systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 20 08:45:23 volumio-gra systemd[1]: Starting Volumio Time Update Utility... Jan 20 08:45:23 volumio-gra systemd[1]: Starting triggerhappy global hotkey daemon... Jan 20 08:45:23 volumio-gra systemd[1]: Started UPnP Renderer front-end to MPD. Jan 20 08:45:23 volumio-gra systemd[1]: Started volumio-remote-updater.service. Jan 20 08:45:23 volumio-gra systemd[1]: Started D-Bus System Message Bus. Jan 20 08:45:23 volumio-gra systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jan 20 08:45:23 volumio-gra systemd[1]: Starting Login Service... Jan 20 08:45:23 volumio-gra systemd[1]: Starting WPA supplicant... Jan 20 08:45:23 volumio-gra systemd[1]: Started Manage Sound Card State (restore and store). Jan 20 08:45:23 volumio-gra systemd[1]: Starting Save/Restore Sound Card State... Jan 20 08:45:23 volumio-gra alsactl[587]: alsactl 1.1.8 daemon started Jan 20 08:45:23 volumio-gra dhcpcd[565]: Not running dhcpcd because /etc/network/interfaces Jan 20 08:45:23 volumio-gra dhcpcd[565]: defines some interfaces that will use a Jan 20 08:45:23 volumio-gra dhcpcd[565]: DHCP client or static address Jan 20 08:45:23 volumio-gra systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jan 20 08:45:23 volumio-gra systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jan 20 08:45:23 volumio-gra volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Jan 20 08:45:23 volumio-gra systemd[1]: Failed to start dhcpcd on all interfaces. Jan 20 08:45:23 volumio-gra thd[569]: Unable to parse trigger line: Jan 20 08:45:23 volumio-gra thd[569]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 20 08:45:23 volumio-gra thd[569]: Unable to parse trigger line: Jan 20 08:45:24 volumio-gra thd[569]: Found socket passed from systemd Jan 20 08:45:24 volumio-gra systemd-logind[582]: New seat seat0. Jan 20 08:45:24 volumio-gra mpd_monitor.sh[563]: MPD error: Connection refused Jan 20 08:45:24 volumio-gra avahi-daemon[567]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jan 20 08:45:24 volumio-gra avahi-daemon[567]: Successfully dropped root privileges. Jan 20 08:45:24 volumio-gra avahi-daemon[567]: avahi-daemon 0.7 starting up. Jan 20 08:45:24 volumio-gra mpd_monitor.sh[563]: mpd: no process found Jan 20 08:45:24 volumio-gra sh[477]: eth0: waiting for carrier Jan 20 08:45:24 volumio-gra dhcpcd[527]: eth0: waiting for carrier Jan 20 08:45:24 volumio-gra kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jan 20 08:45:24 volumio-gra kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jan 20 08:45:25 volumio-gra systemd[1]: Started triggerhappy global hotkey daemon. Jan 20 08:45:25 volumio-gra systemd[1]: Started RPC bind portmap service. Jan 20 08:45:25 volumio-gra systemd[1]: hciuart.service: Succeeded. Jan 20 08:45:25 volumio-gra systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 20 08:45:25 volumio-gra systemd[1]: Started Save/Restore Sound Card State. Jan 20 08:45:25 volumio-gra avahi-daemon[567]: Successfully called chroot(). Jan 20 08:45:25 volumio-gra avahi-daemon[567]: Successfully dropped remaining capabilities. Jan 20 08:45:25 volumio-gra wpa_supplicant[586]: Successfully initialized wpa_supplicant Jan 20 08:45:25 volumio-gra avahi-daemon[567]: Loading service file /services/volumio.service. Jan 20 08:45:25 volumio-gra avahi-daemon[567]: Network interface enumeration completed. Jan 20 08:45:25 volumio-gra systemd[1]: Started Login Service. Jan 20 08:45:25 volumio-gra systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 20 08:45:25 volumio-gra systemd[1]: Started WPA supplicant. Jan 20 08:45:25 volumio-gra sh[487]: wlan0=wlan0 Jan 20 08:45:25 volumio-gra avahi-daemon[567]: Server startup complete. Host name is volumio-gra.local. Local service cookie is 1989327931. Jan 20 08:45:25 volumio-gra avahi-daemon[567]: Service "Volumio góra" (/services/volumio.service) successfully established. Jan 20 08:45:25 volumio-gra systemd[1]: Listening on mpd.socket. Jan 20 08:45:25 volumio-gra systemd[1]: Reached target Sound Card. Jan 20 08:45:25 volumio-gra systemd[1]: Starting Raspberry Pi bluetooth helper... Jan 20 08:45:25 volumio-gra systemd[1]: Reached target Remote File Systems (Pre). Jan 20 08:45:25 volumio-gra systemd[1]: Reached target Remote File Systems. Jan 20 08:45:25 volumio-gra volumio-remote-updater[571]: Error: No active session Jan 20 08:45:25 volumio-gra volumio-remote-updater[571]: [2025-01-20 08:45:25] [info] asio async_connect error: system:111 (Connection refused) Jan 20 08:45:25 volumio-gra volumio-remote-updater[571]: [2025-01-20 08:45:25] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 20 08:45:25 volumio-gra volumio-remote-updater[571]: [2025-01-20 08:45:25] [error] handle_connect error: Underlying Transport Error Jan 20 08:45:25 volumio-gra systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 20 08:45:25 volumio-gra systemd[1]: Reached target RPC Port Mapper. Jan 20 08:45:25 volumio-gra systemd[1]: Started Raise network interfaces. Jan 20 08:45:25 volumio-gra systemd[1]: Reached target Network. Jan 20 08:45:25 volumio-gra volumio[570]: Could not open config: /tmp/upmpdcli.conf Jan 20 08:45:25 volumio-gra systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 20 08:45:25 volumio-gra volumio-time-update[568]: volumio-time-update-util: Date not found in response Jan 20 08:45:25 volumio-gra volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Jan 20 08:45:25 volumio-gra systemd[1]: Starting OpenBSD Secure Shell server... Jan 20 08:45:25 volumio-gra systemd[1]: Starting Network Time Service... Jan 20 08:45:25 volumio-gra systemd[1]: Starting Music Player Daemon... Jan 20 08:45:25 volumio-gra bthelper[660]: Raspberry Pi BDADDR already set Jan 20 08:45:25 volumio-gra systemd[1]: Starting Permit User Sessions... Jan 20 08:45:25 volumio-gra systemd[1]: Reached target Network is Online. Jan 20 08:45:25 volumio-gra systemd[1]: Starting /etc/rc.local Compatibility... Jan 20 08:45:25 volumio-gra systemd[1]: Starting Samba NMB Daemon... Jan 20 08:45:25 volumio-gra systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 20 08:45:25 volumio-gra systemd[1]: Starting LSB: Brings up/down network automatically... Jan 20 08:45:25 volumio-gra systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 20 08:45:25 volumio-gra systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 20 08:45:25 volumio-gra systemd[1]: Started Raspberry Pi bluetooth helper. Jan 20 08:45:25 volumio-gra systemd[1]: Started /etc/rc.local Compatibility. Jan 20 08:45:25 volumio-gra systemd[1]: Started Permit User Sessions. Jan 20 08:45:25 volumio-gra systemd[1]: iptables.service: Succeeded. Jan 20 08:45:25 volumio-gra systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 20 08:45:25 volumio-gra systemd[1]: Starting Hold until boot process finishes up... Jan 20 08:45:26 volumio-gra systemd[1]: Starting Bluetooth service... Jan 20 08:45:26 volumio-gra haveged[538]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jan 20 08:45:26 volumio-gra haveged[538]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jan 20 08:45:26 volumio-gra haveged[538]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00136 Jan 20 08:45:26 volumio-gra haveged[538]: haveged: fills: 0, generated: 0 Jan 20 08:45:27 volumio-gra systemd[1]: plymouth-quit.service: Succeeded. Jan 20 08:45:27 volumio-gra systemd[1]: Started Terminate Plymouth Boot Screen. Jan 20 08:45:27 volumio-gra systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 20 08:45:27 volumio-gra systemd[1]: Started Hold until boot process finishes up. Jan 20 08:45:27 volumio-gra systemd[1]: Received SIGRTMIN+21 from PID 190 (n/a). Jan 20 08:45:27 volumio-gra systemd[1]: Received SIGRTMIN+21 from PID 190 (n/a). Jan 20 08:45:27 volumio-gra systemd[1]: Started Getty on tty1. Jan 20 08:45:27 volumio-gra systemd[1]: Reached target Login Prompts. Jan 20 08:45:27 volumio-gra ifplugd(eth0)[720]: ifplugd 0.28 initializing. Jan 20 08:45:27 volumio-gra ifplugd(eth0)[720]: Using interface eth0/D8:3A:DD:9B:83:39 with driver (version: 6.6.62-v7l+) Jan 20 08:45:27 volumio-gra ifplugd(eth0)[720]: Using detection mode: SIOCETHTOOL Jan 20 08:45:27 volumio-gra ifplugd(eth0)[720]: Initialization complete, link beat not detected. Jan 20 08:45:28 volumio-gra ntpd[678]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 20 08:45:28 volumio-gra systemd[1]: Started Network Time Service. Jan 20 08:45:28 volumio-gra ntpd[678]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jan 20 08:45:28 volumio-gra ntpd[724]: proto: precision = 2.018 usec (-19) Jan 20 08:45:28 volumio-gra ntpd[724]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 20 08:45:28 volumio-gra ntpd[724]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 20 08:45:28 volumio-gra ntpd[724]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 24 days ago Jan 20 08:45:28 volumio-gra ntpd[724]: Listen and drop on 0 v6wildcard [::]:123 Jan 20 08:45:28 volumio-gra sudo[679]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:45:28 volumio-gra ntpd[724]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 20 08:45:28 volumio-gra sudo[679]: root : unable to resolve host volumio-gra Jan 20 08:45:28 volumio-gra sudo[679]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:28 volumio-gra ntpd[724]: Listen normally on 2 lo 127.0.0.1:123 Jan 20 08:45:28 volumio-gra ntpd[724]: Listening on routing socket on fd #19 for interface updates Jan 20 08:45:28 volumio-gra ntpd[724]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 20 08:45:28 volumio-gra ntpd[724]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 20 08:45:28 volumio-gra ifplugd[687]: Network Interface Plugging Daemon...start eth0...done. Jan 20 08:45:28 volumio-gra systemd[1]: Started LSB: Brings up/down network automatically. Jan 20 08:45:28 volumio-gra sshd[721]: Server listening on 0.0.0.0 port 22. Jan 20 08:45:28 volumio-gra sshd[721]: Server listening on :: port 22. Jan 20 08:45:28 volumio-gra systemd[1]: Started OpenBSD Secure Shell server. Jan 20 08:45:28 volumio-gra systemd[1]: systemd-rfkill.service: Succeeded. Jan 20 08:45:28 volumio-gra loadcpufreq[661]: Loading cpufreq kernel modules...done (none). Jan 20 08:45:28 volumio-gra systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 20 08:45:28 volumio-gra bluetoothd[696]: Bluetooth daemon 5.50 Jan 20 08:45:28 volumio-gra systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 20 08:45:28 volumio-gra bluetoothd[696]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 20 08:45:28 volumio-gra bluetoothd[696]: Starting SDP server Jan 20 08:45:28 volumio-gra bluetoothd[696]: Excluding (cli) sap Jan 20 08:45:28 volumio-gra systemd[1]: Started Bluetooth service. Jan 20 08:45:28 volumio-gra systemd[1]: Reached target Bluetooth. Jan 20 08:45:28 volumio-gra kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 20 08:45:28 volumio-gra kernel: Bluetooth: BNEP filters: protocol multicast Jan 20 08:45:28 volumio-gra kernel: Bluetooth: BNEP socket layer initialized Jan 20 08:45:28 volumio-gra kernel: Bluetooth: MGMT ver 1.22 Jan 20 08:45:28 volumio-gra bluetoothd[696]: Bluetooth management interface 1.22 initialized Jan 20 08:45:28 volumio-gra dbus-daemon[572]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=696 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 20 08:45:28 volumio-gra sudo[679]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 20 08:45:28 volumio-gra sudo[679]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:28 volumio-gra sudo[679]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 20 08:45:28 volumio-gra sudo[679]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:28 volumio-gra cpufrequtils[746]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 20 08:45:28 volumio-gra bluetoothd[696]: Failed to set privacy: Rejected (0x0b) Jan 20 08:45:28 volumio-gra systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 20 08:45:28 volumio-gra systemd[1]: Starting Hostname Service... Jan 20 08:45:28 volumio-gra dbus-daemon[572]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 20 08:45:28 volumio-gra systemd[1]: Started Hostname Service. Jan 20 08:45:29 volumio-gra ntpd[724]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 20 08:45:29 volumio-gra sh[477]: eth0: carrier acquired Jan 20 08:45:29 volumio-gra dhcpcd[527]: eth0: carrier acquired Jan 20 08:45:29 volumio-gra kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jan 20 08:45:29 volumio-gra ifplugd(eth0)[720]: Link beat detected. Jan 20 08:45:29 volumio-gra sh[477]: DUID 00:01:00:01:2e:f2:a2:da:d8:3a:dd:9b:83:39 Jan 20 08:45:29 volumio-gra sh[477]: eth0: IAID dd:9b:83:39 Jan 20 08:45:29 volumio-gra dhcpcd[527]: DUID 00:01:00:01:2e:f2:a2:da:d8:3a:dd:9b:83:39 Jan 20 08:45:29 volumio-gra dhcpcd[527]: eth0: IAID dd:9b:83:39 Jan 20 08:45:29 volumio-gra sh[477]: eth0: adding address fe80::99bc:d01c:bcff:3e63 Jan 20 08:45:29 volumio-gra sh[477]: ipv6_addaddr1: Permission denied Jan 20 08:45:29 volumio-gra dhcpcd[527]: eth0: adding address fe80::99bc:d01c:bcff:3e63 Jan 20 08:45:29 volumio-gra dhcpcd[527]: ipv6_addaddr1: Permission denied Jan 20 08:45:29 volumio-gra ifplugd(eth0)[720]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 20 08:45:29 volumio-gra sh[477]: eth0: soliciting a DHCP lease Jan 20 08:45:29 volumio-gra dhcpcd[527]: eth0: soliciting a DHCP lease Jan 20 08:45:29 volumio-gra ifplugd(eth0)[720]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jan 20 08:45:29 volumio-gra sh[477]: eth0: soliciting an IPv6 router Jan 20 08:45:29 volumio-gra dhcpcd[527]: eth0: soliciting an IPv6 router Jan 20 08:45:29 volumio-gra nmbd[691]: [2025/01/20 08:45:29.661179, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 20 08:45:29 volumio-gra nmbd[691]: started asyncdns process 769 Jan 20 08:45:29 volumio-gra nmbd[691]: [2025/01/20 08:45:29.664582, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 20 08:45:29 volumio-gra nmbd[691]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 20 08:45:29 volumio-gra nmbd[691]: [2025/01/20 08:45:29.664834, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 20 08:45:29 volumio-gra nmbd[691]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 20 08:45:30 volumio-gra ntpd[724]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 20 08:45:30 volumio-gra wireless.js[562]: Cleaning previous... Jan 20 08:45:30 volumio-gra sudo[777]: root : unable to resolve host volumio-gra Jan 20 08:45:30 volumio-gra sudo[777]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:30 volumio-gra sudo[777]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 20 08:45:30 volumio-gra sudo[777]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:30 volumio-gra sudo[777]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:30 volumio-gra sudo[779]: root : unable to resolve host volumio-gra Jan 20 08:45:30 volumio-gra sudo[779]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:30 volumio-gra sudo[779]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 20 08:45:30 volumio-gra volumio-remote-updater[571]: [2025-01-20 08:45:30] [info] asio async_connect error: system:111 (Connection refused) Jan 20 08:45:30 volumio-gra volumio-remote-updater[571]: [2025-01-20 08:45:30] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 20 08:45:30 volumio-gra volumio-remote-updater[571]: [2025-01-20 08:45:30] [error] handle_connect error: Underlying Transport Error Jan 20 08:45:30 volumio-gra sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:30 volumio-gra volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Jan 20 08:45:30 volumio-gra volumio-time-update[568]: volumio-time-update-util: Date not found in response Jan 20 08:45:30 volumio-gra volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Jan 20 08:45:30 volumio-gra bthelper[660]: Changing power off succeeded Jan 20 08:45:31 volumio-gra ntpd[724]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 20 08:45:31 volumio-gra sudo[779]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:31 volumio-gra wireless.js[562]: Stopped aP Jan 20 08:45:31 volumio-gra bthelper[660]: [CHG] Controller D8:3A:DD:9B:83:3B Class: 0x0000041c Jan 20 08:45:31 volumio-gra bthelper[660]: Changing power on succeeded Jan 20 08:45:31 volumio-gra sudo[798]: volumio : unable to resolve host volumio-gra Jan 20 08:45:31 volumio-gra sudo[798]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:31 volumio-gra sudo[798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 20 08:45:31 volumio-gra sudo[798]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:31 volumio-gra kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 20 08:45:31 volumio-gra sudo[798]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:31 volumio-gra sudo[800]: volumio : unable to resolve host volumio-gra Jan 20 08:45:31 volumio-gra sudo[800]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:31 volumio-gra sudo[800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 20 08:45:31 volumio-gra sudo[800]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:31 volumio-gra sudo[800]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:31 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:45:31 volumio-gra sudo[807]: volumio : unable to resolve host volumio-gra Jan 20 08:45:31 volumio-gra sudo[807]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:31 volumio-gra sudo[807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 20 08:45:31 volumio-gra sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:31 volumio-gra sudo[807]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:31 volumio-gra sudo[809]: volumio : unable to resolve host volumio-gra Jan 20 08:45:31 volumio-gra sudo[809]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:31 volumio-gra sudo[809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 20 08:45:31 volumio-gra sudo[809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:31 volumio-gra sh[477]: eth0: offered 192.168.100.79 from 192.168.100.1 Jan 20 08:45:31 volumio-gra dhcpcd[527]: eth0: offered 192.168.100.79 from 192.168.100.1 Jan 20 08:45:31 volumio-gra sh[477]: eth0: probing address 192.168.100.79/24 Jan 20 08:45:31 volumio-gra dhcpcd[527]: eth0: probing address 192.168.100.79/24 Jan 20 08:45:32 volumio-gra ntpd[724]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 20 08:45:33 volumio-gra mpd[760]: Jan 20 08:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 20 08:45:33 volumio-gra systemd[1]: Started Music Player Daemon. Jan 20 08:45:33 volumio-gra mpd_monitor.sh[563]: MPD restarted due to no mpc output. Jan 20 08:45:34 volumio-gra sudo[809]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:34 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:45:34 volumio-gra wireless.js[562]: SETTING APPROPRIATE REG DOMAIN: DE Jan 20 08:45:34 volumio-gra sudo[817]: volumio : unable to resolve host volumio-gra Jan 20 08:45:34 volumio-gra sudo[817]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:34 volumio-gra sudo[817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 20 08:45:34 volumio-gra sudo[817]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:34 volumio-gra sudo[817]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:34 volumio-gra sudo[819]: volumio : unable to resolve host volumio-gra Jan 20 08:45:34 volumio-gra sudo[819]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:34 volumio-gra sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jan 20 08:45:34 volumio-gra sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:34 volumio-gra sudo[819]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:34 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:45:34 volumio-gra wireless.js[562]: SUCCESSFULLY SET NEW REGDOMAIN: DE Jan 20 08:45:34 volumio-gra wireless.js[562]: Start wireless flow Jan 20 08:45:34 volumio-gra wireless.js[562]: Stopped hotspot (if there).. Jan 20 08:45:34 volumio-gra sudo[824]: root : unable to resolve host volumio-gra Jan 20 08:45:34 volumio-gra sudo[824]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:34 volumio-gra sudo[824]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 20 08:45:34 volumio-gra sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:34 volumio-gra sudo[824]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:34 volumio-gra sudo[826]: root : unable to resolve host volumio-gra Jan 20 08:45:34 volumio-gra sudo[826]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:34 volumio-gra sudo[826]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 20 08:45:34 volumio-gra sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:34 volumio-gra sudo[826]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:34 volumio-gra wireless.js[562]: DHCP IP Jan 20 08:45:34 volumio-gra wireless.js[562]: Start ap Jan 20 08:45:34 volumio-gra wpa_supplicant[828]: Successfully initialized wpa_supplicant Jan 20 08:45:34 volumio-gra sudo[829]: root : unable to resolve host volumio-gra Jan 20 08:45:34 volumio-gra sudo[829]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:34 volumio-gra sudo[829]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 20 08:45:34 volumio-gra kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 20 08:45:34 volumio-gra sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:35 volumio-gra dhcpcd[830]: dev: loaded udev Jan 20 08:45:35 volumio-gra dhcpcd-run-hooks[839]: wlan0: starting wpa_supplicant Jan 20 08:45:35 volumio-gra dhcpcd-run-hooks[844]: wlan0: failed to start wpa_supplicant Jan 20 08:45:35 volumio-gra dhcpcd-run-hooks[845]: 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 Jan 20 08:45:35 volumio-gra dhcpcd[830]: wlan0: connected to Access Point `' Jan 20 08:45:35 volumio-gra dhcpcd[830]: DUID 00:01:00:01:2e:f2:a2:da:d8:3a:dd:9b:83:39 Jan 20 08:45:35 volumio-gra dhcpcd[830]: eth0: IAID dd:9b:83:39 Jan 20 08:45:35 volumio-gra dhcpcd[830]: eth0: adding address fe80::99bc:d01c:bcff:3e63 Jan 20 08:45:35 volumio-gra dhcpcd[830]: ipv6_addaddr1: Permission denied Jan 20 08:45:35 volumio-gra dhcpcd[830]: wlan0: waiting for carrier Jan 20 08:45:35 volumio-gra dhcpcd[830]: eth0: soliciting an IPv6 router Jan 20 08:45:35 volumio-gra volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Jan 20 08:45:35 volumio-gra volumio-time-update[568]: volumio-time-update-util: Date not found in response Jan 20 08:45:35 volumio-gra volumio-time-update[568]: volumio-time-update-util: Retrying in 5 seconds... Jan 20 08:45:35 volumio-gra wireless.js[562]: trying... Jan 20 08:45:35 volumio-gra dhcpcd[830]: eth0: soliciting a DHCP lease Jan 20 08:45:36 volumio-gra sudo[861]: volumio : unable to resolve host volumio-gra Jan 20 08:45:36 volumio-gra sudo[861]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:36 volumio-gra sudo[861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 08:45:36 volumio-gra sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:36 volumio-gra sudo[861]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:36 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:45:36 volumio-gra sh[477]: eth0: leased 192.168.100.79 for 3024000 seconds Jan 20 08:45:36 volumio-gra sh[477]: eth0: adding route to 192.168.100.0/24 Jan 20 08:45:36 volumio-gra sh[477]: eth0: adding default route via 192.168.100.1 Jan 20 08:45:36 volumio-gra dhcpcd[527]: eth0: leased 192.168.100.79 for 3024000 seconds Jan 20 08:45:36 volumio-gra avahi-daemon[567]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.100.79. Jan 20 08:45:36 volumio-gra avahi-daemon[567]: New relevant interface eth0.IPv4 for mDNS. Jan 20 08:45:36 volumio-gra dhcpcd[527]: eth0: adding route to 192.168.100.0/24 Jan 20 08:45:36 volumio-gra avahi-daemon[567]: Registering new address record for 192.168.100.79 on eth0.IPv4. Jan 20 08:45:36 volumio-gra dhcpcd[527]: eth0: adding default route via 192.168.100.1 Jan 20 08:45:36 volumio-gra sh[477]: forked to background, child pid 883 Jan 20 08:45:36 volumio-gra dhcpcd[527]: forked to background, child pid 883 Jan 20 08:45:36 volumio-gra wireless.js[562]: trying... Jan 20 08:45:37 volumio-gra sudo[906]: volumio : unable to resolve host volumio-gra Jan 20 08:45:37 volumio-gra sudo[906]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:37 volumio-gra sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 08:45:37 volumio-gra sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:37 volumio-gra ifplugd(eth0)[720]: client: ifup: interface eth0 already configured Jan 20 08:45:37 volumio-gra sudo[906]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:37 volumio-gra sh[477]: eth0=eth0 Jan 20 08:45:37 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:45:37 volumio-gra ifplugd(eth0)[720]: Program executed successfully. Jan 20 08:45:37 volumio-gra wireless.js[562]: trying... Jan 20 08:45:37 volumio-gra volumio-remote-updater[571]: [2025-01-20 08:45:37] [info] asio async_connect error: system:111 (Connection refused) Jan 20 08:45:37 volumio-gra volumio-remote-updater[571]: [2025-01-20 08:45:37] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 20 08:45:37 volumio-gra volumio-remote-updater[571]: [2025-01-20 08:45:37] [error] handle_connect error: Underlying Transport Error Jan 20 08:45:38 volumio-gra sudo[913]: volumio : unable to resolve host volumio-gra Jan 20 08:45:38 volumio-gra sudo[913]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:38 volumio-gra sudo[913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 08:45:38 volumio-gra sudo[913]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:38 volumio-gra sudo[913]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:38 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:45:38 volumio-gra dhcpcd[830]: eth0: offered 192.168.100.79 from 192.168.100.1 Jan 20 08:45:38 volumio-gra dhcpcd[830]: eth0: leased 192.168.100.79 for 3024000 seconds Jan 20 08:45:38 volumio-gra dhcpcd[830]: eth0: adding route to 192.168.100.0/24 Jan 20 08:45:38 volumio-gra dhcpcd[830]: eth0: adding default route via 192.168.100.1 Jan 20 08:45:38 volumio-gra dhcpcd[830]: forked to background, child pid 935 Jan 20 08:45:38 volumio-gra sudo[829]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:38 volumio-gra wireless.js[562]: trying... Jan 20 08:45:39 volumio-gra sudo[937]: volumio : unable to resolve host volumio-gra Jan 20 08:45:39 volumio-gra sudo[937]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:39 volumio-gra sudo[937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 08:45:39 volumio-gra sudo[937]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:39 volumio-gra sudo[937]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:39 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:45:39 volumio-gra nmbd[691]: [2025/01/20 08:45:39.967366, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 20 08:45:39 volumio-gra nmbd[691]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 20 08:45:39 volumio-gra systemd[1]: Started Samba NMB Daemon. Jan 20 08:45:39 volumio-gra wireless.js[562]: trying... Jan 20 08:45:40 volumio-gra sudo[942]: volumio : unable to resolve host volumio-gra Jan 20 08:45:40 volumio-gra systemd[1]: Starting Samba Winbind Daemon... Jan 20 08:45:40 volumio-gra sudo[942]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:40 volumio-gra ntpd[724]: Listen normally on 3 eth0 192.168.100.79:123 Jan 20 08:45:40 volumio-gra ntpd[724]: new interface(s) found: waking up resolver Jan 20 08:45:40 volumio-gra sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 08:45:40 volumio-gra sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:40 volumio-gra sudo[942]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:40 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:45:40 volumio-gra winbindd[939]: [2025/01/20 08:45:40.407121, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 20 08:45:40 volumio-gra winbindd[939]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 20 08:45:40 volumio-gra winbindd[939]: [2025/01/20 08:45:40.437902, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 20 08:45:40 volumio-gra systemd[1]: Started Samba Winbind Daemon. Jan 20 08:45:40 volumio-gra winbindd[939]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 20 08:45:40 volumio-gra systemd[1]: Starting Samba SMB Daemon... Jan 20 08:45:40 volumio-gra volumio-time-update[568]: volumio-time-update-util: Fetching time from Volumio... Jan 20 08:45:40 volumio-gra volumio-time-update[568]: volumio-time-update-util: Setting system time to: 2025-01-20 08:46:14 Jan 20 08:45:40 volumio-gra sudo[953]: root : unable to resolve host volumio-gra Jan 20 08:45:40 volumio-gra volumio-time-update[568]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:45:40 volumio-gra sudo[953]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:40 volumio-gra wireless.js[562]: trying... Jan 20 08:45:40 volumio-gra sudo[953]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-01-20 08:46:14 Jan 20 08:45:40 volumio-gra sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:41 volumio-gra sudo[957]: volumio : unable to resolve host volumio-gra Jan 20 08:45:41 volumio-gra sudo[957]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:45:41 volumio-gra sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 08:45:41 volumio-gra sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:45:41 volumio-gra sudo[957]: pam_unix(sudo:session): session closed for user root Jan 20 08:45:41 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:45:41 volumio-gra dbus-daemon[572]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=956 comm="timedatectl set-time 2025-01-20 08:46:14 ") Jan 20 08:45:41 volumio-gra systemd[1]: Starting Time & Date Service... Jan 20 08:45:41 volumio-gra dbus-daemon[572]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 20 08:45:41 volumio-gra systemd[1]: Started Time & Date Service. Jan 20 08:46:14 volumio-gra systemd-timedated[961]: Changed local time to Mon Jan 20 08:46:14 2025 Jan 20 08:46:14 volumio-gra sudo[953]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:14 volumio-gra volumio-time-update[568]: volumio-time-update-util: System time updated successfully. Jan 20 08:46:14 volumio-gra systemd[1]: Started Volumio Time Update Utility. Jan 20 08:46:14 volumio-gra wpa_supplicant[843]: wlan0: Trying to associate with SSID 'SMC-5' Jan 20 08:46:14 volumio-gra dhcpcd[935]: wlan0: carrier acquired Jan 20 08:46:14 volumio-gra dhcpcd[935]: wlan0: connected to Access Point `SMC-5' Jan 20 08:46:14 volumio-gra wpa_supplicant[843]: wlan0: Associated with c4:e9:0a:47:8b:c9 Jan 20 08:46:14 volumio-gra wpa_supplicant[843]: wlan0: CTRL-EVENT-CONNECTED - Connection to c4:e9:0a:47:8b:c9 completed [id=0 id_str=] Jan 20 08:46:14 volumio-gra wpa_supplicant[843]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 20 08:46:14 volumio-gra wpa_supplicant[843]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jan 20 08:46:14 volumio-gra dhcpcd[935]: wlan0: IAID dd:9b:83:3a Jan 20 08:46:14 volumio-gra dhcpcd[935]: wlan0: adding address fe80::2423:6b8e:e57e:920f Jan 20 08:46:14 volumio-gra dhcpcd[935]: ipv6_addaddr1: Permission denied Jan 20 08:46:14 volumio-gra dhcpcd[935]: wlan0: carrier lost Jan 20 08:46:14 volumio-gra smbd[947]: [2025/01/20 08:46:14.236553, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 20 08:46:14 volumio-gra systemd[1]: Started Samba SMB Daemon. Jan 20 08:46:14 volumio-gra smbd[947]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 20 08:46:14 volumio-gra dhcpcd[935]: wlan0: carrier acquired Jan 20 08:46:14 volumio-gra dhcpcd[935]: wlan0: IAID dd:9b:83:3a Jan 20 08:46:14 volumio-gra dhcpcd[935]: wlan0: rebinding lease of 192.168.100.80 Jan 20 08:46:14 volumio-gra dhcpcd[935]: wlan0: probing address 192.168.100.80/24 Jan 20 08:46:14 volumio-gra dhcpcd[935]: wlan0: soliciting an IPv6 router Jan 20 08:46:14 volumio-gra wireless.js[562]: trying... Jan 20 08:46:14 volumio-gra sudo[987]: volumio : unable to resolve host volumio-gra Jan 20 08:46:14 volumio-gra sudo[987]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:14 volumio-gra sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 08:46:14 volumio-gra sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:14 volumio-gra sudo[987]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:14 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:46:15 volumio-gra wireless.js[562]: trying... Jan 20 08:46:15 volumio-gra sudo[990]: volumio : unable to resolve host volumio-gra Jan 20 08:46:15 volumio-gra sudo[990]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:15 volumio-gra sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 08:46:15 volumio-gra sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:15 volumio-gra sudo[990]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:15 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:46:16 volumio-gra wireless.js[562]: trying... Jan 20 08:46:16 volumio-gra sudo[996]: volumio : unable to resolve host volumio-gra Jan 20 08:46:16 volumio-gra sudo[996]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:16 volumio-gra sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 08:46:16 volumio-gra sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:16 volumio-gra sudo[996]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:16 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:46:17 volumio-gra wireless.js[562]: trying... Jan 20 08:46:17 volumio-gra sudo[999]: volumio : unable to resolve host volumio-gra Jan 20 08:46:17 volumio-gra sudo[999]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:17 volumio-gra sudo[999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 08:46:17 volumio-gra sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:17 volumio-gra sudo[999]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:17 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:46:18 volumio-gra wireless.js[562]: trying... Jan 20 08:46:18 volumio-gra sudo[1002]: volumio : unable to resolve host volumio-gra Jan 20 08:46:18 volumio-gra sudo[1002]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:18 volumio-gra sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 08:46:18 volumio-gra sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:18 volumio-gra sudo[1002]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:18 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:46:18 volumio-gra dhcpcd[935]: wlan0: leased 192.168.100.80 for 3024000 seconds Jan 20 08:46:18 volumio-gra avahi-daemon[567]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.80. Jan 20 08:46:18 volumio-gra avahi-daemon[567]: New relevant interface wlan0.IPv4 for mDNS. Jan 20 08:46:18 volumio-gra dhcpcd[935]: wlan0: adding route to 192.168.100.0/24 Jan 20 08:46:18 volumio-gra avahi-daemon[567]: Registering new address record for 192.168.100.80 on wlan0.IPv4. Jan 20 08:46:18 volumio-gra dhcpcd[935]: wlan0: adding default route via 192.168.100.1 Jan 20 08:46:19 volumio-gra wireless.js[562]: trying... Jan 20 08:46:19 volumio-gra sudo[1025]: volumio : unable to resolve host volumio-gra Jan 20 08:46:19 volumio-gra sudo[1025]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:19 volumio-gra sudo[1025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 08:46:19 volumio-gra sudo[1025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:19 volumio-gra sudo[1025]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:19 volumio-gra wireless.js[562]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:46:19 volumio-gra wireless.js[562]: Connected to: ----SMC-5 Jan 20 08:46:19 volumio-gra wireless.js[562]: ---- Jan 20 08:46:19 volumio-gra sudo[1028]: root : unable to resolve host volumio-gra Jan 20 08:46:19 volumio-gra sudo[1028]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:19 volumio-gra sudo[1028]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 20 08:46:19 volumio-gra sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:19 volumio-gra sudo[1028]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:19 volumio-gra wireless.js[562]: ... joined AP, wlan0 IPv4 is 192.168.100.80, ipV6 is undefined Jan 20 08:46:19 volumio-gra wireless.js[562]: It's done! AP Jan 20 08:46:19 volumio-gra kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 20 08:46:19 volumio-gra systemd[1]: Started Wireless Services. Jan 20 08:46:19 volumio-gra systemd[1]: Started Volumio Backend Module. Jan 20 08:46:19 volumio-gra systemd[1]: Started Volumio Cpu Tweaker. Jan 20 08:46:19 volumio-gra systemd[1]: Reached target Multi-User System. Jan 20 08:46:19 volumio-gra systemd[1]: Reached target Graphical Interface. Jan 20 08:46:19 volumio-gra systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 20 08:46:19 volumio-gra systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 20 08:46:19 volumio-gra systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 20 08:46:19 volumio-gra systemd[1]: Startup finished in 15.347s (kernel) + 30.146s (userspace) = 45.493s. Jan 20 08:46:19 volumio-gra volumio-cpu-tweak[1034]: Setting RT Priority for mpd Jan 20 08:46:20 volumio-gra volumio-cpu-tweak[1034]: Setting MPD Affinity Jan 20 08:46:20 volumio-gra volumio-cpu-tweak[1034]: pid 760's current affinity mask: f Jan 20 08:46:20 volumio-gra volumio-cpu-tweak[1034]: pid 760's new affinity mask: 3 Jan 20 08:46:20 volumio-gra volumio-cpu-tweak[1034]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jan 20 08:46:20 volumio-gra volumio-cpu-tweak[1034]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 20 08:46:20 volumio-gra systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 20 08:46:20 volumio-gra volumio-remote-updater[571]: [2025-01-20 08:46:20] [info] asio async_connect error: system:111 (Connection refused) Jan 20 08:46:20 volumio-gra volumio-remote-updater[571]: [2025-01-20 08:46:20] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 20 08:46:20 volumio-gra volumio-remote-updater[571]: [2025-01-20 08:46:20] [error] handle_connect error: Underlying Transport Error Jan 20 08:46:20 volumio-gra ntpd[724]: Listen normally on 4 wlan0 192.168.100.80:123 Jan 20 08:46:20 volumio-gra ntpd[724]: new interface(s) found: waking up resolver Jan 20 08:46:23 volumio-gra volumio[1033]: info: ------------------------------------------- Jan 20 08:46:23 volumio-gra volumio[1033]: info: ----- Volumio3 ---- Jan 20 08:46:23 volumio-gra volumio[1033]: info: ------------------------------------------- Jan 20 08:46:23 volumio-gra volumio[1033]: info: ----- System startup ---- Jan 20 08:46:23 volumio-gra volumio[1033]: info: ------------------------------------------- Jan 20 08:46:25 volumio-gra systemd[1]: systemd-fsckd.service: Succeeded. Jan 20 08:46:25 volumio-gra volumio[1033]: info: MYVOLUMIO Environment detected Jan 20 08:46:26 volumio-gra volumio[1033]: info: Plugin folders cleanup Jan 20 08:46:26 volumio-gra volumio[1033]: info: Scanning into folder /volumio/app/plugins/ Jan 20 08:46:26 volumio-gra volumio[1033]: info: Scanning category audio_interface Jan 20 08:46:26 volumio-gra volumio[1033]: info: Scanning category miscellanea Jan 20 08:46:26 volumio-gra volumio[1033]: info: Scanning category music_service Jan 20 08:46:26 volumio-gra volumio[1033]: info: Scanning category plugins.json Jan 20 08:46:26 volumio-gra volumio[1033]: info: Scanning category system_controller Jan 20 08:46:26 volumio-gra volumio[1033]: info: Scanning category user_interface Jan 20 08:46:26 volumio-gra volumio[1033]: info: Scanning into folder /data/plugins/ Jan 20 08:46:26 volumio-gra volumio[1033]: info: Scanning category music_service Jan 20 08:46:26 volumio-gra volumio[1033]: info: Plugin folders cleanup completed Jan 20 08:46:26 volumio-gra volumio[1033]: info: ------------------------------------------- Jan 20 08:46:26 volumio-gra volumio[1033]: info: ----- Core plugins startup ---- Jan 20 08:46:26 volumio-gra volumio[1033]: info: ------------------------------------------- Jan 20 08:46:26 volumio-gra volumio[1033]: info: Loading plugins from folder /volumio/app/plugins/ Jan 20 08:46:26 volumio-gra volumio[1033]: info: Adding plugin upnp to MyMusic Plugins Jan 20 08:46:26 volumio-gra volumio[1033]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 20 08:46:26 volumio-gra volumio[1033]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 20 08:46:26 volumio-gra volumio[1033]: info: Loading plugins from folder /data/plugins/ Jan 20 08:46:26 volumio-gra volumio[1033]: info: Loading plugin "system"... Jan 20 08:46:26 volumio-gra volumio[1033]: info: Loading plugin "appearance"... Jan 20 08:46:28 volumio-gra volumio[1033]: info: Loading plugin "network"... Jan 20 08:46:28 volumio-gra volumio[1033]: info: Refreshing Cached IP Addresses Jan 20 08:46:28 volumio-gra sudo[1068]: volumio : unable to resolve host volumio-gra Jan 20 08:46:28 volumio-gra sudo[1068]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:28 volumio-gra sudo[1068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 20 08:46:28 volumio-gra sudo[1068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:28 volumio-gra sudo[1068]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:28 volumio-gra sudo[1070]: volumio : unable to resolve host volumio-gra Jan 20 08:46:28 volumio-gra sudo[1070]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:28 volumio-gra sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 20 08:46:28 volumio-gra volumio[1033]: info: Loading plugin "services"... Jan 20 08:46:28 volumio-gra sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:28 volumio-gra volumio[1033]: info: Loading plugin "alsa_controller"... Jan 20 08:46:28 volumio-gra sudo[1070]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:28 volumio-gra sudo[1078]: volumio : unable to resolve host volumio-gra Jan 20 08:46:28 volumio-gra sudo[1078]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:28 volumio-gra sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 20 08:46:28 volumio-gra sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:28 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 08:46:28 volumio-gra volumio[1033]: info: Loading plugin "wizard"... Jan 20 08:46:28 volumio-gra volumio[1033]: info: Loading plugin "networkfs"... Jan 20 08:46:28 volumio-gra volumio[1033]: info: Starting Udev Watcher for removable devices Jan 20 08:46:28 volumio-gra sudo[1094]: volumio : unable to resolve host volumio-gra Jan 20 08:46:28 volumio-gra sudo[1094]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:28 volumio-gra sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.100.6/Public /mnt/NAS/Public Jan 20 08:46:28 volumio-gra sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:28 volumio-gra volumio[1033]: info: Ignoring mount for partition: boot Jan 20 08:46:28 volumio-gra volumio[1033]: info: Ignoring mount for partition: volumio Jan 20 08:46:28 volumio-gra volumio[1033]: info: Ignoring mount for partition: volumio_data Jan 20 08:46:28 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 08:46:28 volumio-gra volumio[1033]: info: Loading plugin "volumio_command_line_client"... Jan 20 08:46:28 volumio-gra volumio[1033]: info: Loading plugin "upnp"... Jan 20 08:46:28 volumio-gra volumio[1033]: info: [1737359188513] Starting Upmpd Daemon Jan 20 08:46:28 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 08:46:28 volumio-gra volumio[1033]: info: Loading plugin "my_music"... Jan 20 08:46:28 volumio-gra volumio[1033]: info: Loading plugin "mpd"... Jan 20 08:46:28 volumio-gra kernel: Key type cifs.spnego registered Jan 20 08:46:28 volumio-gra kernel: Key type cifs.idmap registered Jan 20 08:46:28 volumio-gra 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. Jan 20 08:46:28 volumio-gra kernel: CIFS: Attempting to mount //192.168.100.6/Public Jan 20 08:46:28 volumio-gra kernel: cryptd: max_cpu_qlen set to 1000 Jan 20 08:46:29 volumio-gra sudo[1094]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:29 volumio-gra volumio[1033]: info: Loading plugin "upnp_browser"... Jan 20 08:46:29 volumio-gra volumio[1033]: info: Loading plugin "alarm-clock"... Jan 20 08:46:30 volumio-gra volumio[1033]: info: Loading plugin "airplay_emulation"... Jan 20 08:46:30 volumio-gra volumio[1033]: info: Starting Shairport Sync Jan 20 08:46:30 volumio-gra volumio[1033]: info: Loading plugin "last_100"... Jan 20 08:46:30 volumio-gra volumio[1033]: info: Loading plugin "webradio"... Jan 20 08:46:30 volumio-gra volumio[1033]: info: Loading plugin "i2s_dacs"... Jan 20 08:46:30 volumio-gra volumio[1033]: info: Loading plugin "volumiodiscovery"... Jan 20 08:46:30 volumio-gra volumio[1033]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 20 08:46:30 volumio-gra volumio[1033]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 08:46:30 volumio-gra volumio[1033]: *** WARNING *** For more information see Jan 20 08:46:30 volumio-gra volumio[1033]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 20 08:46:30 volumio-gra volumio[1033]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 08:46:30 volumio-gra volumio[1033]: *** WARNING *** For more information see Jan 20 08:46:30 volumio-gra node[1033]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 20 08:46:30 volumio-gra node[1033]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 08:46:30 volumio-gra node[1033]: *** WARNING *** For more information see Jan 20 08:46:30 volumio-gra node[1033]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 20 08:46:30 volumio-gra node[1033]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 08:46:30 volumio-gra node[1033]: *** WARNING *** For more information see Jan 20 08:46:30 volumio-gra volumio[1033]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 20 08:46:30 volumio-gra volumio[1033]: info: Discovery: Started advertising with name: Volumio góra Jan 20 08:46:30 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 08:46:30 volumio-gra volumio[1033]: info: Loading plugin "spop"... Jan 20 08:46:30 volumio-gra volumio-remote-updater[571]: [2025-01-20 08:46:30] [connect] Successful connection Jan 20 08:46:31 volumio-gra systemd[1]: systemd-hostnamed.service: Succeeded. Jan 20 08:46:32 volumio-gra sudo[1078]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:32 volumio-gra volumio[1033]: info: Loading plugin "outputs"... Jan 20 08:46:32 volumio-gra volumio[1033]: info: Loading plugin "albumart"... Jan 20 08:46:32 volumio-gra volumio[1033]: info: Plugin example_plugin is not enabled Jan 20 08:46:32 volumio-gra volumio[1033]: info: Loading plugin "inputs"... Jan 20 08:46:32 volumio-gra volumio[1033]: info: Loading plugin "updater_comm"... Jan 20 08:46:33 volumio-gra volumio[1033]: info: Plugin mpdemulation is not enabled Jan 20 08:46:33 volumio-gra volumio[1033]: info: Loading plugin "rest_api"... Jan 20 08:46:33 volumio-gra volumio[1033]: info: Loading plugin "websocket"... Jan 20 08:46:33 volumio-gra volumio[1033]: info: Starting Socket.io Server version 2.3.0 Jan 20 08:46:33 volumio-gra volumio[1033]: info: Loading i18n strings for locale pl Jan 20 08:46:33 volumio-gra volumio[1033]: Updating browse sources language Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 08:46:33 volumio-gra volumio[1033]: Forking 3 albumart workers Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::initPlayerControls Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 08:46:33 volumio-gra volumio[1033]: Express server listening on port 3000 Jan 20 08:46:33 volumio-gra volumio[1033]: [Metrics] WebUI: 11s 422.57ms Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreStateMachine::resetVolumioState Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreStateMachine::getcurrentVolume Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioRetrievevolume Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreStateMachine::pushState Jan 20 08:46:33 volumio-gra volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 08:46:33 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioPushState Jan 20 08:46:33 volumio-gra volumio[1033]: info: Volumio Network Manager: Network status updated: 3 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.80 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.80 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.80 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.80 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.80 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.80 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jan 20 08:46:34 volumio-gra volumio-remote-updater[571]: [2025-01-20 08:46:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737359190 101 Jan 20 08:46:34 volumio-gra volumio[1033]: 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: 13 Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 08:46:34 volumio-gra volumio[1033]: info: Discovery: Getting this device information Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 20 08:46:34 volumio-gra volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.80 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.80 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.80 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.80 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 23 Jan 20 08:46:34 volumio-gra volumio[1033]: Starting albumart workers Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.80 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 24 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.80 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 25 Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 08:46:34 volumio-gra volumio[1033]: Starting albumart workers Jan 20 08:46:34 volumio-gra volumio[1033]: info: Reloading queue from file Jan 20 08:46:34 volumio-gra volumio[1033]: Starting albumart workers Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 26 Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 14; SM-S911B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 27 Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreStateMachine::setRepeat null single undefined Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreStateMachine::pushState Jan 20 08:46:34 volumio-gra volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioPushState Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreStateMachine::setRandom null Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreStateMachine::pushState Jan 20 08:46:34 volumio-gra volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioPushState Jan 20 08:46:34 volumio-gra volumio[1033]: info: Setting Device type: Raspberry PI Jan 20 08:46:34 volumio-gra volumio[1033]: verbose: New Socket.io Connection to 192.168.100.79:3000 from 192.168.100.36 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 28 Jan 20 08:46:34 volumio-gra volumio[1033]: info: Completed loading Core Plugins Jan 20 08:46:34 volumio-gra volumio[1033]: info: Preparing to generate the ALSA configuration file Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 20 08:46:34 volumio-gra volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 20 08:46:34 volumio-gra volumio[1033]: info: Received Get System Info Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 08:46:34 volumio-gra volumio[1033]: info: Discovery: Getting this device information Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 20 08:46:34 volumio-gra volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 20 08:46:34 volumio-gra volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioGetVisibleSources Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 20 08:46:34 volumio-gra volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioGetQueue Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreStateMachine::getQueue Jan 20 08:46:34 volumio-gra volumio[1033]: info: CorePlayQueue::getQueue Jan 20 08:46:34 volumio-gra volumio[1033]: info: Listing playlists Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 20 08:46:34 volumio-gra volumio[1033]: info: Received Get System Info Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 08:46:34 volumio-gra volumio[1033]: info: Discovery: Getting this device information Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 20 08:46:34 volumio-gra volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 20 08:46:34 volumio-gra volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 20 08:46:34 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:34 volumio-gra volumio[1033]: info: Asound.conf file unchanged, so no further update is needed Jan 20 08:46:34 volumio-gra volumio[1033]: info: Output device has changed, restarting MPD Jan 20 08:46:35 volumio-gra volumio[1033]: info: Output device has changed, restarting Shairport Sync Jan 20 08:46:35 volumio-gra sudo[1175]: volumio : unable to resolve host volumio-gra Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 08:46:35 volumio-gra sudo[1175]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:35 volumio-gra sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 20 08:46:35 volumio-gra sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:35 volumio-gra sudo[1177]: volumio : unable to resolve host volumio-gra Jan 20 08:46:35 volumio-gra sudo[1177]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:35 volumio-gra sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 20 08:46:35 volumio-gra sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:35 volumio-gra sudo[1175]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:35 volumio-gra systemd[1]: Stopping Music Player Daemon... Jan 20 08:46:35 volumio-gra volumio[1033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 08:46:35 volumio-gra volumio[1033]: info: ___________ START PLUGINS ___________ Jan 20 08:46:35 volumio-gra volumio[1033]: info: ControllerMpd::onStart: Initializing MPD Jan 20 08:46:35 volumio-gra volumio[1033]: info: Creating MPD Configuration file Jan 20 08:46:35 volumio-gra sudo[1184]: volumio : unable to resolve host volumio-gra Jan 20 08:46:35 volumio-gra sudo[1184]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 08:46:35 volumio-gra volumio[1033]: info: [1737359195280] CoreMusicLibrary::Adding element Serwery Mediów Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 08:46:35 volumio-gra sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 20 08:46:35 volumio-gra sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:35 volumio-gra sudo[1184]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:35 volumio-gra sudo[1190]: volumio : unable to resolve host volumio-gra Jan 20 08:46:35 volumio-gra sudo[1190]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:35 volumio-gra systemd[1]: mpd.service: Succeeded. Jan 20 08:46:35 volumio-gra systemd[1]: Stopped Music Player Daemon. Jan 20 08:46:35 volumio-gra sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 20 08:46:35 volumio-gra sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 08:46:35 volumio-gra systemd[1]: Starting Music Player Daemon... Jan 20 08:46:35 volumio-gra systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 20 08:46:35 volumio-gra systemd[1]: mpd.service: Succeeded. Jan 20 08:46:35 volumio-gra systemd[1]: Stopped Music Player Daemon. Jan 20 08:46:35 volumio-gra volumio[1033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 08:46:35 volumio-gra systemd[1]: Starting Music Player Daemon... Jan 20 08:46:35 volumio-gra volumio[1033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 08:46:35 volumio-gra volumio[1033]: info: [1737359195611] CoreMusicLibrary::Adding element Last_100 Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 08:46:35 volumio-gra volumio[1033]: info: [1737359195617] CoreMusicLibrary::Adding element Webradio Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 08:46:35 volumio-gra volumio[1033]: info: Initializing BBC Radios Jan 20 08:46:35 volumio-gra sudo[1201]: root : unable to resolve host volumio-gra Jan 20 08:46:35 volumio-gra sudo[1201]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:35 volumio-gra sudo[1201]: sudo: unable to resolve host volumio-gra: System error Jan 20 08:46:35 volumio-gra sudo[1201]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 20 08:46:35 volumio-gra sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:35 volumio-gra sudo[1201]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 08:46:35 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 08:46:35 volumio-gra volumio[1033]: info: Creating Spotify config file Jan 20 08:46:36 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:36 volumio-gra volumio[1033]: info: Volumio Calling Home Jan 20 08:46:36 volumio-gra volumio[1033]: info: Discovery: adding 401886b0-f12b-4c27-ae04-36dbc6fdbf18 Jan 20 08:46:36 volumio-gra volumio[1033]: info: Discovery: Found device Volumio góra Jan 20 08:46:36 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 20 08:46:36 volumio-gra volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 20 08:46:36 volumio-gra volumio[1033]: info: Discovery: this is already registered, 401886b0-f12b-4c27-ae04-36dbc6fdbf18 Jan 20 08:46:36 volumio-gra volumio[1033]: info: Discovery: Found device Volumio góra Jan 20 08:46:36 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 20 08:46:36 volumio-gra volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:37 volumio-gra volumio[1033]: info: MPD Permissions set Jan 20 08:46:37 volumio-gra volumio[1033]: info: MPD Permissions set Jan 20 08:46:37 volumio-gra volumio[1033]: info: Volumio called home Jan 20 08:46:37 volumio-gra volumio[1033]: info: Spotify config file written Jan 20 08:46:37 volumio-gra mpd[1232]: Jan 20 08:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 20 08:46:37 volumio-gra systemd[1]: Started Music Player Daemon. Jan 20 08:46:37 volumio-gra sudo[1190]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:37 volumio-gra sudo[1177]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:37 volumio-gra sudo[1239]: volumio : unable to resolve host volumio-gra Jan 20 08:46:37 volumio-gra sudo[1239]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:37 volumio-gra sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 20 08:46:37 volumio-gra sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:37 volumio-gra volumio[1033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 29 Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra systemd[1]: Started go-librespot Daemon. Jan 20 08:46:37 volumio-gra go-librespot[1244]: Librespot-go daemon starting... Jan 20 08:46:37 volumio-gra sudo[1239]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: No need to fix Spotify hosts Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:46:37 volumio-gra volumio[1033]: info: Completed starting Core Plugins Jan 20 08:46:37 volumio-gra volumio[1033]: info: ------------------------------------------- Jan 20 08:46:37 volumio-gra volumio[1033]: info: ----- MyVolumio plugins startup ---- Jan 20 08:46:37 volumio-gra volumio[1033]: info: ------------------------------------------- Jan 20 08:46:37 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 20 08:46:37 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:37 volumio-gra volumio[1033]: info: MPD running with PID1232 Jan 20 08:46:37 volumio-gra volumio[1033]: ,establishing connection Jan 20 08:46:38 volumio-gra volumio[1033]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 20 08:46:38 volumio-gra volumio[1033]: SPOTIFY: BQARjFyDfOf5sgbF7OI84ig7ZJ3qFneNRTe2lH4yM9KSvu7CW7JNlbPjNw1B6gln0UjoLew8a5l2yEKcF0Yh9RCWrgG5bTHfpK6gowV4xlnkl5O3U0JBqZ6knMEis16VY6ivniZyBNsVvqQhAyQ5m8ZHMwv_RQf-hXxMjTGOOBCi_hCEdPs9WN6jSdBW_IwB6yhoxI4VRX-qQKmRQt7cZes_0BYU2iecYWHu67ksOufLTICNdX0Vgw Jan 20 08:46:38 volumio-gra volumio[1033]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 20 08:46:38 volumio-gra volumio[1033]: info: New Spotify access token = BQARjFyDfOf5sgbF7OI84ig7ZJ3qFneNRTe2lH4yM9KSvu7CW7JNlbPjNw1B6gln0UjoLew8a5l2yEKcF0Yh9RCWrgG5bTHfpK6gowV4xlnkl5O3U0JBqZ6knMEis16VY6ivniZyBNsVvqQhAyQ5m8ZHMwv_RQf-hXxMjTGOOBCi_hCEdPs9WN6jSdBW_IwB6yhoxI4VRX-qQKmRQt7cZes_0BYU2iecYWHu67ksOufLTICNdX0Vgw Jan 20 08:46:38 volumio-gra volumio[1033]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 20 08:46:38 volumio-gra volumio[1033]: error: MPD error: The expression evaluated to a falsy value: Jan 20 08:46:38 volumio-gra volumio[1033]: assert.ok(self.idling) Jan 20 08:46:38 volumio-gra volumio[1033]: error: The expression evaluated to a falsy value: Jan 20 08:46:38 volumio-gra volumio[1033]: assert.ok(self.idling) Jan 20 08:46:38 volumio-gra volumio[1033]: error: MPD error: The expression evaluated to a falsy value: Jan 20 08:46:38 volumio-gra volumio[1033]: assert.ok(self.idling) Jan 20 08:46:38 volumio-gra volumio[1033]: error: The expression evaluated to a falsy value: Jan 20 08:46:38 volumio-gra volumio[1033]: assert.ok(self.idling) Jan 20 08:46:38 volumio-gra volumio[1033]: info: Starting Shairport Sync Jan 20 08:46:38 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:38+01:00" level=info msg="generated new device id: 8a54ddb764933da08fcd246aa287591d677ffbd6" Jan 20 08:46:38 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:38+01:00" level=debug msg="stored credentials found for plpija" Jan 20 08:46:38 volumio-gra volumio[1033]: info: Starting Shairport Sync Jan 20 08:46:38 volumio-gra volumio[1033]: info: Starting Shairport Sync Jan 20 08:46:38 volumio-gra sudo[1263]: volumio : unable to resolve host volumio-gra Jan 20 08:46:38 volumio-gra sudo[1263]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:38 volumio-gra sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 08:46:38 volumio-gra sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:38 volumio-gra sudo[1265]: volumio : unable to resolve host volumio-gra Jan 20 08:46:38 volumio-gra sudo[1265]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:38 volumio-gra sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 08:46:38 volumio-gra sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:38 volumio-gra sudo[1268]: volumio : unable to resolve host volumio-gra Jan 20 08:46:38 volumio-gra sudo[1268]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:38 volumio-gra systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 20 08:46:38 volumio-gra sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 08:46:38 volumio-gra systemd[1]: shairport-sync.service: Succeeded. Jan 20 08:46:38 volumio-gra sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:38 volumio-gra systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 20 08:46:38 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:38 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 20 08:46:38 volumio-gra volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 20 08:46:38 volumio-gra volumio[1033]: error: updateQueue error: null Jan 20 08:46:38 volumio-gra systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 20 08:46:38 volumio-gra sudo[1263]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:38 volumio-gra systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 20 08:46:38 volumio-gra systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 20 08:46:38 volumio-gra systemd[1]: shairport-sync.service: Succeeded. Jan 20 08:46:38 volumio-gra systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 20 08:46:38 volumio-gra systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 20 08:46:38 volumio-gra sudo[1268]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:38 volumio-gra sudo[1265]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:38 volumio-gra volumio[1033]: info: Shairport-Sync Started Jan 20 08:46:38 volumio-gra volumio[1033]: Error adding Membership: Error: addMembership EINVAL Jan 20 08:46:38 volumio-gra volumio[1033]: info: Shairport-Sync Started Jan 20 08:46:38 volumio-gra volumio[1033]: info: Shairport-Sync Started Jan 20 08:46:38 volumio-gra sudo[1290]: volumio : unable to resolve host volumio-gra Jan 20 08:46:38 volumio-gra sudo[1290]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:38 volumio-gra sudo[1290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 20 08:46:38 volumio-gra sudo[1290]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:38 volumio-gra sudo[1290]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:38 volumio-gra sudo[1293]: volumio : unable to resolve host volumio-gra Jan 20 08:46:38 volumio-gra sudo[1293]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:38 volumio-gra sudo[1293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 20 08:46:38 volumio-gra sudo[1293]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:38 volumio-gra sudo[1293]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:38 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:38 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:38+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 20 08:46:38 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:38+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 20 08:46:38 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:38+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 20 08:46:38 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:38+01:00" level=debug msg="zeroconf server listening on port 42863" Jan 20 08:46:38 volumio-gra volumio[1033]: SPOTIFY: User informations: {"country":"PL","display_name":"plpija","email":"plpija@wp.pl","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/plpija"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/plpija","id":"plpija","images":[],"product":"premium","type":"user","uri":"spotify:user:plpija"} Jan 20 08:46:38 volumio-gra volumio[1033]: info: Spotify Successfully logged in Jan 20 08:46:38 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 08:46:38 volumio-gra volumio[1033]: info: [1737359198757] CoreMusicLibrary::Adding element Spotify Jan 20 08:46:38 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 08:46:38 volumio-gra volumio[1033]: Cannot find translation for source Spotify Jan 20 08:46:38 volumio-gra sudo[1297]: volumio : unable to resolve host volumio-gra Jan 20 08:46:38 volumio-gra sudo[1297]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:38 volumio-gra sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 20 08:46:38 volumio-gra sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:38 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:38+01:00" level=debug msg="obtained new client token: AAA1q+5BjD8vRqhptGn4I3m5yziZWNZHPoXYQ2Ebz0TynDtg5c2tWNbaslZrfl57qs6O7KyRz0l1DyoVgi0HCUl8s8F34RtLAAry6w/zaA9y+fqAJA5E81UCQyhw8Yf5x6XlTwjxoF6x9DhqIYIZmCPE58W47sNNHEut5hohishwWZC9fdE666q3om5cslCbCiDSjbIm0SiSbQ2EX14tPfiDWSG8TU5VGdxyoE2eeagNJ7Xq25izrbLBlNwq" Jan 20 08:46:38 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:38+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 20 08:46:38 volumio-gra systemd[1]: Started UPnP Renderer front-end to MPD. Jan 20 08:46:38 volumio-gra sudo[1297]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:38 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:38+01:00" level=debug msg="completed keyexchange" Jan 20 08:46:38 volumio-gra volumio[1033]: info: Upmpdcli Daemon Started Jan 20 08:46:39 volumio-gra volumio[1299]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 20 08:46:39 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:39+01:00" level=debug msg="completed challenge" Jan 20 08:46:39 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:39+01:00" level=debug msg="authenticated as plpija" Jan 20 08:46:39 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:39+01:00" level=debug msg="authenticated as plpija" Jan 20 08:46:39 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:39 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:39+01:00" level=debug msg="dealer connection opened" Jan 20 08:46:39 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:39+01:00" level=debug msg="initializing zeroconf session, username: plpija" Jan 20 08:46:39 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:39+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 20 08:46:39 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:39+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 20 08:46:39 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:39+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 20 08:46:39 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:39+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 20 08:46:39 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:39+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 20 08:46:39 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:39+01:00" level=debug msg="autoplay enabled: false" Jan 20 08:46:39 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:39+01:00" level=debug msg="received connection id: NWU3Y2YzOTQtNTljMC00YTU4LWFiNTItMDJkY2M2ZTliZmFiK2RlYWxlcit0Y3A6Ly8wYWNhNWE0OS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArM0U1NDAwQUJFQTlENkYxMzNEQjEzNzdFRkRBMzE0QkM4NjgyOTE1MTFDOUEzNzNGM0RCNTJGMEQyMThFMjIyNA==" Jan 20 08:46:39 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:39+01:00" level=debug msg="put connect state because NEW_DEVICE" Jan 20 08:46:40 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:41 volumio-gra volumio[1033]: info: go-librespot daemon successfully initialized Jan 20 08:46:41 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:41 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:42 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:42 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:43 volumio-gra volumio[1033]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 20 08:46:43 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:43 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:44 volumio-gra volumio[1033]: info: Initializing connection to go-librespot Websocket Jan 20 08:46:44 volumio-gra systemd[1]: systemd-timedated.service: Succeeded. Jan 20 08:46:44 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:44+01:00" level=debug msg="new websocket client" Jan 20 08:46:44 volumio-gra volumio[1033]: info: Connection to go-librespot Websocket established Jan 20 08:46:44 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:44 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:44 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:44+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 20 08:46:44 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:44+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 250" Jan 20 08:46:44 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:44+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 20 08:46:44 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:44+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1299" Jan 20 08:46:45 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 20 08:46:46 volumio-gra volumio[1033]: info: Adding plugin bluetooth to MyMusic Plugins Jan 20 08:46:46 volumio-gra volumio[1033]: info: Adding plugin multiroom to MyMusic Plugins Jan 20 08:46:46 volumio-gra volumio[1033]: info: Adding plugin metavolumio to MyMusic Plugins Jan 20 08:46:46 volumio-gra volumio[1033]: info: Adding plugin cd_controller to MyMusic Plugins Jan 20 08:46:46 volumio-gra volumio[1033]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 20 08:46:46 volumio-gra volumio[1033]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 20 08:46:46 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 20 08:46:47 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:47+01:00" level=debug msg="handling transfer player command from 60e695bb56d73c6cc96197b983d56b6bbb417b0f" Jan 20 08:46:47 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:47+01:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1EVHGWrwldPRtj" Jan 20 08:46:47 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:47+01:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1EVHGWrwldPRtj" Jan 20 08:46:47 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:47+01:00" level=debug msg="loading track (paused: false, position: 2500ms)" uri="spotify:track:2v0rBoXDiCohtKK7wYVvkO" Jan 20 08:46:47 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:47+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 20 08:46:47 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:47+01:00" level=trace msg="emitting websocket event: will_play" Jan 20 08:46:47 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:47+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 338" Jan 20 08:46:47 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:47+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jan 20 08:46:47 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:47+01:00" level=debug msg="selected format OGG_VORBIS_320 (3f13a895b7c9e41912a759ab1bcbf8751bbc03a5)" uri="spotify:track:2v0rBoXDiCohtKK7wYVvkO" Jan 20 08:46:47 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:47+01:00" level=debug msg="requested aes key for file 3f13a895b7c9e41912a759ab1bcbf8751bbc03a5, gid: 2v0rBoXDiCohtKK7wYVvkO" Jan 20 08:46:47 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:47+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1330" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="fetched first chunk of 18, total size is 9335878 bytes" uri="spotify:track:2v0rBoXDiCohtKK7wYVvkO" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=warning msg="reducing track normalisation factor to prevent clipping, please add negative pregain to avoid" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1112" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=trace msg="seek to 2500ms (diff: 96ms, samples: 110250, bytes: 72059)" uri="spotify:track:2v0rBoXDiCohtKK7wYVvkO" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5102 us, period size = 225 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 98 frames" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="created new output device" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=info msg="loaded track \"Amb23 - 3\" (paused: false, position: 2500ms, duration: 323760ms, prefetched: false)" uri="spotify:track:2v0rBoXDiCohtKK7wYVvkO" Jan 20 08:46:48 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 20 08:46:48 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 20 08:46:48 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 20 08:46:48 volumio-gra volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 08:46:48 volumio-gra volumio[1033]: info: Starting MyVolumio Remote Streaming Endpoints Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=trace msg="scheduling prefetch in 291s" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=trace msg="emitting websocket event: metadata" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=trace msg="emitting websocket event: active" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="sending successful reply for dealer request" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="fetched chunk 3/17, size: 524288" uri="spotify:track:2v0rBoXDiCohtKK7wYVvkO" Jan 20 08:46:48 volumio-gra volumio[1033]: info: MyVolumio login type: Token Jan 20 08:46:48 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 20 08:46:48 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=trace msg="emitting websocket event: playing" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="fetched chunk 2/17, size: 524288" uri="spotify:track:2v0rBoXDiCohtKK7wYVvkO" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=trace msg="closed output device because of stop command" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="fetched chunk 1/17, size: 524288" uri="spotify:track:2v0rBoXDiCohtKK7wYVvkO" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="put connect state inactive" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=trace msg="emitting websocket event: inactive" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=trace msg="emitting websocket event: stopped" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusGoingAway and reason = \"\"" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.100.79:41664->34.158.1.133:4070: use of closed network connection" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="obtained new client token: AABN/LaKdoLgxD35as+qwcnF7Q4U5NR14fcilaD9IrngqVapHqYYqEe5mP2AFlNj/WYzAVBaoYMlzf1WSZu/Evxz0SZJ4MC6OoPV3tVDB4lOjIpi+nKTbD40z9ebYy9w9yUxjBi+Lm7S4v7NUXfB67LIxHUrX5DhrLIbrBBvla2wsRslyrT6AbY6OvpuIEaNwyMCCvCOVpXvFTNm8vRyZ4zR0Au4roJdrNG80HIiRciciyEkeXCCdR5djnss" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 20 08:46:48 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:48+01:00" level=debug msg="completed keyexchange" Jan 20 08:46:49 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:49+01:00" level=debug msg="completed challenge" Jan 20 08:46:49 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:49+01:00" level=debug msg="authenticated as plpija" Jan 20 08:46:49 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:49+01:00" level=debug msg="authenticated as plpija" Jan 20 08:46:49 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:49+01:00" level=debug msg="dealer connection opened" Jan 20 08:46:49 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:49+01:00" level=debug msg="restored session after logout, username: plpija" Jan 20 08:46:49 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:49+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 20 08:46:49 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:49+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 20 08:46:49 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:49+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 20 08:46:49 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:49+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 20 08:46:49 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:49+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 20 08:46:49 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:49+01:00" level=debug msg="autoplay enabled: false" Jan 20 08:46:49 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:49+01:00" level=debug msg="received connection id: ODQ0NzVkNjktOTAwMi00NjNkLWEzZWQtODgxMTFmODhhZjBiK2RlYWxlcit0Y3A6Ly8wYWNhNThiZi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQzNFQzBFM0VGNzZCMDNDNThGODMyNDg1QTM0RURDOEY1MjQzQjNCMUJCNkJBNEFFNDlFQzE3ODVCQUQ0Njk1MQ==" Jan 20 08:46:49 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:49+01:00" level=debug msg="put connect state because NEW_DEVICE" Jan 20 08:46:50 volumio-gra volumio[1033]: info: Starting Streaming Service Transparent Proxy Jan 20 08:46:50 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 20 08:46:50 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 20 08:46:50 volumio-gra volumio[1033]: info: Streaming services startup Jan 20 08:46:50 volumio-gra volumio[1033]: info: Starting Streaming Daemon Jan 20 08:46:50 volumio-gra sudo[1343]: volumio : unable to resolve host volumio-gra Jan 20 08:46:50 volumio-gra sudo[1343]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:50 volumio-gra sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 20 08:46:50 volumio-gra sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:46:50 volumio-gra volumio[1033]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 20 08:46:50 volumio-gra sudo[1343]: pam_unix(sudo:session): session closed for user root Jan 20 08:46:50 volumio-gra volumio[1033]: info: Getting Spotify volume Jan 20 08:46:50 volumio-gra volumio[1033]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2v0rBoXDiCohtKK7wYVvkO","play_origin":"playlist"}} Jan 20 08:46:50 volumio-gra volumio[1033]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2v0rBoXDiCohtKK7wYVvkO","name":"Amb23 - 3","artist_names":["Moby"],"album_name":"Ambient 23","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02c49e8515383725204a7e13e3","position":2500,"duration":323760,"release_date":"year:2023 month:1 day:1","track_number":3,"disc_number":1}} Jan 20 08:46:50 volumio-gra volumio[1033]: SPOTIFY: received: {"type":"active","data":null} Jan 20 08:46:50 volumio-gra volumio[1033]: info: Aligning Spotify Volume to Volumio Volume Jan 20 08:46:50 volumio-gra volumio[1033]: info: CoreCommandRouter::volumioGetState Jan 20 08:46:50 volumio-gra volumio[1033]: info: CorePlayQueue::getTrack 0 Jan 20 08:46:50 volumio-gra volumio[1033]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2v0rBoXDiCohtKK7wYVvkO","play_origin":"playlist"}} Jan 20 08:46:50 volumio-gra volumio[1033]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 08:46:50 volumio-gra volumio[1033]: TypeError: Cannot read property 'service' of undefined Jan 20 08:46:50 volumio-gra volumio[1033]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Jan 20 08:46:50 volumio-gra volumio[1033]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Jan 20 08:46:50 volumio-gra volumio[1033]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Jan 20 08:46:50 volumio-gra volumio[1033]: at WebSocket.emit (events.js:315:20) Jan 20 08:46:50 volumio-gra volumio[1033]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Jan 20 08:46:50 volumio-gra volumio[1033]: at Receiver.emit (events.js:315:20) Jan 20 08:46:50 volumio-gra volumio[1033]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Jan 20 08:46:50 volumio-gra volumio[1033]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jan 20 08:46:50 volumio-gra volumio[1033]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jan 20 08:46:50 volumio-gra volumio[1033]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jan 20 08:46:50 volumio-gra volumio[1033]: at writeOrBuffer (internal/streams/writable.js:358:12) Jan 20 08:46:50 volumio-gra volumio[1033]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Jan 20 08:46:50 volumio-gra volumio[1033]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Jan 20 08:46:50 volumio-gra volumio[1033]: at Socket.emit (events.js:315:20) Jan 20 08:46:50 volumio-gra volumio[1033]: at addChunk (internal/streams/readable.js:309:12) Jan 20 08:46:50 volumio-gra volumio[1033]: at readableAddChunk (internal/streams/readable.js:284:9) Jan 20 08:46:50 volumio-gra volumio[1033]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 08:46:51 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:51+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 20 08:46:51 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:51+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 338" Jan 20 08:46:51 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:51+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 20 08:46:51 volumio-gra go-librespot[1244]: time="2025-01-20T08:46:51+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1387" Jan 20 08:46:51 volumio-gra sudo[1359]: volumio : unable to resolve host volumio-gra Jan 20 08:46:51 volumio-gra sudo[1359]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 20 08:46:51 volumio-gra sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-20 08:45 Jan 20 08:46:51 volumio-gra sudo[1359]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"