-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2024-06-04 20:47:22 CEST. --
Jun 04 20:46:45 volumio-niclas kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Jun 04 20:46:45 volumio-niclas kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
Jun 04 20:46:45 volumio-niclas kernel: Key type asymmetric registered
Jun 04 20:46:45 volumio-niclas kernel: Asymmetric key parser 'x509' registered
Jun 04 20:46:45 volumio-niclas kernel: bounce: pool size: 64 pages
Jun 04 20:46:45 volumio-niclas kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
Jun 04 20:46:45 volumio-niclas kernel: io scheduler mq-deadline registered
Jun 04 20:46:45 volumio-niclas kernel: io scheduler kyber registered
Jun 04 20:46:45 volumio-niclas kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
Jun 04 20:46:45 volumio-niclas kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
Jun 04 20:46:45 volumio-niclas kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000
Jun 04 20:46:45 volumio-niclas kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
Jun 04 20:46:45 volumio-niclas kernel: brcm-pcie fd500000.pcie: setting SCB_ACCESS_EN, READ_UR_MODE, MAX_BURST_SIZE
Jun 04 20:46:45 volumio-niclas kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
Jun 04 20:46:45 volumio-niclas kernel: pci_bus 0000:00: root bus resource [bus 00-ff]
Jun 04 20:46:45 volumio-niclas kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
Jun 04 20:46:45 volumio-niclas kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
Jun 04 20:46:45 volumio-niclas kernel: pci 0000:00:00.0: PME# supported from D0 D3hot
Jun 04 20:46:45 volumio-niclas kernel: PCI: bus0: Fast back to back transfers disabled
Jun 04 20:46:45 volumio-niclas kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
Jun 04 20:46:45 volumio-niclas kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator
Jun 04 20:46:45 volumio-niclas kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator
Jun 04 20:46:45 volumio-niclas kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator
Jun 04 20:46:45 volumio-niclas kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
Jun 04 20:46:45 volumio-niclas kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
Jun 04 20:46:45 volumio-niclas kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
Jun 04 20:46:45 volumio-niclas kernel: pci 0000:01:00.0: PME# supported from D0 D3cold
Jun 04 20:46:45 volumio-niclas kernel: PCI: bus1: Fast back to back transfers disabled
Jun 04 20:46:45 volumio-niclas kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
Jun 04 20:46:45 volumio-niclas kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
Jun 04 20:46:45 volumio-niclas kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
Jun 04 20:46:45 volumio-niclas kernel: pci 0000:00:00.0: PCI bridge to [bus 01]
Jun 04 20:46:45 volumio-niclas kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff]
Jun 04 20:46:45 volumio-niclas kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512
Jun 04 20:46:45 volumio-niclas kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512
Jun 04 20:46:45 volumio-niclas kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002)
Jun 04 20:46:45 volumio-niclas kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 45
Jun 04 20:46:45 volumio-niclas kernel: bcm2708_fb soc:fb: FB found 1 display(s)
Jun 04 20:46:45 volumio-niclas kernel: Console: switching to colour frame buffer device 80x30
Jun 04 20:46:45 volumio-niclas kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480
Jun 04 20:46:45 volumio-niclas kernel: iproc-rng200 fe104000.rng: hwrng registered
Jun 04 20:46:45 volumio-niclas kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Jun 04 20:46:45 volumio-niclas kernel: brd: module loaded
Jun 04 20:46:45 volumio-niclas kernel: loop: module loaded
Jun 04 20:46:45 volumio-niclas kernel: Loading iSCSI transport class v2.0-870.
Jun 04 20:46:45 volumio-niclas kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
Jun 04 20:46:45 volumio-niclas kernel: Freeing initrd memory: 14316K
Jun 04 20:46:45 volumio-niclas kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
Jun 04 20:46:45 volumio-niclas kernel: usbcore: registered new device driver r8152-cfgselector
Jun 04 20:46:45 volumio-niclas kernel: usbcore: registered new interface driver r8152
Jun 04 20:46:45 volumio-niclas kernel: usbcore: registered new interface driver lan78xx
Jun 04 20:46:45 volumio-niclas kernel: usbcore: registered new interface driver smsc95xx
Jun 04 20:46:45 volumio-niclas kernel: xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142)
Jun 04 20:46:45 volumio-niclas kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller
Jun 04 20:46:45 volumio-niclas kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
Jun 04 20:46:45 volumio-niclas kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0f00040000000890
Jun 04 20:46:45 volumio-niclas kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller
Jun 04 20:46:45 volumio-niclas kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
Jun 04 20:46:45 volumio-niclas kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
Jun 04 20:46:45 volumio-niclas kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01
Jun 04 20:46:45 volumio-niclas kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jun 04 20:46:45 volumio-niclas kernel: usb usb1: Product: xHCI Host Controller
Jun 04 20:46:45 volumio-niclas kernel: usb usb1: Manufacturer: Linux 6.1.77-v7l+ xhci-hcd
Jun 04 20:46:45 volumio-niclas kernel: usb usb1: SerialNumber: 0000:01:00.0
Jun 04 20:46:45 volumio-niclas kernel: hub 1-0:1.0: USB hub found
Jun 04 20:46:45 volumio-niclas kernel: hub 1-0:1.0: 1 port detected
Jun 04 20:46:45 volumio-niclas kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01
Jun 04 20:46:45 volumio-niclas kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jun 04 20:46:45 volumio-niclas kernel: usb usb2: Product: xHCI Host Controller
Jun 04 20:46:45 volumio-niclas kernel: usb usb2: Manufacturer: Linux 6.1.77-v7l+ xhci-hcd
Jun 04 20:46:45 volumio-niclas kernel: usb usb2: SerialNumber: 0000:01:00.0
Jun 04 20:46:45 volumio-niclas kernel: hub 2-0:1.0: USB hub found
Jun 04 20:46:45 volumio-niclas kernel: hub 2-0:1.0: 4 ports detected
Jun 04 20:46:45 volumio-niclas kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Jun 04 20:46:45 volumio-niclas kernel: dwc_otg: FIQ enabled
Jun 04 20:46:45 volumio-niclas kernel: dwc_otg: NAK holdoff enabled
Jun 04 20:46:45 volumio-niclas kernel: dwc_otg: FIQ split-transaction FSM enabled
Jun 04 20:46:45 volumio-niclas kernel: Module dwc_common_port init
Jun 04 20:46:45 volumio-niclas kernel: usbcore: registered new interface driver uas
Jun 04 20:46:45 volumio-niclas kernel: usbcore: registered new interface driver usb-storage
Jun 04 20:46:45 volumio-niclas kernel: mousedev: PS/2 mouse device common for all mice
Jun 04 20:46:45 volumio-niclas kernel: sdhci: Secure Digital Host Controller Interface driver
Jun 04 20:46:45 volumio-niclas kernel: sdhci: Copyright(c) Pierre Ossman
Jun 04 20:46:45 volumio-niclas kernel: sdhci-pltfm: SDHCI platform and OF driver helper
Jun 04 20:46:45 volumio-niclas kernel: ledtrig-cpu: registered to indicate activity on CPUs
Jun 04 20:46:45 volumio-niclas kernel: hid: raw HID events driver (C) Jiri Kosina
Jun 04 20:46:45 volumio-niclas kernel: usbcore: registered new interface driver usbhid
Jun 04 20:46:45 volumio-niclas kernel: usbhid: USB HID core driver
Jun 04 20:46:45 volumio-niclas kernel: Initializing XFRM netlink socket
Jun 04 20:46:45 volumio-niclas kernel: NET: Registered PF_PACKET protocol family
Jun 04 20:46:45 volumio-niclas kernel: Key type dns_resolver registered
Jun 04 20:46:45 volumio-niclas kernel: Registering SWP/SWPB emulation handler
Jun 04 20:46:45 volumio-niclas kernel: registered taskstats version 1
Jun 04 20:46:45 volumio-niclas kernel: Loading compiled-in X.509 certificates
Jun 04 20:46:45 volumio-niclas kernel: Key type .fscrypt registered
Jun 04 20:46:45 volumio-niclas kernel: Key type fscrypt-provisioning registered
Jun 04 20:46:45 volumio-niclas kernel: uart-pl011 fe201000.serial: there is not valid maps for state default
Jun 04 20:46:45 volumio-niclas kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled
Jun 04 20:46:45 volumio-niclas kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2
Jun 04 20:46:45 volumio-niclas kernel: serial serial0: tty port ttyAMA1 registered
Jun 04 20:46:45 volumio-niclas kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
Jun 04 20:46:45 volumio-niclas kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
Jun 04 20:46:45 volumio-niclas kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
Jun 04 20:46:45 volumio-niclas kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
Jun 04 20:46:45 volumio-niclas kernel: of_cfs_init
Jun 04 20:46:45 volumio-niclas kernel: of_cfs_init: OK
Jun 04 20:46:45 volumio-niclas kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
Jun 04 20:46:45 volumio-niclas kernel: Freeing unused kernel image (initmem) memory: 2048K
Jun 04 20:46:45 volumio-niclas kernel: mmc1: new high speed SDIO card at address 0001
Jun 04 20:46:45 volumio-niclas kernel: Run /init as init process
Jun 04 20:46:45 volumio-niclas kernel: with arguments:
Jun 04 20:46:45 volumio-niclas kernel: /init
Jun 04 20:46:45 volumio-niclas kernel: splash
Jun 04 20:46:45 volumio-niclas kernel: nodebug
Jun 04 20:46:45 volumio-niclas kernel: with environment:
Jun 04 20:46:45 volumio-niclas kernel: HOME=/
Jun 04 20:46:45 volumio-niclas kernel: TERM=linux
Jun 04 20:46:45 volumio-niclas kernel: imgpart=UUID=826680f7-a407-4ab9-8bd5-1855bda526fd
Jun 04 20:46:45 volumio-niclas kernel: imgfile=/volumio_current.sqsh
Jun 04 20:46:45 volumio-niclas kernel: bootpart=UUID=FD90-ED9E
Jun 04 20:46:45 volumio-niclas kernel: datapart=UUID=fecb0823-2e5b-4bba-9498-dd37b5084148
Jun 04 20:46:45 volumio-niclas kernel: uuidconfig=cmdline.txt
Jun 04 20:46:45 volumio-niclas kernel: pcie_aspm=off
Jun 04 20:46:45 volumio-niclas kernel: bootdelay=7
Jun 04 20:46:45 volumio-niclas kernel: use_kmsg=no
Jun 04 20:46:45 volumio-niclas kernel: mmc0: new ultra high speed DDR50 SDXC card at address e624
Jun 04 20:46:45 volumio-niclas kernel: mmcblk0: mmc0:e624 SN64G 59.5 GiB
Jun 04 20:46:45 volumio-niclas kernel: mmcblk0: p1 p2 p3
Jun 04 20:46:45 volumio-niclas kernel: mmcblk0: mmc0:e624 SN64G 59.5 GiB (quirks 0x00004000)
Jun 04 20:46:45 volumio-niclas kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd
Jun 04 20:46:45 volumio-niclas kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.20
Jun 04 20:46:45 volumio-niclas kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Jun 04 20:46:45 volumio-niclas kernel: usb 1-1: Product: USB2.0 Hub
Jun 04 20:46:45 volumio-niclas kernel: hub 1-1:1.0: USB hub found
Jun 04 20:46:45 volumio-niclas kernel: hub 1-1:1.0: 4 ports detected
Jun 04 20:46:45 volumio-niclas kernel: fuse: init (API version 7.37)
Jun 04 20:46:45 volumio-niclas kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Jun 04 20:46:45 volumio-niclas kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Jun 04 20:46:45 volumio-niclas kernel: EXT4-fs (mmcblk0p2): recovery complete
Jun 04 20:46:45 volumio-niclas kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none.
Jun 04 20:46:45 volumio-niclas kernel: loop0: detected capacity change from 0 to 979240
Jun 04 20:46:45 volumio-niclas kernel: EXT4-fs (mmcblk0p3): recovery complete
Jun 04 20:46:45 volumio-niclas kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none.
Jun 04 20:46:45 volumio-niclas systemd[1]: System time before build time, advancing clock.
Jun 04 20:46:45 volumio-niclas kernel: NET: Registered PF_INET6 protocol family
Jun 04 20:46:45 volumio-niclas kernel: Segment Routing with IPv6
Jun 04 20:46:45 volumio-niclas kernel: In-situ OAM (IOAM) with IPv6
Jun 04 20:46:45 volumio-niclas 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)
Jun 04 20:46:45 volumio-niclas systemd[1]: Detected architecture arm.
Jun 04 20:46:45 volumio-niclas systemd[1]: Set hostname to .
Jun 04 20:46:45 volumio-niclas 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.
Jun 04 20:46:45 volumio-niclas systemd[1]: Listening on fsck to fsckd communication Socket.
Jun 04 20:46:45 volumio-niclas systemd[1]: Created slice system-systemd\x2dfsck.slice.
Jun 04 20:46:45 volumio-niclas systemd[1]: Created slice system-getty.slice.
Jun 04 20:46:45 volumio-niclas systemd[1]: Listening on Journal Audit Socket.
Jun 04 20:46:45 volumio-niclas systemd[1]: Started Forward Password Requests to Wall Directory Watch.
Jun 04 20:46:45 volumio-niclas systemd[1]: Listening on udev Control Socket.
Jun 04 20:46:45 volumio-niclas kernel: i2c_dev: i2c /dev entries driver
Jun 04 20:46:45 volumio-niclas systemd-journald[324]: Journal started
Jun 04 20:46:45 volumio-niclas systemd-journald[324]: Runtime journal (/run/log/journal/c28a768762e55f60d289ca8f665f5f47) is 7.5M, max 30.0M, 22.5M free.
Feb 14 11:11:59 volumio-niclas systemd-modules-load[322]: Inserted module 'i2c_dev'
Jun 04 20:46:45 volumio-niclas fake-hwclock[321]: Tue Jun 4 18:46:45 UTC 2024
Jun 04 20:46:45 volumio-niclas systemd[1]: dynamicswap.service: Succeeded.
Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted Kernel Configuration File System.
Jun 04 20:46:45 volumio-niclas systemd[1]: Started Load/Save Random Seed.
Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted FUSE Control File System.
Jun 04 20:46:45 volumio-niclas systemd[1]: Started Create System Users.
Jun 04 20:46:45 volumio-niclas systemd[1]: Starting Create Static Device Nodes in /dev...
Jun 04 20:46:45 volumio-niclas systemd[1]: Started Apply Kernel Variables.
Jun 04 20:46:45 volumio-niclas systemd[1]: Started Create Static Device Nodes in /dev.
Jun 04 20:46:45 volumio-niclas systemd[1]: Reached target Local File Systems (Pre).
Jun 04 20:46:45 volumio-niclas systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway.
Jun 04 20:46:45 volumio-niclas systemd[1]: Mounting /var/log...
Jun 04 20:46:45 volumio-niclas systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway.
Jun 04 20:46:45 volumio-niclas systemd[1]: Mounting /tmp...
Jun 04 20:46:45 volumio-niclas systemd[1]: Mounting /var/spool/cups...
Jun 04 20:46:45 volumio-niclas systemd[1]: Starting udev Kernel Device Manager...
Jun 04 20:46:45 volumio-niclas systemd[1]: Started udev Coldplug all Devices.
Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted /var/log.
Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted /tmp.
Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted /var/spool/cups.
Jun 04 20:46:45 volumio-niclas systemd[1]: Mounting /var/spool/cups/tmp...
Jun 04 20:46:45 volumio-niclas systemd[1]: Starting Flush Journal to Persistent Storage...
Jun 04 20:46:45 volumio-niclas systemd[1]: Starting Helper to synchronize boot up for ifupdown...
Jun 04 20:46:45 volumio-niclas systemd[1]: Mounted /var/spool/cups/tmp.
Jun 04 20:46:45 volumio-niclas systemd-udevd[362]: Network interface NamePolicy= disabled on kernel command line, ignoring.
Jun 04 20:46:45 volumio-niclas systemd[1]: Started Helper to synchronize boot up for ifupdown.
Jun 04 20:46:45 volumio-niclas systemd-journald[324]: Runtime journal (/run/log/journal/c28a768762e55f60d289ca8f665f5f47) is 7.5M, max 30.0M, 22.5M free.
Jun 04 20:46:45 volumio-niclas systemd[1]: Started Flush Journal to Persistent Storage.
Jun 04 20:46:45 volumio-niclas systemd[1]: Started udev Kernel Device Manager.
Jun 04 20:46:45 volumio-niclas systemd[1]: Starting Show Plymouth Boot Screen...
Jun 04 20:46:45 volumio-niclas systemd[1]: plymouth-start.service: Succeeded.
Jun 04 20:46:45 volumio-niclas systemd[1]: Started Show Plymouth Boot Screen.
Jun 04 20:46:45 volumio-niclas systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped.
Jun 04 20:46:45 volumio-niclas systemd[1]: Reached target Paths.
Jun 04 20:46:45 volumio-niclas systemd[1]: Reached target Local Encrypted Volumes.
Jun 04 20:46:45 volumio-niclas systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
Jun 04 20:46:46 volumio-niclas systemd-udevd[390]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jun 04 20:46:46 volumio-niclas systemd-udevd[382]: Using default interface naming scheme 'v240'.
Jun 04 20:46:46 volumio-niclas systemd-udevd[382]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jun 04 20:46:46 volumio-niclas kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
Jun 04 20:46:46 volumio-niclas kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver
Jun 04 20:46:46 volumio-niclas kernel: [vc_sm_connected_init]: start
Jun 04 20:46:46 volumio-niclas kernel: [vc_sm_connected_init]: installed successfully
Jun 04 20:46:46 volumio-niclas kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000
Jun 04 20:46:46 volumio-niclas kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem
Jun 04 20:46:46 volumio-niclas kernel: mc: Linux media interface: v0.10
Jun 04 20:46:46 volumio-niclas kernel: videodev: Linux video capture interface: v2.00
Jun 04 20:46:46 volumio-niclas kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Jun 04 20:46:46 volumio-niclas systemd[1]: Found device /dev/disk/by-uuid/FD90-ED9E.
Jun 04 20:46:46 volumio-niclas kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Jun 04 20:46:47 volumio-niclas kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned.
Jun 04 20:46:47 volumio-niclas kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
Jun 04 20:46:47 volumio-niclas kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
Jun 04 20:46:47 volumio-niclas kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
Jun 04 20:46:47 volumio-niclas kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database
Jun 04 20:46:47 volumio-niclas systemd[1]: Starting File System Check on /dev/disk/by-uuid/FD90-ED9E...
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
Jun 04 20:46:47 volumio-niclas kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf'
Jun 04 20:46:47 volumio-niclas kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328'
Jun 04 20:46:47 volumio-niclas kernel: cfg80211: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
Jun 04 20:46:47 volumio-niclas kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp
Jun 04 20:46:47 volumio-niclas kernel: rpivid feb10000.codec: Device registered as /dev/video19
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
Jun 04 20:46:47 volumio-niclas kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
Jun 04 20:46:47 volumio-niclas kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
Jun 04 20:46:47 volumio-niclas kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
Jun 04 20:46:47 volumio-niclas kernel: bcm2835_audio bcm2835_audio: card created with 4 channels
Jun 04 20:46:47 volumio-niclas kernel: bcm2835_audio bcm2835_audio: card created with 4 channels
Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: Core ver 2.22
Jun 04 20:46:47 volumio-niclas kernel: NET: Registered PF_BLUETOOTH protocol family
Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: HCI device and connection manager initialized
Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: HCI socket layer initialized
Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: L2CAP socket layer initialized
Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: SCO socket layer initialized
Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: HCI UART driver ver 2.3
Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: HCI UART protocol H4 registered
Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered
Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: HCI UART protocol Broadcom registered
Jun 04 20:46:47 volumio-niclas kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator
Jun 04 20:46:47 volumio-niclas kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator
Jun 04 20:46:47 volumio-niclas kernel: brcmfmac: F1 signature read @0x18000000=0x15264345
Jun 04 20:46:47 volumio-niclas kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
Jun 04 20:46:47 volumio-niclas kernel: usbcore: registered new interface driver brcmfmac
Jun 04 20:46:47 volumio-niclas kernel: uart-pl011 fe201000.serial: no DMA platform data
Jun 04 20:46:47 volumio-niclas systemd-fsck[446]: fsck.fat 4.1 (2017-01-24)
Jun 04 20:46:47 volumio-niclas systemd-fsck[446]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt.
Jun 04 20:46:47 volumio-niclas systemd-fsck[446]: Automatically removing dirty bit.
Jun 04 20:46:47 volumio-niclas systemd-fsck[446]: Performing changes.
Jun 04 20:46:47 volumio-niclas systemd-fsck[446]: /dev/mmcblk0p1: 369 files, 134026/184582 clusters
Jun 04 20:46:47 volumio-niclas systemd[1]: Started File System Check on /dev/disk/by-uuid/FD90-ED9E.
Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: hci0: BCM: chip id 107
Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: hci0: BCM: features 0x2f
Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: hci0: BCM4345C0
Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000
Jun 04 20:46:47 volumio-niclas kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch
Jun 04 20:46:47 volumio-niclas 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
Jun 04 20:46:47 volumio-niclas systemd-udevd[396]: Using default interface naming scheme 'v240'.
Jun 04 20:46:47 volumio-niclas systemd-udevd[396]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jun 04 20:46:48 volumio-niclas systemd[1]: Created slice system-bthelper.slice.
Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Huge Pages File System being skipped.
Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Show Plymouth Boot Screen...
Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Jun 04 20:46:48 volumio-niclas systemd[1]: Mounting /boot...
Jun 04 20:46:48 volumio-niclas systemd[1]: Started File System Check Daemon to report status.
Jun 04 20:46:48 volumio-niclas systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Jun 04 20:46:48 volumio-niclas systemd[1]: Mounted /boot.
Jun 04 20:46:48 volumio-niclas systemd[1]: Found device /sys/subsystem/net/devices/eth0.
Jun 04 20:46:48 volumio-niclas systemd[1]: plymouth-start.service: Succeeded.
Jun 04 20:46:48 volumio-niclas systemd[1]: Started Show Plymouth Boot Screen.
Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped.
Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Load/Save RF Kill Switch Status...
Jun 04 20:46:48 volumio-niclas systemd[1]: Reached target Local File Systems.
Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Raise network interfaces...
Jun 04 20:46:48 volumio-niclas systemd[1]: Started ifup for eth0.
Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Preprocess NFS configuration...
Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped.
Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Create Volatile Files and Directories...
Jun 04 20:46:48 volumio-niclas systemd[1]: Started Load/Save RF Kill Switch Status.
Jun 04 20:46:48 volumio-niclas kernel: Bluetooth: hci0: BCM: features 0x2f
Jun 04 20:46:48 volumio-niclas kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+
Jun 04 20:46:48 volumio-niclas kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342
Jun 04 20:46:48 volumio-niclas systemd[1]: Found device /sys/subsystem/net/devices/wlan0.
Jun 04 20:46:48 volumio-niclas systemd[1]: plymouth-read-write.service: Succeeded.
Jun 04 20:46:48 volumio-niclas systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
Jun 04 20:46:48 volumio-niclas systemd[1]: nfs-config.service: Succeeded.
Jun 04 20:46:48 volumio-niclas systemd[1]: Started Preprocess NFS configuration.
Jun 04 20:46:48 volumio-niclas systemd[1]: Received SIGRTMIN+20 from PID 217 (plymouthd).
Jun 04 20:46:48 volumio-niclas systemd[1]: Started Create Volatile Files and Directories.
Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Network Time Synchronization being skipped.
Jun 04 20:46:48 volumio-niclas systemd[1]: Starting RPC bind portmap service...
Jun 04 20:46:48 volumio-niclas systemd[1]: Started Entropy daemon using the HAVEGE algorithm.
Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in RPC security service for NFS server being skipped.
Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped.
Jun 04 20:46:48 volumio-niclas systemd[1]: Reached target NFS client services.
Jun 04 20:46:48 volumio-niclas systemd[1]: Started ifup for wlan0.
Jun 04 20:46:48 volumio-niclas systemd[1]: Started Update UTMP about System Boot/Shutdown.
Jun 04 20:46:48 volumio-niclas systemd[1]: Reached target System Initialization.
Jun 04 20:46:48 volumio-niclas systemd[1]: Listening on triggerhappy.socket.
Jun 04 20:46:48 volumio-niclas systemd[1]: apt-daily.timer: Not using persistent file timestamp Tue 2024-07-16 13:18:33 CEST as it is in the future.
Jun 04 20:46:48 volumio-niclas systemd[1]: Started Daily apt download activities.
Jun 04 20:46:48 volumio-niclas systemd[1]: Listening on D-Bus System Message Bus Socket.
Jun 04 20:46:48 volumio-niclas systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Tue 2024-07-16 06:55:21 CEST as it is in the future.
Jun 04 20:46:48 volumio-niclas systemd[1]: Started Daily apt upgrade and clean activities.
Jun 04 20:46:48 volumio-niclas systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Jun 04 20:46:48 volumio-niclas systemd[1]: Reached target Sockets.
Jun 04 20:46:48 volumio-niclas systemd[1]: Reached target Basic System.
Jun 04 20:46:48 volumio-niclas kernel: 8021q: 802.1Q VLAN Support v1.8
Jun 04 20:46:48 volumio-niclas systemd[1]: Started D-Bus System Message Bus.
Jun 04 20:46:48 volumio-niclas kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Jun 04 20:46:48 volumio-niclas systemd[1]: Starting triggerhappy global hotkey daemon...
Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Login Service...
Jun 04 20:46:48 volumio-niclas systemd[1]: Started volumio-remote-updater.service.
Jun 04 20:46:48 volumio-niclas systemd[1]: Starting Wireless Services...
Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped.
Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped.
Jun 04 20:46:48 volumio-niclas systemd[1]: Starting dhcpcd on all interfaces...
Jun 04 20:46:48 volumio-niclas systemd[1]: Condition check resulted in Volumio SSH enabler being skipped.
Jun 04 20:46:48 volumio-niclas systemd[1]: Started Daily Cleanup of Temporary Directories.
Jun 04 20:46:48 volumio-niclas systemd[1]: Reached target Timers.
Jun 04 20:46:48 volumio-niclas systemd[1]: Started Volumio Iptables Module.
Jun 04 20:46:48 volumio-niclas thd[612]: Unable to parse trigger line:
Jun 04 20:46:48 volumio-niclas thd[612]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle
Jun 04 20:46:48 volumio-niclas thd[612]: Unable to parse trigger line:
Jun 04 20:46:48 volumio-niclas thd[612]: Found socket passed from systemd
Jun 04 20:46:48 volumio-niclas systemd[1]: Started UPnP Renderer front-end to MPD.
Jun 04 20:46:48 volumio-niclas dhcpcd[620]: Not running dhcpcd because /etc/network/interfaces
Jun 04 20:46:48 volumio-niclas dhcpcd[620]: defines some interfaces that will use a
Jun 04 20:46:48 volumio-niclas dhcpcd[620]: DHCP client or static address
Jun 04 20:46:48 volumio-niclas systemd[1]: Started Manage Sound Card State (restore and store).
Jun 04 20:46:49 volumio-niclas alsactl[628]: alsactl 1.1.8 daemon started
Jun 04 20:46:49 volumio-niclas systemd[1]: Starting Save/Restore Sound Card State...
Jun 04 20:46:49 volumio-niclas sh[524]: eth0: waiting for carrier
Jun 04 20:46:49 volumio-niclas kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
Jun 04 20:46:49 volumio-niclas kernel: bcmgenet fd580000.ethernet eth0: Link is Down
Jun 04 20:46:49 volumio-niclas dhcpcd[556]: eth0: waiting for carrier
Jun 04 20:46:49 volumio-niclas systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Jun 04 20:46:49 volumio-niclas systemd[1]: Started Volumio Log Rotation Service.
Jun 04 20:46:49 volumio-niclas systemd[1]: Starting Configure Bluetooth Modems connected by UART...
Jun 04 20:46:49 volumio-niclas systemd-logind[616]: New seat seat0.
Jun 04 20:46:49 volumio-niclas systemd[1]: Starting WPA supplicant...
Jun 04 20:46:49 volumio-niclas systemd[1]: Started RPC bind portmap service.
Jun 04 20:46:49 volumio-niclas systemd[1]: Started triggerhappy global hotkey daemon.
Jun 04 20:46:49 volumio-niclas systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED
Jun 04 20:46:49 volumio-niclas systemd[1]: dhcpcd.service: Failed with result 'exit-code'.
Jun 04 20:46:49 volumio-niclas systemd[1]: Failed to start dhcpcd on all interfaces.
Jun 04 20:46:49 volumio-niclas systemd[1]: Started Save/Restore Sound Card State.
Jun 04 20:46:49 volumio-niclas systemd[1]: Reached target Sound Card.
Jun 04 20:46:49 volumio-niclas systemd[1]: Reached target Remote File Systems (Pre).
Jun 04 20:46:49 volumio-niclas systemd[1]: Reached target Remote File Systems.
Jun 04 20:46:49 volumio-niclas sh[577]: wlan0=wlan0
Jun 04 20:46:49 volumio-niclas systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling...
Jun 04 20:46:49 volumio-niclas avahi-daemon[639]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113).
Jun 04 20:46:49 volumio-niclas systemd[1]: Reached target RPC Port Mapper.
Jun 04 20:46:49 volumio-niclas avahi-daemon[639]: Successfully dropped root privileges.
Jun 04 20:46:49 volumio-niclas systemd[1]: hciuart.service: Succeeded.
Jun 04 20:46:49 volumio-niclas avahi-daemon[639]: avahi-daemon 0.7 starting up.
Jun 04 20:46:49 volumio-niclas systemd[1]: Started Configure Bluetooth Modems connected by UART.
Jun 04 20:46:49 volumio-niclas systemd[1]: Starting Raspberry Pi bluetooth helper...
Jun 04 20:46:49 volumio-niclas systemd[1]: Started Login Service.
Jun 04 20:46:49 volumio-niclas avahi-daemon[639]: Successfully called chroot().
Jun 04 20:46:49 volumio-niclas avahi-daemon[639]: Successfully dropped remaining capabilities.
Jun 04 20:46:49 volumio-niclas bthelper[674]: Raspberry Pi BDADDR already set
Jun 04 20:46:49 volumio-niclas avahi-daemon[639]: Loading service file /services/volumio.service.
Jun 04 20:46:49 volumio-niclas systemd[1]: Started Raspberry Pi bluetooth helper.
Jun 04 20:46:49 volumio-niclas avahi-daemon[639]: Network interface enumeration completed.
Jun 04 20:46:49 volumio-niclas avahi-daemon[639]: Server startup complete. Host name is volumio-niclas.local. Local service cookie is 3653469984.
Jun 04 20:46:49 volumio-niclas avahi-daemon[639]: Service "Volumio Niclas" (/services/volumio.service) successfully established.
Jun 04 20:46:49 volumio-niclas wpa_supplicant[649]: Successfully initialized wpa_supplicant
Jun 04 20:46:49 volumio-niclas systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Jun 04 20:46:49 volumio-niclas systemd[1]: Started WPA supplicant.
Jun 04 20:46:49 volumio-niclas systemd[1]: Starting Bluetooth service...
Jun 04 20:46:49 volumio-niclas systemd[1]: Started Raise network interfaces.
Jun 04 20:46:49 volumio-niclas systemd[1]: Reached target Network.
Jun 04 20:46:49 volumio-niclas systemd[1]: Starting Network Time Service...
Jun 04 20:46:50 volumio-niclas systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
Jun 04 20:46:50 volumio-niclas systemd[1]: Reached target Network is Online.
Jun 04 20:46:50 volumio-niclas systemd[1]: Starting LSB: Brings up/down network automatically...
Jun 04 20:46:50 volumio-niclas systemd[1]: Starting Permit User Sessions...
Jun 04 20:46:50 volumio-niclas systemd[1]: Starting Samba NMB Daemon...
Jun 04 20:46:50 volumio-niclas systemd[1]: Starting /etc/rc.local Compatibility...
Jun 04 20:46:50 volumio-niclas systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 04 20:46:50 volumio-niclas systemd[1]: Started /etc/rc.local Compatibility.
Jun 04 20:46:50 volumio-niclas volumio-remote-updater[618]: Error: No active session
Jun 04 20:46:50 volumio-niclas systemd[1]: Started Permit User Sessions.
Jun 04 20:46:50 volumio-niclas volumio-remote-updater[618]: [2024-06-04 20:46:50] [info] asio async_connect error: system:111 (Connection refused)
Jun 04 20:46:50 volumio-niclas volumio-remote-updater[618]: [2024-06-04 20:46:50] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Jun 04 20:46:50 volumio-niclas volumio-remote-updater[618]: [2024-06-04 20:46:50] [error] handle_connect error: Underlying Transport Error
Jun 04 20:46:50 volumio-niclas systemd[1]: Starting Terminate Plymouth Boot Screen...
Jun 04 20:46:50 volumio-niclas systemd[1]: Starting Hold until boot process finishes up...
Jun 04 20:46:50 volumio-niclas volumio[625]: Could not open config: /tmp/upmpdcli.conf
Jun 04 20:46:50 volumio-niclas systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Jun 04 20:46:50 volumio-niclas systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Jun 04 20:46:50 volumio-niclas systemd[1]: Received SIGRTMIN+21 from PID 217 (plymouthd).
Jun 04 20:46:50 volumio-niclas bluetoothd[709]: Bluetooth daemon 5.50
Jun 04 20:46:50 volumio-niclas ifplugd(eth0)[763]: ifplugd 0.28 initializing.
Jun 04 20:46:50 volumio-niclas ifplugd(eth0)[763]: Using interface eth0/DC:A6:32:17:AA:8A with driver (version: 6.1.77-v7l+)
Jun 04 20:46:50 volumio-niclas ifplugd(eth0)[763]: Using detection mode: SIOCETHTOOL
Jun 04 20:46:50 volumio-niclas ifplugd(eth0)[763]: Initialization complete, link beat not detected.
Jun 04 20:46:50 volumio-niclas systemd[1]: Received SIGRTMIN+21 from PID 217 (plymouthd).
Jun 04 20:46:50 volumio-niclas systemd[1]: plymouth-quit.service: Succeeded.
Jun 04 20:46:50 volumio-niclas systemd[1]: Started Terminate Plymouth Boot Screen.
Jun 04 20:46:50 volumio-niclas systemd[1]: plymouth-quit-wait.service: Succeeded.
Jun 04 20:46:50 volumio-niclas loadcpufreq[664]: Loading cpufreq kernel modules...done (none).
Jun 04 20:46:50 volumio-niclas systemd[1]: Started Hold until boot process finishes up.
Jun 04 20:46:50 volumio-niclas systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling.
Jun 04 20:46:50 volumio-niclas systemd[1]: Starting LSB: set CPUFreq kernel parameters...
Jun 04 20:46:50 volumio-niclas bluetoothd[709]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf
Jun 04 20:46:50 volumio-niclas systemd[1]: Started Getty on tty1.
Jun 04 20:46:50 volumio-niclas systemd[1]: Reached target Login Prompts.
Jun 04 20:46:50 volumio-niclas ifplugd[716]: Network Interface Plugging Daemon...start eth0...done.
Jun 04 20:46:50 volumio-niclas systemd[1]: Started LSB: Brings up/down network automatically.
Jun 04 20:46:50 volumio-niclas systemd[1]: Started Bluetooth service.
Jun 04 20:46:50 volumio-niclas systemd[1]: Reached target Bluetooth.
Jun 04 20:46:50 volumio-niclas bluetoothd[709]: Starting SDP server
Jun 04 20:46:50 volumio-niclas bluetoothd[709]: Excluding (cli) sap
Jun 04 20:46:50 volumio-niclas ntpd[712]: ntpd 4.2.8p12@1.3728-o (1): Starting
Jun 04 20:46:50 volumio-niclas ntpd[712]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103
Jun 04 20:46:50 volumio-niclas systemd[1]: Started Network Time Service.
Jun 04 20:46:50 volumio-niclas ntpd[774]: proto: precision = 2.037 usec (-19)
Jun 04 20:46:50 volumio-niclas kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Jun 04 20:46:50 volumio-niclas kernel: Bluetooth: BNEP filters: protocol multicast
Jun 04 20:46:50 volumio-niclas kernel: Bluetooth: BNEP socket layer initialized
Jun 04 20:46:50 volumio-niclas ntpd[774]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Jun 04 20:46:50 volumio-niclas bluetoothd[709]: Bluetooth management interface 1.22 initialized
Jun 04 20:46:50 volumio-niclas dbus-daemon[588]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=709 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ")
Jun 04 20:46:50 volumio-niclas kernel: Bluetooth: MGMT ver 1.22
Jun 04 20:46:50 volumio-niclas ntpd[774]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Jun 04 20:46:50 volumio-niclas ntpd[774]: Listen and drop on 0 v6wildcard [::]:123
Jun 04 20:46:50 volumio-niclas ntpd[774]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Jun 04 20:46:50 volumio-niclas ntpd[774]: Listen normally on 2 lo 127.0.0.1:123
Jun 04 20:46:50 volumio-niclas ntpd[774]: Listening on routing socket on fd #19 for interface updates
Jun 04 20:46:50 volumio-niclas ntpd[774]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Jun 04 20:46:50 volumio-niclas ntpd[774]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Jun 04 20:46:50 volumio-niclas bluetoothd[709]: Failed to set privacy: Rejected (0x0b)
Jun 04 20:46:50 volumio-niclas cpufrequtils[766]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done.
Jun 04 20:46:50 volumio-niclas systemd[1]: Started LSB: set CPUFreq kernel parameters.
Jun 04 20:46:50 volumio-niclas systemd[1]: iptables.service: Succeeded.
Jun 04 20:46:51 volumio-niclas systemd[1]: Starting Hostname Service...
Jun 04 20:46:51 volumio-niclas dbus-daemon[588]: [system] Successfully activated service 'org.freedesktop.hostname1'
Jun 04 20:46:51 volumio-niclas systemd[1]: Started Hostname Service.
Jun 04 20:46:51 volumio-niclas nmbd[730]: [2024/06/04 20:46:51.412660, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns)
Jun 04 20:46:51 volumio-niclas nmbd[730]: started asyncdns process 800
Jun 04 20:46:51 volumio-niclas nmbd[730]: [2024/06/04 20:46:51.414431, 0] ../lib/util/become_daemon.c:149(daemon_status)
Jun 04 20:46:51 volumio-niclas nmbd[730]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...
Jun 04 20:46:51 volumio-niclas nmbd[730]: [2024/06/04 20:46:51.414563, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets)
Jun 04 20:46:51 volumio-niclas nmbd[730]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6).
Jun 04 20:46:51 volumio-niclas haveged[575]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K
Jun 04 20:46:51 volumio-niclas haveged[575]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488
Jun 04 20:46:51 volumio-niclas haveged[575]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99955
Jun 04 20:46:51 volumio-niclas haveged[575]: haveged: fills: 0, generated: 0
Jun 04 20:46:51 volumio-niclas wireless.js[619]: Cleaning previous...
Jun 04 20:46:51 volumio-niclas ntpd[774]: error resolving pool 0.debian.pool.ntp.org: System error (-11)
Jun 04 20:46:51 volumio-niclas sudo[808]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Jun 04 20:46:51 volumio-niclas sudo[808]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:51 volumio-niclas sudo[808]: pam_unix(sudo:session): session closed for user root
Jun 04 20:46:51 volumio-niclas sudo[810]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Jun 04 20:46:51 volumio-niclas sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:52 volumio-niclas sudo[810]: pam_unix(sudo:session): session closed for user root
Jun 04 20:46:52 volumio-niclas wireless.js[619]: Stopped aP
Jun 04 20:46:52 volumio-niclas sudo[817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Jun 04 20:46:52 volumio-niclas sudo[817]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:52 volumio-niclas sudo[817]: pam_unix(sudo:session): session closed for user root
Jun 04 20:46:52 volumio-niclas kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Jun 04 20:46:52 volumio-niclas sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get
Jun 04 20:46:52 volumio-niclas sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:52 volumio-niclas sudo[819]: pam_unix(sudo:session): session closed for user root
Jun 04 20:46:52 volumio-niclas sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Jun 04 20:46:52 volumio-niclas sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:52 volumio-niclas sudo[826]: pam_unix(sudo:session): session closed for user root
Jun 04 20:46:52 volumio-niclas sudo[828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan
Jun 04 20:46:52 volumio-niclas sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:52 volumio-niclas ntpd[774]: error resolving pool 1.debian.pool.ntp.org: System error (-11)
Jun 04 20:46:53 volumio-niclas systemd[1]: systemd-rfkill.service: Succeeded.
Jun 04 20:46:53 volumio-niclas ntpd[774]: error resolving pool 2.debian.pool.ntp.org: System error (-11)
Jun 04 20:46:54 volumio-niclas ntpd[774]: error resolving pool 3.debian.pool.ntp.org: System error (-11)
Jun 04 20:46:54 volumio-niclas bthelper[674]: Changing power off succeeded
Jun 04 20:46:54 volumio-niclas bthelper[674]: [CHG] Controller DC:A6:32:17:AA:8E Powered: no
Jun 04 20:46:54 volumio-niclas bthelper[674]: [CHG] Controller DC:A6:32:17:AA:8E Discovering: no
Jun 04 20:46:54 volumio-niclas bthelper[674]: [CHG] Controller DC:A6:32:17:AA:8E Class: 0x00000000
Jun 04 20:46:54 volumio-niclas bthelper[674]: [CHG] Controller DC:A6:32:17:AA:8E Class: 0x0000041c
Jun 04 20:46:54 volumio-niclas bthelper[674]: Changing power on succeeded
Jun 04 20:46:55 volumio-niclas sudo[828]: pam_unix(sudo:session): session closed for user root
Jun 04 20:46:55 volumio-niclas wireless.js[619]: SETTING APPROPRIATE REG DOMAIN: DE
Jun 04 20:46:55 volumio-niclas sudo[840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Jun 04 20:46:55 volumio-niclas sudo[840]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:55 volumio-niclas sudo[840]: pam_unix(sudo:session): session closed for user root
Jun 04 20:46:55 volumio-niclas sudo[842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE
Jun 04 20:46:55 volumio-niclas sudo[842]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:55 volumio-niclas sudo[842]: pam_unix(sudo:session): session closed for user root
Jun 04 20:46:55 volumio-niclas wireless.js[619]: SUCCESSFULLY SET NEW REGDOMAIN: DE
Jun 04 20:46:55 volumio-niclas wireless.js[619]: Start wireless flow
Jun 04 20:46:55 volumio-niclas wireless.js[619]: Stopped hotspot (if there)..
Jun 04 20:46:55 volumio-niclas sudo[847]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Jun 04 20:46:55 volumio-niclas sudo[847]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:55 volumio-niclas sudo[847]: pam_unix(sudo:session): session closed for user root
Jun 04 20:46:55 volumio-niclas sudo[849]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Jun 04 20:46:55 volumio-niclas sudo[849]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:55 volumio-niclas volumio-remote-updater[618]: [2024-06-04 20:46:55] [info] asio async_connect error: system:111 (Connection refused)
Jun 04 20:46:55 volumio-niclas volumio-remote-updater[618]: [2024-06-04 20:46:55] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Jun 04 20:46:55 volumio-niclas volumio-remote-updater[618]: [2024-06-04 20:46:55] [error] handle_connect error: Underlying Transport Error
Jun 04 20:46:55 volumio-niclas sudo[849]: pam_unix(sudo:session): session closed for user root
Jun 04 20:46:55 volumio-niclas wireless.js[619]: DHCP IP
Jun 04 20:46:55 volumio-niclas wireless.js[619]: Start ap
Jun 04 20:46:55 volumio-niclas wpa_supplicant[851]: Successfully initialized wpa_supplicant
Jun 04 20:46:55 volumio-niclas sudo[852]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd
Jun 04 20:46:55 volumio-niclas sudo[852]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:55 volumio-niclas kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Jun 04 20:46:55 volumio-niclas dhcpcd[853]: dev: loaded udev
Jun 04 20:46:55 volumio-niclas dhcpcd-run-hooks[871]: wlan0: starting wpa_supplicant
Jun 04 20:46:56 volumio-niclas dhcpcd-run-hooks[876]: wlan0: failed to start wpa_supplicant
Jun 04 20:46:56 volumio-niclas dhcpcd-run-hooks[877]: 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
Jun 04 20:46:56 volumio-niclas dhcpcd[853]: wlan0: connected to Access Point `'
Jun 04 20:46:56 volumio-niclas dhcpcd[853]: no interfaces have a carrier
Jun 04 20:46:56 volumio-niclas dhcpcd[853]: forked to background, child pid 887
Jun 04 20:46:56 volumio-niclas dhcpcd[887]: eth0: waiting for carrier
Jun 04 20:46:56 volumio-niclas dhcpcd[887]: wlan0: waiting for carrier
Jun 04 20:46:56 volumio-niclas sudo[852]: pam_unix(sudo:session): session closed for user root
Jun 04 20:46:56 volumio-niclas wireless.js[619]: trying...
Jun 04 20:46:56 volumio-niclas sudo[889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 04 20:46:56 volumio-niclas sudo[889]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:56 volumio-niclas sudo[889]: pam_unix(sudo:session): session closed for user root
Jun 04 20:46:57 volumio-niclas wireless.js[619]: trying...
Jun 04 20:46:57 volumio-niclas sudo[892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 04 20:46:57 volumio-niclas sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:57 volumio-niclas sudo[892]: pam_unix(sudo:session): session closed for user root
Jun 04 20:46:58 volumio-niclas wpa_supplicant[875]: wlan0: Trying to associate with SSID 'Elbenwald'
Jun 04 20:46:58 volumio-niclas wireless.js[619]: trying...
Jun 04 20:46:58 volumio-niclas sudo[895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 04 20:46:58 volumio-niclas sudo[895]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:58 volumio-niclas sudo[895]: pam_unix(sudo:session): session closed for user root
Jun 04 20:46:59 volumio-niclas wireless.js[619]: trying...
Jun 04 20:46:59 volumio-niclas sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 04 20:46:59 volumio-niclas sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:46:59 volumio-niclas sudo[898]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:00 volumio-niclas wireless.js[619]: trying...
Jun 04 20:47:00 volumio-niclas sudo[901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 04 20:47:00 volumio-niclas sudo[901]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:00 volumio-niclas sudo[901]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:01 volumio-niclas dhcpcd[887]: wlan0: carrier acquired
Jun 04 20:47:01 volumio-niclas dhcpcd[887]: wlan0: connected to Access Point `Elbenwald'
Jun 04 20:47:01 volumio-niclas wpa_supplicant[875]: wlan0: Associated with d4:24:dd:97:f4:29
Jun 04 20:47:01 volumio-niclas wpa_supplicant[875]: wlan0: CTRL-EVENT-CONNECTED - Connection to d4:24:dd:97:f4:29 completed [id=0 id_str=]
Jun 04 20:47:01 volumio-niclas wpa_supplicant[875]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jun 04 20:47:01 volumio-niclas wpa_supplicant[875]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE
Jun 04 20:47:01 volumio-niclas dhcpcd[887]: DUID 00:01:00:01:2d:f2:1e:49:dc:a6:32:17:aa:8a
Jun 04 20:47:01 volumio-niclas dhcpcd[887]: wlan0: IAID 32:17:aa:8c
Jun 04 20:47:01 volumio-niclas dhcpcd[887]: wlan0: adding address fe80::5305:fd61:e725:d861
Jun 04 20:47:01 volumio-niclas dhcpcd[887]: ipv6_addaddr1: Permission denied
Jun 04 20:47:01 volumio-niclas dhcpcd[887]: wlan0: carrier lost
Jun 04 20:47:01 volumio-niclas dhcpcd[887]: wlan0: carrier acquired
Jun 04 20:47:01 volumio-niclas dhcpcd[887]: wlan0: IAID 32:17:aa:8c
Jun 04 20:47:01 volumio-niclas dhcpcd[887]: wlan0: rebinding lease of 192.168.178.56
Jun 04 20:47:01 volumio-niclas dhcpcd[887]: wlan0: probing address 192.168.178.56/24
Jun 04 20:47:01 volumio-niclas wireless.js[619]: trying...
Jun 04 20:47:01 volumio-niclas sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 04 20:47:01 volumio-niclas sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:01 volumio-niclas sudo[916]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:02 volumio-niclas dhcpcd[887]: wlan0: soliciting an IPv6 router
Jun 04 20:47:02 volumio-niclas wireless.js[619]: trying...
Jun 04 20:47:02 volumio-niclas sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 04 20:47:02 volumio-niclas sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:02 volumio-niclas sudo[919]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:03 volumio-niclas volumio-remote-updater[618]: [2024-06-04 20:47:03] [info] asio async_connect error: system:111 (Connection refused)
Jun 04 20:47:03 volumio-niclas volumio-remote-updater[618]: [2024-06-04 20:47:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Jun 04 20:47:03 volumio-niclas volumio-remote-updater[618]: [2024-06-04 20:47:03] [error] handle_connect error: Underlying Transport Error
Jun 04 20:47:03 volumio-niclas wireless.js[619]: trying...
Jun 04 20:47:03 volumio-niclas sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 04 20:47:03 volumio-niclas sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:03 volumio-niclas sudo[922]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:04 volumio-niclas wireless.js[619]: trying...
Jun 04 20:47:04 volumio-niclas sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 04 20:47:04 volumio-niclas sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:04 volumio-niclas sudo[925]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:05 volumio-niclas wireless.js[619]: trying...
Jun 04 20:47:05 volumio-niclas sudo[928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 04 20:47:05 volumio-niclas sudo[928]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:05 volumio-niclas sudo[928]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:06 volumio-niclas dhcpcd[887]: wlan0: leased 192.168.178.56 for 864000 seconds
Jun 04 20:47:06 volumio-niclas avahi-daemon[639]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.56.
Jun 04 20:47:06 volumio-niclas dhcpcd[887]: wlan0: adding route to 192.168.178.0/24
Jun 04 20:47:06 volumio-niclas dhcpcd[887]: wlan0: adding default route via 192.168.178.1
Jun 04 20:47:06 volumio-niclas avahi-daemon[639]: New relevant interface wlan0.IPv4 for mDNS.
Jun 04 20:47:06 volumio-niclas avahi-daemon[639]: Registering new address record for 192.168.178.56 on wlan0.IPv4.
Jun 04 20:47:06 volumio-niclas nmbd[730]: [2024/06/04 20:47:06.419269, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Jun 04 20:47:06 volumio-niclas systemd[1]: Started Samba NMB Daemon.
Jun 04 20:47:06 volumio-niclas nmbd[730]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Jun 04 20:47:06 volumio-niclas nmbd[730]: [2024/06/04 20:47:06.437792, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Jun 04 20:47:06 volumio-niclas nmbd[730]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.56 for name WORKGROUP<1d>.
Jun 04 20:47:06 volumio-niclas nmbd[730]: This response was from IP 192.168.178.35, reporting an IP address of 192.168.178.35.
Jun 04 20:47:06 volumio-niclas systemd[1]: Starting Samba Winbind Daemon...
Jun 04 20:47:06 volumio-niclas winbindd[941]: [2024/06/04 20:47:06.660229, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
Jun 04 20:47:06 volumio-niclas winbindd[941]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Jun 04 20:47:06 volumio-niclas winbindd[941]: [2024/06/04 20:47:06.675808, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Jun 04 20:47:06 volumio-niclas winbindd[941]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Jun 04 20:47:06 volumio-niclas systemd[1]: Started Samba Winbind Daemon.
Jun 04 20:47:06 volumio-niclas systemd[1]: Starting Samba SMB Daemon...
Jun 04 20:47:06 volumio-niclas wireless.js[619]: trying...
Jun 04 20:47:06 volumio-niclas sudo[958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 04 20:47:06 volumio-niclas sudo[958]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:06 volumio-niclas sudo[958]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:06 volumio-niclas wireless.js[619]: Connected to: ----Elbenwald
Jun 04 20:47:06 volumio-niclas wireless.js[619]: ----
Jun 04 20:47:06 volumio-niclas sudo[961]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 04 20:47:06 volumio-niclas sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:06 volumio-niclas sudo[961]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:06 volumio-niclas wireless.js[619]: ... joined AP, wlan0 IPv4 is 192.168.178.56, ipV6 is undefined
Jun 04 20:47:06 volumio-niclas wireless.js[619]: It's done! AP
Jun 04 20:47:06 volumio-niclas systemd[1]: Started Wireless Services.
Jun 04 20:47:06 volumio-niclas kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Jun 04 20:47:06 volumio-niclas systemd[1]: Started Volumio Backend Module.
Jun 04 20:47:06 volumio-niclas systemd[1]: Started Volumio Cpu Tweaker.
Jun 04 20:47:06 volumio-niclas volumio-cpu-tweak[967]: Setting RT Priority for mpd
Jun 04 20:47:06 volumio-niclas volumio-cpu-tweak[967]: pid 35's current scheduling policy: SCHED_OTHER
Jun 04 20:47:06 volumio-niclas volumio-cpu-tweak[967]: pid 35's current scheduling priority: 0
Jun 04 20:47:06 volumio-niclas volumio-cpu-tweak[967]: Setting MPD Affinity
Jun 04 20:47:06 volumio-niclas volumio-cpu-tweak[967]: pid 3's current affinity mask: f
Jun 04 20:47:06 volumio-niclas volumio-cpu-tweak[967]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input
Jun 04 20:47:06 volumio-niclas volumio-cpu-tweak[967]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance
Jun 04 20:47:06 volumio-niclas systemd[1]: volumio_cpu_tweak.service: Succeeded.
Jun 04 20:47:07 volumio-niclas smbd[956]: [2024/06/04 20:47:07.101139, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Jun 04 20:47:07 volumio-niclas smbd[956]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Jun 04 20:47:07 volumio-niclas systemd[1]: Started Samba SMB Daemon.
Jun 04 20:47:07 volumio-niclas systemd[1]: Reached target Multi-User System.
Jun 04 20:47:07 volumio-niclas systemd[1]: Reached target Graphical Interface.
Jun 04 20:47:07 volumio-niclas systemd[1]: Starting Update UTMP about System Runlevel Changes...
Jun 04 20:47:07 volumio-niclas systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Jun 04 20:47:07 volumio-niclas systemd[1]: Started Update UTMP about System Runlevel Changes.
Jun 04 20:47:07 volumio-niclas systemd[1]: Startup finished in 14.257s (kernel) + 24.183s (userspace) = 38.440s.
Jun 04 20:47:07 volumio-niclas ntpd[774]: Listen normally on 3 wlan0 192.168.178.56:123
Jun 04 20:47:07 volumio-niclas ntpd[774]: new interface(s) found: waking up resolver
Jun 04 20:47:08 volumio-niclas volumio[966]: info: -------------------------------------------
Jun 04 20:47:08 volumio-niclas volumio[966]: info: ----- Volumio3 ----
Jun 04 20:47:08 volumio-niclas volumio[966]: info: -------------------------------------------
Jun 04 20:47:08 volumio-niclas volumio[966]: info: ----- System startup ----
Jun 04 20:47:08 volumio-niclas volumio[966]: info: -------------------------------------------
Jun 04 20:47:09 volumio-niclas volumio[966]: info: MYVOLUMIO Environment detected
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Plugin folders cleanup
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Scanning into folder /volumio/app/plugins/
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Scanning category audio_interface
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Scanning category miscellanea
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Scanning category music_service
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Scanning category plugins.json
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Scanning category system_controller
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Scanning category user_interface
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Scanning into folder /data/plugins/
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Scanning category music_service
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Plugin folders cleanup completed
Jun 04 20:47:09 volumio-niclas volumio[966]: info: -------------------------------------------
Jun 04 20:47:09 volumio-niclas volumio[966]: info: ----- Core plugins startup ----
Jun 04 20:47:09 volumio-niclas volumio[966]: info: -------------------------------------------
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Loading plugins from folder /volumio/app/plugins/
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Adding plugin upnp to MyMusic Plugins
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Adding plugin airplay_emulation to MyMusic Plugins
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Adding plugin upnp_browser to MyMusic Plugins
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Loading plugins from folder /data/plugins/
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Loading plugin "system"...
Jun 04 20:47:09 volumio-niclas volumio[966]: info: Loading plugin "appearance"...
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Loading plugin "network"...
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Refreshing Cached IP Addresses
Jun 04 20:47:10 volumio-niclas sudo[1005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 04 20:47:10 volumio-niclas sudo[1005]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:10 volumio-niclas sudo[1005]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:10 volumio-niclas sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 04 20:47:10 volumio-niclas sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Loading plugin "services"...
Jun 04 20:47:10 volumio-niclas sudo[1007]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Loading plugin "alsa_controller"...
Jun 04 20:47:10 volumio-niclas sudo[1010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jun 04 20:47:10 volumio-niclas sudo[1010]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:10 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Loading plugin "wizard"...
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Loading plugin "networkfs"...
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Starting Udev Watcher for removable devices
Jun 04 20:47:10 volumio-niclas sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=volumio,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.12/Multimedia/Musik/Alben /mnt/NAS/Datenzwerg
Jun 04 20:47:10 volumio-niclas sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Ignoring mount for partition: boot
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Ignoring mount for partition: volumio
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Ignoring mount for partition: volumio_data
Jun 04 20:47:10 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Loading plugin "volumio_command_line_client"...
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Loading plugin "upnp"...
Jun 04 20:47:10 volumio-niclas volumio[966]: info: [1717526830474] Starting Upmpd Daemon
Jun 04 20:47:10 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Loading plugin "my_music"...
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Loading plugin "mpd"...
Jun 04 20:47:10 volumio-niclas kernel: Key type cifs.spnego registered
Jun 04 20:47:10 volumio-niclas kernel: Key type cifs.idmap registered
Jun 04 20:47:10 volumio-niclas 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.
Jun 04 20:47:10 volumio-niclas kernel: CIFS: Attempting to mount \\192.168.178.12\Multimedia
Jun 04 20:47:10 volumio-niclas volumio[966]: info: Loading plugin "upnp_browser"...
Jun 04 20:47:11 volumio-niclas volumio[966]: info: Loading plugin "alarm-clock"...
Jun 04 20:47:11 volumio-niclas volumio[966]: info: Loading plugin "airplay_emulation"...
Jun 04 20:47:11 volumio-niclas volumio[966]: info: Starting Shairport Sync
Jun 04 20:47:11 volumio-niclas volumio[966]: info: Loading plugin "last_100"...
Jun 04 20:47:11 volumio-niclas volumio[966]: info: Loading plugin "webradio"...
Jun 04 20:47:11 volumio-niclas sudo[1018]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:11 volumio-niclas volumio[966]: info: Loading plugin "i2s_dacs"...
Jun 04 20:47:11 volumio-niclas volumio[966]: info: I2S DAC not set, start Auto-detection
Jun 04 20:47:11 volumio-niclas volumio[966]: info: Loading plugin "volumiodiscovery"...
Jun 04 20:47:11 volumio-niclas volumio[966]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jun 04 20:47:11 volumio-niclas node[966]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jun 04 20:47:11 volumio-niclas volumio[966]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 04 20:47:11 volumio-niclas node[966]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 04 20:47:11 volumio-niclas volumio[966]: *** WARNING *** For more information see
Jun 04 20:47:11 volumio-niclas node[966]: *** WARNING *** For more information see
Jun 04 20:47:11 volumio-niclas volumio[966]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jun 04 20:47:11 volumio-niclas node[966]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jun 04 20:47:11 volumio-niclas volumio[966]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 04 20:47:11 volumio-niclas node[966]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 04 20:47:11 volumio-niclas volumio[966]: *** WARNING *** For more information see
Jun 04 20:47:11 volumio-niclas node[966]: *** WARNING *** For more information see
Jun 04 20:47:11 volumio-niclas volumio[966]: info: Applying required configuration parameters for plugin volumiodiscovery
Jun 04 20:47:11 volumio-niclas volumio[966]: info: Discovery: Started advertising with name: Volumio Niclas
Jun 04 20:47:11 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 04 20:47:11 volumio-niclas volumio[966]: info: Loading plugin "spop"...
Jun 04 20:47:12 volumio-niclas volumio[966]: info: Loading plugin "outputs"...
Jun 04 20:47:12 volumio-niclas volumio[966]: info: Loading plugin "albumart"...
Jun 04 20:47:12 volumio-niclas volumio[966]: info: Plugin example_plugin is not enabled
Jun 04 20:47:12 volumio-niclas volumio[966]: info: Loading plugin "inputs"...
Jun 04 20:47:12 volumio-niclas volumio[966]: info: Loading plugin "updater_comm"...
Jun 04 20:47:12 volumio-niclas volumio[966]: info: Plugin mpdemulation is not enabled
Jun 04 20:47:12 volumio-niclas volumio[966]: info: Loading plugin "rest_api"...
Jun 04 20:47:12 volumio-niclas volumio[966]: info: Loading plugin "websocket"...
Jun 04 20:47:12 volumio-niclas volumio[966]: info: Starting Socket.io Server version 2.3.0
Jun 04 20:47:12 volumio-niclas volumio[966]: info: Loading i18n strings for locale de
Jun 04 20:47:12 volumio-niclas volumio[966]: Updating browse sources language
Jun 04 20:47:12 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 04 20:47:12 volumio-niclas volumio[966]: Forking 3 albumart workers
Jun 04 20:47:12 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 04 20:47:12 volumio-niclas volumio[966]: info: CoreCommandRouter::initPlayerControls
Jun 04 20:47:12 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:12 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:12 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:12 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:12 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:12 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:12 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:12 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:12 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 04 20:47:12 volumio-niclas volumio[966]: Express server listening on port 3000
Jun 04 20:47:12 volumio-niclas volumio[966]: [Metrics] WebUI: 5s 15.35ms
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreStateMachine::resetVolumioState
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreStateMachine::getcurrentVolume
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::volumioRetrievevolume
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 04 20:47:13 volumio-niclas volumio[966]: verbose: New Socket.io Connection to 192.168.178.56:3000 from 192.168.178.62 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1
Jun 04 20:47:13 volumio-niclas volumio[966]: verbose: New Socket.io Connection to 192.168.178.56 from 192.168.178.109 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 2
Jun 04 20:47:13 volumio-niclas volumio[966]: verbose: New Socket.io Connection to 192.168.178.56:3000 from 192.168.178.62 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Jun 04 20:47:13 volumio-niclas volumio[966]: verbose: New Socket.io Connection to 192.168.178.56:3000 from 192.168.178.62 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Jun 04 20:47:13 volumio-niclas volumio-remote-updater[618]: [2024-06-04 20:47:13] [connect] Successful connection
Jun 04 20:47:13 volumio-niclas volumio[966]: verbose: New Socket.io Connection to 192.168.178.56 from 192.168.178.109 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 5
Jun 04 20:47:13 volumio-niclas volumio[966]: verbose: New Socket.io Connection to 192.168.178.56:3000 from 192.168.178.62 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Jun 04 20:47:13 volumio-niclas volumio[966]: info: Reloading queue from file
Jun 04 20:47:13 volumio-niclas volumio-remote-updater[618]: [2024-06-04 20:47:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1717526833 101
Jun 04 20:47:13 volumio-niclas volumio[966]: 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: 7
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreStateMachine::setRepeat null single undefined
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreStateMachine::pushState
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CorePlayQueue::getTrack 0
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::volumioPushState
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreStateMachine::setRandom null
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreStateMachine::pushState
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CorePlayQueue::getTrack 0
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::volumioPushState
Jun 04 20:47:13 volumio-niclas volumio[966]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb
Jun 04 20:47:13 volumio-niclas volumio[966]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111
Jun 04 20:47:13 volumio-niclas volumio[966]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI
Jun 04 20:47:13 volumio-niclas volumio[966]: info: Setting Device type: Raspberry PI
Jun 04 20:47:13 volumio-niclas volumio[966]: info: VolumeController:: Volume=100 Mute =false
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreStateMachine::pushState
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CorePlayQueue::getTrack 0
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::volumioPushState
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreStateMachine::updateTrackBlock
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CorePlayQueue::getTrackBlock
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::volumioRetrievevolume
Jun 04 20:47:13 volumio-niclas volumio[966]: Starting albumart workers
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Jun 04 20:47:13 volumio-niclas volumio[966]: Starting albumart workers
Jun 04 20:47:13 volumio-niclas volumio[966]: Starting albumart workers
Jun 04 20:47:13 volumio-niclas volumio[966]: info: Completed loading Core Plugins
Jun 04 20:47:13 volumio-niclas volumio[966]: info: Preparing to generate the ALSA configuration file
Jun 04 20:47:13 volumio-niclas volumio[966]: info: Asound.conf file unchanged, so no further update is needed
Jun 04 20:47:13 volumio-niclas volumio[966]: info: Output device has changed, restarting MPD
Jun 04 20:47:13 volumio-niclas volumio[966]: info: Output device has changed, restarting Shairport Sync
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 04 20:47:13 volumio-niclas sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 04 20:47:13 volumio-niclas sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:13 volumio-niclas sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 04 20:47:13 volumio-niclas sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:13 volumio-niclas sudo[1086]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:13 volumio-niclas systemd[1]: Listening on mpd.socket.
Jun 04 20:47:13 volumio-niclas systemd[1]: Starting Music Player Daemon...
Jun 04 20:47:13 volumio-niclas volumio[966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 04 20:47:13 volumio-niclas volumio[966]: info: ___________ START PLUGINS ___________
Jun 04 20:47:13 volumio-niclas volumio[966]: info: ControllerMpd::onStart: Initializing MPD
Jun 04 20:47:13 volumio-niclas volumio[966]: info: Creating MPD Configuration file
Jun 04 20:47:13 volumio-niclas sudo[1096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 04 20:47:13 volumio-niclas sudo[1096]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 04 20:47:13 volumio-niclas volumio[966]: info: [1717526833634] CoreMusicLibrary::Adding element Medienserver
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 04 20:47:13 volumio-niclas sudo[1094]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jun 04 20:47:13 volumio-niclas sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:13 volumio-niclas sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 04 20:47:13 volumio-niclas sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:13 volumio-niclas sudo[1096]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:13 volumio-niclas sudo[1094]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Jun 04 20:47:13 volumio-niclas sudo[1094]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 04 20:47:13 volumio-niclas systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Jun 04 20:47:13 volumio-niclas systemd[1]: mpd.service: Succeeded.
Jun 04 20:47:13 volumio-niclas systemd[1]: Stopped Music Player Daemon.
Jun 04 20:47:13 volumio-niclas systemd[1]: Starting Music Player Daemon...
Jun 04 20:47:13 volumio-niclas volumio[966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 04 20:47:13 volumio-niclas sudo[1104]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jun 04 20:47:13 volumio-niclas sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:13 volumio-niclas sudo[1104]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Jun 04 20:47:13 volumio-niclas sudo[1104]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:13 volumio-niclas volumio[966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 04 20:47:13 volumio-niclas volumio[966]: info: [1717526833791] CoreMusicLibrary::Adding element Last_100
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 04 20:47:13 volumio-niclas volumio[966]: info: [1717526833794] CoreMusicLibrary::Adding element Webradio
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 04 20:47:13 volumio-niclas volumio[966]: info: Initializing BBC Radios
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 04 20:47:13 volumio-niclas sudo[1010]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:13 volumio-niclas volumio[966]: info: Creating Spotify config file
Jun 04 20:47:13 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Volumio Calling Home
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Discovery: adding 5a0c4c77-43bb-4b6b-adc2-1ba3ded445b8
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Discovery: Found device Volumio Niclas
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::volumioGetState
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CorePlayQueue::getTrack 0
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::volumioGetState
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CorePlayQueue::getTrack 0
Jun 04 20:47:14 volumio-niclas volumio[966]: info: MPD Permissions set
Jun 04 20:47:14 volumio-niclas volumio[966]: info: MPD Permissions set
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Spotify config file written
Jun 04 20:47:14 volumio-niclas volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Jun 04 20:47:14 volumio-niclas sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 04 20:47:14 volumio-niclas systemd[1]: Started go-librespot Daemon.
Jun 04 20:47:14 volumio-niclas go-librespot[1158]: Librespot-go daemon starting...
Jun 04 20:47:14 volumio-niclas sudo[1151]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:14 volumio-niclas volumio[966]: info: VolumeController:: Volume=100 Mute =false
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreStateMachine::pushState
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CorePlayQueue::getTrack 0
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::volumioPushState
Jun 04 20:47:14 volumio-niclas volumio[966]: info: An error occurred while refreshing Spotify Token Error: certificate is not yet valid
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Volumio called home
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Starting Shairport Sync
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Starting Shairport Sync
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Starting Shairport Sync
Jun 04 20:47:14 volumio-niclas sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 04 20:47:14 volumio-niclas sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:14 volumio-niclas systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jun 04 20:47:14 volumio-niclas sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CoreCommandRouter::volumioGetState
Jun 04 20:47:14 volumio-niclas sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:14 volumio-niclas systemd[1]: shairport-sync.service: Succeeded.
Jun 04 20:47:14 volumio-niclas volumio[966]: info: CorePlayQueue::getTrack 0
Jun 04 20:47:14 volumio-niclas systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jun 04 20:47:14 volumio-niclas sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 04 20:47:14 volumio-niclas sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:14 volumio-niclas systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 04 20:47:14 volumio-niclas sudo[1165]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Shairport-Sync Started
Jun 04 20:47:14 volumio-niclas volumio[966]: Error adding Membership: Error: addMembership EINVAL
Jun 04 20:47:14 volumio-niclas systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jun 04 20:47:14 volumio-niclas systemd[1]: shairport-sync.service: Succeeded.
Jun 04 20:47:14 volumio-niclas systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jun 04 20:47:14 volumio-niclas systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 04 20:47:14 volumio-niclas sudo[1172]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:14 volumio-niclas sudo[1174]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Shairport-Sync Started
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Shairport-Sync Started
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Discovery: adding 3267fee1-416a-48a6-89be-407692d91282
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Discovery: Found device Volumio Wohnzimmer
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Discovery: Connecting to remote: 192.168.178.62
Jun 04 20:47:14 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:14+02:00" level=info msg="generated new device id: 47757c7315e5b65896c444e7854541a6857d3a1f"
Jun 04 20:47:14 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:14+02:00" level=debug msg="stored credentials found for l5d20ev0p495e4wwtl6jt0xan"
Jun 04 20:47:14 volumio-niclas volumio[966]: info: Discovery: Connected to remote: 192.168.178.62
Jun 04 20:47:15 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:15+02:00" level=debug msg="obtained new client token: AAAHKPv7Ch9fJ0fj0Gu7Co0AdvcdtO03eJbjoy6XyPwrvsW+SHKqOzn2A9wuKW2G2GTRlDkHejG+YBuVwXuRD0dz48oLtaTf/bM4s1OKpAkkTWdNUiKRjypKSn/LRdYvthYJ3WwEEPRa5KflwKhTt0j8meMgch+UH33twXFkVQxVF7aOpvg9E6/c3pTNy8oHiUBlo/0s5I3UWiveM+hUIA3sGzLJhdVvsgxYqroQ+gMvmbk2ssgdu62raKauDJs="
Jun 04 20:47:15 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:15+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Jun 04 20:47:15 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:15+02:00" level=debug msg="completed keyexchange"
Jun 04 20:47:15 volumio-niclas mpd[1111]: Jun 04 20:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jun 04 20:47:15 volumio-niclas systemd[1]: Started Music Player Daemon.
Jun 04 20:47:15 volumio-niclas sudo[1099]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:15 volumio-niclas sudo[1088]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:15 volumio-niclas volumio[966]: info: Completed starting Core Plugins
Jun 04 20:47:15 volumio-niclas volumio[966]: info: -------------------------------------------
Jun 04 20:47:15 volumio-niclas volumio[966]: info: ----- MyVolumio plugins startup ----
Jun 04 20:47:15 volumio-niclas volumio[966]: info: -------------------------------------------
Jun 04 20:47:15 volumio-niclas volumio[966]: info: [MyVolumio PluginManager] Fetching plans data....
Jun 04 20:47:15 volumio-niclas volumio[966]: error: MPD error: The expression evaluated to a falsy value:
Jun 04 20:47:15 volumio-niclas volumio[966]: assert.ok(self.idling)
Jun 04 20:47:15 volumio-niclas volumio[966]: error: The expression evaluated to a falsy value:
Jun 04 20:47:15 volumio-niclas volumio[966]: assert.ok(self.idling)
Jun 04 20:47:15 volumio-niclas volumio[966]: info: MPD running with PID1111
Jun 04 20:47:15 volumio-niclas volumio[966]: ,establishing connection
Jun 04 20:47:15 volumio-niclas volumio[966]: error: updateQueue error: null
Jun 04 20:47:15 volumio-niclas volumio[966]: error: updateQueue error: null
Jun 04 20:47:15 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:15+02:00" level=debug msg="completed challenge"
Jun 04 20:47:15 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:15+02:00" level=debug msg="authenticated as l5d20ev0p495e4wwtl6jt0xan"
Jun 04 20:47:16 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:16+02:00" level=debug msg="authenticated as l5d20ev0p495e4wwtl6jt0xan"
Jun 04 20:47:16 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Jun 04 20:47:16 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:16+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Jun 04 20:47:16 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:16+02:00" level=debug msg="dealer connection opened"
Jun 04 20:47:16 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:16+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Jun 04 20:47:16 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:16+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Jun 04 20:47:16 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:16+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Jun 04 20:47:16 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:16+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Jun 04 20:47:16 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513"
Jun 04 20:47:16 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:16+02:00" level=debug msg="received connection id: ODhkZDU1MTEtOThlMi00ZmQzLWIxZjEtZDIyNjYzYmM3YjdmK2RlYWxlcit0Y3A6Ly9nZXc0LWRlYWxlci1hLXgzMTUuZ2V3NC5zcG90aWZ5Lm5ldDo1NzAwKzk0RjZBOTU5RDAzMTBEMkVBMEUwRTBCQ0ZDMjM4QURDMkQ1RTI1QkI4MUU2RDUyRDZDNDVDNjhFMTI3NDFFOUE="
Jun 04 20:47:16 volumio-niclas volumio[966]: verbose: New Socket.io Connection to 192.168.178.56:3000 from 192.168.178.62 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Jun 04 20:47:16 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:16+02:00" level=debug msg="put connect state because NEW_DEVICE"
Jun 04 20:47:17 volumio-niclas volumio[966]: info: go-librespot daemon successfully initialized
Jun 04 20:47:18 volumio-niclas systemd[1]: systemd-fsckd.service: Succeeded.
Jun 04 20:47:19 volumio-niclas sh[524]: timed out
Jun 04 20:47:19 volumio-niclas dhcpcd[556]: timed out
Jun 04 20:47:19 volumio-niclas sh[524]: dhcpcd exited
Jun 04 20:47:19 volumio-niclas dhcpcd[556]: dhcpcd exited
Jun 04 20:47:19 volumio-niclas sh[524]: ifup: failed to bring up eth0
Jun 04 20:47:19 volumio-niclas systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE
Jun 04 20:47:19 volumio-niclas systemd[1]: ifup@eth0.service: Failed with result 'exit-code'.
Jun 04 20:47:20 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:20+02:00" level=debug msg="handling transfer player command from add795d49c59dbd00c37d5a9b0ffc67172603c3e"
Jun 04 20:47:20 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:20+02:00" level=trace msg="fetched new page 0 with 600 items (list: 600)"
Jun 04 20:47:20 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:20+02:00" level=debug msg="shuffled context with seed 7601996451327752033 (len: 600, keep: 378)"
Jun 04 20:47:20 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:20+02:00" level=debug msg="loading track spotify:track:04cMUwER7r2VwqMCj4kYJ9 (paused: true, position: 93992ms)"
Jun 04 20:47:20 volumio-niclas volumio[966]: info: Initializing connection to go-librespot Websocket
Jun 04 20:47:20 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:20+02:00" level=debug msg="new websocket client"
Jun 04 20:47:20 volumio-niclas volumio[966]: info: Connection to go-librespot Websocket established
Jun 04 20:47:20 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:20+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Jun 04 20:47:20 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:20+02:00" level=trace msg="emitting websocket event: will_play"
Jun 04 20:47:20 volumio-niclas volumio[966]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:04cMUwER7r2VwqMCj4kYJ9","play_origin":"collection-songs"}}
Jun 04 20:47:20 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:20+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241"
Jun 04 20:47:20 volumio-niclas sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 04 20:47:20 volumio-niclas sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:20 volumio-niclas sudo[1191]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:20 volumio-niclas sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 04 20:47:20 volumio-niclas sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:20 volumio-niclas sudo[1193]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:20 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:20+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:04cMUwER7r2VwqMCj4kYJ9"
Jun 04 20:47:20 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:20+02:00" level=debug msg="requested aes key for file 3bf31b5620415a432149c3a36893246037d13374, gid: 04cMUwER7r2VwqMCj4kYJ9"
Jun 04 20:47:20 volumio-niclas sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jun 04 20:47:20 volumio-niclas sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 04 20:47:20 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:20+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1974"
Jun 04 20:47:20 volumio-niclas systemd[1]: Started UPnP Renderer front-end to MPD.
Jun 04 20:47:20 volumio-niclas sudo[1197]: pam_unix(sudo:session): session closed for user root
Jun 04 20:47:20 volumio-niclas volumio[966]: info: Upmpdcli Daemon Started
Jun 04 20:47:20 volumio-niclas volumio[1199]: Generating RSA private key, 4096 bit long modulus (2 primes)
Jun 04 20:47:20 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:20+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1565"
Jun 04 20:47:20 volumio-niclas volumio[966]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=debug msg="fetched first chunk of 12, total size is 5931384 bytes"
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=debug msg="fetched chunk 1/11, size: 524288"
Jun 04 20:47:21 volumio-niclas systemd[1]: systemd-hostnamed.service: Succeeded.
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=debug msg="fetched chunk 3/11, size: 524288"
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=debug msg="fetched chunk 9/11, size: 524288"
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=debug msg="fetched chunk 10/11, size: 524288"
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=debug msg="fetched chunk 8/11, size: 524288"
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=debug msg="fetched chunk 2/11, size: 524288"
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=debug msg="fetched chunk 7/11, size: 524288"
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=trace msg="seek to 93992ms (diff: 169ms, samples: 4145047, bytes: 4077370)"
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=info msg="loaded track \"Devil Eyes\" (uri: spotify:track:04cMUwER7r2VwqMCj4kYJ9, paused: true, position: 93992ms, duration: 136651ms)"
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=trace msg="emitting websocket event: metadata"
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=trace msg="emitting websocket event: active"
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=debug msg="sending successful reply for delaer request"
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update
Jun 04 20:47:21 volumio-niclas volumio[966]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:04cMUwER7r2VwqMCj4kYJ9","name":"Devil Eyes","artist_names":["ZODIVK"],"album_name":"Devil Eyes","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02715060680e6e0fd335de85b7","position":93992,"duration":136651,"release_date":"year:2022 month:1 day:28","track_number":1,"disc_number":1}}
Jun 04 20:47:21 volumio-niclas volumio[966]: SPOTIFY: received: {"type":"active","data":null}
Jun 04 20:47:21 volumio-niclas volumio[966]: info: Aligning Spotify Volume to Volumio Volume
Jun 04 20:47:21 volumio-niclas volumio[966]: info: CoreCommandRouter::volumioGetState
Jun 04 20:47:21 volumio-niclas volumio[966]: info: CorePlayQueue::getTrack 0
Jun 04 20:47:21 volumio-niclas volumio[966]: info: Setting Spotify Volume from Volumio: 100
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Jun 04 20:47:21 volumio-niclas go-librespot[1158]: time="2024-06-04T20:47:21+02:00" level=trace msg="emitting websocket event: paused"
Jun 04 20:47:21 volumio-niclas volumio[966]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:04cMUwER7r2VwqMCj4kYJ9","play_origin":"collection-songs"}}
Jun 04 20:47:21 volumio-niclas volumio[966]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 04 20:47:21 volumio-niclas volumio[966]: TypeError: Cannot read property 'service' of undefined
Jun 04 20:47:21 volumio-niclas volumio[966]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50)
Jun 04 20:47:21 volumio-niclas volumio[966]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:270:18)
Jun 04 20:47:21 volumio-niclas volumio[966]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14)
Jun 04 20:47:21 volumio-niclas volumio[966]: at WebSocket.emit (events.js:315:20)
Jun 04 20:47:21 volumio-niclas volumio[966]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1209:20)
Jun 04 20:47:21 volumio-niclas volumio[966]: at Receiver.emit (events.js:315:20)
Jun 04 20:47:21 volumio-niclas volumio[966]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:594:14)
Jun 04 20:47:21 volumio-niclas volumio[966]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10)
Jun 04 20:47:21 volumio-niclas volumio[966]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16)
Jun 04 20:47:21 volumio-niclas volumio[966]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10)
Jun 04 20:47:21 volumio-niclas volumio[966]: at writeOrBuffer (internal/streams/writable.js:358:12)
Jun 04 20:47:21 volumio-niclas volumio[966]: at Receiver.Writable.write (internal/streams/writable.js:303:10)
Jun 04 20:47:21 volumio-niclas volumio[966]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1303:35)
Jun 04 20:47:21 volumio-niclas volumio[966]: at Socket.emit (events.js:315:20)
Jun 04 20:47:21 volumio-niclas volumio[966]: at addChunk (internal/streams/readable.js:309:12)
Jun 04 20:47:21 volumio-niclas volumio[966]: at readableAddChunk (internal/streams/readable.js:284:9)
Jun 04 20:47:21 volumio-niclas volumio[966]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 04 20:47:22 volumio-niclas sudo[1227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-04 20:46
Jun 04 20:47:22 volumio-niclas sudo[1227]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST"
VOLUMIO_VERSION="3.703"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"