-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Tue 2024-11-19 21:49:11 CET. -- Nov 19 21:48:39 kueche kernel: raspberrypi-firmware soc:firmware: Firmware hash is 30f0c5e4d076da3ab4f341d88e7d505760b93ad7 Nov 19 21:48:39 kueche kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 19 21:48:39 kueche kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 Nov 19 21:48:39 kueche kernel: SCSI subsystem initialized Nov 19 21:48:39 kueche kernel: usbcore: registered new interface driver usbfs Nov 19 21:48:39 kueche kernel: usbcore: registered new interface driver hub Nov 19 21:48:39 kueche kernel: usbcore: registered new device driver usb Nov 19 21:48:39 kueche kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Nov 19 21:48:39 kueche kernel: pps_core: LinuxPPS API ver. 1 registered Nov 19 21:48:39 kueche kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 19 21:48:39 kueche kernel: PTP clock support registered Nov 19 21:48:39 kueche kernel: clocksource: Switched to clocksource arch_sys_counter Nov 19 21:48:39 kueche kernel: VFS: Disk quotas dquot_6.6.0 Nov 19 21:48:39 kueche kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Nov 19 21:48:39 kueche kernel: FS-Cache: Loaded Nov 19 21:48:39 kueche kernel: CacheFiles: Loaded Nov 19 21:48:39 kueche kernel: NET: Registered PF_INET protocol family Nov 19 21:48:39 kueche kernel: IP idents hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 19 21:48:39 kueche kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Nov 19 21:48:39 kueche kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 19 21:48:39 kueche kernel: TCP established hash table entries: 4096 (order: 2, 16384 bytes, linear) Nov 19 21:48:39 kueche kernel: TCP bind hash table entries: 4096 (order: 4, 65536 bytes, linear) Nov 19 21:48:39 kueche kernel: TCP: Hash tables configured (established 4096 bind 4096) Nov 19 21:48:39 kueche kernel: UDP hash table entries: 256 (order: 1, 8192 bytes, linear) Nov 19 21:48:39 kueche kernel: UDP-Lite hash table entries: 256 (order: 1, 8192 bytes, linear) Nov 19 21:48:39 kueche kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 19 21:48:39 kueche kernel: RPC: Registered named UNIX socket transport module. Nov 19 21:48:39 kueche kernel: RPC: Registered udp transport module. Nov 19 21:48:39 kueche kernel: RPC: Registered tcp transport module. Nov 19 21:48:39 kueche kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 19 21:48:39 kueche kernel: Trying to unpack rootfs image as initramfs... Nov 19 21:48:39 kueche kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Nov 19 21:48:39 kueche kernel: Initialise system trusted keyrings Nov 19 21:48:39 kueche kernel: workingset: timestamp_bits=14 max_order=17 bucket_order=3 Nov 19 21:48:39 kueche kernel: zbud: loaded Nov 19 21:48:39 kueche kernel: NFS: Registering the id_resolver key type Nov 19 21:48:39 kueche kernel: Key type id_resolver registered Nov 19 21:48:39 kueche kernel: Key type id_legacy registered Nov 19 21:48:39 kueche kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Nov 19 21:48:39 kueche kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Nov 19 21:48:39 kueche kernel: Key type asymmetric registered Nov 19 21:48:39 kueche kernel: Asymmetric key parser 'x509' registered Nov 19 21:48:39 kueche kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Nov 19 21:48:39 kueche kernel: io scheduler mq-deadline registered Nov 19 21:48:39 kueche kernel: io scheduler kyber registered Nov 19 21:48:39 kueche kernel: bcm2708_fb soc:fb: FB found 1 display(s) Nov 19 21:48:39 kueche kernel: Console: switching to colour frame buffer device 80x30 Nov 19 21:48:39 kueche kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Nov 19 21:48:39 kueche kernel: bcm2835-rng 3f104000.rng: hwrng registered Nov 19 21:48:39 kueche kernel: vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB) Nov 19 21:48:39 kueche kernel: brd: module loaded Nov 19 21:48:39 kueche kernel: loop: module loaded Nov 19 21:48:39 kueche kernel: Loading iSCSI transport class v2.0-870. Nov 19 21:48:39 kueche kernel: usbcore: registered new interface driver lan78xx Nov 19 21:48:39 kueche kernel: usbcore: registered new interface driver smsc95xx Nov 19 21:48:39 kueche kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Nov 19 21:48:39 kueche kernel: Core Release: 2.80a Nov 19 21:48:39 kueche kernel: Setting default values for core params Nov 19 21:48:39 kueche kernel: Finished setting default values for core params Nov 19 21:48:39 kueche kernel: Freeing initrd memory: 14316K Nov 19 21:48:39 kueche kernel: Using Buffer DMA mode Nov 19 21:48:39 kueche kernel: Periodic Transfer Interrupt Enhancement - disabled Nov 19 21:48:39 kueche kernel: Multiprocessor Interrupt Enhancement - disabled Nov 19 21:48:39 kueche kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Nov 19 21:48:39 kueche kernel: Dedicated Tx FIFOs mode Nov 19 21:48:39 kueche kernel: Nov 19 21:48:39 kueche kernel: WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = 99114000 dma = 0xd9114000 len=9024 Nov 19 21:48:39 kueche kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Nov 19 21:48:39 kueche kernel: dwc_otg: Microframe scheduler enabled Nov 19 21:48:39 kueche kernel: Nov 19 21:48:39 kueche kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Nov 19 21:48:39 kueche kernel: Nov 19 21:48:39 kueche kernel: WARN::hcd_init_fiq:458: FIQ ASM at 807cb80c length 36 Nov 19 21:48:39 kueche kernel: Nov 19 21:48:39 kueche kernel: WARN::hcd_init_fiq:497: MPHI regs_base at 9e810000 Nov 19 21:48:39 kueche kernel: dwc_otg 3f980000.usb: DWC OTG Controller Nov 19 21:48:39 kueche kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Nov 19 21:48:39 kueche kernel: dwc_otg 3f980000.usb: irq 89, io mem 0x00000000 Nov 19 21:48:39 kueche kernel: Init: Port Power? op_state=1 Nov 19 21:48:39 kueche kernel: Init: Power Port (0) Nov 19 21:48:39 kueche kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Nov 19 21:48:39 kueche kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Nov 19 21:48:39 kueche kernel: usb usb1: Product: DWC OTG Controller Nov 19 21:48:39 kueche kernel: usb usb1: Manufacturer: Linux 6.1.69-v7+ dwc_otg_hcd Nov 19 21:48:39 kueche kernel: usb usb1: SerialNumber: 3f980000.usb Nov 19 21:48:39 kueche kernel: hub 1-0:1.0: USB hub found Nov 19 21:48:39 kueche kernel: hub 1-0:1.0: 1 port detected Nov 19 21:48:39 kueche kernel: dwc_otg: FIQ enabled Nov 19 21:48:39 kueche kernel: dwc_otg: NAK holdoff enabled Nov 19 21:48:39 kueche kernel: dwc_otg: FIQ split-transaction FSM enabled Nov 19 21:48:39 kueche kernel: Module dwc_common_port init Nov 19 21:48:39 kueche kernel: usbcore: registered new interface driver usb-storage Nov 19 21:48:39 kueche kernel: mousedev: PS/2 mouse device common for all mice Nov 19 21:48:39 kueche kernel: sdhci: Secure Digital Host Controller Interface driver Nov 19 21:48:39 kueche kernel: sdhci: Copyright(c) Pierre Ossman Nov 19 21:48:39 kueche kernel: sdhci-pltfm: SDHCI platform and OF driver helper Nov 19 21:48:39 kueche kernel: ledtrig-cpu: registered to indicate activity on CPUs Nov 19 21:48:39 kueche kernel: hid: raw HID events driver (C) Jiri Kosina Nov 19 21:48:39 kueche kernel: usbcore: registered new interface driver usbhid Nov 19 21:48:39 kueche kernel: usbhid: USB HID core driver Nov 19 21:48:39 kueche kernel: bcm2835_vchiq 3f00b840.mailbox: there is not valid maps for state default Nov 19 21:48:39 kueche kernel: Initializing XFRM netlink socket Nov 19 21:48:39 kueche kernel: NET: Registered PF_PACKET protocol family Nov 19 21:48:39 kueche kernel: Key type dns_resolver registered Nov 19 21:48:39 kueche kernel: Registering SWP/SWPB emulation handler Nov 19 21:48:39 kueche kernel: registered taskstats version 1 Nov 19 21:48:39 kueche kernel: Loading compiled-in X.509 certificates Nov 19 21:48:39 kueche kernel: Key type .fscrypt registered Nov 19 21:48:39 kueche kernel: Key type fscrypt-provisioning registered Nov 19 21:48:39 kueche kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Nov 19 21:48:39 kueche kernel: 3f201000.serial: ttyAMA1 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2 Nov 19 21:48:39 kueche kernel: serial serial0: tty port ttyAMA1 registered Nov 19 21:48:39 kueche kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Nov 19 21:48:39 kueche kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Nov 19 21:48:39 kueche kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Nov 19 21:48:39 kueche kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Nov 19 21:48:39 kueche kernel: sdhost: log_buf @ 9d50e3dc (d9113000) Nov 19 21:48:39 kueche kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Nov 19 21:48:39 kueche kernel: of_cfs_init Nov 19 21:48:39 kueche kernel: of_cfs_init: OK Nov 19 21:48:39 kueche kernel: Freeing unused kernel image (initmem) memory: 1024K Nov 19 21:48:39 kueche kernel: mmc0: host does not support reading read-only switch, assuming write-enable Nov 19 21:48:39 kueche kernel: mmc0: new high speed SDHC card at address 59b4 Nov 19 21:48:39 kueche kernel: mmcblk0: mmc0:59b4 USDU1 29.8 GiB Nov 19 21:48:39 kueche kernel: mmcblk0: p1 p2 p3 Nov 19 21:48:39 kueche kernel: mmcblk0: mmc0:59b4 USDU1 29.8 GiB Nov 19 21:48:39 kueche kernel: Run /init as init process Nov 19 21:48:39 kueche kernel: with arguments: Nov 19 21:48:39 kueche kernel: /init Nov 19 21:48:39 kueche kernel: splash Nov 19 21:48:39 kueche kernel: nodebug Nov 19 21:48:39 kueche kernel: with environment: Nov 19 21:48:39 kueche kernel: HOME=/ Nov 19 21:48:39 kueche kernel: TERM=linux Nov 19 21:48:39 kueche kernel: imgpart=UUID=2444606b-dd85-4a71-b16f-de2deeb9d824 Nov 19 21:48:39 kueche kernel: imgfile=/volumio_current.sqsh Nov 19 21:48:39 kueche kernel: bootpart=UUID=7F72-C251 Nov 19 21:48:39 kueche kernel: datapart=UUID=968195c0-62a4-4bc1-b680-1c23ba99f7b5 Nov 19 21:48:39 kueche kernel: uuidconfig=cmdline.txt Nov 19 21:48:39 kueche kernel: pcie_aspm=off Nov 19 21:48:39 kueche kernel: pci=pcie_bus_safe Nov 19 21:48:39 kueche kernel: bootdelay=7 Nov 19 21:48:39 kueche kernel: use_kmsg=no Nov 19 21:48:39 kueche kernel: mmc1: new high speed SDIO card at address 0001 Nov 19 21:48:39 kueche kernel: fuse: init (API version 7.37) Nov 19 21:48:39 kueche kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 19 21:48:39 kueche kernel: usbcore: registered new interface driver uas Nov 19 21:48:39 kueche kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Nov 19 21:48:39 kueche kernel: EXT4-fs (mmcblk0p2): recovery complete Nov 19 21:48:39 kueche kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Nov 19 21:48:39 kueche kernel: loop0: detected capacity change from 0 to 983416 Nov 19 21:48:39 kueche kernel: EXT4-fs (mmcblk0p3): recovery complete Nov 19 21:48:39 kueche kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Nov 19 21:48:39 kueche systemd[1]: System time before build time, advancing clock. Nov 19 21:48:39 kueche kernel: NET: Registered PF_INET6 protocol family Nov 19 21:48:39 kueche kernel: Segment Routing with IPv6 Nov 19 21:48:39 kueche kernel: In-situ OAM (IOAM) with IPv6 Nov 19 21:48:39 kueche 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) Nov 19 21:48:39 kueche systemd[1]: Detected architecture arm. Nov 19 21:48:39 kueche systemd[1]: Set hostname to . Nov 19 21:48:39 kueche 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. Nov 19 21:48:39 kueche systemd[1]: Listening on udev Control Socket. Nov 19 21:48:39 kueche systemd[1]: Listening on Journal Socket (/dev/log). Nov 19 21:48:39 kueche systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Nov 19 21:48:39 kueche systemd[1]: Listening on RPCbind Server Activation Socket. Nov 19 21:48:39 kueche systemd[1]: Listening on initctl Compatibility Named Pipe. Nov 19 21:48:39 kueche systemd[1]: Created slice system-getty.slice. Nov 19 21:48:39 kueche kernel: i2c_dev: i2c /dev entries driver Nov 19 21:48:39 kueche systemd-journald[292]: Journal started Nov 19 21:48:39 kueche systemd-journald[292]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 7.5M, max 30.0M, 22.5M free. Feb 14 11:12:00 kueche systemd-modules-load[290]: Inserted module 'i2c_dev' Nov 19 21:48:39 kueche fake-hwclock[291]: Tue Nov 19 20:48:39 UTC 2024 Nov 19 21:48:39 kueche systemd[1]: Started Restore / save the current clock. Nov 19 21:48:39 kueche systemd[1]: Started Load/Save Random Seed. Nov 19 21:48:39 kueche systemd[1]: Started Create System Users. Nov 19 21:48:39 kueche systemd[1]: Mounted Kernel Configuration File System. Nov 19 21:48:39 kueche systemd[1]: Starting Create Static Device Nodes in /dev... Nov 19 21:48:39 kueche systemd[1]: Mounted FUSE Control File System. Nov 19 21:48:39 kueche volumio[285]: 512 MB or less RAM Detected, need to enable swap Nov 19 21:48:39 kueche systemd[1]: Started Apply Kernel Variables. Nov 19 21:48:39 kueche systemd[1]: Started udev Coldplug all Devices. Nov 19 21:48:39 kueche systemd[1]: Starting Helper to synchronize boot up for ifupdown... Nov 19 21:48:39 kueche volumio[285]: Enabling Swap Nov 19 21:48:39 kueche kernel: ext4 filesystem being mounted at /swap supports timestamps until 2038 (0x7fffffff) Nov 19 21:48:39 kueche systemd[1]: Started Create Static Device Nodes in /dev. Nov 19 21:48:39 kueche volumio[285]: Setting swappiness to 40 Nov 19 21:48:39 kueche kernel: Adding 524236k swap on /swap/swapfile. Priority:-2 extents:12 across:575480k SSFS Nov 19 21:48:39 kueche volumio[285]: vm.swappiness = 40 Nov 19 21:48:39 kueche systemd[1]: Starting udev Kernel Device Manager... Nov 19 21:48:39 kueche systemd[1]: Reached target Local File Systems (Pre). Nov 19 21:48:39 kueche systemd[1]: Mounting /var/spool/cups... Nov 19 21:48:39 kueche systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Nov 19 21:48:39 kueche systemd[1]: Mounting /tmp... Nov 19 21:48:39 kueche systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Nov 19 21:48:39 kueche systemd[1]: Mounting /var/log... Nov 19 21:48:39 kueche systemd[1]: dynamicswap.service: Succeeded. Nov 19 21:48:39 kueche systemd[1]: Mounted /var/spool/cups. Nov 19 21:48:39 kueche systemd[1]: Mounted /tmp. Nov 19 21:48:39 kueche systemd[1]: Mounted /var/log. Nov 19 21:48:39 kueche systemd-udevd[322]: Network interface NamePolicy= disabled on kernel command line, ignoring. Nov 19 21:48:39 kueche systemd[1]: Starting Flush Journal to Persistent Storage... Nov 19 21:48:39 kueche systemd[1]: Mounting /var/spool/cups/tmp... Nov 19 21:48:39 kueche systemd[1]: Mounted /var/spool/cups/tmp. Nov 19 21:48:39 kueche systemd[1]: Started udev Kernel Device Manager. Nov 19 21:48:39 kueche systemd[1]: Starting Show Plymouth Boot Screen... Nov 19 21:48:39 kueche systemd-journald[292]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 7.5M, max 30.0M, 22.5M free. Nov 19 21:48:39 kueche systemd[1]: Started Flush Journal to Persistent Storage. Nov 19 21:48:39 kueche systemd[1]: plymouth-start.service: Succeeded. Nov 19 21:48:39 kueche systemd[1]: Started Show Plymouth Boot Screen. Nov 19 21:48:39 kueche systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Nov 19 21:48:39 kueche systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Nov 19 21:48:39 kueche systemd[1]: Reached target Local Encrypted Volumes. Nov 19 21:48:39 kueche systemd[1]: Reached target Paths. Nov 19 21:48:40 kueche kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Nov 19 21:48:40 kueche kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Nov 19 21:48:40 kueche kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Nov 19 21:48:40 kueche kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Nov 19 21:48:40 kueche kernel: [vc_sm_connected_init]: start Nov 19 21:48:40 kueche kernel: [vc_sm_connected_init]: installed successfully Nov 19 21:48:40 kueche kernel: mc: Linux media interface: v0.10 Nov 19 21:48:40 kueche kernel: videodev: Linux video capture interface: v2.00 Nov 19 21:48:40 kueche kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Nov 19 21:48:40 kueche kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Nov 19 21:48:40 kueche kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Nov 19 21:48:40 kueche kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Nov 19 21:48:40 kueche kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Nov 19 21:48:40 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Nov 19 21:48:40 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Nov 19 21:48:40 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Nov 19 21:48:40 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Nov 19 21:48:40 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Nov 19 21:48:40 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Nov 19 21:48:40 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Nov 19 21:48:40 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Nov 19 21:48:40 kueche kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Nov 19 21:48:40 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Nov 19 21:48:40 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Nov 19 21:48:40 kueche kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Nov 19 21:48:40 kueche kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Nov 19 21:48:40 kueche kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Nov 19 21:48:40 kueche kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Nov 19 21:48:40 kueche systemd[1]: Found device /dev/disk/by-uuid/7F72-C251. Nov 19 21:48:41 kueche kernel: Bluetooth: Core ver 2.22 Nov 19 21:48:41 kueche kernel: NET: Registered PF_BLUETOOTH protocol family Nov 19 21:48:41 kueche kernel: Bluetooth: HCI device and connection manager initialized Nov 19 21:48:41 kueche kernel: Bluetooth: HCI socket layer initialized Nov 19 21:48:41 kueche kernel: Bluetooth: L2CAP socket layer initialized Nov 19 21:48:41 kueche kernel: Bluetooth: SCO socket layer initialized Nov 19 21:48:41 kueche kernel: Bluetooth: HCI UART driver ver 2.3 Nov 19 21:48:41 kueche kernel: Bluetooth: HCI UART protocol H4 registered Nov 19 21:48:41 kueche kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Nov 19 21:48:41 kueche kernel: Bluetooth: HCI UART protocol Broadcom registered Nov 19 21:48:41 kueche kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Nov 19 21:48:41 kueche kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Nov 19 21:48:41 kueche systemd[1]: Starting File System Check on /dev/disk/by-uuid/7F72-C251... Nov 19 21:48:41 kueche kernel: uart-pl011 3f201000.serial: no DMA platform data Nov 19 21:48:41 kueche systemd-udevd[337]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 19 21:48:41 kueche kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Nov 19 21:48:41 kueche kernel: Bluetooth: hci0: BCM: chip id 115 Nov 19 21:48:41 kueche kernel: Bluetooth: hci0: BCM: features 0x0e Nov 19 21:48:41 kueche kernel: Bluetooth: hci0: BCM43430B0 Nov 19 21:48:41 kueche kernel: Bluetooth: hci0: BCM43430B0 (002.001.012) build 0000 Nov 19 21:48:41 kueche kernel: Bluetooth: hci0: BCM43430B0 'brcm/BCM43430B0.hcd' Patch Nov 19 21:48:41 kueche kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Nov 19 21:48:41 kueche kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Nov 19 21:48:41 kueche kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Nov 19 21:48:41 kueche systemd-fsck[387]: fsck.fat 4.1 (2017-01-24) Nov 19 21:48:41 kueche systemd-fsck[387]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Nov 19 21:48:41 kueche systemd-fsck[387]: Automatically removing dirty bit. Nov 19 21:48:41 kueche systemd-fsck[387]: Performing changes. Nov 19 21:48:41 kueche systemd-fsck[387]: /dev/mmcblk0p1: 375 files, 33705/46774 clusters Nov 19 21:48:41 kueche systemd[1]: Started File System Check on /dev/disk/by-uuid/7F72-C251. Nov 19 21:48:42 kueche kernel: brcmfmac: F1 signature read @0x18000000=0x1542a9a6 Nov 19 21:48:42 kueche kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430b0-sdio for chip BCM43430/2 Nov 19 21:48:42 kueche kernel: usbcore: registered new interface driver brcmfmac Nov 19 21:48:42 kueche kernel: Bluetooth: hci0: BCM: features 0x0e Nov 19 21:48:42 kueche kernel: Bluetooth: hci0: BCM4343B0 37.4MHz wlbga_iLNA_iTR [Baseline: 0092] Nov 19 21:48:42 kueche kernel: Bluetooth: hci0: BCM43430B0 (002.001.012) build 0092 Nov 19 21:48:42 kueche kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/2 wl0: Mar 29 2022 19:22:43 version 9.88.4.77 (g58bc5cc) FWID 01-623162e Nov 19 21:48:42 kueche systemd-udevd[331]: Using default interface naming scheme 'v240'. Nov 19 21:48:42 kueche systemd-udevd[331]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 19 21:48:42 kueche systemd[1]: Created slice system-bthelper.slice. Nov 19 21:48:42 kueche systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Nov 19 21:48:42 kueche systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Nov 19 21:48:42 kueche systemd[1]: Starting Show Plymouth Boot Screen... Nov 19 21:48:42 kueche systemd[1]: Condition check resulted in Huge Pages File System being skipped. Nov 19 21:48:42 kueche systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Nov 19 21:48:42 kueche systemd[1]: Mounting /boot... Nov 19 21:48:42 kueche systemd[1]: Started File System Check Daemon to report status. Nov 19 21:48:42 kueche systemd[1]: Mounted /boot. Nov 19 21:48:42 kueche systemd[1]: plymouth-start.service: Succeeded. Nov 19 21:48:42 kueche systemd[1]: Started Show Plymouth Boot Screen. Nov 19 21:48:42 kueche systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Nov 19 21:48:42 kueche systemd[1]: Reached target Local File Systems. Nov 19 21:48:42 kueche systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Nov 19 21:48:42 kueche systemd[1]: Starting Create Volatile Files and Directories... Nov 19 21:48:42 kueche systemd[1]: Starting Preprocess NFS configuration... Nov 19 21:48:42 kueche systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Nov 19 21:48:43 kueche systemd[1]: Starting Load/Save RF Kill Switch Status... Nov 19 21:48:43 kueche systemd[1]: nfs-config.service: Succeeded. Nov 19 21:48:43 kueche systemd[1]: Started Preprocess NFS configuration. Nov 19 21:48:43 kueche systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Nov 19 21:48:43 kueche systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Nov 19 21:48:43 kueche systemd[1]: Reached target NFS client services. Nov 19 21:48:43 kueche systemd[1]: Received SIGRTMIN+20 from PID 177 (plymouthd). Nov 19 21:48:43 kueche systemd[1]: plymouth-read-write.service: Succeeded. Nov 19 21:48:43 kueche systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Nov 19 21:48:43 kueche systemd[1]: Started Load/Save RF Kill Switch Status. Nov 19 21:48:43 kueche systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Nov 19 21:48:43 kueche systemd[1]: Started Helper to synchronize boot up for ifupdown. Nov 19 21:48:43 kueche systemd[1]: Started Create Volatile Files and Directories. Nov 19 21:48:43 kueche systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Nov 19 21:48:43 kueche systemd[1]: Starting Update UTMP about System Boot/Shutdown... Nov 19 21:48:43 kueche systemd[1]: Starting RPC bind portmap service... Nov 19 21:48:43 kueche systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Nov 19 21:48:43 kueche systemd[1]: Starting Raise network interfaces... Nov 19 21:48:43 kueche systemd[1]: Started ifup for wlan0. Nov 19 21:48:43 kueche systemd[1]: Started Update UTMP about System Boot/Shutdown. Nov 19 21:48:43 kueche systemd[1]: Reached target System Initialization. Nov 19 21:48:43 kueche systemd[1]: Started Daily Cleanup of Temporary Directories. Nov 19 21:48:43 kueche systemd[1]: Listening on triggerhappy.socket. Nov 19 21:48:43 kueche systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Nov 19 21:48:43 kueche systemd[1]: Listening on D-Bus System Message Bus Socket. Nov 19 21:48:43 kueche systemd[1]: Reached target Sockets. Nov 19 21:48:43 kueche systemd[1]: Reached target Basic System. Nov 19 21:48:43 kueche systemd[1]: Starting Wireless Services... Nov 19 21:48:43 kueche systemd[1]: Starting Configure Bluetooth Modems connected by UART... Nov 19 21:48:43 kueche systemd[1]: Started volumio-remote-updater.service. Nov 19 21:48:43 kueche systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Nov 19 21:48:43 kueche systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Nov 19 21:48:43 kueche systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Nov 19 21:48:43 kueche systemd[1]: Started UPnP Renderer front-end to MPD. Nov 19 21:48:43 kueche systemd[1]: Started Manage Sound Card State (restore and store). Nov 19 21:48:43 kueche alsactl[490]: alsactl 1.1.8 daemon started Nov 19 21:48:43 kueche systemd[1]: Starting Save/Restore Sound Card State... Nov 19 21:48:43 kueche systemd[1]: Started D-Bus System Message Bus. Nov 19 21:48:43 kueche systemd[1]: Starting WPA supplicant... Nov 19 21:48:43 kueche systemd[1]: Starting triggerhappy global hotkey daemon... Nov 19 21:48:43 kueche systemd[1]: Started Volumio Log Rotation Service. Nov 19 21:48:43 kueche systemd[1]: Started Volumio Iptables Module. Nov 19 21:48:43 kueche systemd[1]: Starting dhcpcd on all interfaces... Nov 19 21:48:43 kueche systemd[1]: apt-daily.timer: Not using persistent file timestamp Thu 2024-11-28 21:17:05 CET as it is in the future. Nov 19 21:48:43 kueche systemd[1]: Started Daily apt download activities. Nov 19 21:48:43 kueche systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Fri 2024-11-29 06:23:53 CET as it is in the future. Nov 19 21:48:43 kueche systemd[1]: Started Daily apt upgrade and clean activities. Nov 19 21:48:43 kueche systemd[1]: Reached target Timers. Nov 19 21:48:43 kueche systemd[1]: Starting Login Service... Nov 19 21:48:43 kueche systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Nov 19 21:48:43 kueche systemd[1]: Started RPC bind portmap service. Nov 19 21:48:43 kueche systemd[1]: Started Save/Restore Sound Card State. Nov 19 21:48:43 kueche systemd[1]: Reached target Sound Card. Nov 19 21:48:43 kueche systemd[1]: Reached target RPC Port Mapper. Nov 19 21:48:43 kueche systemd[1]: Reached target Remote File Systems (Pre). Nov 19 21:48:43 kueche systemd[1]: Reached target Remote File Systems. Nov 19 21:48:43 kueche dhcpcd[510]: Not running dhcpcd because /etc/network/interfaces Nov 19 21:48:43 kueche dhcpcd[510]: defines some interfaces that will use a Nov 19 21:48:43 kueche dhcpcd[510]: DHCP client or static address Nov 19 21:48:43 kueche systemd[1]: Started Regular background program processing daemon. Nov 19 21:48:43 kueche systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Nov 19 21:48:43 kueche systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Nov 19 21:48:43 kueche systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Nov 19 21:48:43 kueche systemd[1]: Failed to start dhcpcd on all interfaces. Nov 19 21:48:43 kueche cron[528]: (CRON) INFO (pidfile fd = 3) Nov 19 21:48:43 kueche cron[528]: Error: bad username; while reading /etc/crontab Nov 19 21:48:43 kueche cron[528]: (*system*) ERROR (Syntax error, this crontab file will be ignored) Nov 19 21:48:43 kueche cron[528]: (CRON) INFO (Running @reboot jobs) Nov 19 21:48:44 kueche thd[501]: Unable to parse trigger line: Nov 19 21:48:44 kueche thd[501]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Nov 19 21:48:44 kueche thd[501]: Unable to parse trigger line: Nov 19 21:48:44 kueche thd[501]: Unable to parse trigger line: Nov 19 21:48:44 kueche thd[501]: Found socket passed from systemd Nov 19 21:48:44 kueche systemd-logind[524]: New seat seat0. Nov 19 21:48:44 kueche kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 19 21:48:44 kueche avahi-daemon[525]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Nov 19 21:48:44 kueche avahi-daemon[525]: Successfully dropped root privileges. Nov 19 21:48:44 kueche avahi-daemon[525]: avahi-daemon 0.7 starting up. Nov 19 21:48:45 kueche systemd[1]: Started triggerhappy global hotkey daemon. Nov 19 21:48:45 kueche systemd[1]: hciuart.service: Succeeded. Nov 19 21:48:45 kueche systemd[1]: Started Configure Bluetooth Modems connected by UART. Nov 19 21:48:45 kueche wpa_supplicant[497]: Successfully initialized wpa_supplicant Nov 19 21:48:45 kueche avahi-daemon[525]: Successfully called chroot(). Nov 19 21:48:45 kueche avahi-daemon[525]: Successfully dropped remaining capabilities. Nov 19 21:48:45 kueche avahi-daemon[525]: Loading service file /services/volumio.service. Nov 19 21:48:45 kueche avahi-daemon[525]: Network interface enumeration completed. Nov 19 21:48:45 kueche avahi-daemon[525]: Server startup complete. Host name is kueche.local. Local service cookie is 1260679606. Nov 19 21:48:45 kueche avahi-daemon[525]: Service "kueche" (/services/volumio.service) successfully established. Nov 19 21:48:45 kueche ifup[465]: ifup: waiting for lock on /run/network/ifstate.wlan0 Nov 19 21:48:45 kueche systemd[1]: Started Login Service. Nov 19 21:48:45 kueche systemd[1]: Started WPA supplicant. Nov 19 21:48:45 kueche systemd[1]: Started Avahi mDNS/DNS-SD Stack. Nov 19 21:48:45 kueche sh[467]: wlan0=wlan0 Nov 19 21:48:45 kueche systemd[1]: Starting Raspberry Pi bluetooth helper... Nov 19 21:48:45 kueche volumio-remote-updater[487]: Error: No active session Nov 19 21:48:45 kueche volumio-remote-updater[487]: [2024-11-19 21:48:45] [info] asio async_connect error: system:111 (Connection refused) Nov 19 21:48:45 kueche volumio-remote-updater[487]: [2024-11-19 21:48:45] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 19 21:48:45 kueche volumio-remote-updater[487]: [2024-11-19 21:48:45] [error] handle_connect error: Underlying Transport Error Nov 19 21:48:45 kueche systemd[1]: Started Raise network interfaces. Nov 19 21:48:45 kueche systemd[1]: Reached target Network. Nov 19 21:48:45 kueche volumio[489]: Could not open config: /tmp/upmpdcli.conf Nov 19 21:48:45 kueche systemd[1]: Starting OpenBSD Secure Shell server... Nov 19 21:48:45 kueche systemd[1]: Starting Permit User Sessions... Nov 19 21:48:45 kueche bthelper[595]: Raspberry Pi BDADDR already set Nov 19 21:48:45 kueche systemd[1]: Starting Network Time Service... Nov 19 21:48:45 kueche systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Nov 19 21:48:45 kueche systemd[1]: Reached target Network is Online. Nov 19 21:48:45 kueche systemd[1]: Starting /etc/rc.local Compatibility... Nov 19 21:48:45 kueche systemd[1]: Starting LSB: Brings up/down network automatically... Nov 19 21:48:45 kueche systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 19 21:48:45 kueche systemd[1]: Starting Samba NMB Daemon... Nov 19 21:48:46 kueche systemd[1]: Starting LSB: exim Mail Transport Agent... Nov 19 21:48:46 kueche systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Nov 19 21:48:46 kueche systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Nov 19 21:48:46 kueche systemd[1]: Started Raspberry Pi bluetooth helper. Nov 19 21:48:46 kueche systemd[1]: Started Permit User Sessions. Nov 19 21:48:46 kueche systemd[1]: Started /etc/rc.local Compatibility. Nov 19 21:48:46 kueche systemd[1]: iptables.service: Succeeded. Nov 19 21:48:46 kueche loadcpufreq[530]: Loading cpufreq kernel modules...done (none). Nov 19 21:48:46 kueche systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Nov 19 21:48:46 kueche ntpd[639]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 19 21:48:46 kueche ntpd[639]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Nov 19 21:48:46 kueche systemd[1]: Starting LSB: set CPUFreq kernel parameters... Nov 19 21:48:46 kueche systemd[1]: Starting Hold until boot process finishes up... Nov 19 21:48:46 kueche systemd[1]: Starting Terminate Plymouth Boot Screen... Nov 19 21:48:46 kueche systemd[1]: Starting Bluetooth service... Nov 19 21:48:46 kueche systemd[1]: Started Network Time Service. Nov 19 21:48:46 kueche ifplugd[650]: Network Interface Plugging Daemon...skip eth0...done. Nov 19 21:48:46 kueche systemd[1]: Started LSB: Brings up/down network automatically. Nov 19 21:48:46 kueche ntpd[686]: proto: precision = 1.459 usec (-19) Nov 19 21:48:46 kueche systemd[1]: Received SIGRTMIN+21 from PID 177 (plymouthd). Nov 19 21:48:46 kueche systemd[1]: plymouth-quit-wait.service: Succeeded. Nov 19 21:48:46 kueche systemd[1]: Started Hold until boot process finishes up. Nov 19 21:48:46 kueche systemd[1]: plymouth-quit.service: Succeeded. Nov 19 21:48:46 kueche systemd[1]: Started Terminate Plymouth Boot Screen. Nov 19 21:48:46 kueche ntpd[686]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 19 21:48:46 kueche ntpd[686]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 19 21:48:46 kueche ntpd[686]: Listen and drop on 0 v6wildcard [::]:123 Nov 19 21:48:46 kueche ntpd[686]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 19 21:48:46 kueche ntpd[686]: Listen normally on 2 lo 127.0.0.1:123 Nov 19 21:48:46 kueche ntpd[686]: Listening on routing socket on fd #19 for interface updates Nov 19 21:48:46 kueche ntpd[686]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 19 21:48:46 kueche ntpd[686]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 19 21:48:46 kueche systemd[1]: Received SIGRTMIN+21 from PID 177 (n/a). Nov 19 21:48:46 kueche haveged[461]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Nov 19 21:48:46 kueche haveged[461]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Nov 19 21:48:46 kueche haveged[461]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00065 Nov 19 21:48:46 kueche haveged[461]: haveged: fills: 0, generated: 0 Nov 19 21:48:46 kueche systemd[1]: Started Getty on tty1. Nov 19 21:48:46 kueche systemd[1]: Reached target Login Prompts. Nov 19 21:48:46 kueche sshd[701]: Server listening on 0.0.0.0 port 22. Nov 19 21:48:46 kueche sshd[701]: Server listening on :: port 22. Nov 19 21:48:46 kueche systemd[1]: Started OpenBSD Secure Shell server. Nov 19 21:48:47 kueche cpufrequtils[683]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Nov 19 21:48:47 kueche systemd[1]: Started LSB: set CPUFreq kernel parameters. Nov 19 21:48:47 kueche bluetoothd[692]: Bluetooth daemon 5.50 Nov 19 21:48:47 kueche bluetoothd[692]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Nov 19 21:48:47 kueche systemd[1]: Started Bluetooth service. Nov 19 21:48:47 kueche systemd[1]: Reached target Bluetooth. Nov 19 21:48:47 kueche bluetoothd[692]: Starting SDP server Nov 19 21:48:47 kueche bluetoothd[692]: Excluding (cli) sap Nov 19 21:48:47 kueche kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Nov 19 21:48:47 kueche kernel: Bluetooth: BNEP filters: protocol multicast Nov 19 21:48:47 kueche kernel: Bluetooth: BNEP socket layer initialized Nov 19 21:48:47 kueche bluetoothd[692]: Bluetooth management interface 1.22 initialized Nov 19 21:48:47 kueche dbus-daemon[493]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=692 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Nov 19 21:48:47 kueche kernel: Bluetooth: MGMT ver 1.22 Nov 19 21:48:47 kueche bluetoothd[692]: Failed to set privacy: Rejected (0x0b) Nov 19 21:48:47 kueche ntpd[686]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 19 21:48:47 kueche systemd[1]: Starting Hostname Service... Nov 19 21:48:47 kueche dbus-daemon[493]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 19 21:48:47 kueche systemd[1]: Started Hostname Service. Nov 19 21:48:47 kueche nmbd[675]: [2024/11/19 21:48:47.952346, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Nov 19 21:48:47 kueche nmbd[675]: started asyncdns process 954 Nov 19 21:48:47 kueche nmbd[675]: [2024/11/19 21:48:47.955113, 0] ../lib/util/become_daemon.c:149(daemon_status) Nov 19 21:48:47 kueche nmbd[675]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Nov 19 21:48:47 kueche nmbd[675]: [2024/11/19 21:48:47.955314, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Nov 19 21:48:47 kueche nmbd[675]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Nov 19 21:48:47 kueche exim4[661]: Starting MTA: exim4. Nov 19 21:48:47 kueche systemd[1]: Started LSB: exim Mail Transport Agent. Nov 19 21:48:47 kueche exim[955]: 2024-11-19 21:48:47 socket bind() to port 25 for address ::1 failed: Cannot assign requested address: waiting 30s before trying again (9 more tries) Nov 19 21:48:47 kueche exim[955]: 2024-11-19 21:48:47 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=0 egid=124 Nov 19 21:48:47 kueche exim[955]: exim: could not open panic log - aborting: see message(s) above Nov 19 21:48:48 kueche systemd[1]: systemd-rfkill.service: Succeeded. Nov 19 21:48:48 kueche wireless.js[475]: Cleaning previous... Nov 19 21:48:48 kueche ntpd[686]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 19 21:48:48 kueche sudo[965]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 19 21:48:48 kueche sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:48 kueche sudo[965]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:48 kueche sudo[967]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 19 21:48:48 kueche sudo[967]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:49 kueche sudo[967]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:49 kueche wireless.js[475]: Stopped aP Nov 19 21:48:49 kueche sudo[974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 19 21:48:49 kueche sudo[974]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:49 kueche kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 19 21:48:49 kueche sudo[974]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:49 kueche sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Nov 19 21:48:49 kueche sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:49 kueche sudo[976]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:49 kueche sudo[983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 19 21:48:49 kueche sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:49 kueche sudo[983]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:49 kueche sudo[985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Nov 19 21:48:49 kueche sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:49 kueche ntpd[686]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 19 21:48:50 kueche sudo[985]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:50 kueche wireless.js[475]: Start wireless flow Nov 19 21:48:50 kueche wireless.js[475]: Stopped hotspot (if there).. Nov 19 21:48:50 kueche sudo[990]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 19 21:48:50 kueche sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:50 kueche sudo[990]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:50 kueche sudo[992]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 19 21:48:50 kueche sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:50 kueche ntpd[686]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 19 21:48:50 kueche volumio-remote-updater[487]: [2024-11-19 21:48:50] [info] asio async_connect error: system:111 (Connection refused) Nov 19 21:48:50 kueche volumio-remote-updater[487]: [2024-11-19 21:48:50] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 19 21:48:50 kueche volumio-remote-updater[487]: [2024-11-19 21:48:50] [error] handle_connect error: Underlying Transport Error Nov 19 21:48:50 kueche sudo[992]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:50 kueche wireless.js[475]: DHCP IP Nov 19 21:48:50 kueche wireless.js[475]: Start ap Nov 19 21:48:50 kueche wpa_supplicant[994]: Successfully initialized wpa_supplicant Nov 19 21:48:50 kueche sudo[995]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 19 21:48:50 kueche sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:50 kueche kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 19 21:48:50 kueche dhcpcd[996]: dev: loaded udev Nov 19 21:48:50 kueche kernel: 8021q: 802.1Q VLAN Support v1.8 Nov 19 21:48:50 kueche dhcpcd-run-hooks[1004]: wlan0: starting wpa_supplicant Nov 19 21:48:50 kueche bthelper[595]: Changing power off succeeded Nov 19 21:48:51 kueche bthelper[595]: [CHG] Controller E4:5F:01:77:2E:9D Class: 0x0000041c Nov 19 21:48:51 kueche bthelper[595]: Changing power on succeeded Nov 19 21:48:51 kueche dhcpcd-run-hooks[1015]: wlan0: failed to start wpa_supplicant Nov 19 21:48:51 kueche dhcpcd-run-hooks[1016]: 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 Nov 19 21:48:51 kueche dhcpcd[996]: wlan0: connected to Access Point `' Nov 19 21:48:51 kueche dhcpcd[996]: no interfaces have a carrier Nov 19 21:48:51 kueche dhcpcd[996]: forked to background, child pid 1028 Nov 19 21:48:51 kueche dhcpcd[1028]: wlan0: waiting for carrier Nov 19 21:48:51 kueche sudo[995]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:51 kueche wpa_supplicant[1009]: wlan0: Trying to associate with SSID 'AnJo' Nov 19 21:48:51 kueche wireless.js[475]: trying... Nov 19 21:48:51 kueche sudo[1030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 19 21:48:51 kueche sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:51 kueche sudo[1030]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:52 kueche dhcpcd[1028]: wlan0: carrier acquired Nov 19 21:48:52 kueche dhcpcd[1028]: wlan0: connected to Access Point `AnJo' Nov 19 21:48:52 kueche wpa_supplicant[1009]: wlan0: Associated with b4:b0:24:0f:86:9c Nov 19 21:48:52 kueche wpa_supplicant[1009]: wlan0: CTRL-EVENT-CONNECTED - Connection to b4:b0:24:0f:86:9c completed [id=0 id_str=] Nov 19 21:48:52 kueche wpa_supplicant[1009]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 19 21:48:52 kueche dhcpcd[1028]: DUID 00:01:00:01:2b:1f:b7:25:e4:5f:01:7e:4a:8c Nov 19 21:48:52 kueche dhcpcd[1028]: wlan0: IAID 01:77:2e:9b Nov 19 21:48:52 kueche dhcpcd[1028]: wlan0: adding address fe80::e65f:1ff:fe77:2e9b Nov 19 21:48:52 kueche dhcpcd[1028]: ipv6_addaddr1: Permission denied Nov 19 21:48:52 kueche dhcpcd[1028]: wlan0: probing address 192.168.0.133/24 Nov 19 21:48:52 kueche dhcpcd[1028]: wlan0: carrier lost Nov 19 21:48:52 kueche dhcpcd[1028]: wlan0: carrier acquired Nov 19 21:48:52 kueche dhcpcd[1028]: wlan0: IAID 01:77:2e:9b Nov 19 21:48:52 kueche dhcpcd[1028]: wlan0: probing address 192.168.0.133/24 Nov 19 21:48:52 kueche wireless.js[475]: trying... Nov 19 21:48:52 kueche sudo[1054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 19 21:48:52 kueche sudo[1054]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:52 kueche sudo[1054]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:53 kueche dhcpcd[1028]: wlan0: soliciting an IPv6 router Nov 19 21:48:53 kueche wireless.js[475]: trying... Nov 19 21:48:53 kueche sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 19 21:48:53 kueche sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:53 kueche sudo[1057]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:54 kueche wireless.js[475]: trying... Nov 19 21:48:54 kueche sudo[1060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 19 21:48:54 kueche sudo[1060]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:54 kueche sudo[1060]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:55 kueche wireless.js[475]: trying... Nov 19 21:48:55 kueche sudo[1063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 19 21:48:55 kueche sudo[1063]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:55 kueche sudo[1063]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:56 kueche wireless.js[475]: trying... Nov 19 21:48:56 kueche sudo[1066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 19 21:48:56 kueche sudo[1066]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:56 kueche sudo[1066]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:57 kueche dhcpcd[1028]: wlan0: using static address 192.168.0.133/24 Nov 19 21:48:57 kueche avahi-daemon[525]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.133. Nov 19 21:48:57 kueche avahi-daemon[525]: New relevant interface wlan0.IPv4 for mDNS. Nov 19 21:48:57 kueche dhcpcd[1028]: wlan0: adding route to 192.168.0.0/24 Nov 19 21:48:57 kueche avahi-daemon[525]: Registering new address record for 192.168.0.133 on wlan0.IPv4. Nov 19 21:48:57 kueche dhcpcd[1028]: wlan0: adding default route via 192.168.0.1 Nov 19 21:48:57 kueche wireless.js[475]: trying... Nov 19 21:48:57 kueche sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 19 21:48:57 kueche sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:57 kueche sudo[1090]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:57 kueche wireless.js[475]: Connected to: ----AnJo Nov 19 21:48:57 kueche wireless.js[475]: ---- Nov 19 21:48:57 kueche sudo[1093]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 19 21:48:57 kueche sudo[1093]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:48:57 kueche sudo[1093]: pam_unix(sudo:session): session closed for user root Nov 19 21:48:57 kueche wireless.js[475]: ... joined AP, wlan0 IPv4 is 192.168.0.133, ipV6 is undefined Nov 19 21:48:57 kueche wireless.js[475]: It's done! AP Nov 19 21:48:57 kueche systemd[1]: Started Wireless Services. Nov 19 21:48:57 kueche kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 19 21:48:57 kueche systemd[1]: Started Volumio Backend Module. Nov 19 21:48:57 kueche systemd[1]: Started Volumio Cpu Tweaker. Nov 19 21:48:57 kueche volumio-cpu-tweak[1099]: Setting RT Priority for mpd Nov 19 21:48:57 kueche nmbd[675]: [2024/11/19 21:48:57.963100, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 19 21:48:57 kueche nmbd[675]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 19 21:48:57 kueche systemd[1]: Started Samba NMB Daemon. Nov 19 21:48:57 kueche systemd[1]: Starting Samba Winbind Daemon... Nov 19 21:48:57 kueche volumio-cpu-tweak[1099]: pid 35's current scheduling policy: SCHED_OTHER Nov 19 21:48:57 kueche volumio-cpu-tweak[1099]: pid 35's current scheduling priority: 0 Nov 19 21:48:57 kueche volumio-cpu-tweak[1099]: Setting MPD Affinity Nov 19 21:48:58 kueche volumio-cpu-tweak[1099]: pid 3's current affinity mask: f Nov 19 21:48:58 kueche volumio-cpu-tweak[1099]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Nov 19 21:48:58 kueche volumio-cpu-tweak[1099]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Nov 19 21:48:58 kueche volumio-remote-updater[487]: [2024-11-19 21:48:58] [info] asio async_connect error: system:111 (Connection refused) Nov 19 21:48:58 kueche volumio-remote-updater[487]: [2024-11-19 21:48:58] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 19 21:48:58 kueche volumio-remote-updater[487]: [2024-11-19 21:48:58] [error] handle_connect error: Underlying Transport Error Nov 19 21:48:58 kueche systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 19 21:48:58 kueche winbindd[1111]: [2024/11/19 21:48:58.401475, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 19 21:48:58 kueche winbindd[1111]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 19 21:48:58 kueche winbindd[1111]: [2024/11/19 21:48:58.454304, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 19 21:48:58 kueche systemd[1]: Started Samba Winbind Daemon. Nov 19 21:48:58 kueche winbindd[1111]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 19 21:48:58 kueche ntpd[686]: Listen normally on 3 wlan0 192.168.0.133:123 Nov 19 21:48:58 kueche ntpd[686]: new interface(s) found: waking up resolver Nov 19 21:48:58 kueche systemd[1]: Starting Samba SMB Daemon... Nov 19 21:48:59 kueche winbindd[1111]: [2024/11/19 21:48:59.182491, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Nov 19 21:48:59 kueche winbindd[1111]: res_names->count = 3, expected 4 Nov 19 21:48:59 kueche systemd[1]: Started Samba SMB Daemon. Nov 19 21:48:59 kueche systemd[1]: Reached target Multi-User System. Nov 19 21:48:59 kueche smbd[1120]: [2024/11/19 21:48:59.226024, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 19 21:48:59 kueche smbd[1120]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 19 21:48:59 kueche systemd[1]: Starting watchdog daemon... Nov 19 21:48:59 kueche watchdog[1131]: starting daemon (5.15): Nov 19 21:48:59 kueche watchdog[1131]: int=10s realtime=no sync=no load=24,18,12 soft=no Nov 19 21:48:59 kueche watchdog[1131]: memory not checked Nov 19 21:48:59 kueche watchdog[1131]: ping: no machine to check Nov 19 21:48:59 kueche watchdog[1131]: file: no file to check Nov 19 21:48:59 kueche watchdog[1131]: pidfile: no server process to check Nov 19 21:48:59 kueche watchdog[1131]: interface: no interface to check Nov 19 21:48:59 kueche watchdog[1131]: temperature: no sensors to check Nov 19 21:48:59 kueche watchdog[1131]: no test binary files Nov 19 21:48:59 kueche watchdog[1131]: no repair binary files Nov 19 21:48:59 kueche watchdog[1131]: error retry time-out = 60 seconds Nov 19 21:48:59 kueche watchdog[1131]: repair attempts = 1 Nov 19 21:48:59 kueche watchdog[1131]: alive=/dev/watchdog heartbeat=[none] to=root no_act=no force=no Nov 19 21:48:59 kueche watchdog[1131]: cannot set timeout 60 (errno = 22 = 'Invalid argument') Nov 19 21:48:59 kueche watchdog[1131]: hardware watchdog identity: Broadcom BCM2835 Watchdog timer Nov 19 21:48:59 kueche systemd[1]: Started watchdog daemon. Nov 19 21:48:59 kueche systemd[1]: Reached target Graphical Interface. Nov 19 21:48:59 kueche systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 19 21:48:59 kueche systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 19 21:48:59 kueche systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 19 21:48:59 kueche systemd[1]: Startup finished in 17.181s (kernel) + 22.794s (userspace) = 39.975s. Nov 19 21:49:01 kueche volumio[1098]: info: ------------------------------------------- Nov 19 21:49:01 kueche volumio[1098]: info: ----- Volumio3 ---- Nov 19 21:49:01 kueche volumio[1098]: info: ------------------------------------------- Nov 19 21:49:01 kueche volumio[1098]: info: ----- System startup ---- Nov 19 21:49:01 kueche volumio[1098]: info: ------------------------------------------- Nov 19 21:49:02 kueche volumio[1098]: info: MYVOLUMIO Environment detected Nov 19 21:49:03 kueche volumio[1098]: info: Plugin folders cleanup Nov 19 21:49:03 kueche volumio[1098]: info: Scanning into folder /volumio/app/plugins/ Nov 19 21:49:03 kueche volumio[1098]: info: Scanning category audio_interface Nov 19 21:49:03 kueche volumio[1098]: info: Scanning category miscellanea Nov 19 21:49:03 kueche volumio[1098]: info: Scanning category music_service Nov 19 21:49:03 kueche volumio[1098]: info: Scanning category plugins.json Nov 19 21:49:03 kueche volumio[1098]: info: Scanning category system_controller Nov 19 21:49:03 kueche volumio[1098]: info: Scanning category user_interface Nov 19 21:49:03 kueche volumio[1098]: info: Scanning into folder /data/plugins/ Nov 19 21:49:03 kueche volumio[1098]: info: Scanning category music_service Nov 19 21:49:03 kueche volumio[1098]: info: Plugin folders cleanup completed Nov 19 21:49:03 kueche volumio[1098]: info: ------------------------------------------- Nov 19 21:49:03 kueche volumio[1098]: info: ----- Core plugins startup ---- Nov 19 21:49:03 kueche volumio[1098]: info: ------------------------------------------- Nov 19 21:49:03 kueche volumio[1098]: info: Loading plugins from folder /volumio/app/plugins/ Nov 19 21:49:03 kueche volumio[1098]: info: Adding plugin upnp to MyMusic Plugins Nov 19 21:49:03 kueche volumio[1098]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 19 21:49:03 kueche volumio[1098]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 19 21:49:03 kueche volumio[1098]: info: Loading plugins from folder /data/plugins/ Nov 19 21:49:03 kueche volumio[1098]: info: Loading plugin "system"... Nov 19 21:49:03 kueche volumio[1098]: info: Loading plugin "appearance"... Nov 19 21:49:04 kueche volumio[1098]: info: Loading plugin "network"... Nov 19 21:49:04 kueche volumio[1098]: info: Refreshing Cached IP Addresses Nov 19 21:49:05 kueche sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 19 21:49:05 kueche sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:49:05 kueche volumio[1098]: info: Loading plugin "services"... Nov 19 21:49:05 kueche sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 19 21:49:05 kueche sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:49:05 kueche volumio[1098]: info: Loading plugin "alsa_controller"... Nov 19 21:49:05 kueche sudo[1149]: pam_unix(sudo:session): session closed for user root Nov 19 21:49:05 kueche sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 19 21:49:05 kueche sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:49:05 kueche sudo[1147]: pam_unix(sudo:session): session closed for user root Nov 19 21:49:05 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 19 21:49:05 kueche volumio[1098]: info: Loading plugin "wizard"... Nov 19 21:49:05 kueche volumio[1098]: info: Loading plugin "networkfs"... Nov 19 21:49:05 kueche volumio[1098]: info: Starting Udev Watcher for removable devices Nov 19 21:49:05 kueche sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Josef,password=h1t3l2170,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.36/AnJo\\Musik /mnt/NAS/NAS Nov 19 21:49:05 kueche sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 21:49:05 kueche volumio[1098]: info: Ignoring mount for partition: boot Nov 19 21:49:05 kueche volumio[1098]: info: Ignoring mount for partition: volumio Nov 19 21:49:05 kueche volumio[1098]: info: Ignoring mount for partition: volumio_data Nov 19 21:49:05 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 19 21:49:05 kueche volumio[1098]: info: Loading plugin "volumio_command_line_client"... Nov 19 21:49:05 kueche volumio[1098]: info: Loading plugin "upnp"... Nov 19 21:49:05 kueche volumio[1098]: info: [1732049345367] Starting Upmpd Daemon Nov 19 21:49:05 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 19 21:49:05 kueche volumio[1098]: info: Loading plugin "my_music"... Nov 19 21:49:05 kueche volumio[1098]: info: Loading plugin "mpd"... Nov 19 21:49:05 kueche kernel: Key type cifs.spnego registered Nov 19 21:49:05 kueche kernel: Key type cifs.idmap registered Nov 19 21:49:05 kueche 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. Nov 19 21:49:05 kueche kernel: CIFS: Attempting to mount \\192.168.0.36\AnJo Nov 19 21:49:05 kueche sudo[1162]: pam_unix(sudo:session): session closed for user root Nov 19 21:49:06 kueche sudo[1152]: pam_unix(sudo:session): session closed for user root Nov 19 21:49:06 kueche volumio[1098]: info: Loading plugin "upnp_browser"... Nov 19 21:49:06 kueche volumio[1098]: info: Loading plugin "alarm-clock"... Nov 19 21:49:06 kueche volumio[1098]: info: Loading plugin "airplay_emulation"... Nov 19 21:49:06 kueche volumio[1098]: info: Starting Shairport Sync Nov 19 21:49:06 kueche volumio[1098]: info: Loading plugin "last_100"... Nov 19 21:49:06 kueche volumio[1098]: info: Loading plugin "webradio"... Nov 19 21:49:07 kueche volumio[1098]: info: Loading plugin "i2s_dacs"... Nov 19 21:49:07 kueche volumio[1098]: info: Loading plugin "volumiodiscovery"... Nov 19 21:49:07 kueche volumio[1098]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 19 21:49:07 kueche volumio[1098]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 19 21:49:07 kueche volumio[1098]: *** WARNING *** For more information see Nov 19 21:49:07 kueche volumio[1098]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 19 21:49:07 kueche node[1098]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 19 21:49:07 kueche volumio[1098]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 19 21:49:07 kueche volumio[1098]: *** WARNING *** For more information see Nov 19 21:49:07 kueche node[1098]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 19 21:49:07 kueche node[1098]: *** WARNING *** For more information see Nov 19 21:49:07 kueche node[1098]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 19 21:49:07 kueche node[1098]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 19 21:49:07 kueche node[1098]: *** WARNING *** For more information see Nov 19 21:49:07 kueche volumio[1098]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 19 21:49:07 kueche volumio[1098]: info: Discovery: Started advertising with name: kueche Nov 19 21:49:07 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 19 21:49:07 kueche volumio[1098]: info: Loading plugin "spop"... Nov 19 21:49:08 kueche volumio-remote-updater[487]: [2024-11-19 21:49:08] [connect] Successful connection Nov 19 21:49:09 kueche volumio[1098]: info: Loading plugin "outputs"... Nov 19 21:49:09 kueche volumio[1098]: info: Loading plugin "albumart"... Nov 19 21:49:09 kueche volumio[1098]: info: Plugin example_plugin is not enabled Nov 19 21:49:09 kueche volumio[1098]: info: Loading plugin "inputs"... Nov 19 21:49:09 kueche volumio[1098]: info: Loading plugin "updater_comm"... Nov 19 21:49:09 kueche volumio[1098]: info: Plugin mpdemulation is not enabled Nov 19 21:49:09 kueche volumio[1098]: info: Loading plugin "rest_api"... Nov 19 21:49:09 kueche volumio[1098]: info: Loading plugin "websocket"... Nov 19 21:49:09 kueche volumio[1098]: info: Starting Socket.io Server version 2.3.0 Nov 19 21:49:09 kueche volumio[1098]: info: Loading i18n strings for locale de Nov 19 21:49:09 kueche volumio[1098]: Updating browse sources language Nov 19 21:49:09 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 19 21:49:09 kueche volumio[1098]: Forking 3 albumart workers Nov 19 21:49:09 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 19 21:49:09 kueche volumio[1098]: info: CoreCommandRouter::initPlayerControls Nov 19 21:49:09 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 19 21:49:09 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 19 21:49:09 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 19 21:49:09 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 19 21:49:09 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 19 21:49:09 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 19 21:49:09 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 19 21:49:09 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 19 21:49:09 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 19 21:49:09 kueche volumio[1098]: Express server listening on port 3000 Nov 19 21:49:09 kueche volumio[1098]: [Metrics] WebUI: 9s 657.00ms Nov 19 21:49:10 kueche volumio[1098]: info: CoreStateMachine::resetVolumioState Nov 19 21:49:10 kueche volumio[1098]: info: CoreStateMachine::getcurrentVolume Nov 19 21:49:10 kueche volumio[1098]: info: CoreCommandRouter::volumioRetrievevolume Nov 19 21:49:10 kueche volumio[1098]: verbose: New Socket.io Connection to 192.168.0.133:3000 from 192.168.0.164 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Nov 19 21:49:10 kueche volumio-remote-updater[487]: [2024-11-19 21:49:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732049348 101 Nov 19 21:49:10 kueche volumio[1098]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Nov 19 21:49:10 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 19 21:49:10 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 19 21:49:10 kueche volumio[1098]: info: Reloading queue from file Nov 19 21:49:10 kueche volumio[1098]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 19 21:49:10 kueche volumio[1098]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Nov 19 21:49:10 kueche volumio[1098]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 19 21:49:10 kueche volumio[1098]: info: CoreStateMachine::setRepeat null single undefined Nov 19 21:49:10 kueche volumio[1098]: info: CoreStateMachine::pushState Nov 19 21:49:10 kueche volumio[1098]: info: CorePlayQueue::getTrack 0 Nov 19 21:49:10 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 19 21:49:10 kueche volumio[1098]: info: CoreCommandRouter::volumioPushState Nov 19 21:49:10 kueche volumio[1098]: info: CoreStateMachine::setRandom null Nov 19 21:49:10 kueche volumio[1098]: info: CoreStateMachine::pushState Nov 19 21:49:10 kueche volumio[1098]: info: CorePlayQueue::getTrack 0 Nov 19 21:49:10 kueche volumio[1098]: info: CoreCommandRouter::volumioPushState Nov 19 21:49:10 kueche volumio[1098]: info: CoreCommandRouter::volumioGetState Nov 19 21:49:10 kueche volumio[1098]: info: CorePlayQueue::getTrack 0 Nov 19 21:49:10 kueche volumio[1098]: info: VolumeController:: Volume=49 Mute =false Nov 19 21:49:10 kueche volumio[1098]: info: CoreStateMachine::pushState Nov 19 21:49:10 kueche volumio[1098]: info: CorePlayQueue::getTrack 0 Nov 19 21:49:10 kueche volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 19 21:49:10 kueche volumio[1098]: info: CoreCommandRouter::volumioPushState Nov 19 21:49:10 kueche volumio[1098]: info: CoreStateMachine::updateTrackBlock Nov 19 21:49:10 kueche volumio[1098]: info: CorePlayQueue::getTrackBlock Nov 19 21:49:10 kueche volumio[1098]: info: CoreCommandRouter::volumioRetrievevolume Nov 19 21:49:10 kueche volumio[1098]: info: Setting Device type: Raspberry PI Nov 19 21:49:10 kueche volumio[1098]: info: Completed loading Core Plugins Nov 19 21:49:10 kueche volumio[1098]: info: Preparing to generate the ALSA configuration file Nov 19 21:49:10 kueche volumio[1098]: Starting albumart workers Nov 19 21:49:10 kueche volumio[1098]: info: CoreCommandRouter::volumioGetState Nov 19 21:49:10 kueche volumio[1098]: info: CorePlayQueue::getTrack 0 Nov 19 21:49:10 kueche volumio[1098]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 19 21:49:10 kueche volumio[1098]: info: CoreStateMachine::ClearQueue Nov 19 21:49:10 kueche volumio[1098]: info: CoreStateMachine::stop Nov 19 21:49:10 kueche volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 19 21:49:10 kueche volumio[1098]: info: CorePlayQueue::clearPlayQueue Nov 19 21:49:10 kueche volumio[1098]: info: CorePlayQueue::saveQueue Nov 19 21:49:10 kueche volumio[1098]: info: CoreCommandRouter::volumioPushQueue Nov 19 21:49:10 kueche volumio[1098]: Starting albumart workers Nov 19 21:49:10 kueche volumio[1098]: info: CoreStateMachine::addQueueItems Nov 19 21:49:10 kueche volumio[1098]: info: CorePlayQueue::addQueueItems Nov 19 21:49:10 kueche volumio[1098]: info: Preload queue cleared Nov 19 21:49:10 kueche volumio[1098]: info: Adding Item to queue: mnt/NAS/NAS/doorbell.mp3 Nov 19 21:49:10 kueche volumio[1098]: info: Exploding uri mnt/NAS/NAS/doorbell.mp3 in service mpd Nov 19 21:49:10 kueche volumio[1098]: Starting albumart workers Nov 19 21:49:11 kueche volumio[1098]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 19 21:49:11 kueche volumio[1098]: TypeError: Cannot read property 'then' of undefined Nov 19 21:49:11 kueche volumio[1098]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Nov 19 21:49:11 kueche volumio[1098]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Nov 19 21:49:11 kueche volumio[1098]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1365:16) Nov 19 21:49:11 kueche volumio[1098]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Nov 19 21:49:11 kueche volumio[1098]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Nov 19 21:49:11 kueche volumio[1098]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Nov 19 21:49:11 kueche volumio[1098]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Nov 19 21:49:11 kueche volumio[1098]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:500:25) Nov 19 21:49:11 kueche volumio[1098]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:108:33) Nov 19 21:49:11 kueche volumio[1098]: at Socket.emit (events.js:315:20) Nov 19 21:49:11 kueche volumio[1098]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 19 21:49:11 kueche volumio[1098]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 19 21:49:11 kueche volumio[1098]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 19 21:49:11 kueche sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-19 21:48 Nov 19 21:49:11 kueche sudo[1262]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"