Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000110000.pcie: MEM 0x1800000000..0x1b7fffffff -> 0x0400000000
Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000110000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000
Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000110000.pcie: IB MEM 0x1000131000..0x1000131fff -> 0xfffffff000
Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000110000.pcie: PCI host bridge to bus 0001:00
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0001:00: root bus resource [bus 00-ff]
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0001:00: root bus resource [mem 0x1b80000000-0x1bffffffff] (bus address [0x80000000-0xffffffff])
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0001:00: root bus resource [mem 0x1800000000-0x1b7fffffff pref] (bus address [0x400000000-0x77fffffff])
Apr 01 12:03:53 avishays-pi kernel: pci 0001:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port
Apr 01 12:03:53 avishays-pi kernel: pci 0001:00:00.0: PCI bridge to [bus 00]
Apr 01 12:03:53 avishays-pi kernel: pci 0001:00:00.0: bridge window [mem 0x00000000-0x000fffff]
Apr 01 12:03:53 avishays-pi kernel: pci 0001:00:00.0: bridge window [mem 0x00000000-0x000fffff 64bit pref]
Apr 01 12:03:53 avishays-pi kernel: pci 0001:00:00.0: PME# supported from D0 D3hot
Apr 01 12:03:53 avishays-pi kernel: pci 0001:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000110000.pcie: link down
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0001:01: busn_res: [bus 01-ff] end is updated to 01
Apr 01 12:03:53 avishays-pi kernel: pci 0001:00:00.0: PCI bridge to [bus 01]
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0001:00: resource 4 [mem 0x1b80000000-0x1bffffffff]
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0001:00: resource 5 [mem 0x1800000000-0x1b7fffffff pref]
Apr 01 12:03:53 avishays-pi kernel: pci 0001:00:00.0: Max Payload Size set to 512/ 512 (was 128), Max Read Rq 512
Apr 01 12:03:53 avishays-pi kernel: pcieport 0001:00:00.0: PME: Signaling with IRQ 38
Apr 01 12:03:53 avishays-pi kernel: pcieport 0001:00:00.0: AER: enabled with IRQ 38
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0001:01: busn_res: [bus 01] is released
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0001:00: busn_res: [bus 00-ff] is released
Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000120000.pcie: host bridge /axi/pcie@1000120000 ranges:
Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000120000.pcie: No bus range found for /axi/pcie@1000120000, using [bus 00-ff]
Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000120000.pcie: MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000
Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000120000.pcie: MEM 0x1c00000000..0x1effffffff -> 0x0400000000
Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000
Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000120000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000
Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1000130000..0x1000130fff -> 0xfffffff000
Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000120000.pcie: PCI host bridge to bus 0002:00
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0002:00: root bus resource [bus 00-ff]
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0002:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb])
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0002:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff])
Apr 01 12:03:53 avishays-pi kernel: pci 0002:00:00.0: [14e4:2712] type 01 class 0x060400 PCIe Root Port
Apr 01 12:03:53 avishays-pi kernel: pci 0002:00:00.0: PCI bridge to [bus 00]
Apr 01 12:03:53 avishays-pi kernel: pci 0002:00:00.0: bridge window [mem 0x1f80000000-0x1fbfffffff]
Apr 01 12:03:53 avishays-pi kernel: pci 0002:00:00.0: PME# supported from D0 D3hot
Apr 01 12:03:53 avishays-pi kernel: pci 0002:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000120000.pcie: clkreq-mode set to default
Apr 01 12:03:53 avishays-pi kernel: brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC)
Apr 01 12:03:53 avishays-pi kernel: pci 0002:01:00.0: [1de4:0001] type 00 class 0x020000 PCIe Endpoint
Apr 01 12:03:53 avishays-pi kernel: pci 0002:01:00.0: BAR 0 [mem 0xffffc000-0xffffffff]
Apr 01 12:03:53 avishays-pi kernel: pci 0002:01:00.0: BAR 1 [mem 0xffc00000-0xffffffff]
Apr 01 12:03:53 avishays-pi kernel: pci 0002:01:00.0: BAR 2 [mem 0xffff0000-0xffffffff]
Apr 01 12:03:53 avishays-pi kernel: pci 0002:01:00.0: supports D1
Apr 01 12:03:53 avishays-pi kernel: pci 0002:01:00.0: PME# supported from D0 D1 D3hot D3cold
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0002:01: busn_res: [bus 01-ff] end is updated to 01
Apr 01 12:03:53 avishays-pi kernel: pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff]: assigned
Apr 01 12:03:53 avishays-pi kernel: pci 0002:01:00.0: BAR 1 [mem 0x1f00000000-0x1f003fffff]: assigned
Apr 01 12:03:53 avishays-pi kernel: pci 0002:01:00.0: BAR 2 [mem 0x1f00400000-0x1f0040ffff]: assigned
Apr 01 12:03:53 avishays-pi kernel: pci 0002:01:00.0: BAR 0 [mem 0x1f00410000-0x1f00413fff]: assigned
Apr 01 12:03:53 avishays-pi kernel: pci 0002:00:00.0: PCI bridge to [bus 01]
Apr 01 12:03:53 avishays-pi kernel: pci 0002:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff]
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0002:00: resource 4 [mem 0x1f00000000-0x1ffffffffb]
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0002:00: resource 5 [mem 0x1c00000000-0x1effffffff pref]
Apr 01 12:03:53 avishays-pi kernel: pci_bus 0002:01: resource 1 [mem 0x1f00000000-0x1f005fffff]
Apr 01 12:03:53 avishays-pi kernel: pci 0002:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512
Apr 01 12:03:53 avishays-pi kernel: pci 0002:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512
Apr 01 12:03:53 avishays-pi kernel: pcieport 0002:00:00.0: enabling device (0000 -> 0002)
Apr 01 12:03:53 avishays-pi kernel: pcieport 0002:00:00.0: PME: Signaling with IRQ 39
Apr 01 12:03:53 avishays-pi kernel: pcieport 0002:00:00.0: AER: enabled with IRQ 39
Apr 01 12:03:53 avishays-pi kernel: rp1 0002:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200
Apr 01 12:03:53 avishays-pi kernel: rp1 0002:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200
Apr 01 12:03:53 avishays-pi kernel: rp1 0002:01:00.0: enabling device (0000 -> 0002)
Apr 01 12:03:53 avishays-pi kernel: rp1 0002:01:00.0: chip_id 0x20001927
Apr 01 12:03:53 avishays-pi kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774
Apr 01 12:03:53 avishays-pi kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success
Apr 01 12:03:53 avishays-pi kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774
Apr 01 12:03:53 avishays-pi kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success
Apr 01 12:03:53 avishays-pi kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774
Apr 01 12:03:53 avishays-pi kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success
Apr 01 12:03:53 avishays-pi kernel: sdhci-brcmstb 1000fff000.mmc: Got CD GPIO
Apr 01 12:03:53 avishays-pi kernel: mmc0: CQHCI version 5.10
Apr 01 12:03:53 avishays-pi kernel: genirq: irq_chip rp1_irq_chip did not update eff. affinity mask of irq 101
Apr 01 12:03:53 avishays-pi kernel: mmc1: CQHCI version 5.10
Apr 01 12:03:53 avishays-pi kernel: macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 107 (2c:cf:67:5e:e6:d5)
Apr 01 12:03:53 avishays-pi kernel: dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels
Apr 01 12:03:53 avishays-pi kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller
Apr 01 12:03:53 avishays-pi kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1
Apr 01 12:03:53 avishays-pi kernel: xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810
Apr 01 12:03:53 avishays-pi kernel: xhci-hcd xhci-hcd.0: irq 132, io mem 0x1f00200000
Apr 01 12:03:53 avishays-pi kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller
Apr 01 12:03:53 avishays-pi kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2
Apr 01 12:03:53 avishays-pi kernel: xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed
Apr 01 12:03:53 avishays-pi kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12
Apr 01 12:03:53 avishays-pi kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Apr 01 12:03:53 avishays-pi kernel: usb usb1: Product: xHCI Host Controller
Apr 01 12:03:53 avishays-pi kernel: usb usb1: Manufacturer: Linux 6.12.47-v8+ xhci-hcd
Apr 01 12:03:53 avishays-pi kernel: usb usb1: SerialNumber: xhci-hcd.0
Apr 01 12:03:53 avishays-pi kernel: hub 1-0:1.0: USB hub found
Apr 01 12:03:53 avishays-pi kernel: hub 1-0:1.0: 2 ports detected
Apr 01 12:03:53 avishays-pi kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12
Apr 01 12:03:53 avishays-pi kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Apr 01 12:03:53 avishays-pi kernel: usb usb2: Product: xHCI Host Controller
Apr 01 12:03:53 avishays-pi kernel: usb usb2: Manufacturer: Linux 6.12.47-v8+ xhci-hcd
Apr 01 12:03:53 avishays-pi kernel: usb usb2: SerialNumber: xhci-hcd.0
Apr 01 12:03:53 avishays-pi kernel: hub 2-0:1.0: USB hub found
Apr 01 12:03:53 avishays-pi kernel: hub 2-0:1.0: 1 port detected
Apr 01 12:03:53 avishays-pi kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller
Apr 01 12:03:53 avishays-pi kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3
Apr 01 12:03:53 avishays-pi kernel: xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000808000000810
Apr 01 12:03:53 avishays-pi kernel: xhci-hcd xhci-hcd.1: irq 137, io mem 0x1f00300000
Apr 01 12:03:53 avishays-pi kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller
Apr 01 12:03:53 avishays-pi kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4
Apr 01 12:03:53 avishays-pi kernel: xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed
Apr 01 12:03:53 avishays-pi kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12
Apr 01 12:03:53 avishays-pi kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Apr 01 12:03:53 avishays-pi kernel: usb usb3: Product: xHCI Host Controller
Apr 01 12:03:53 avishays-pi kernel: usb usb3: Manufacturer: Linux 6.12.47-v8+ xhci-hcd
Apr 01 12:03:53 avishays-pi kernel: usb usb3: SerialNumber: xhci-hcd.1
Apr 01 12:03:53 avishays-pi kernel: hub 3-0:1.0: USB hub found
Apr 01 12:03:53 avishays-pi kernel: hub 3-0:1.0: 2 ports detected
Apr 01 12:03:53 avishays-pi kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12
Apr 01 12:03:53 avishays-pi kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Apr 01 12:03:53 avishays-pi kernel: usb usb4: Product: xHCI Host Controller
Apr 01 12:03:53 avishays-pi kernel: usb usb4: Manufacturer: Linux 6.12.47-v8+ xhci-hcd
Apr 01 12:03:53 avishays-pi kernel: usb usb4: SerialNumber: xhci-hcd.1
Apr 01 12:03:53 avishays-pi kernel: hub 4-0:1.0: USB hub found
Apr 01 12:03:53 avishays-pi kernel: hub 4-0:1.0: 1 port detected
Apr 01 12:03:53 avishays-pi kernel: of_cfs_init
Apr 01 12:03:53 avishays-pi kernel: of_cfs_init: OK
Apr 01 12:03:53 avishays-pi kernel: clk: Disabling unused clocks
Apr 01 12:03:53 avishays-pi kernel: PM: genpd: Disabling unused power domains
Apr 01 12:03:53 avishays-pi kernel: mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit
Apr 01 12:03:53 avishays-pi kernel: mmc0: new ultra high speed SDR104 SDXC card at address 1388
Apr 01 12:03:53 avishays-pi kernel: mmcblk0: mmc0:1388 Stora 58.0 GiB
Apr 01 12:03:53 avishays-pi kernel: mmcblk0: p1 p2 p3
Apr 01 12:03:53 avishays-pi kernel: mmcblk0: mmc0:1388 Stora 58.0 GiB
Apr 01 12:03:53 avishays-pi kernel: mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit
Apr 01 12:03:53 avishays-pi kernel: Freeing unused kernel memory: 5440K
Apr 01 12:03:53 avishays-pi kernel: Run /init as init process
Apr 01 12:03:53 avishays-pi kernel: with arguments:
Apr 01 12:03:53 avishays-pi kernel: /init
Apr 01 12:03:53 avishays-pi kernel: splash
Apr 01 12:03:53 avishays-pi kernel: nodebug
Apr 01 12:03:53 avishays-pi kernel: with environment:
Apr 01 12:03:53 avishays-pi kernel: HOME=/
Apr 01 12:03:53 avishays-pi kernel: TERM=linux
Apr 01 12:03:53 avishays-pi kernel: imgpart=UUID=b0b466fc-4556-43af-9f97-ebe56fe4abe4
Apr 01 12:03:53 avishays-pi kernel: imgfile=/volumio_current.sqsh
Apr 01 12:03:53 avishays-pi kernel: bootpart=UUID=0428-C7E4
Apr 01 12:03:53 avishays-pi kernel: datapart=UUID=82051e0e-a859-4f54-aa9f-0cc23a932af8
Apr 01 12:03:53 avishays-pi kernel: uuidconfig=cmdline.txt
Apr 01 12:03:53 avishays-pi kernel: bootdelay=7
Apr 01 12:03:53 avishays-pi kernel: use_kmsg=no
Apr 01 12:03:53 avishays-pi kernel: mmc1: new ultra high speed DDR50 SDIO card at address 0001
Apr 01 12:03:53 avishays-pi kernel: rp1-firmware rp1_firmware: RP1 Firmware version 23fca1cd776798b50bd3e640ecb242215778e7f8
Apr 01 12:03:53 avishays-pi kernel: rp1-pio 1f00178000.pio: Created instance as pio0
Apr 01 12:03:53 avishays-pi kernel: input: pwr_button as /devices/platform/pwr_button/input/input0
Apr 01 12:03:53 avishays-pi kernel: brcmstb-i2c 107d508200.i2c: @97500hz registered in interrupt mode
Apr 01 12:03:53 avishays-pi kernel: brcmstb-i2c 107d508280.i2c: @97500hz registered in interrupt mode
Apr 01 12:03:53 avishays-pi kernel: v3d 1002000000.v3d: [drm] Transparent Hugepage support is recommended for optimal performance on this platform!
Apr 01 12:03:53 avishays-pi kernel: [drm] Initialized v3d 1.0.0 for 1002000000.v3d on minor 0
Apr 01 12:03:53 avishays-pi kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_of_xlate: MMU 1000005200.iommu
Apr 01 12:03:53 avishays-pi kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_probe_device: MMU 1000005200.iommu
Apr 01 12:03:53 avishays-pi kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_device_group: MMU 1000005200.iommu
Apr 01 12:03:53 avishays-pi kernel: vc4_hvs 107c580000.hvs: Adding to iommu group 1
Apr 01 12:03:53 avishays-pi kernel: vc4_hvs 107c580000.hvs: bcm2712_iommu_attach_dev: MMU 1000005200.iommu
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: Adding to iommu group 1
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4])
Apr 01 12:03:53 avishays-pi kernel: Registered IR keymap rc-cec
Apr 01 12:03:53 avishays-pi kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0
Apr 01 12:03:53 avishays-pi kernel: input: vc4-hdmi-0 as /devices/platform/soc@107c000000/107c701400.hdmi/rc/rc0/input1
Apr 01 12:03:53 avishays-pi kernel: input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc@107c000000/107c701400.hdmi/sound/card0/input2
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4])
Apr 01 12:03:53 avishays-pi kernel: Registered IR keymap rc-cec
Apr 01 12:03:53 avishays-pi kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1
Apr 01 12:03:53 avishays-pi kernel: input: vc4-hdmi-1 as /devices/platform/soc@107c000000/107c706400.hdmi/rc/rc1/input3
Apr 01 12:03:53 avishays-pi kernel: input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc@107c000000/107c706400.hdmi/sound/card1/input4
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4])
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4])
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4])
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4])
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4])
Apr 01 12:03:53 avishays-pi kernel: [drm] Initialized vc4 0.0.0 for axi:gpu on minor 1
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes
Apr 01 12:03:53 avishays-pi kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes
Apr 01 12:03:53 avishays-pi kernel: fuse: init (API version 7.41)
Apr 01 12:03:53 avishays-pi kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Apr 01 12:03:53 avishays-pi kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
Apr 01 12:03:53 avishays-pi kernel: fbtft: module is from the staging directory, the quality is unknown, you have been warned.
Apr 01 12:03:53 avishays-pi kernel: fb_ili9340: module is from the staging directory, the quality is unknown, you have been warned.
Apr 01 12:03:53 avishays-pi kernel: SPI driver fb_ili9340 has no spi_device_id for ilitek,ili9340
Apr 01 12:03:53 avishays-pi kernel: fb_ili9341: module is from the staging directory, the quality is unknown, you have been warned.
Apr 01 12:03:53 avishays-pi kernel: fb_st7735r: module is from the staging directory, the quality is unknown, you have been warned.
Apr 01 12:03:53 avishays-pi kernel: SPI driver fb_st7735r has no spi_device_id for sitronix,st7735r
Apr 01 12:03:53 avishays-pi kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,sainsmart18
Apr 01 12:03:53 avishays-pi kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,adafruit18
Apr 01 12:03:53 avishays-pi kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,adafruit18_green
Apr 01 12:03:53 avishays-pi kernel: fb_st7789v: module is from the staging directory, the quality is unknown, you have been warned.
Apr 01 12:03:53 avishays-pi kernel: SPI driver fb_st7789v has no spi_device_id for sitronix,st7789v
Apr 01 12:03:53 avishays-pi kernel: SPI driver fb_st7789v has no spi_device_id for fbtft,minipitft13
Apr 01 12:03:53 avishays-pi kernel: fb_hx8357d: module is from the staging directory, the quality is unknown, you have been warned.
Apr 01 12:03:53 avishays-pi kernel: SPI driver fb_hx8357d has no spi_device_id for himax,hx8357d
Apr 01 12:03:53 avishays-pi kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Apr 01 12:03:53 avishays-pi kernel: EXT4-fs (mmcblk0p2): recovery complete
Apr 01 12:03:53 avishays-pi kernel: EXT4-fs (mmcblk0p2): mounted filesystem b0b466fc-4556-43af-9f97-ebe56fe4abe4 r/w with ordered data mode. Quota mode: none.
Apr 01 12:03:53 avishays-pi kernel: loop0: detected capacity change from 0 to 1423840
Apr 01 12:03:53 avishays-pi kernel: EXT4-fs (mmcblk0p3): recovery complete
Apr 01 12:03:53 avishays-pi kernel: EXT4-fs (mmcblk0p3): mounted filesystem 82051e0e-a859-4f54-aa9f-0cc23a932af8 r/w with ordered data mode. Quota mode: none.
Apr 01 12:03:53 avishays-pi systemd[1]: System time before build time, advancing clock.
Apr 01 12:03:53 avishays-pi kernel: NET: Registered PF_INET6 protocol family
Apr 01 12:03:53 avishays-pi kernel: Segment Routing with IPv6
Apr 01 12:03:53 avishays-pi kernel: In-situ OAM (IOAM) with IPv6
Apr 01 12:03:53 avishays-pi systemd[1]: systemd 252.39-1~deb12u1+rpi1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified)
Apr 01 12:03:53 avishays-pi systemd[1]: Detected architecture arm64.
Apr 01 12:03:53 avishays-pi systemd[1]: Hostname set to .
Apr 01 12:03:53 avishays-pi systemd[1]: Queued start job for default target graphical.target.
Apr 01 12:03:53 avishays-pi systemd[1]: Created slice system-getty.slice - Slice /system/getty.
Apr 01 12:03:53 avishays-pi systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
Apr 01 12:03:53 avishays-pi systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty.
Apr 01 12:03:53 avishays-pi systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
Apr 01 12:03:53 avishays-pi systemd[1]: Created slice user.slice - User and Session Slice.
Apr 01 12:03:53 avishays-pi systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
Apr 01 12:03:53 avishays-pi systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
Apr 01 12:03:53 avishays-pi systemd[1]: Expecting device dev-disk-by\x2duuid-0428\x2dC7E4.device - /dev/disk/by-uuid/0428-C7E4...
Apr 01 12:03:53 avishays-pi systemd[1]: Expecting device dev-ttyAMA10.device - /dev/ttyAMA10...
Apr 01 12:03:53 avishays-pi systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
Apr 01 12:03:53 avishays-pi systemd[1]: Reached target slices.target - Slice Units.
Apr 01 12:03:53 avishays-pi systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
Apr 01 12:03:53 avishays-pi systemd[1]: Listening on rpcbind.socket - RPCbind Server Activation Socket.
Apr 01 12:03:53 avishays-pi systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket.
Apr 01 12:03:53 avishays-pi systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe.
Apr 01 12:03:53 avishays-pi systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket.
Apr 01 12:03:53 avishays-pi systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
Apr 01 12:03:53 avishays-pi systemd[1]: Listening on systemd-journald.socket - Journal Socket.
Apr 01 12:03:53 avishays-pi systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Apr 01 12:03:53 avishays-pi systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Apr 01 12:03:53 avishays-pi systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages).
Apr 01 12:03:53 avishays-pi systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
Apr 01 12:03:53 avishays-pi systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
Apr 01 12:03:53 avishays-pi systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
Apr 01 12:03:53 avishays-pi systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab).
Apr 01 12:03:53 avishays-pi systemd[1]: Started dynamicswap.service - dynamicswap service.
Apr 01 12:03:53 avishays-pi systemd[1]: Reached target swap.target - Swaps.
Apr 01 12:03:53 avishays-pi systemd[1]: Starting fake-hwclock.service - Restore / save the current clock...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting systemd-journald.service - Journal Service...
Apr 01 12:03:53 avishays-pi kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
Apr 01 12:03:53 avishays-pi systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Apr 01 12:03:53 avishays-pi systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
Apr 01 12:03:53 avishays-pi systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
Apr 01 12:03:53 avishays-pi systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@configfs.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@drm.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@fuse.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@loop.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Apr 01 12:03:53 avishays-pi systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
Apr 01 12:03:53 avishays-pi systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
Apr 01 12:03:53 avishays-pi systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished fake-hwclock.service - Restore / save the current clock.
Apr 01 12:03:53 avishays-pi systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
Apr 01 12:03:53 avishays-pi systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
Apr 01 12:03:53 avishays-pi systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes).
Apr 01 12:03:53 avishays-pi systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Apr 01 12:03:53 avishays-pi systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting systemd-sysusers.service - Create System Users...
Apr 01 12:03:53 avishays-pi kernel: i2c_dev: i2c /dev entries driver
Apr 01 12:03:53 avishays-pi systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Apr 01 12:03:53 avishays-pi systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Apr 01 12:03:53 avishays-pi systemd-journald[525]: Journal started
Apr 01 12:03:53 avishays-pi systemd-journald[525]: Runtime Journal (/run/log/journal/5d75154b0edd470a8cb44210694cfe4a) is 3.9M, max 30.0M, 26.0M free.
Apr 01 12:03:53 avishays-pi systemd-modules-load[527]: Inserted module 'i2c_dev'
Apr 01 12:03:53 avishays-pi fake-hwclock[534]: Wed Apr 1 09:03:53 UTC 2026
Apr 01 12:03:53 avishays-pi systemd[1]: Started systemd-journald.service - Journal Service.
Apr 01 12:03:53 avishays-pi systemd[1]: dynamicswap.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed.
Apr 01 12:03:53 avishays-pi systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes).
Apr 01 12:03:53 avishays-pi systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished systemd-sysusers.service - Create System Users.
Apr 01 12:03:53 avishays-pi systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Apr 01 12:03:53 avishays-pi systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Apr 01 12:03:53 avishays-pi systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems.
Apr 01 12:03:53 avishays-pi systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Apr 01 12:03:53 avishays-pi systemd-udevd[551]: Using default interface naming scheme 'v252'.
Apr 01 12:03:53 avishays-pi systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Apr 01 12:03:53 avishays-pi systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown...
Apr 01 12:03:53 avishays-pi systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown.
Apr 01 12:03:53 avishays-pi (udev-worker)[568]: Network interface NamePolicy= disabled on kernel command line.
Apr 01 12:03:53 avishays-pi kernel: rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040
Apr 01 12:03:53 avishays-pi kernel: rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1
Apr 01 12:03:53 avishays-pi kernel: rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040
Apr 01 12:03:53 avishays-pi kernel: rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2
Apr 01 12:03:53 avishays-pi kernel: rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020
Apr 01 12:03:53 avishays-pi kernel: rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3
Apr 01 12:03:53 avishays-pi kernel: rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020
Apr 01 12:03:53 avishays-pi kernel: rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4
Apr 01 12:03:53 avishays-pi kernel: rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000
Apr 01 12:03:53 avishays-pi kernel: rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0
Apr 01 12:03:53 avishays-pi systemd[1]: Found device dev-disk-by\x2duuid-0428\x2dC7E4.device - /dev/disk/by-uuid/0428-C7E4.
Apr 01 12:03:53 avishays-pi kernel: mc: Linux media interface: v0.10
Apr 01 12:03:53 avishays-pi systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-0428\x2dC7E4.service - File System Check on /dev/disk/by-uuid/0428-C7E4...
Apr 01 12:03:53 avishays-pi kernel: videodev: Linux video capture interface: v2.00
Apr 01 12:03:53 avishays-pi systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status.
Apr 01 12:03:53 avishays-pi kernel: snd-rpi-hifiberry-dacplus soc@107c000000:sound: activating headphone amplifier
Apr 01 12:03:53 avishays-pi kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database
Apr 01 12:03:53 avishays-pi systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0...
Apr 01 12:03:53 avishays-pi kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf'
Apr 01 12:03:53 avishays-pi kernel: Bluetooth: Core ver 2.22
Apr 01 12:03:53 avishays-pi kernel: NET: Registered PF_BLUETOOTH protocol family
Apr 01 12:03:53 avishays-pi kernel: Bluetooth: HCI device and connection manager initialized
Apr 01 12:03:53 avishays-pi kernel: Bluetooth: HCI socket layer initialized
Apr 01 12:03:53 avishays-pi kernel: Bluetooth: L2CAP socket layer initialized
Apr 01 12:03:53 avishays-pi kernel: Bluetooth: SCO socket layer initialized
Apr 01 12:03:53 avishays-pi kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328'
Apr 01 12:03:53 avishays-pi kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
Apr 01 12:03:53 avishays-pi kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
Apr 01 12:03:53 avishays-pi kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu
Apr 01 12:03:53 avishays-pi kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu
Apr 01 12:03:53 avishays-pi kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu
Apr 01 12:03:53 avishays-pi kernel: pispbe 1000880000.pisp_be: Adding to iommu group 0
Apr 01 12:03:53 avishays-pi kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu
Apr 01 12:03:53 avishays-pi kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu
Apr 01 12:03:53 avishays-pi kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu
Apr 01 12:03:53 avishays-pi kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu
Apr 01 12:03:53 avishays-pi kernel: rpi-hevc-dec 1000800000.codec: Adding to iommu group 0
Apr 01 12:03:53 avishays-pi kernel: rpi-hevc-dec 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu
Apr 01 12:03:53 avishays-pi systemd-fsck[751]: fsck.fat 4.2 (2021-01-31)
Apr 01 12:03:53 avishays-pi systemd-fsck[751]: There are differences between boot sector and its backup.
Apr 01 12:03:53 avishays-pi systemd-fsck[751]: This is mostly harmless. Differences: (offset:original/backup)
Apr 01 12:03:53 avishays-pi systemd-fsck[751]: 65:01/00
Apr 01 12:03:53 avishays-pi systemd-fsck[751]: Not automatically fixing this.
Apr 01 12:03:53 avishays-pi systemd-fsck[751]: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt.
Apr 01 12:03:53 avishays-pi systemd-fsck[751]: Automatically removing dirty bit.
Apr 01 12:03:53 avishays-pi systemd-fsck[751]: *** Filesystem was changed ***
Apr 01 12:03:53 avishays-pi systemd-fsck[751]: Writing changes.
Apr 01 12:03:53 avishays-pi systemd-fsck[751]: /dev/mmcblk0p1: 427 files, 23456/93500 clusters
Apr 01 12:03:53 avishays-pi kernel: rpi-hevc-dec 1000800000.codec: Device registered as /dev/video19
Apr 01 12:03:53 avishays-pi kernel: Bluetooth: HCI UART driver ver 2.3
Apr 01 12:03:53 avishays-pi kernel: Bluetooth: HCI UART protocol H4 registered
Apr 01 12:03:53 avishays-pi kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered
Apr 01 12:03:53 avishays-pi kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator
Apr 01 12:03:53 avishays-pi kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator
Apr 01 12:03:53 avishays-pi kernel: Bluetooth: HCI UART protocol Broadcom registered
Apr 01 12:03:53 avishays-pi systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-0428\x2dC7E4.service - File System Check on /dev/disk/by-uuid/0428-C7E4.
Apr 01 12:03:53 avishays-pi systemd[1]: Found device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0.
Apr 01 12:03:53 avishays-pi systemd[1]: Found device dev-ttyAMA10.device - /dev/ttyAMA10.
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Apr 01 12:03:53 avishays-pi systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes).
Apr 01 12:03:53 avishays-pi systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes).
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@loop.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Apr 01 12:03:53 avishays-pi systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes).
Apr 01 12:03:53 avishays-pi systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes).
Apr 01 12:03:53 avishays-pi systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Apr 01 12:03:53 avishays-pi systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Apr 01 12:03:53 avishays-pi kernel: brcmfmac: F1 signature read @0x18000000=0x15264345
Apr 01 12:03:53 avishays-pi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
Apr 01 12:03:53 avishays-pi kernel: usbcore: registered new interface driver brcmfmac
Apr 01 12:03:53 avishays-pi systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch.
Apr 01 12:03:53 avishays-pi kernel: tpa6130a2 1-0060: supply Vdd not found, using dummy regulator
Apr 01 12:03:53 avishays-pi systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status...
Apr 01 12:03:53 avishays-pi kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2)
Apr 01 12:03:53 avishays-pi kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Aug 29 2023 01:47:08 version 7.45.265 (28bca26 CY) FWID 01-b677b91b
Apr 01 12:03:53 avishays-pi (udev-worker)[555]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1.
Apr 01 12:03:53 avishays-pi systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status.
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Apr 01 12:03:53 avishays-pi systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes).
Apr 01 12:03:53 avishays-pi systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes).
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Apr 01 12:03:53 avishays-pi systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@loop.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Apr 01 12:03:53 avishays-pi systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Apr 01 12:03:53 avishays-pi (udev-worker)[571]: Network interface NamePolicy= disabled on kernel command line.
Apr 01 12:03:53 avishays-pi systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Apr 01 12:03:53 avishays-pi systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Apr 01 12:03:53 avishays-pi systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes).
Apr 01 12:03:53 avishays-pi systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes).
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Apr 01 12:03:53 avishays-pi systemd[1]: modprobe@loop.service: Deactivated successfully.
Apr 01 12:03:53 avishays-pi systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Apr 01 12:03:53 avishays-pi systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0.
Apr 01 12:03:53 avishays-pi systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Apr 01 12:03:53 avishays-pi systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Apr 01 12:03:53 avishays-pi kernel: Bluetooth: hci0: BCM: chip id 107
Apr 01 12:03:53 avishays-pi kernel: Bluetooth: hci0: BCM: features 0x2f
Apr 01 12:03:53 avishays-pi kernel: Bluetooth: hci0: BCM4345C0
Apr 01 12:03:53 avishays-pi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000
Apr 01 12:03:53 avishays-pi kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.raspberrypi,5-model-b.hcd' Patch
Apr 01 12:03:53 avishays-pi systemd[1]: boot.mount: Directory /boot to mount over is not empty, mounting anyway.
Apr 01 12:03:54 avishays-pi systemd[1]: Mounting boot.mount - /boot...
Apr 01 12:03:54 avishays-pi systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages).
Apr 01 12:03:54 avishays-pi systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway.
Apr 01 12:03:54 avishays-pi systemd[1]: Mounting tmp.mount - /tmp...
Apr 01 12:03:54 avishays-pi systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway.
Apr 01 12:03:54 avishays-pi systemd[1]: Mounting var-log.mount - /var/log...
Apr 01 12:03:54 avishays-pi systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups...
Apr 01 12:03:54 avishays-pi systemd[1]: Mounted tmp.mount - /tmp.
Apr 01 12:03:54 avishays-pi systemd[1]: Mounted var-log.mount - /var/log.
Apr 01 12:03:54 avishays-pi systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups.
Apr 01 12:03:54 avishays-pi systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp...
Apr 01 12:03:54 avishays-pi systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage...
Apr 01 12:03:54 avishays-pi systemd[1]: Mounted boot.mount - /boot.
Apr 01 12:03:54 avishays-pi systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp.
Apr 01 12:03:54 avishays-pi systemd[1]: Reached target local-fs.target - Local File Systems.
Apr 01 12:03:54 avishays-pi systemd[1]: Started ifup@eth0.service - ifup for eth0.
Apr 01 12:03:54 avishays-pi systemd[1]: Started ifup@wlan0.service - ifup for wlan0.
Apr 01 12:03:54 avishays-pi systemd[1]: Starting networking.service - Raise network interfaces...
Apr 01 12:03:54 avishays-pi systemd-journald[525]: Runtime Journal (/run/log/journal/5d75154b0edd470a8cb44210694cfe4a) is 3.9M, max 30.0M, 26.0M free.
Apr 01 12:03:54 avishays-pi systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data...
Apr 01 12:03:54 avishays-pi systemd-journald[525]: Received client request to flush runtime journal.
Apr 01 12:03:54 avishays-pi systemd[1]: Starting plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot...
Apr 01 12:03:54 avishays-pi systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats...
Apr 01 12:03:54 avishays-pi systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id).
Apr 01 12:03:54 avishays-pi systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage.
Apr 01 12:03:54 avishays-pi systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Apr 01 12:03:54 avishays-pi systemd[1]: Received SIGRTMIN+20 from PID 403 (plymouthd).
Apr 01 12:03:54 avishays-pi systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 870 (systemd-binfmt)
Apr 01 12:03:54 avishays-pi systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data.
Apr 01 12:03:54 avishays-pi kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211
Apr 01 12:03:54 avishays-pi systemd[1]: Finished plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot.
Apr 01 12:03:54 avishays-pi systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen...
Apr 01 12:03:54 avishays-pi systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Apr 01 12:03:54 avishays-pi systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm.
Apr 01 12:03:54 avishays-pi systemd[1]: Starting rpcbind.service - RPC bind portmap service...
Apr 01 12:03:54 avishays-pi systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP...
Apr 01 12:03:54 avishays-pi sh[911]: dhcpcd-9.4.1 starting
Apr 01 12:03:54 avishays-pi dhcpcd[911]: dhcpcd-9.4.1 starting
Apr 01 12:03:54 avishays-pi dhcpcd[931]: DUID 00:01:00:01:30:df:bc:b4:2c:cf:67:5e:e6:d5
Apr 01 12:03:54 avishays-pi dhcpcd[931]: dhcp_vendor: No such process
Apr 01 12:03:54 avishays-pi sh[911]: DUID 00:01:00:01:30:df:bc:b4:2c:cf:67:5e:e6:d5
Apr 01 12:03:54 avishays-pi sh[911]: dhcp_vendor: No such process
Apr 01 12:03:54 avishays-pi systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen.
Apr 01 12:03:54 avishays-pi systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid).
Apr 01 12:03:54 avishays-pi systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch.
Apr 01 12:03:54 avishays-pi systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Apr 01 12:03:54 avishays-pi systemd[1]: Reached target paths.target - Path Units.
Apr 01 12:03:54 avishays-pi systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP.
Apr 01 12:03:54 avishays-pi kernel: 8021q: 802.1Q VLAN Support v1.8
Apr 01 12:03:54 avishays-pi haveged[915]: haveged: command socket is listening at fd 3
Apr 01 12:03:54 avishays-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Apr 01 12:03:54 avishays-pi systemd[1]: Started rpcbind.service - RPC bind portmap service.
Apr 01 12:03:54 avishays-pi systemd[1]: Reached target rpcbind.target - RPC Port Mapper.
Apr 01 12:03:54 avishays-pi sh[911]: eth0: waiting for carrier
Apr 01 12:03:54 avishays-pi dhcpcd[931]: eth0: waiting for carrier
Apr 01 12:03:54 avishays-pi kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL)
Apr 01 12:03:54 avishays-pi kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode
Apr 01 12:03:54 avishays-pi kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered.
Apr 01 12:03:54 avishays-pi sh[987]: wlan0=wlan0
Apr 01 12:03:54 avishays-pi systemd[1]: Finished networking.service - Raise network interfaces.
Apr 01 12:03:54 avishays-pi kernel: Bluetooth: hci0: BCM: features 0x2f
Apr 01 12:03:54 avishays-pi kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190
Apr 01 12:03:54 avishays-pi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382
Apr 01 12:03:54 avishays-pi kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac)
Apr 01 12:03:54 avishays-pi haveged[915]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K
Apr 01 12:03:54 avishays-pi haveged[915]: haveged: cpu: (VC); data: 64K (V); inst: 64K (V); idx: 39/40; sz: 63356/63356
Apr 01 12:03:54 avishays-pi haveged[915]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00031
Apr 01 12:03:54 avishays-pi haveged[915]: haveged: fills: 0, generated: 0
Apr 01 12:03:55 avishays-pi systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System...
Apr 01 12:03:55 avishays-pi systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System...
Apr 01 12:03:55 avishays-pi systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System.
Apr 01 12:03:55 avishays-pi systemd[1]: Reached target rpc_pipefs.target.
Apr 01 12:03:55 avishays-pi systemd[1]: rpc-gssd.service - RPC security service for NFS client and server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab).
Apr 01 12:03:55 avishays-pi systemd[1]: Reached target nfs-client.target - NFS client services.
Apr 01 12:03:55 avishays-pi systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems.
Apr 01 12:03:55 avishays-pi systemd[1]: Reached target remote-fs.target - Remote File Systems.
Apr 01 12:03:55 avishays-pi systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System.
Apr 01 12:03:55 avishays-pi systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats.
Apr 01 12:03:55 avishays-pi systemd[1]: Reached target sysinit.target - System Initialization.
Apr 01 12:03:55 avishays-pi systemd[1]: Started apt-daily.timer - Daily apt download activities.
Apr 01 12:03:55 avishays-pi systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Fri 2026-04-03 11:27:11 IDT as it is in the future.
Apr 01 12:03:55 avishays-pi systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities.
Apr 01 12:03:55 avishays-pi systemd[1]: dpkg-db-backup.timer: Not using persistent file timestamp Fri 2026-04-03 11:16:57 IDT as it is in the future.
Apr 01 12:03:55 avishays-pi systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer.
Apr 01 12:03:55 avishays-pi systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems.
Apr 01 12:03:55 avishays-pi systemd[1]: Started fstrim.timer - Discard unused blocks once a week.
Apr 01 12:03:55 avishays-pi systemd[1]: ntpsec-rotate-stats.timer: Not using persistent file timestamp Fri 2026-04-03 11:16:57 IDT as it is in the future.
Apr 01 12:03:55 avishays-pi systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily.
Apr 01 12:03:55 avishays-pi systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer.
Apr 01 12:03:55 avishays-pi systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories.
Apr 01 12:03:55 avishays-pi systemd[1]: Reached target timers.target - Timer Units.
Apr 01 12:03:55 avishays-pi systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket.
Apr 01 12:03:55 avishays-pi systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket.
Apr 01 12:03:55 avishays-pi systemd[1]: Reached target sockets.target - Socket Units.
Apr 01 12:03:55 avishays-pi systemd[1]: systemd-pcrphase-sysinit.service - TPM2 PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f).
Apr 01 12:03:55 avishays-pi systemd[1]: Reached target basic.target - Basic System.
Apr 01 12:03:55 avishays-pi systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf).
Apr 01 12:03:55 avishays-pi systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State...
Apr 01 12:03:55 avishays-pi systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack...
Apr 01 12:03:55 avishays-pi systemd[1]: Starting bluetooth.service - Bluetooth service...
Apr 01 12:03:55 avishays-pi systemd[1]: Starting dbus.service - D-Bus System Message Bus...
Apr 01 12:03:55 avishays-pi systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots...
Apr 01 12:03:55 avishays-pi systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon).
Apr 01 12:03:55 avishays-pi systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf).
Apr 01 12:03:55 avishays-pi systemd[1]: Started iptables.service - Volumio Iptables Module.
Apr 01 12:03:55 avishays-pi systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling...
Apr 01 12:03:55 avishays-pi systemd[1]: Starting rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates...
Apr 01 12:03:55 avishays-pi systemd[1]: Starting systemd-logind.service - User Login Management...
Apr 01 12:03:55 avishays-pi systemd[1]: systemd-pcrphase.service - TPM2 PCR Barrier (User) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f).
Apr 01 12:03:55 avishays-pi systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon...
Apr 01 12:03:55 avishays-pi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 01 12:03:55 avishays-pi systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater.
Apr 01 12:03:55 avishays-pi systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state...
Apr 01 12:03:55 avishays-pi systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}).
Apr 01 12:03:55 avishays-pi systemd[1]: Starting wireless.service - Wireless Services...
Apr 01 12:03:55 avishays-pi systemd[1]: e2scrub_reap.service: Deactivated successfully.
Apr 01 12:03:55 avishays-pi systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots.
Apr 01 12:03:55 avishays-pi thd[1022]: Unable to parse trigger line:
Apr 01 12:03:55 avishays-pi thd[1022]: Unable to parse trigger line:
Apr 01 12:03:55 avishays-pi volumio_rfkill_unblock.sh[1025]: Volumio WiFi Soft Blocked check script
Apr 01 12:03:55 avishays-pi volumio_rfkill_unblock.sh[1025]: Check if rfkill is available
Apr 01 12:03:55 avishays-pi systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon.
Apr 01 12:03:55 avishays-pi systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy...
Apr 01 12:03:55 avishays-pi alsactl[1032]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Apr 01 12:03:55 avishays-pi alsactl[1032]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Apr 01 12:03:55 avishays-pi alsactl[1032]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2
Apr 01 12:03:55 avishays-pi avahi-daemon[1011]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109).
Apr 01 12:03:55 avishays-pi avahi-daemon[1011]: Successfully dropped root privileges.
Apr 01 12:03:55 avishays-pi systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State.
Apr 01 12:03:55 avishays-pi avahi-daemon[1011]: avahi-daemon 0.8 starting up.
Apr 01 12:03:55 avishays-pi systemd[1]: Reached target sound.target - Sound Card.
Apr 01 12:03:55 avishays-pi volumio_rfkill_unblock.sh[1025]: Check if rfkill listed devices are already unblocked
Apr 01 12:03:55 avishays-pi th-udev-rebind.sh[1033]: Rebinding /dev/input/event0 to thd using --add...
Apr 01 12:03:55 avishays-pi th-udev-rebind.sh[1033]: Rebinding /dev/input/event1 to thd using --add...
Apr 01 12:03:55 avishays-pi th-udev-rebind.sh[1033]: Rebinding /dev/input/event2 to thd using --add...
Apr 01 12:03:55 avishays-pi th-udev-rebind.sh[1033]: Rebinding /dev/input/event3 to thd using --add...
Apr 01 12:03:55 avishays-pi th-udev-rebind.sh[1033]: Rebinding /dev/input/event4 to thd using --add...
Apr 01 12:03:55 avishays-pi systemd[1]: th-udev-rebind.service: Deactivated successfully.
Apr 01 12:03:55 avishays-pi systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy.
Apr 01 12:03:55 avishays-pi volumio_rfkill_unblock.sh[1025]: Wi-Fi is already unblocked.
Apr 01 12:03:55 avishays-pi systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state.
Apr 01 12:03:55 avishays-pi systemd-logind[1020]: New seat seat0.
Apr 01 12:03:55 avishays-pi systemd-logind[1020]: Watching system buttons on /dev/input/event0 (pwr_button)
Apr 01 12:03:55 avishays-pi systemd-logind[1020]: Watching system buttons on /dev/input/event1 (vc4-hdmi-0)
Apr 01 12:03:55 avishays-pi systemd-logind[1020]: Watching system buttons on /dev/input/event3 (vc4-hdmi-1)
Apr 01 12:03:55 avishays-pi systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces...
Apr 01 12:03:55 avishays-pi dhcpcd[1045]: dhcpcd-9.4.1 starting
Apr 01 12:03:55 avishays-pi rpi-eeprom-update[1017]: WARNING: flashrom not found. Setting RPI_EEPROM_USE_FLASHROM to 0
Apr 01 12:03:55 avishays-pi dhcpcd[1059]: dev: loaded udev
Apr 01 12:03:55 avishays-pi dhcpcd[1059]: DUID 00:01:00:01:30:df:bc:b4:2c:cf:67:5e:e6:d5
Apr 01 12:03:55 avishays-pi dhcpcd[1045]: dhcp_vendor: No such process
Apr 01 12:03:55 avishays-pi dhcpcd[1059]: dhcp_vendor: No such process
Apr 01 12:03:55 avishays-pi systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces.
Apr 01 12:03:55 avishays-pi bluetoothd[1012]: Bluetooth daemon 5.83
Apr 01 12:03:55 avishays-pi loadcpufreq[1016]: Loading cpufreq kernel modules...done (none).
Apr 01 12:03:55 avishays-pi systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling.
Apr 01 12:03:55 avishays-pi dhcpcd[1059]: eth0: waiting for carrier
Apr 01 12:03:55 avishays-pi dhcpcd[1059]: wlan0: waiting for carrier
Apr 01 12:03:55 avishays-pi systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters...
Apr 01 12:03:55 avishays-pi systemd[1]: Started dbus.service - D-Bus System Message Bus.
Apr 01 12:03:55 avishays-pi avahi-daemon[1011]: Successfully called chroot().
Apr 01 12:03:55 avishays-pi avahi-daemon[1011]: Successfully dropped remaining capabilities.
Apr 01 12:03:55 avishays-pi dbus-daemon[1013]: [system] Successfully activated service 'org.freedesktop.systemd1'
Apr 01 12:03:55 avishays-pi avahi-daemon[1011]: Loading service file /services/volumio.service.
Apr 01 12:03:55 avishays-pi bluetoothd[1012]: Starting SDP server
Apr 01 12:03:55 avishays-pi bluetoothd[1012]: Excluding (cli) sap
Apr 01 12:03:55 avishays-pi bluetoothd[1012]: Excluding (cli) midi
Apr 01 12:03:55 avishays-pi bluetoothd[1012]: Excluding (cli) battery
Apr 01 12:03:55 avishays-pi avahi-daemon[1011]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
Apr 01 12:03:55 avishays-pi avahi-daemon[1011]: New relevant interface lo.IPv4 for mDNS.
Apr 01 12:03:55 avishays-pi avahi-daemon[1011]: Network interface enumeration completed.
Apr 01 12:03:55 avishays-pi avahi-daemon[1011]: Registering new address record for 127.0.0.1 on lo.IPv4.
Apr 01 12:03:55 avishays-pi cpufrequtils[1099]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done.
Apr 01 12:03:55 avishays-pi kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Apr 01 12:03:55 avishays-pi kernel: Bluetooth: BNEP filters: protocol multicast
Apr 01 12:03:55 avishays-pi kernel: Bluetooth: BNEP socket layer initialized
Apr 01 12:03:55 avishays-pi systemd[1]: Starting wpa_supplicant.service - WPA supplicant...
Apr 01 12:03:55 avishays-pi bluetoothd[1012]: Bluetooth management interface 1.23 initialized
Apr 01 12:03:55 avishays-pi dbus-daemon[1013]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.2' (uid=0 pid=1012 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b")
Apr 01 12:03:55 avishays-pi bluetoothd[1012]: Battery Provider Manager created
Apr 01 12:03:55 avishays-pi kernel: Bluetooth: MGMT ver 1.23
Apr 01 12:03:55 avishays-pi systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters.
Apr 01 12:03:55 avishays-pi systemd[1]: iptables.service: Deactivated successfully.
Apr 01 12:03:55 avishays-pi systemd[1]: Started systemd-logind.service - User Login Management.
Apr 01 12:03:55 avishays-pi systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack.
Apr 01 12:03:55 avishays-pi systemd[1]: Started bluetooth.service - Bluetooth service.
Apr 01 12:03:55 avishays-pi kernel: NET: Registered PF_ALG protocol family
Apr 01 12:03:55 avishays-pi systemd[1]: Starting bluealsa.service - BlueALSA service...
Apr 01 12:03:55 avishays-pi systemd[1]: Starting systemd-hostnamed.service - Hostname Service...
Apr 01 12:03:55 avishays-pi dbus-daemon[1013]: [system] Successfully activated service 'org.freedesktop.hostname1'
Apr 01 12:03:55 avishays-pi systemd[1]: Started systemd-hostnamed.service - Hostname Service.
Apr 01 12:03:55 avishays-pi bluetoothd[1012]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled
Apr 01 12:03:55 avishays-pi bluetoothd[1012]: bap: Operation not supported (95)
Apr 01 12:03:55 avishays-pi bluetoothd[1012]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16
Apr 01 12:03:55 avishays-pi wpa_supplicant[1134]: Successfully initialized wpa_supplicant
Apr 01 12:03:55 avishays-pi systemd[1]: Started wpa_supplicant.service - WPA supplicant.
Apr 01 12:03:55 avishays-pi systemd[1]: Reached target network.target - Network.
Apr 01 12:03:55 avishays-pi systemd[1]: Reached target network-online.target - Network is Online.
Apr 01 12:03:55 avishays-pi systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically...
Apr 01 12:03:55 avishays-pi systemd[1]: Starting nmbd.service - Samba NMB Daemon...
Apr 01 12:03:55 avishays-pi systemd[1]: Starting ntpsec.service - Network Time Service...
Apr 01 12:03:55 avishays-pi systemd[1]: Started plexamp.service - Plexamp.
Apr 01 12:03:55 avishays-pi systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility...
Apr 01 12:03:55 avishays-pi systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart...
Apr 01 12:03:55 avishays-pi systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon...
Apr 01 12:03:55 avishays-pi systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Apr 01 12:03:55 avishays-pi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 01 12:03:55 avishays-pi systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions...
Apr 01 12:03:55 avishays-pi sm-notify[1159]: Version 2.6.2 starting
Apr 01 12:03:55 avishays-pi systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor.
Apr 01 12:03:55 avishays-pi systemd[1]: Starting welcome.service - Show a welcome message on console...
Apr 01 12:03:55 avishays-pi systemd[1]: Started rc-local.service - /etc/rc.local Compatibility.
Apr 01 12:03:55 avishays-pi volumio-remote-updater[1024]: Volumio OTA Client: 1.7.0
Apr 01 12:03:55 avishays-pi volumio-remote-updater[1024]: Error: No active session
Apr 01 12:03:55 avishays-pi volumio-remote-updater[1024]: [2026-04-01 12:03:55] [info] asio async_connect error: asio.system:111 (Connection refused)
Apr 01 12:03:55 avishays-pi volumio-remote-updater[1024]: [2026-04-01 12:03:55] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected)
Apr 01 12:03:55 avishays-pi volumio-remote-updater[1024]: [2026-04-01 12:03:55] [error] handle_connect error: Connection refused
Apr 01 12:03:55 avishays-pi systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions.
Apr 01 12:03:55 avishays-pi ifplugd(eth0)[1220]: ifplugd 0.28 initializing.
Apr 01 12:03:55 avishays-pi ifplugd(eth0)[1220]: Using interface eth0/2C:CF:67:5E:E6:D5 with driver (version: 6.12.47-v8+)
Apr 01 12:03:55 avishays-pi ifplugd(eth0)[1220]: Using detection mode: SIOCETHTOOL
Apr 01 12:03:55 avishays-pi ifplugd(eth0)[1220]: Initialization complete, link beat not detected.
Apr 01 12:03:55 avishays-pi welcome[1166]: Resolved ip:[0]
Apr 01 12:03:55 avishays-pi systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up...
Apr 01 12:03:55 avishays-pi systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart.
Apr 01 12:03:55 avishays-pi ntpd[1171]: INIT: ntpd ntpsec-1.2.2: Starting
Apr 01 12:03:55 avishays-pi ntpd[1171]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec
Apr 01 12:03:55 avishays-pi ntp-systemd-wrapper[1171]: 2026-04-01T12:03:55 ntpd[1171]: INIT: ntpd ntpsec-1.2.2: Starting
Apr 01 12:03:55 avishays-pi ntp-systemd-wrapper[1171]: 2026-04-01T12:03:55 ntpd[1171]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec
Apr 01 12:03:55 avishays-pi systemd[1]: Started ntpsec.service - Network Time Service.
Apr 01 12:03:55 avishays-pi upmpdcli[1023]: Could not open config: /tmp/upmpdcli.conf
Apr 01 12:03:55 avishays-pi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Apr 01 12:03:55 avishays-pi systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Apr 01 12:03:55 avishays-pi ntpd[1237]: INIT: precision = 0.389 usec (-21)
Apr 01 12:03:55 avishays-pi ntpd[1237]: INIT: successfully locked into RAM
Apr 01 12:03:55 avishays-pi ntpd[1237]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf
Apr 01 12:03:55 avishays-pi ntpd[1237]: CONFIG: restrict nopeer ignored
Apr 01 12:03:55 avishays-pi ifplugd[1150]: Network Interface Plugging Daemon...start eth0...done.
Apr 01 12:03:55 avishays-pi systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically.
Apr 01 12:03:55 avishays-pi rpi-eeprom-update[1017]: BOOTLOADER: up to date
Apr 01 12:03:55 avishays-pi rpi-eeprom-update[1017]: CURRENT: Mon Dec 8 19:29:54 UTC 2025 (1765222194)
Apr 01 12:03:55 avishays-pi rpi-eeprom-update[1017]: LATEST: Mon Dec 8 19:29:54 UTC 2025 (1765222194)
Apr 01 12:03:55 avishays-pi rpi-eeprom-update[1017]: RELEASE: default (/usr/lib/firmware/raspberrypi/bootloader-2712/default)
Apr 01 12:03:55 avishays-pi rpi-eeprom-update[1017]: Use raspi-config to change the release.
Apr 01 12:03:55 avishays-pi systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates.
Apr 01 12:03:55 avishays-pi ntpd[1237]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Apr 01 12:03:55 avishays-pi ntpd[1237]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37
Apr 01 12:03:55 avishays-pi ntpd[1237]: INIT: Using SO_TIMESTAMPNS(ns)
Apr 01 12:03:55 avishays-pi ntpd[1237]: IO: Listen and drop on 0 v6wildcard [::]:123
Apr 01 12:03:55 avishays-pi ntpd[1237]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123
Apr 01 12:03:55 avishays-pi ntpd[1237]: IO: Listen normally on 2 lo 127.0.0.1:123
Apr 01 12:03:55 avishays-pi ntpd[1237]: IO: Listening on routing socket on fd #19 for interface updates
Apr 01 12:03:55 avishays-pi systemd[1]: Finished welcome.service - Show a welcome message on console.
Apr 01 12:03:55 avishays-pi ntpd[1237]: INIT: This system has a 32-bit time_t.
Apr 01 12:03:55 avishays-pi ntpd[1237]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z.
Apr 01 12:03:55 avishays-pi ntpd[1237]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Apr 01 12:03:55 avishays-pi ntpd[1237]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090
Apr 01 12:03:55 avishays-pi ntpd[1237]: INIT: Running with OpenSSL 3.0.18 30 Sep 2025, 30000120
Apr 01 12:03:55 avishays-pi ntpd[1237]: NTSc: Using system default root certificates.
Apr 01 12:03:55 avishays-pi ntpd[1237]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory
Apr 01 12:03:56 avishays-pi bluealsa[1142]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa
Apr 01 12:03:56 avishays-pi bluealsa[1142]: main.c:663: Starting main dispatching loop
Apr 01 12:03:56 avishays-pi systemd[1]: Started bluealsa.service - BlueALSA service.
Apr 01 12:03:56 avishays-pi bluealsa[1142]: main.c:118: Acquired D-Bus service name: org.bluealsa
Apr 01 12:03:56 avishays-pi bluealsa[1142]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa
Apr 01 12:03:56 avishays-pi systemd[1]: Reached target bluetooth.target - Bluetooth Support.
Apr 01 12:03:56 avishays-pi avahi-daemon[1011]: Server startup complete. Host name is avishays-pi.local. Local service cookie is 1792481796.
Apr 01 12:03:56 avishays-pi bluealsa[1142]: bluez.c:153: Registering media application: /org/bluez/hci0
Apr 01 12:03:56 avishays-pi bluealsa[1142]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1
Apr 01 12:03:56 avishays-pi bluealsa[1142]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2
Apr 01 12:03:56 avishays-pi bluealsa[1142]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1
Apr 01 12:03:56 avishays-pi bluealsa[1142]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2
Apr 01 12:03:56 avishays-pi bluetoothd[1012]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1
Apr 01 12:03:56 avishays-pi bluetoothd[1012]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2
Apr 01 12:03:56 avishays-pi bluetoothd[1012]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2
Apr 01 12:03:56 avishays-pi bluetoothd[1012]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1
Apr 01 12:03:56 avishays-pi ntpd[1237]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101
Apr 01 12:03:56 avishays-pi ntpd[1237]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101
Apr 01 12:03:56 avishays-pi ntpd[1237]: DNS: dns_check: DNS error: -11, System error
Apr 01 12:03:56 avishays-pi ntpd[1237]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12
Apr 01 12:03:57 avishays-pi systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'.
Apr 01 12:03:57 avishays-pi systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped.
Apr 01 12:03:57 avishays-pi avahi-daemon[1011]: Service "Avishays Pi" (/services/volumio.service) successfully established.
Apr 01 12:03:57 avishays-pi nmbd[1392]: [2026/04/01 12:03:57.207941, 0] ../../source3/nmbd/nmbd.c:901(main)
Apr 01 12:03:57 avishays-pi nmbd[1392]: nmbd version 4.17.8-Raspbian started.
Apr 01 12:03:57 avishays-pi nmbd[1392]: Copyright Andrew Tridgell and the Samba Team 1992-2022
Apr 01 12:03:57 avishays-pi nmbd[1392]: [2026/04/01 12:03:57.215582, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns)
Apr 01 12:03:57 avishays-pi nmbd[1392]: started asyncdns process 1394
Apr 01 12:03:57 avishays-pi nmbd[1392]: [2026/04/01 12:03:57.216008, 0] ../../lib/util/become_daemon.c:150(daemon_status)
Apr 01 12:03:57 avishays-pi nmbd[1392]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...
Apr 01 12:03:57 avishays-pi nmbd[1392]: [2026/04/01 12:03:57.216031, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets)
Apr 01 12:03:57 avishays-pi nmbd[1392]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6).
Apr 01 12:03:57 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless
Apr 01 12:03:57 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow
Apr 01 12:03:57 avishays-pi sudo[1415]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Apr 01 12:03:57 avishays-pi sudo[1415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Apr 01 12:03:57 avishays-pi sudo[1415]: pam_unix(sudo:session): session closed for user root
Apr 01 12:03:57 avishays-pi sudo[1418]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Apr 01 12:03:57 avishays-pi sudo[1418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Apr 01 12:03:57 avishays-pi ntpd[1237]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101
Apr 01 12:03:57 avishays-pi ntpd[1237]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101
Apr 01 12:03:57 avishays-pi ntpd[1237]: DNS: dns_check: DNS error: -11, System error
Apr 01 12:03:57 avishays-pi ntpd[1237]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12
Apr 01 12:03:58 avishays-pi sudo[1418]: pam_unix(sudo:session): session closed for user root
Apr 01 12:03:58 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: Cleaning previous...
Apr 01 12:03:58 avishays-pi sudo[1422]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Apr 01 12:03:58 avishays-pi sudo[1422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Apr 01 12:03:58 avishays-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Apr 01 12:03:58 avishays-pi sudo[1422]: pam_unix(sudo:session): session closed for user root
Apr 01 12:03:58 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations
Apr 01 12:03:58 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 1ms
Apr 01 12:03:58 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 2c:cf:67:5e:e6:d6)
Apr 01 12:03:58 avishays-pi sudo[1433]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get
Apr 01 12:03:58 avishays-pi sudo[1433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:03:58 avishays-pi sudo[1433]: pam_unix(sudo:session): session closed for user root
Apr 01 12:03:58 avishays-pi sudo[1443]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan
Apr 01 12:03:58 avishays-pi sudo[1443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:03:58 avishays-pi systemd[1]: systemd-rfkill.service: Deactivated successfully.
Apr 01 12:03:58 avishays-pi ntpd[1237]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101
Apr 01 12:03:58 avishays-pi ntpd[1237]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101
Apr 01 12:03:58 avishays-pi ntpd[1237]: DNS: dns_check: DNS error: -11, System error
Apr 01 12:03:58 avishays-pi ntpd[1237]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12
Apr 01 12:03:59 avishays-pi nvm-exec[1157]: Starting Plexamp 4.12.4
Apr 01 12:03:59 avishays-pi nvm-exec[1157]: Settings: Parsing 27928 bytes into JSON for discovery:hubs.
Apr 01 12:03:59 avishays-pi ntpd[1237]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101
Apr 01 12:03:59 avishays-pi ntpd[1237]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101
Apr 01 12:03:59 avishays-pi ntpd[1237]: DNS: dns_check: DNS error: -11, System error
Apr 01 12:03:59 avishays-pi ntpd[1237]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12
Apr 01 12:04:00 avishays-pi volumio-remote-updater[1024]: [2026-04-01 12:04:00] [info] asio async_connect error: asio.system:111 (Connection refused)
Apr 01 12:04:00 avishays-pi volumio-remote-updater[1024]: [2026-04-01 12:04:00] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected)
Apr 01 12:04:00 avishays-pi volumio-remote-updater[1024]: [2026-04-01 12:04:00] [error] handle_connect error: Connection refused
Apr 01 12:04:01 avishays-pi sudo[1443]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:01 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: IL
Apr 01 12:04:01 avishays-pi sudo[1527]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set IL
Apr 01 12:04:01 avishays-pi sudo[1527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:01 avishays-pi sudo[1527]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:01 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: IL
Apr 01 12:04:01 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot
Apr 01 12:04:01 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations
Apr 01 12:04:01 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 1ms
Apr 01 12:04:01 avishays-pi sudo[1537]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Apr 01 12:04:01 avishays-pi sudo[1537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Apr 01 12:04:01 avishays-pi sudo[1537]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:01 avishays-pi sudo[1539]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Apr 01 12:04:01 avishays-pi sudo[1539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Apr 01 12:04:01 avishays-pi sudo[1539]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:01 avishays-pi avahi-daemon[1011]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Apr 01 12:04:01 avishays-pi systemd[1]: welcome.service: Deactivated successfully.
Apr 01 12:04:01 avishays-pi systemd[1]: Stopped welcome.service - Show a welcome message on console.
Apr 01 12:04:01 avishays-pi systemd[1]: Stopping welcome.service - Show a welcome message on console...
Apr 01 12:04:01 avishays-pi avahi-daemon[1011]: New relevant interface wlan0.IPv4 for mDNS.
Apr 01 12:04:01 avishays-pi avahi-daemon[1011]: Registering new address record for 192.168.211.1 on wlan0.IPv4.
Apr 01 12:04:01 avishays-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Apr 01 12:04:01 avishays-pi systemd[1]: Starting welcome.service - Show a welcome message on console...
Apr 01 12:04:01 avishays-pi welcome[1550]: Resolved ip:[1] 192.168.211.1
Apr 01 12:04:01 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: Notified systemd about wireless ready
Apr 01 12:04:01 avishays-pi systemd[1]: Finished welcome.service - Show a welcome message on console.
Apr 01 12:04:01 avishays-pi systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0.
Apr 01 12:04:01 avishays-pi systemd[1]: Started wireless.service - Wireless Services.
Apr 01 12:04:01 avishays-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Apr 01 12:04:01 avishays-pi systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet...
Apr 01 12:04:01 avishays-pi systemd[1]: Started volumio.service - Volumio Backend Module.
Apr 01 12:04:01 avishays-pi systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen...
Apr 01 12:04:01 avishays-pi systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker.
Apr 01 12:04:01 avishays-pi systemd[1]: Received SIGRTMIN+21 from PID 403 (plymouthd).
Apr 01 12:04:01 avishays-pi systemd[1]: Received SIGRTMIN+21 from PID 403 (plymouthd).
Apr 01 12:04:01 avishays-pi systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen.
Apr 01 12:04:01 avishays-pi systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up.
Apr 01 12:04:01 avishays-pi systemd[1]: Started getty@tty1.service - Getty on tty1.
Apr 01 12:04:01 avishays-pi volumio-cpu-tweak[1563]: Setting RT Priority for mpd
Apr 01 12:04:01 avishays-pi systemd[1]: Started serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10.
Apr 01 12:04:01 avishays-pi systemd[1]: Reached target getty.target - Login Prompts.
Apr 01 12:04:01 avishays-pi volumio-cpu-tweak[1580]: pid 35's current scheduling policy: SCHED_OTHER
Apr 01 12:04:01 avishays-pi volumio-cpu-tweak[1580]: pid 35's current scheduling priority: 0
Apr 01 12:04:01 avishays-pi volumio-cpu-tweak[1563]: Setting MPD Affinity
Apr 01 12:04:01 avishays-pi volumio-cpu-tweak[1581]: pid 3's current affinity mask: f
Apr 01 12:04:01 avishays-pi volumio-cpu-tweak[1563]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input
Apr 01 12:04:01 avishays-pi volumio-cpu-tweak[1563]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative
Apr 01 12:04:01 avishays-pi systemd[1]: volumio_cpu_tweak.service: Deactivated successfully.
Apr 01 12:04:01 avishays-pi dhcpcd[1059]: wlan0: carrier acquired
Apr 01 12:04:01 avishays-pi hostapd[1560]: wlan0: interface state UNINITIALIZED->ENABLED
Apr 01 12:04:01 avishays-pi hostapd[1560]: wlan0: AP-ENABLED
Apr 01 12:04:01 avishays-pi systemd[1]: Started hostapd.service - Access point and authentication server for Wi-Fi and Ethernet.
Apr 01 12:04:01 avishays-pi dhcpcd[1059]: wlan0: IAID 67:5e:e6:d6
Apr 01 12:04:01 avishays-pi dhcpcd[1059]: wlan0: adding address fe80::3e2a:2d2b:a50c:c051
Apr 01 12:04:01 avishays-pi dhcpcd[1059]: ipv6_addaddr1: Permission denied
Apr 01 12:04:01 avishays-pi systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server...
Apr 01 12:04:02 avishays-pi systemd[1]: Started nmbd.service - Samba NMB Daemon.
Apr 01 12:04:02 avishays-pi dhcpcd[1059]: wlan0: soliciting an IPv6 router
Apr 01 12:04:02 avishays-pi systemd[1]: Starting winbind.service - Samba Winbind Daemon...
Apr 01 12:04:02 avishays-pi dnsmasq[1593]: started, version 2.90 cachesize 150
Apr 01 12:04:02 avishays-pi dnsmasq[1593]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile
Apr 01 12:04:02 avishays-pi dnsmasq-dhcp[1593]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d
Apr 01 12:04:02 avishays-pi dnsmasq[1593]: reading /etc/resolv.conf
Apr 01 12:04:02 avishays-pi dnsmasq[1593]: using nameserver 208.67.222.222#53
Apr 01 12:04:02 avishays-pi dnsmasq[1593]: using nameserver 208.67.220.220#53
Apr 01 12:04:02 avishays-pi dnsmasq[1593]: read /etc/hosts - 4 names
Apr 01 12:04:02 avishays-pi systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server.
Apr 01 12:04:02 avishays-pi systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Apr 01 12:04:02 avishays-pi winbindd[1596]: [2026/04/01 12:04:02.208607, 0] ../../source3/winbindd/winbindd.c:1440(main)
Apr 01 12:04:02 avishays-pi winbindd[1596]: winbindd version 4.17.8-Raspbian started.
Apr 01 12:04:02 avishays-pi winbindd[1596]: Copyright Andrew Tridgell and the Samba Team 1992-2022
Apr 01 12:04:02 avishays-pi winbindd[1596]: [2026/04/01 12:04:02.215459, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache)
Apr 01 12:04:02 avishays-pi winbindd[1596]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Apr 01 12:04:02 avishays-pi systemd[1]: Started winbind.service - Samba Winbind Daemon.
Apr 01 12:04:02 avishays-pi systemd[1]: Starting smbd.service - Samba SMB Daemon...
Apr 01 12:04:02 avishays-pi smbd[1605]: [2026/04/01 12:04:02.357954, 0] ../../source3/smbd/server.c:1741(main)
Apr 01 12:04:02 avishays-pi smbd[1605]: smbd version 4.17.8-Raspbian started.
Apr 01 12:04:02 avishays-pi smbd[1605]: Copyright Andrew Tridgell and the Samba Team 1992-2022
Apr 01 12:04:02 avishays-pi systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0.
Apr 01 12:04:02 avishays-pi systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0...
Apr 01 12:04:02 avishays-pi systemd[1]: welcome.service: Deactivated successfully.
Apr 01 12:04:02 avishays-pi systemd[1]: Stopped welcome.service - Show a welcome message on console.
Apr 01 12:04:02 avishays-pi systemd[1]: Stopping welcome.service - Show a welcome message on console...
Apr 01 12:04:02 avishays-pi systemd[1]: Starting welcome.service - Show a welcome message on console...
Apr 01 12:04:02 avishays-pi welcome[1621]: Resolved ip:[1] 192.168.211.1
Apr 01 12:04:02 avishays-pi systemd[1]: Finished welcome.service - Show a welcome message on console.
Apr 01 12:04:02 avishays-pi systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0.
Apr 01 12:04:02 avishays-pi systemd[1]: Started smbd.service - Samba SMB Daemon.
Apr 01 12:04:02 avishays-pi bash[1163]: setdatetime-helper: all HTTPS Date fallbacks failed
Apr 01 12:04:02 avishays-pi systemd[1]: setdatetime-helper.service: Deactivated successfully.
Apr 01 12:04:02 avishays-pi systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Apr 01 12:04:02 avishays-pi systemd[1]: Reached target multi-user.target - Multi-User System.
Apr 01 12:04:02 avishays-pi systemd[1]: Reached target graphical.target - Graphical Interface.
Apr 01 12:04:02 avishays-pi systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP...
Apr 01 12:04:02 avishays-pi systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully.
Apr 01 12:04:02 avishays-pi systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP.
Apr 01 12:04:02 avishays-pi systemd[1]: Startup finished in 11.018s (kernel) + 10.225s (userspace) = 21.243s.
Apr 01 12:04:02 avishays-pi volumio[1561]: info: -------------------------------------------
Apr 01 12:04:02 avishays-pi volumio[1561]: info: ----- Volumio3 ----
Apr 01 12:04:02 avishays-pi volumio[1561]: info: -------------------------------------------
Apr 01 12:04:02 avishays-pi volumio[1561]: info: ----- System startup ----
Apr 01 12:04:02 avishays-pi volumio[1561]: info: -------------------------------------------
Apr 01 12:04:03 avishays-pi volumio[1561]: info: MYVOLUMIO Environment detected
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Plugin folders cleanup
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Scanning into folder /volumio/app/plugins/
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Scanning category audio_interface
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Scanning category miscellanea
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Scanning category music_service
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Scanning category plugins.json
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Scanning category system_controller
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Scanning category user_interface
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Scanning into folder /data/plugins/
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Scanning category audio_interface
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Scanning category music_service
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Plugin folders cleanup completed
Apr 01 12:04:03 avishays-pi volumio[1561]: info: -------------------------------------------
Apr 01 12:04:03 avishays-pi volumio[1561]: info: ----- Core plugins startup ----
Apr 01 12:04:03 avishays-pi volumio[1561]: info: -------------------------------------------
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Loading plugins from folder /volumio/app/plugins/
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Adding plugin upnp to MyMusic Plugins
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Loading plugins from folder /data/plugins/
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Loading plugin "system"...
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Loading plugin "appearance"...
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Loading plugin "network"...
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Refreshing Cached IP Addresses
Apr 01 12:04:03 avishays-pi sudo[1645]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 01 12:04:03 avishays-pi sudo[1645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:03 avishays-pi sudo[1647]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 01 12:04:03 avishays-pi sudo[1647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:03 avishays-pi sudo[1645]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:03 avishays-pi sudo[1647]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Loading plugin "services"...
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Loading plugin "alsa_controller"...
Apr 01 12:04:03 avishays-pi sudo[1660]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Apr 01 12:04:03 avishays-pi sudo[1660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:03 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Loading plugin "wizard"...
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Loading plugin "networkfs"...
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Starting Udev Watcher for removable devices
Apr 01 12:04:03 avishays-pi sudo[1681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o 'username=Avishay Blacher,password=719191,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft' //10.100.102.96/AvishaysWD/Music /mnt/NAS/Mac_Music
Apr 01 12:04:03 avishays-pi sudo[1681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Ignoring mount for partition: boot
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Ignoring mount for partition: volumio
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Ignoring mount for partition: volumio_data
Apr 01 12:04:03 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Loading plugin "volumio_command_line_client"...
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Loading plugin "upnp"...
Apr 01 12:04:03 avishays-pi volumio[1561]: info: [1775034243745] Starting Upmpd Daemon
Apr 01 12:04:03 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Loading plugin "my_music"...
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Loading plugin "mpd"...
Apr 01 12:04:03 avishays-pi kernel: netfs: FS-Cache loaded
Apr 01 12:04:03 avishays-pi dhcpcd[1059]: wlan0: soliciting a DHCP lease
Apr 01 12:04:03 avishays-pi sudo[1681]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:03 avishays-pi kernel: Key type cifs.spnego registered
Apr 01 12:04:03 avishays-pi kernel: Key type cifs.idmap registered
Apr 01 12:04:03 avishays-pi 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.
Apr 01 12:04:03 avishays-pi kernel: CIFS: Attempting to mount //10.100.102.96/AvishaysWD/Music
Apr 01 12:04:03 avishays-pi kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Apr 01 12:04:03 avishays-pi kernel: CIFS: VFS: cifs_mount failed w/return code = -101
Apr 01 12:04:03 avishays-pi ntpd[1237]: IO: Listen normally on 3 wlan0 192.168.211.1:123
Apr 01 12:04:03 avishays-pi ntpd[1237]: IO: new interface(s) found: waking up resolver
Apr 01 12:04:03 avishays-pi ntpd[1237]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101
Apr 01 12:04:03 avishays-pi ntpd[1237]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101
Apr 01 12:04:03 avishays-pi ntpd[1237]: DNS: dns_check: DNS error: -11, System error
Apr 01 12:04:03 avishays-pi ntpd[1237]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12
Apr 01 12:04:03 avishays-pi volumio[1561]: info: Loading plugin "upnp_browser"...
Apr 01 12:04:04 avishays-pi volumio[1561]: info: Starting UPNP Browser
Apr 01 12:04:04 avishays-pi volumio[1561]: info: Loading plugin "alarm-clock"...
Apr 01 12:04:04 avishays-pi volumio[1561]: info: Loading plugin "airplay_emulation"...
Apr 01 12:04:04 avishays-pi volumio[1561]: info: Starting Shairport Sync
Apr 01 12:04:04 avishays-pi volumio[1561]: info: Loading plugin "last_100"...
Apr 01 12:04:04 avishays-pi volumio[1561]: info: Loading plugin "webradio"...
Apr 01 12:04:04 avishays-pi volumio[1561]: info: Loading plugin "i2s_dacs"...
Apr 01 12:04:04 avishays-pi volumio[1561]: info: Loading plugin "volumiodiscovery"...
Apr 01 12:04:04 avishays-pi volumio[1561]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 01 12:04:04 avishays-pi volumio[1561]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 01 12:04:04 avishays-pi volumio[1561]: *** WARNING *** For more information see
Apr 01 12:04:04 avishays-pi volumio[1561]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 01 12:04:04 avishays-pi volumio[1561]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 01 12:04:04 avishays-pi volumio[1561]: *** WARNING *** For more information see
Apr 01 12:04:04 avishays-pi node[1561]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 01 12:04:04 avishays-pi node[1561]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 01 12:04:04 avishays-pi node[1561]: *** WARNING *** For more information see
Apr 01 12:04:04 avishays-pi node[1561]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 01 12:04:04 avishays-pi node[1561]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 01 12:04:04 avishays-pi node[1561]: *** WARNING *** For more information see
Apr 01 12:04:04 avishays-pi volumio[1561]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 01 12:04:04 avishays-pi volumio[1561]: info: Discovery: Started advertising with name: Avishays Pi
Apr 01 12:04:04 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 01 12:04:04 avishays-pi volumio[1561]: info: Loading plugin "spop"...
Apr 01 12:04:04 avishays-pi ntpd[1237]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101
Apr 01 12:04:04 avishays-pi ntpd[1237]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101
Apr 01 12:04:04 avishays-pi ntpd[1237]: DNS: dns_check: DNS error: -11, System error
Apr 01 12:04:04 avishays-pi ntpd[1237]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12
Apr 01 12:04:04 avishays-pi volumio[1561]: info: Loading plugin "youtube2"...
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Loading plugin "ytmusic"...
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Loading plugin "outputs"...
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Loading plugin "albumart"...
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Plugin example_plugin is not enabled
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Loading plugin "inputs"...
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Loading plugin "updater_comm"...
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Plugin mpdemulation is not enabled
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Loading plugin "rest_api"...
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Loading plugin "websocket"...
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Starting Socket.io Server version 1.7.4
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Plugin fusiondsp is not enabled
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Loading plugin "motherearthradio"...
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Loading i18n strings for locale en
Apr 01 12:04:05 avishays-pi volumio[1561]: Updating browse sources language
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 01 12:04:05 avishays-pi volumio[1708]: Forking 3 albumart workers
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::initPlayerControls
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: Express server listening on port 3000
Apr 01 12:04:05 avishays-pi volumio[1561]: [Metrics] WebUI: 2s 938.92ms
Apr 01 12:04:05 avishays-pi sudo[1660]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreStateMachine::resetVolumioState
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreStateMachine::getcurrentVolume
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioRetrievevolume
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Volumio Network Manager: Network status updated: 0
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Cannot mount NAS Mac_Music at system boot, trial number 1 ,retrying in 5 seconds
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Reloading queue from file
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreStateMachine::setRepeat null single undefined
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreStateMachine::pushState
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CorePlayQueue::getTrack 0
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioPushState
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreStateMachine::setRandom null
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreStateMachine::pushState
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CorePlayQueue::getTrack 0
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioPushState
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Setting Device type: Raspberry PI
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Completed loading Core Plugins
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Preparing to generate the ALSA configuration file
Apr 01 12:04:05 avishays-pi volumio[1561]: info: VolumeController:: Volume=100 Mute =false
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreStateMachine::pushState
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CorePlayQueue::getTrack 0
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioPushState
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreStateMachine::updateTrackBlock
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CorePlayQueue::getTrackBlock
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioRetrievevolume
Apr 01 12:04:05 avishays-pi volumio[1561]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb
Apr 01 12:04:05 avishays-pi volumio[1561]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170
Apr 01 12:04:05 avishays-pi volumio[1561]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Discovery: adding 4b6d9f93-c218-459b-8cda-d637db40c119
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Discovery: Found device Avishays Pi
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioGetState
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CorePlayQueue::getTrack 0
Apr 01 12:04:05 avishays-pi volumio[1561]: info: VolumeController:: Volume=100 Mute =false
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreStateMachine::pushState
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CorePlayQueue::getTrack 0
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioPushState
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Asound.conf file unchanged, so no further update is needed
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Output device has changed, restarting MPD
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Output device has changed, restarting Shairport Sync
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 01 12:04:05 avishays-pi volumio[1561]: info: ___________ START PLUGINS ___________
Apr 01 12:04:05 avishays-pi sudo[1769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 01 12:04:05 avishays-pi volumio[1561]: info: ControllerMpd::onStart: Initializing MPD
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Creating MPD Configuration file
Apr 01 12:04:05 avishays-pi sudo[1769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:05 avishays-pi sudo[1767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 01 12:04:05 avishays-pi sudo[1767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:05 avishays-pi sudo[1767]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 01 12:04:05 avishays-pi volumio[1561]: info: [1775034245733] CoreMusicLibrary::Adding element Media Servers
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 01 12:04:05 avishays-pi sudo[1775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 01 12:04:05 avishays-pi volumio[1561]: info: UPNP Browser: Client initialized successfully
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 01 12:04:05 avishays-pi sudo[1779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 01 12:04:05 avishays-pi sudo[1779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:05 avishays-pi sudo[1775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:05 avishays-pi sudo[1775]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 01 12:04:05 avishays-pi volumio[1561]: info: [1775034245773] CoreMusicLibrary::Adding element Last_100
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 01 12:04:05 avishays-pi volumio[1561]: info: [1775034245774] CoreMusicLibrary::Adding element Webradio
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Initializing BBC Radios
Apr 01 12:04:05 avishays-pi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Apr 01 12:04:05 avishays-pi systemd[1]: Starting mpd.service - Music Player Daemon...
Apr 01 12:04:05 avishays-pi volumio[1720]: Starting albumart workers
Apr 01 12:04:05 avishays-pi systemd[1]: mpd.service: Deactivated successfully.
Apr 01 12:04:05 avishays-pi systemd[1]: Stopped mpd.service - Music Player Daemon.
Apr 01 12:04:05 avishays-pi systemd[1]: mpd.socket: Deactivated successfully.
Apr 01 12:04:05 avishays-pi systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Apr 01 12:04:05 avishays-pi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Apr 01 12:04:05 avishays-pi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Apr 01 12:04:05 avishays-pi systemd[1]: Starting mpd.service - Music Player Daemon...
Apr 01 12:04:05 avishays-pi volumio[1718]: Starting albumart workers
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Creating Spotify config file
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 01 12:04:05 avishays-pi volumio[1561]: info: [1775034245827] CoreMusicLibrary::Adding element YouTube2
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 01 12:04:05 avishays-pi volumio[1561]: Cannot find translation for source YouTube2
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 01 12:04:05 avishays-pi volumio[1561]: info: [1775034245835] CoreMusicLibrary::Adding element YouTube Music
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 01 12:04:05 avishays-pi volumio[1561]: Cannot find translation for source YouTube2
Apr 01 12:04:05 avishays-pi volumio[1561]: Cannot find translation for source YouTube Music
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 01 12:04:05 avishays-pi volumio[1561]: info: [1775034245841] CoreMusicLibrary::Adding element Mother Earth Radio
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 01 12:04:05 avishays-pi volumio[1561]: Cannot find translation for source YouTube2
Apr 01 12:04:05 avishays-pi volumio[1561]: Cannot find translation for source YouTube Music
Apr 01 12:04:05 avishays-pi volumio[1561]: Cannot find translation for source Mother Earth Radio
Apr 01 12:04:05 avishays-pi volumio[1561]: info: [MER] Plugin started (SSE mode)
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Volumio Calling Home
Apr 01 12:04:05 avishays-pi sudo[1801]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Apr 01 12:04:05 avishays-pi sudo[1801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Apr 01 12:04:05 avishays-pi sudo[1809]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Apr 01 12:04:05 avishays-pi sudo[1801]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:05 avishays-pi ntpd[1237]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101
Apr 01 12:04:05 avishays-pi ntpd[1237]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101
Apr 01 12:04:05 avishays-pi ntpd[1237]: DNS: dns_check: DNS error: -11, System error
Apr 01 12:04:05 avishays-pi ntpd[1237]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12
Apr 01 12:04:05 avishays-pi volumio[1719]: Starting albumart workers
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Discovery: this is already registered, 4b6d9f93-c218-459b-8cda-d637db40c119
Apr 01 12:04:05 avishays-pi volumio[1561]: info: Discovery: Found device Avishays Pi
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioGetState
Apr 01 12:04:05 avishays-pi volumio[1561]: info: CorePlayQueue::getTrack 0
Apr 01 12:04:06 avishays-pi volumio[1561]: info: MPD Permissions set
Apr 01 12:04:06 avishays-pi volumio[1561]: info: MPD Permissions set
Apr 01 12:04:06 avishays-pi volumio[1561]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1
Apr 01 12:04:06 avishays-pi volumio[1561]: info: Spotify config file written
Apr 01 12:04:06 avishays-pi volumio[1561]: info: No need to fix Spotify hosts
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioGetState
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CorePlayQueue::getTrack 0
Apr 01 12:04:06 avishays-pi sudo[1836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Apr 01 12:04:06 avishays-pi sudo[1836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:06 avishays-pi systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 01 12:04:06 avishays-pi systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:06 avishays-pi volumio[1561]: info: Starting Shairport Sync
Apr 01 12:04:06 avishays-pi volumio[1561]: info: Starting Shairport Sync
Apr 01 12:04:06 avishays-pi volumio[1561]: info: Starting Shairport Sync
Apr 01 12:04:06 avishays-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:06 avishays-pi go-librespot[1838]: go-librespot daemon starting...
Apr 01 12:04:06 avishays-pi sudo[1850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 01 12:04:06 avishays-pi sudo[1850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:06 avishays-pi sudo[1852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 01 12:04:06 avishays-pi sudo[1852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:06 avishays-pi sudo[1836]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:06 avishays-pi sudo[1855]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 01 12:04:06 avishays-pi sudo[1855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:06 avishays-pi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Apr 01 12:04:06 avishays-pi systemd[1]: shairport-sync.service: Deactivated successfully.
Apr 01 12:04:06 avishays-pi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 01 12:04:06 avishays-pi systemd[1]: shairport-sync.service: Consumed 1.610s CPU time.
Apr 01 12:04:06 avishays-pi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Apr 01 12:04:06 avishays-pi sudo[1852]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:06 avishays-pi sudo[1855]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:06 avishays-pi sudo[1850]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:06 avishays-pi volumio[1561]: info: Shairport-Sync Started
Apr 01 12:04:06 avishays-pi volumio[1561]: Error adding Membership: Error: addMembership EINVAL
Apr 01 12:04:06 avishays-pi volumio[1561]: info: Shairport-Sync Started
Apr 01 12:04:06 avishays-pi volumio[1561]: info: Shairport-Sync Started
Apr 01 12:04:06 avishays-pi ntpd[1237]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101
Apr 01 12:04:06 avishays-pi ntpd[1237]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101
Apr 01 12:04:06 avishays-pi ntpd[1237]: DNS: dns_check: DNS error: -11, System error
Apr 01 12:04:06 avishays-pi ntpd[1237]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12
Apr 01 12:04:06 avishays-pi go-librespot[1854]: time="2026-04-01T12:04:06+03:00" level=info msg="running go-librespot 0.6.2"
Apr 01 12:04:06 avishays-pi go-librespot[1854]: time="2026-04-01T12:04:06+03:00" level=debug msg="app state loaded"
Apr 01 12:04:06 avishays-pi go-librespot[1854]: time="2026-04-01T12:04:06+03:00" level=debug msg="stored credentials not found"
Apr 01 12:04:06 avishays-pi go-librespot[1854]: time="2026-04-01T12:04:06+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Apr 01 12:04:06 avishays-pi go-librespot[1854]: time="2026-04-01T12:04:06+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Apr 01 12:04:06 avishays-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Apr 01 12:04:06 avishays-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Apr 01 12:04:08 avishays-pi volumio-remote-updater[1024]: [2026-04-01 12:04:08] [connect] Successful connection
Apr 01 12:04:08 avishays-pi volumio-remote-updater[1024]: [2026-04-01 12:04:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1775034248 101
Apr 01 12:04:08 avishays-pi volumio[1561]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2
Apr 01 12:04:08 avishays-pi mpd[1810]: 2026-04-01T12:04:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 01 12:04:08 avishays-pi systemd[1]: Started mpd.service - Music Player Daemon.
Apr 01 12:04:08 avishays-pi sudo[1769]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:08 avishays-pi sudo[1779]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:08 avishays-pi volumio[1561]: info: Completed starting Core Plugins
Apr 01 12:04:08 avishays-pi volumio[1561]: info: -------------------------------------------
Apr 01 12:04:08 avishays-pi volumio[1561]: info: ----- MyVolumio plugins startup ----
Apr 01 12:04:08 avishays-pi volumio[1561]: info: -------------------------------------------
Apr 01 12:04:08 avishays-pi volumio[1561]: info: [MyVolumio PluginManager] Fetching plans data....
Apr 01 12:04:08 avishays-pi volumio[1561]: error: MPD error: The expression evaluated to a falsy value:
Apr 01 12:04:08 avishays-pi volumio[1561]: assert.ok(self.idling)
Apr 01 12:04:08 avishays-pi volumio[1561]: error: The expression evaluated to a falsy value:
Apr 01 12:04:08 avishays-pi volumio[1561]: assert.ok(self.idling)
Apr 01 12:04:08 avishays-pi volumio[1561]: info: MPD running with PID1810
Apr 01 12:04:08 avishays-pi volumio[1561]: ,establishing connection
Apr 01 12:04:08 avishays-pi volumio[1561]: error: updateQueue error: null
Apr 01 12:04:08 avishays-pi volumio[1561]: error: updateQueue error: null
Apr 01 12:04:09 avishays-pi volumio[1561]: info: go-librespot daemon successfully initialized
Apr 01 12:04:10 avishays-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Apr 01 12:04:10 avishays-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:10 avishays-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:10 avishays-pi go-librespot[1891]: go-librespot daemon starting...
Apr 01 12:04:10 avishays-pi go-librespot[1892]: time="2026-04-01T12:04:10+03:00" level=info msg="running go-librespot 0.6.2"
Apr 01 12:04:10 avishays-pi go-librespot[1892]: time="2026-04-01T12:04:10+03:00" level=debug msg="app state loaded"
Apr 01 12:04:10 avishays-pi go-librespot[1892]: time="2026-04-01T12:04:10+03:00" level=debug msg="stored credentials not found"
Apr 01 12:04:10 avishays-pi go-librespot[1892]: time="2026-04-01T12:04:10+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Apr 01 12:04:10 avishays-pi go-librespot[1892]: time="2026-04-01T12:04:10+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Apr 01 12:04:10 avishays-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Apr 01 12:04:10 avishays-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Apr 01 12:04:10 avishays-pi sudo[1899]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o 'username=Avishay Blacher,password=719191,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft' //10.100.102.96/AvishaysWD/Music /mnt/NAS/Mac_Music
Apr 01 12:04:10 avishays-pi sudo[1899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:10 avishays-pi sudo[1899]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:10 avishays-pi volumio[1561]: info: Cannot mount NAS Mac_Music at system boot, trial number 2 ,retrying in 5 seconds
Apr 01 12:04:10 avishays-pi kernel: CIFS: Attempting to mount //10.100.102.96/AvishaysWD/Music
Apr 01 12:04:10 avishays-pi kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Apr 01 12:04:10 avishays-pi kernel: CIFS: VFS: cifs_mount failed w/return code = -101
Apr 01 12:04:10 avishays-pi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1.
Apr 01 12:04:10 avishays-pi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 01 12:04:10 avishays-pi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 01 12:04:10 avishays-pi upmpdcli[1905]: Could not open config: /tmp/upmpdcli.conf
Apr 01 12:04:10 avishays-pi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Apr 01 12:04:10 avishays-pi systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Apr 01 12:04:12 avishays-pi volumio[1561]: info: Initializing connection to go-librespot Websocket
Apr 01 12:04:12 avishays-pi volumio[1561]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Apr 01 12:04:13 avishays-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Apr 01 12:04:13 avishays-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:13 avishays-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:13 avishays-pi go-librespot[1908]: go-librespot daemon starting...
Apr 01 12:04:13 avishays-pi go-librespot[1909]: time="2026-04-01T12:04:13+03:00" level=info msg="running go-librespot 0.6.2"
Apr 01 12:04:13 avishays-pi go-librespot[1909]: time="2026-04-01T12:04:13+03:00" level=debug msg="app state loaded"
Apr 01 12:04:13 avishays-pi go-librespot[1909]: time="2026-04-01T12:04:13+03:00" level=debug msg="stored credentials not found"
Apr 01 12:04:13 avishays-pi go-librespot[1909]: time="2026-04-01T12:04:13+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Apr 01 12:04:13 avishays-pi go-librespot[1909]: time="2026-04-01T12:04:13+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Apr 01 12:04:13 avishays-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Apr 01 12:04:13 avishays-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Apr 01 12:04:13 avishays-pi sudo[1930]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 01 12:04:13 avishays-pi sudo[1930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:13 avishays-pi sudo[1932]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 01 12:04:13 avishays-pi sudo[1932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:13 avishays-pi sudo[1930]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:13 avishays-pi sudo[1932]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:13 avishays-pi sudo[1936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 01 12:04:13 avishays-pi sudo[1936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:14 avishays-pi volumio[1561]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Apr 01 12:04:15 avishays-pi volumio[1561]: info: Initializing connection to go-librespot Websocket
Apr 01 12:04:15 avishays-pi volumio[1561]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Apr 01 12:04:15 avishays-pi sudo[1942]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o 'username=Avishay Blacher,password=719191,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft' //10.100.102.96/AvishaysWD/Music /mnt/NAS/Mac_Music
Apr 01 12:04:15 avishays-pi sudo[1942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:15 avishays-pi sudo[1942]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:15 avishays-pi volumio[1561]: info: Cannot mount NAS Mac_Music at system boot, trial number 3 ,retrying in 5 seconds
Apr 01 12:04:15 avishays-pi kernel: CIFS: Attempting to mount //10.100.102.96/AvishaysWD/Music
Apr 01 12:04:15 avishays-pi kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Apr 01 12:04:15 avishays-pi kernel: CIFS: VFS: cifs_mount failed w/return code = -101
Apr 01 12:04:16 avishays-pi volumio[1561]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.084&uuid=bf646dff0a2a15abd73c688a6acb0a60" http://updates.volumio.org/downloader-v1/track-device
Apr 01 12:04:16 avishays-pi volumio[1561]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 01 12:04:16 avishays-pi volumio[1561]: Dload Upload Total Spent Left Speed
Apr 01 12:04:16 avishays-pi volumio[1561]: [132B blob data]
Apr 01 12:04:16 avishays-pi volumio[1561]: retrying in 5 seconds, trial 0
Apr 01 12:04:16 avishays-pi volumio[1561]: info: Volumio Calling Home
Apr 01 12:04:16 avishays-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Apr 01 12:04:16 avishays-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:16 avishays-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:16 avishays-pi go-librespot[1949]: go-librespot daemon starting...
Apr 01 12:04:16 avishays-pi go-librespot[1950]: time="2026-04-01T12:04:16+03:00" level=info msg="running go-librespot 0.6.2"
Apr 01 12:04:16 avishays-pi go-librespot[1950]: time="2026-04-01T12:04:16+03:00" level=debug msg="app state loaded"
Apr 01 12:04:16 avishays-pi go-librespot[1950]: time="2026-04-01T12:04:16+03:00" level=debug msg="stored credentials not found"
Apr 01 12:04:16 avishays-pi go-librespot[1950]: time="2026-04-01T12:04:16+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Apr 01 12:04:16 avishays-pi go-librespot[1950]: time="2026-04-01T12:04:16+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Apr 01 12:04:16 avishays-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Apr 01 12:04:16 avishays-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Apr 01 12:04:18 avishays-pi volumio[1561]: info: Initializing connection to go-librespot Websocket
Apr 01 12:04:18 avishays-pi volumio[1561]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Apr 01 12:04:18 avishays-pi sh[911]: eth0: carrier acquired
Apr 01 12:04:18 avishays-pi dhcpcd[1059]: eth0: carrier acquired
Apr 01 12:04:18 avishays-pi dhcpcd[931]: eth0: carrier acquired
Apr 01 12:04:18 avishays-pi kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Apr 01 12:04:18 avishays-pi dhcpcd[1059]: eth0: IAID 67:5e:e6:d5
Apr 01 12:04:18 avishays-pi sh[911]: eth0: IAID 67:5e:e6:d5
Apr 01 12:04:18 avishays-pi dhcpcd[1059]: eth0: adding address fe80::5fe4:16e2:9f82:7f87
Apr 01 12:04:18 avishays-pi dhcpcd[931]: eth0: IAID 67:5e:e6:d5
Apr 01 12:04:18 avishays-pi dhcpcd[1059]: ipv6_addaddr1: Permission denied
Apr 01 12:04:18 avishays-pi dhcpcd[931]: eth0: adding address fe80::5fe4:16e2:9f82:7f87
Apr 01 12:04:18 avishays-pi sh[911]: eth0: adding address fe80::5fe4:16e2:9f82:7f87
Apr 01 12:04:18 avishays-pi sh[911]: ipv6_addaddr1: Permission denied
Apr 01 12:04:18 avishays-pi dhcpcd[931]: ipv6_addaddr1: Permission denied
Apr 01 12:04:18 avishays-pi ifplugd(eth0)[1220]: Link beat detected.
Apr 01 12:04:18 avishays-pi ifplugd(eth0)[1220]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Apr 01 12:04:18 avishays-pi dhcpcd[931]: eth0: soliciting an IPv6 router
Apr 01 12:04:18 avishays-pi sh[911]: eth0: soliciting an IPv6 router
Apr 01 12:04:18 avishays-pi ifplugd(eth0)[1220]: client: ifup: waiting for lock on /run/network/ifstate.eth0
Apr 01 12:04:18 avishays-pi dhcpcd[931]: eth0: rebinding lease of 10.100.102.93
Apr 01 12:04:18 avishays-pi sh[911]: eth0: rebinding lease of 10.100.102.93
Apr 01 12:04:19 avishays-pi dhcpcd[1059]: eth0: soliciting an IPv6 router
Apr 01 12:04:19 avishays-pi dhcpcd[1059]: eth0: rebinding lease of 10.100.102.93
Apr 01 12:04:19 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: === SNM TRANSITION ===
Apr 01 12:04:19 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: Previous ethernet state: disconnected
Apr 01 12:04:19 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: New ethernet state: connected
Apr 01 12:04:19 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: Single Network Mode: enabled
Apr 01 12:04:19 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: First start: no
Apr 01 12:04:19 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode)
Apr 01 12:04:19 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: === END TRANSITION ===
Apr 01 12:04:19 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: SNM: Ethernet connected, switching to ethernet (WiFi scan mode)
Apr 01 12:04:19 avishays-pi sudo[1975]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd -k wlan0
Apr 01 12:04:19 avishays-pi sudo[1975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Apr 01 12:04:19 avishays-pi dhcpcd[1976]: dhcpcd not running
Apr 01 12:04:19 avishays-pi sudo[1975]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:19 avishays-pi wireless.js[1026]: dhcpcd not running
Apr 01 12:04:19 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow
Apr 01 12:04:19 avishays-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Apr 01 12:04:19 avishays-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:19 avishays-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:19 avishays-pi go-librespot[1979]: go-librespot daemon starting...
Apr 01 12:04:19 avishays-pi go-librespot[1985]: time="2026-04-01T12:04:19+03:00" level=info msg="running go-librespot 0.6.2"
Apr 01 12:04:19 avishays-pi go-librespot[1985]: time="2026-04-01T12:04:19+03:00" level=debug msg="app state loaded"
Apr 01 12:04:19 avishays-pi go-librespot[1985]: time="2026-04-01T12:04:19+03:00" level=debug msg="stored credentials not found"
Apr 01 12:04:19 avishays-pi go-librespot[1985]: time="2026-04-01T12:04:19+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Apr 01 12:04:19 avishays-pi go-librespot[1985]: time="2026-04-01T12:04:19+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Apr 01 12:04:19 avishays-pi systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server...
Apr 01 12:04:19 avishays-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Apr 01 12:04:19 avishays-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Apr 01 12:04:19 avishays-pi dnsmasq[1593]: exiting on receipt of SIGTERM
Apr 01 12:04:19 avishays-pi systemd[1]: dnsmasq.service: Deactivated successfully.
Apr 01 12:04:19 avishays-pi systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server.
Apr 01 12:04:19 avishays-pi systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet...
Apr 01 12:04:20 avishays-pi dhcpcd[1059]: wlan0: carrier lost
Apr 01 12:04:20 avishays-pi systemd[1]: hostapd.service: Deactivated successfully.
Apr 01 12:04:20 avishays-pi systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet.
Apr 01 12:04:20 avishays-pi sudo[1999]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Apr 01 12:04:20 avishays-pi sudo[1999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Apr 01 12:04:20 avishays-pi avahi-daemon[1011]: Withdrawing address record for 192.168.211.1 on wlan0.
Apr 01 12:04:20 avishays-pi avahi-daemon[1011]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Apr 01 12:04:20 avishays-pi avahi-daemon[1011]: Interface wlan0.IPv4 no longer relevant for mDNS.
Apr 01 12:04:20 avishays-pi sudo[1999]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:20 avishays-pi volumio[1561]: info: Discovery: A device disappeared from network
Apr 01 12:04:20 avishays-pi sudo[2010]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Apr 01 12:04:20 avishays-pi sudo[2010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Apr 01 12:04:20 avishays-pi systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0.
Apr 01 12:04:20 avishays-pi systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0...
Apr 01 12:04:20 avishays-pi systemd[1]: welcome.service: Deactivated successfully.
Apr 01 12:04:20 avishays-pi systemd[1]: Stopped welcome.service - Show a welcome message on console.
Apr 01 12:04:20 avishays-pi systemd[1]: Stopping welcome.service - Show a welcome message on console...
Apr 01 12:04:20 avishays-pi systemd[1]: Starting welcome.service - Show a welcome message on console...
Apr 01 12:04:20 avishays-pi sudo[2010]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:20 avishays-pi welcome[2012]: Resolved ip:[0]
Apr 01 12:04:20 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: Cleaning previous...
Apr 01 12:04:20 avishays-pi sudo[2015]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o 'username=Avishay Blacher,password=719191,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft' //10.100.102.96/AvishaysWD/Music /mnt/NAS/Mac_Music
Apr 01 12:04:20 avishays-pi sudo[2015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:20 avishays-pi systemd[1]: Finished welcome.service - Show a welcome message on console.
Apr 01 12:04:20 avishays-pi sudo[2015]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:20 avishays-pi systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0.
Apr 01 12:04:20 avishays-pi kernel: CIFS: Attempting to mount //10.100.102.96/AvishaysWD/Music
Apr 01 12:04:20 avishays-pi kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Apr 01 12:04:20 avishays-pi kernel: CIFS: VFS: cifs_mount failed w/return code = -101
Apr 01 12:04:20 avishays-pi volumio[1561]: info: Cannot mount NAS Mac_Music at system boot, trial number 4 ,retrying in 5 seconds
Apr 01 12:04:20 avishays-pi sudo[2025]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Apr 01 12:04:20 avishays-pi sudo[2025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Apr 01 12:04:20 avishays-pi sudo[2025]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:20 avishays-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Apr 01 12:04:21 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations
Apr 01 12:04:21 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 0ms
Apr 01 12:04:21 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 2c:cf:67:5e:e6:d6)
Apr 01 12:04:21 avishays-pi sudo[2032]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get
Apr 01 12:04:21 avishays-pi sudo[2032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:21 avishays-pi sudo[2032]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:21 avishays-pi sudo[2040]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan
Apr 01 12:04:21 avishays-pi sudo[2040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:21 avishays-pi volumio[1561]: info: Initializing connection to go-librespot Websocket
Apr 01 12:04:21 avishays-pi volumio[1561]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Apr 01 12:04:22 avishays-pi sudo[2040]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:22 avishays-pi wireless.js[1026]: WIRELESS.JS - INFO: Regdomain already correct: IL
Apr 01 12:04:23 avishays-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Apr 01 12:04:23 avishays-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:23 avishays-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:23 avishays-pi go-librespot[2046]: go-librespot daemon starting...
Apr 01 12:04:23 avishays-pi go-librespot[2047]: time="2026-04-01T12:04:23+03:00" level=info msg="running go-librespot 0.6.2"
Apr 01 12:04:23 avishays-pi go-librespot[2047]: time="2026-04-01T12:04:23+03:00" level=debug msg="app state loaded"
Apr 01 12:04:23 avishays-pi go-librespot[2047]: time="2026-04-01T12:04:23+03:00" level=debug msg="stored credentials not found"
Apr 01 12:04:23 avishays-pi go-librespot[2047]: time="2026-04-01T12:04:23+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Apr 01 12:04:23 avishays-pi go-librespot[2047]: time="2026-04-01T12:04:23+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Apr 01 12:04:23 avishays-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Apr 01 12:04:23 avishays-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Apr 01 12:04:23 avishays-pi systemd[1]: systemd-fsckd.service: Deactivated successfully.
Apr 01 12:04:23 avishays-pi ntpd[1237]: IO: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=20 secs
Apr 01 12:04:23 avishays-pi dhcpcd[931]: eth0: DHCP lease expired
Apr 01 12:04:23 avishays-pi sh[911]: eth0: DHCP lease expired
Apr 01 12:04:23 avishays-pi dhcpcd[931]: eth0: soliciting a DHCP lease
Apr 01 12:04:23 avishays-pi sh[911]: eth0: soliciting a DHCP lease
Apr 01 12:04:24 avishays-pi volumio[1561]: info: Initializing connection to go-librespot Websocket
Apr 01 12:04:24 avishays-pi volumio[1561]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Apr 01 12:04:24 avishays-pi dhcpcd[931]: timed out
Apr 01 12:04:24 avishays-pi sh[911]: timed out
Apr 01 12:04:24 avishays-pi sh[852]: ifup: failed to bring up eth0
Apr 01 12:04:24 avishays-pi systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE
Apr 01 12:04:24 avishays-pi ifplugd(eth0)[1220]: client: sending commands to dhcpcd process
Apr 01 12:04:24 avishays-pi dhcpcd[1059]: control command: dhcpcd eth0
Apr 01 12:04:24 avishays-pi dhcpcd[1059]: control_free: No such file or directory
Apr 01 12:04:24 avishays-pi systemd[1]: ifup@eth0.service: Failed with result 'exit-code'.
Apr 01 12:04:24 avishays-pi ifplugd(eth0)[1220]: Program executed successfully.
Apr 01 12:04:24 avishays-pi dhcpcd[1059]: eth0: DHCP lease expired
Apr 01 12:04:24 avishays-pi dhcpcd[1059]: eth0: soliciting a DHCP lease
Apr 01 12:04:25 avishays-pi systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Apr 01 12:04:25 avishays-pi sudo[2140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o 'username=Avishay Blacher,password=719191,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft' //10.100.102.96/AvishaysWD/Music /mnt/NAS/Mac_Music
Apr 01 12:04:25 avishays-pi sudo[2140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 01 12:04:25 avishays-pi sudo[2140]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:25 avishays-pi kernel: CIFS: Attempting to mount //10.100.102.96/AvishaysWD/Music
Apr 01 12:04:25 avishays-pi kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Apr 01 12:04:25 avishays-pi kernel: CIFS: VFS: cifs_mount failed w/return code = -101
Apr 01 12:04:25 avishays-pi volumio[1561]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Apr 01 12:04:26 avishays-pi volumio[1561]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.084&uuid=bf646dff0a2a15abd73c688a6acb0a60" http://updates.volumio.org/downloader-v1/track-device
Apr 01 12:04:26 avishays-pi volumio[1561]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 01 12:04:26 avishays-pi volumio[1561]: Dload Upload Total Spent Left Speed
Apr 01 12:04:26 avishays-pi volumio[1561]: [132B blob data]
Apr 01 12:04:26 avishays-pi volumio[1561]: retrying in 5 seconds, trial 1
Apr 01 12:04:26 avishays-pi volumio[1561]: info: Volumio Calling Home
Apr 01 12:04:26 avishays-pi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2.
Apr 01 12:04:26 avishays-pi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 01 12:04:26 avishays-pi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 01 12:04:26 avishays-pi sudo[1936]: pam_unix(sudo:session): session closed for user root
Apr 01 12:04:26 avishays-pi volumio[1561]: info: Upmpdcli Daemon Started
Apr 01 12:04:26 avishays-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Apr 01 12:04:26 avishays-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:26 avishays-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:26 avishays-pi go-librespot[2155]: go-librespot daemon starting...
Apr 01 12:04:26 avishays-pi go-librespot[2156]: time="2026-04-01T12:04:26+03:00" level=info msg="running go-librespot 0.6.2"
Apr 01 12:04:26 avishays-pi go-librespot[2156]: time="2026-04-01T12:04:26+03:00" level=debug msg="app state loaded"
Apr 01 12:04:26 avishays-pi go-librespot[2156]: time="2026-04-01T12:04:26+03:00" level=debug msg="stored credentials not found"
Apr 01 12:04:26 avishays-pi go-librespot[2156]: time="2026-04-01T12:04:26+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Apr 01 12:04:26 avishays-pi go-librespot[2156]: time="2026-04-01T12:04:26+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Apr 01 12:04:26 avishays-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Apr 01 12:04:26 avishays-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Apr 01 12:04:27 avishays-pi volumio[1561]: info: Initializing connection to go-librespot Websocket
Apr 01 12:04:27 avishays-pi volumio[1561]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Apr 01 12:04:28 avishays-pi dhcpcd[1059]: eth0: offered 10.100.102.93 from 10.100.102.1
Apr 01 12:04:28 avishays-pi dhcpcd[1059]: eth0: probing address 10.100.102.93/24
Apr 01 12:04:28 avishays-pi volumio[1561]: error: MyVolumio Plugin failed to start in a timely fashion
Apr 01 12:04:28 avishays-pi volumio[1561]: [Metrics] CommandRouter: 26s 87.04ms
Apr 01 12:04:28 avishays-pi volumio[1561]: info: CoreCommandRouter::volumiosetStartupVolume
Apr 01 12:04:28 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 01 12:04:28 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 01 12:04:28 avishays-pi volumio[1561]: info: CoreCommandRouter::Close All Modals sent
Apr 01 12:04:28 avishays-pi volumio[1561]: info: CoreCommandRouter::Close All Modals sent
Apr 01 12:04:28 avishays-pi volumio[1561]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav
Apr 01 12:04:28 avishays-pi volumio[1561]: aplay: main:831: audio open error: Device or resource busy
Apr 01 12:04:29 avishays-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Apr 01 12:04:29 avishays-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:29 avishays-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:29 avishays-pi go-librespot[2164]: go-librespot daemon starting...
Apr 01 12:04:29 avishays-pi go-librespot[2165]: time="2026-04-01T12:04:29+03:00" level=info msg="running go-librespot 0.6.2"
Apr 01 12:04:29 avishays-pi go-librespot[2165]: time="2026-04-01T12:04:29+03:00" level=debug msg="app state loaded"
Apr 01 12:04:29 avishays-pi go-librespot[2165]: time="2026-04-01T12:04:29+03:00" level=debug msg="stored credentials not found"
Apr 01 12:04:29 avishays-pi go-librespot[2165]: time="2026-04-01T12:04:29+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Apr 01 12:04:29 avishays-pi go-librespot[2165]: time="2026-04-01T12:04:29+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Apr 01 12:04:29 avishays-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Apr 01 12:04:29 avishays-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Apr 01 12:04:29 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Apr 01 12:04:29 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 01 12:04:29 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Apr 01 12:04:30 avishays-pi volumio[1561]: info: Initializing connection to go-librespot Websocket
Apr 01 12:04:30 avishays-pi volumio[1561]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Apr 01 12:04:32 avishays-pi dhcpcd[1059]: eth0: leased 10.100.102.93 for 3600 seconds
Apr 01 12:04:32 avishays-pi avahi-daemon[1011]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.100.102.93.
Apr 01 12:04:32 avishays-pi avahi-daemon[1011]: New relevant interface eth0.IPv4 for mDNS.
Apr 01 12:04:32 avishays-pi avahi-daemon[1011]: Registering new address record for 10.100.102.93 on eth0.IPv4.
Apr 01 12:04:32 avishays-pi dhcpcd[1059]: eth0: adding route to 10.100.102.0/24
Apr 01 12:04:32 avishays-pi systemd[1]: welcome.service: Deactivated successfully.
Apr 01 12:04:32 avishays-pi systemd[1]: Stopped welcome.service - Show a welcome message on console.
Apr 01 12:04:32 avishays-pi systemd[1]: Stopping welcome.service - Show a welcome message on console...
Apr 01 12:04:32 avishays-pi dhcpcd[1059]: eth0: adding default route via 10.100.102.1
Apr 01 12:04:32 avishays-pi systemd[1]: Starting welcome.service - Show a welcome message on console...
Apr 01 12:04:32 avishays-pi welcome[2193]: Resolved ip:[1] 10.100.102.93
Apr 01 12:04:32 avishays-pi systemd[1]: Finished welcome.service - Show a welcome message on console.
Apr 01 12:04:32 avishays-pi systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Apr 01 12:04:32 avishays-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Apr 01 12:04:32 avishays-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:32 avishays-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Apr 01 12:04:32 avishays-pi go-librespot[2199]: go-librespot daemon starting...
Apr 01 12:04:32 avishays-pi go-librespot[2200]: time="2026-04-01T12:04:32+03:00" level=info msg="running go-librespot 0.6.2"
Apr 01 12:04:32 avishays-pi go-librespot[2200]: time="2026-04-01T12:04:32+03:00" level=debug msg="app state loaded"
Apr 01 12:04:32 avishays-pi go-librespot[2200]: time="2026-04-01T12:04:32+03:00" level=debug msg="stored credentials not found"
Apr 01 12:04:32 avishays-pi go-librespot[2200]: time="2026-04-01T12:04:32+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Apr 01 12:04:33 avishays-pi volumio[1561]: info: Initializing connection to go-librespot Websocket
Apr 01 12:04:33 avishays-pi volumio[1561]: info: Connection to go-librespot Websocket established
Apr 01 12:04:33 avishays-pi go-librespot[2200]: time="2026-04-01T12:04:33+03:00" level=debug msg="new websocket client"
Apr 01 12:04:33 avishays-pi volumio[1561]: info: Discovery: adding 4b6d9f93-c218-459b-8cda-d637db40c119
Apr 01 12:04:33 avishays-pi volumio[1561]: info: Discovery: Found device Avishays Pi
Apr 01 12:04:33 avishays-pi volumio[1561]: info: CoreCommandRouter::volumioGetState
Apr 01 12:04:33 avishays-pi volumio[1561]: info: CorePlayQueue::getTrack 0
Apr 01 12:04:33 avishays-pi volumio[1561]: info: Volumio Network Manager: Network status updated: 1
Apr 01 12:04:33 avishays-pi ntpd[1237]: IO: Listen normally on 4 eth0 10.100.102.93:123
Apr 01 12:04:33 avishays-pi ntpd[1237]: IO: new interface(s) found: waking up resolver
Apr 01 12:04:33 avishays-pi ntpd[1237]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101
Apr 01 12:04:35 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 01 12:04:35 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 01 12:04:35 avishays-pi volumio[1561]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 01 12:04:35 avishays-pi volumio[1561]: info: BOOT COMPLETED
Apr 01 12:04:36 avishays-pi volumio[1561]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.084&uuid=bf646dff0a2a15abd73c688a6acb0a60" http://updates.volumio.org/downloader-v1/track-device
Apr 01 12:04:36 avishays-pi volumio[1561]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 01 12:04:36 avishays-pi volumio[1561]: Dload Upload Total Spent Left Speed
Apr 01 12:04:36 avishays-pi volumio[1561]: [132B blob data]
Apr 01 12:04:36 avishays-pi volumio[1561]: retrying in 5 seconds, trial 2
Apr 01 12:04:36 avishays-pi volumio[1561]: info: Volumio Calling Home
Apr 01 12:04:36 avishays-pi volumio[1561]: info: Getting Spotify volume
Apr 01 12:04:46 avishays-pi upmpdcli[2254]: writing RSA key
Apr 01 12:04:47 avishays-pi volumio[1561]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Apr 01 12:04:47 avishays-pi go-librespot[2200]: time="2026-04-01T12:04:47+03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy"
Apr 01 12:04:47 avishays-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Apr 01 12:04:47 avishays-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Apr 01 12:04:47 avishays-pi volumio[1561]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 01 12:04:47 avishays-pi volumio[1561]: Error: socket hang up
Apr 01 12:04:47 avishays-pi volumio[1561]: at connResetException (node:internal/errors:720:14)
Apr 01 12:04:47 avishays-pi volumio[1561]: at Socket.socketOnEnd (node:_http_client:519:23)
Apr 01 12:04:47 avishays-pi volumio[1561]: at Socket.emit (node:events:526:35)
Apr 01 12:04:47 avishays-pi volumio[1561]: at endReadableNT (node:internal/streams/readable:1376:12)
Apr 01 12:04:47 avishays-pi volumio[1561]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) {
Apr 01 12:04:47 avishays-pi volumio[1561]: code: 'ECONNRESET',
Apr 01 12:04:47 avishays-pi volumio[1561]: response: undefined
Apr 01 12:04:47 avishays-pi volumio[1561]: }
Apr 01 12:04:47 avishays-pi volumio[1561]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 01 12:04:47 avishays-pi systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Apr 01 12:04:48 avishays-pi sudo[2294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-01 12:03'
Apr 01 12:04:48 avishays-pi sudo[2294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025"
VOLUMIO_VERSION="4.084"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"