-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Tue 2024-08-06 04:09:47 UTC. -- Aug 06 04:09:08 magnavox kernel: usb usb1: SerialNumber: 0000:01:00.0 Aug 06 04:09:08 magnavox kernel: hub 1-0:1.0: USB hub found Aug 06 04:09:08 magnavox kernel: hub 1-0:1.0: 1 port detected Aug 06 04:09:08 magnavox kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01 Aug 06 04:09:08 magnavox kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 06 04:09:08 magnavox kernel: usb usb2: Product: xHCI Host Controller Aug 06 04:09:08 magnavox kernel: usb usb2: Manufacturer: Linux 6.1.69-v7l+ xhci-hcd Aug 06 04:09:08 magnavox kernel: usb usb2: SerialNumber: 0000:01:00.0 Aug 06 04:09:08 magnavox kernel: hub 2-0:1.0: USB hub found Aug 06 04:09:08 magnavox kernel: hub 2-0:1.0: 4 ports detected Aug 06 04:09:08 magnavox kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Aug 06 04:09:08 magnavox kernel: dwc_otg: FIQ enabled Aug 06 04:09:08 magnavox kernel: dwc_otg: NAK holdoff enabled Aug 06 04:09:08 magnavox kernel: dwc_otg: FIQ split-transaction FSM enabled Aug 06 04:09:08 magnavox kernel: Module dwc_common_port init Aug 06 04:09:08 magnavox kernel: usbcore: registered new interface driver uas Aug 06 04:09:08 magnavox kernel: usbcore: registered new interface driver usb-storage Aug 06 04:09:08 magnavox kernel: mousedev: PS/2 mouse device common for all mice Aug 06 04:09:08 magnavox kernel: sdhci: Secure Digital Host Controller Interface driver Aug 06 04:09:08 magnavox kernel: sdhci: Copyright(c) Pierre Ossman Aug 06 04:09:08 magnavox kernel: sdhci-pltfm: SDHCI platform and OF driver helper Aug 06 04:09:08 magnavox kernel: ledtrig-cpu: registered to indicate activity on CPUs Aug 06 04:09:08 magnavox kernel: hid: raw HID events driver (C) Jiri Kosina Aug 06 04:09:08 magnavox kernel: usbcore: registered new interface driver usbhid Aug 06 04:09:08 magnavox kernel: usbhid: USB HID core driver Aug 06 04:09:08 magnavox kernel: Initializing XFRM netlink socket Aug 06 04:09:08 magnavox kernel: NET: Registered PF_PACKET protocol family Aug 06 04:09:08 magnavox kernel: Key type dns_resolver registered Aug 06 04:09:08 magnavox kernel: Registering SWP/SWPB emulation handler Aug 06 04:09:08 magnavox kernel: registered taskstats version 1 Aug 06 04:09:08 magnavox kernel: Loading compiled-in X.509 certificates Aug 06 04:09:08 magnavox kernel: Key type .fscrypt registered Aug 06 04:09:08 magnavox kernel: Key type fscrypt-provisioning registered Aug 06 04:09:08 magnavox kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Aug 06 04:09:08 magnavox kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Aug 06 04:09:08 magnavox kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Aug 06 04:09:08 magnavox kernel: serial serial0: tty port ttyAMA1 registered Aug 06 04:09:08 magnavox kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Aug 06 04:09:08 magnavox kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Aug 06 04:09:08 magnavox kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Aug 06 04:09:08 magnavox kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Aug 06 04:09:08 magnavox kernel: of_cfs_init Aug 06 04:09:08 magnavox kernel: of_cfs_init: OK Aug 06 04:09:08 magnavox kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Aug 06 04:09:08 magnavox kernel: Freeing unused kernel image (initmem) memory: 2048K Aug 06 04:09:08 magnavox kernel: Run /init as init process Aug 06 04:09:08 magnavox kernel: with arguments: Aug 06 04:09:08 magnavox kernel: /init Aug 06 04:09:08 magnavox kernel: splash Aug 06 04:09:08 magnavox kernel: nodebug Aug 06 04:09:08 magnavox kernel: with environment: Aug 06 04:09:08 magnavox kernel: HOME=/ Aug 06 04:09:08 magnavox kernel: TERM=linux Aug 06 04:09:08 magnavox kernel: imgpart=UUID=5234a21b-8461-49e0-9b96-9c9a29c936a7 Aug 06 04:09:08 magnavox kernel: imgfile=/volumio_current.sqsh Aug 06 04:09:08 magnavox kernel: bootpart=UUID=B78E-8150 Aug 06 04:09:08 magnavox kernel: datapart=UUID=e994d73d-3aa0-40bb-8da7-0a35790b5bc0 Aug 06 04:09:08 magnavox kernel: uuidconfig=cmdline.txt Aug 06 04:09:08 magnavox kernel: pcie_aspm=off Aug 06 04:09:08 magnavox kernel: bootdelay=7 Aug 06 04:09:08 magnavox kernel: use_kmsg=no Aug 06 04:09:08 magnavox kernel: mmc1: new high speed SDIO card at address 0001 Aug 06 04:09:08 magnavox kernel: mmc0: new ultra high speed DDR50 SDXC card at address 59b4 Aug 06 04:09:08 magnavox kernel: mmcblk0: mmc0:59b4 GD2S5 119 GiB Aug 06 04:09:08 magnavox kernel: mmcblk0: p1 p2 p3 Aug 06 04:09:08 magnavox kernel: mmcblk0: mmc0:59b4 GD2S5 119 GiB Aug 06 04:09:08 magnavox kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Aug 06 04:09:08 magnavox kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Aug 06 04:09:08 magnavox kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 06 04:09:08 magnavox kernel: usb 1-1: Product: USB2.0 Hub Aug 06 04:09:08 magnavox kernel: hub 1-1:1.0: USB hub found Aug 06 04:09:08 magnavox kernel: hub 1-1:1.0: 4 ports detected Aug 06 04:09:08 magnavox kernel: usb 1-1.1: new high-speed USB device number 3 using xhci_hcd Aug 06 04:09:08 magnavox kernel: usb 1-1.1: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.04 Aug 06 04:09:08 magnavox kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Aug 06 04:09:08 magnavox kernel: usb 1-1.1: Product: D10s Aug 06 04:09:08 magnavox kernel: usb 1-1.1: Manufacturer: Topping Aug 06 04:09:08 magnavox kernel: fuse: init (API version 7.37) Aug 06 04:09:08 magnavox kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 06 04:09:08 magnavox kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Aug 06 04:09:08 magnavox kernel: EXT4-fs (mmcblk0p2): recovery complete Aug 06 04:09:08 magnavox kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Aug 06 04:09:08 magnavox kernel: loop0: detected capacity change from 0 to 983360 Aug 06 04:09:08 magnavox kernel: EXT4-fs (mmcblk0p3): recovery complete Aug 06 04:09:08 magnavox kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Aug 06 04:09:08 magnavox systemd[1]: System time before build time, advancing clock. Aug 06 04:09:08 magnavox kernel: NET: Registered PF_INET6 protocol family Aug 06 04:09:08 magnavox kernel: Segment Routing with IPv6 Aug 06 04:09:08 magnavox kernel: In-situ OAM (IOAM) with IPv6 Aug 06 04:09:08 magnavox systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Aug 06 04:09:08 magnavox systemd[1]: Detected architecture arm. Aug 06 04:09:08 magnavox systemd[1]: Set hostname to . Aug 06 04:09:08 magnavox systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 06 04:09:08 magnavox systemd[1]: Listening on udev Control Socket. Aug 06 04:09:08 magnavox systemd[1]: Created slice system-getty.slice. Aug 06 04:09:08 magnavox systemd[1]: Listening on initctl Compatibility Named Pipe. Aug 06 04:09:08 magnavox systemd[1]: Listening on RPCbind Server Activation Socket. Aug 06 04:09:08 magnavox systemd[1]: Reached target System Time Synchronized. Aug 06 04:09:08 magnavox systemd[1]: Listening on udev Kernel Socket. Aug 06 04:09:08 magnavox systemd-journald[321]: Journal started Aug 06 04:09:08 magnavox systemd-journald[321]: Runtime journal (/run/log/journal/785f013f15a7766cb54a2cb06217cae8) is 7.5M, max 30.0M, 22.5M free. Aug 06 04:09:08 magnavox fake-hwclock[322]: Tue Aug 6 04:09:08 UTC 2024 Aug 06 04:09:08 magnavox kernel: i2c_dev: i2c /dev entries driver Aug 06 04:09:08 magnavox systemd-modules-load[328]: Inserted module 'i2c_dev' Aug 06 04:09:08 magnavox systemd[1]: Started Load Kernel Modules. Aug 06 04:09:08 magnavox systemd[1]: Mounting FUSE Control File System... Aug 06 04:09:08 magnavox systemd[1]: Starting Apply Kernel Variables... Aug 06 04:09:08 magnavox systemd[1]: Mounting Kernel Configuration File System... Aug 06 04:09:08 magnavox systemd[1]: dynamicswap.service: Succeeded. Aug 06 04:09:08 magnavox systemd[1]: Started Create System Users. Aug 06 04:09:08 magnavox systemd[1]: Mounted FUSE Control File System. Aug 06 04:09:08 magnavox systemd[1]: Mounted Kernel Configuration File System. Aug 06 04:09:08 magnavox systemd[1]: Starting Create Static Device Nodes in /dev... Aug 06 04:09:08 magnavox systemd[1]: Started Apply Kernel Variables. Aug 06 04:09:08 magnavox systemd[1]: Started Create Static Device Nodes in /dev. Aug 06 04:09:08 magnavox systemd[1]: Reached target Local File Systems (Pre). Aug 06 04:09:08 magnavox systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Aug 06 04:09:08 magnavox systemd[1]: Mounting /var/log... Aug 06 04:09:08 magnavox systemd[1]: Mounting /var/spool/cups... Aug 06 04:09:08 magnavox systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Aug 06 04:09:08 magnavox systemd[1]: Mounting /tmp... Aug 06 04:09:08 magnavox systemd[1]: Starting udev Kernel Device Manager... Aug 06 04:09:08 magnavox systemd[1]: Started udev Coldplug all Devices. Aug 06 04:09:08 magnavox systemd[1]: Mounted /var/log. Aug 06 04:09:08 magnavox systemd[1]: Mounted /var/spool/cups. Aug 06 04:09:08 magnavox systemd[1]: Mounted /tmp. Aug 06 04:09:08 magnavox systemd[1]: Mounting /var/spool/cups/tmp... Aug 06 04:09:08 magnavox systemd[1]: Starting Flush Journal to Persistent Storage... Aug 06 04:09:08 magnavox systemd[1]: Starting Helper to synchronize boot up for ifupdown... Aug 06 04:09:08 magnavox systemd[1]: Mounted /var/spool/cups/tmp. Aug 06 04:09:08 magnavox systemd-udevd[356]: Network interface NamePolicy= disabled on kernel command line, ignoring. Aug 06 04:09:08 magnavox systemd[1]: Started Helper to synchronize boot up for ifupdown. Aug 06 04:09:08 magnavox systemd-journald[321]: Runtime journal (/run/log/journal/785f013f15a7766cb54a2cb06217cae8) is 7.5M, max 30.0M, 22.5M free. Aug 06 04:09:08 magnavox systemd[1]: Started Flush Journal to Persistent Storage. Aug 06 04:09:08 magnavox systemd[1]: Started udev Kernel Device Manager. Aug 06 04:09:08 magnavox systemd[1]: Starting Show Plymouth Boot Screen... Aug 06 04:09:08 magnavox systemd[1]: plymouth-start.service: Succeeded. Aug 06 04:09:08 magnavox systemd[1]: Started Show Plymouth Boot Screen. Aug 06 04:09:09 magnavox systemd-udevd[377]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 06 04:09:09 magnavox kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Aug 06 04:09:09 magnavox kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Aug 06 04:09:09 magnavox kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Aug 06 04:09:09 magnavox kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Aug 06 04:09:09 magnavox kernel: [vc_sm_connected_init]: start Aug 06 04:09:09 magnavox kernel: [vc_sm_connected_init]: installed successfully Aug 06 04:09:09 magnavox kernel: mc: Linux media interface: v0.10 Aug 06 04:09:09 magnavox systemd-udevd[374]: Using default interface naming scheme 'v240'. Aug 06 04:09:09 magnavox systemd-udevd[374]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 06 04:09:09 magnavox kernel: videodev: Linux video capture interface: v2.00 Aug 06 04:09:09 magnavox kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Aug 06 04:09:09 magnavox kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Aug 06 04:09:09 magnavox kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Aug 06 04:09:10 magnavox kernel: rpivid feb10000.codec: Device registered as /dev/video19 Aug 06 04:09:10 magnavox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Aug 06 04:09:10 magnavox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Aug 06 04:09:10 magnavox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Aug 06 04:09:10 magnavox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Aug 06 04:09:10 magnavox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Aug 06 04:09:10 magnavox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Aug 06 04:09:10 magnavox kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Aug 06 04:09:10 magnavox kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Aug 06 04:09:10 magnavox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Aug 06 04:09:10 magnavox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Aug 06 04:09:10 magnavox kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Aug 06 04:09:10 magnavox kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Aug 06 04:09:10 magnavox kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Aug 06 04:09:10 magnavox kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Aug 06 04:09:10 magnavox kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Aug 06 04:09:10 magnavox kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Aug 06 04:09:10 magnavox kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Aug 06 04:09:10 magnavox kernel: Bluetooth: Core ver 2.22 Aug 06 04:09:10 magnavox kernel: NET: Registered PF_BLUETOOTH protocol family Aug 06 04:09:10 magnavox kernel: Bluetooth: HCI device and connection manager initialized Aug 06 04:09:10 magnavox kernel: Bluetooth: HCI socket layer initialized Aug 06 04:09:10 magnavox kernel: Bluetooth: L2CAP socket layer initialized Aug 06 04:09:10 magnavox kernel: Bluetooth: SCO socket layer initialized Aug 06 04:09:10 magnavox kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Aug 06 04:09:10 magnavox kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Aug 06 04:09:10 magnavox kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Aug 06 04:09:10 magnavox kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Aug 06 04:09:10 magnavox kernel: usbcore: registered new interface driver brcmfmac Aug 06 04:09:10 magnavox kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Aug 06 04:09:10 magnavox kernel: Bluetooth: HCI UART driver ver 2.3 Aug 06 04:09:10 magnavox kernel: Bluetooth: HCI UART protocol H4 registered Aug 06 04:09:10 magnavox kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Aug 06 04:09:10 magnavox kernel: Bluetooth: HCI UART protocol Broadcom registered Aug 06 04:09:10 magnavox kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Aug 06 04:09:10 magnavox kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Aug 06 04:09:10 magnavox kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Aug 06 04:09:10 magnavox systemd[1]: Found device /dev/disk/by-uuid/B78E-8150. Aug 06 04:09:10 magnavox systemd[1]: Starting File System Check on /dev/disk/by-uuid/B78E-8150... Aug 06 04:09:10 magnavox systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Aug 06 04:09:10 magnavox systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 06 04:09:10 magnavox systemd[1]: Reached target Paths. Aug 06 04:09:10 magnavox systemd[1]: Reached target Local Encrypted Volumes. Aug 06 04:09:10 magnavox systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Aug 06 04:09:10 magnavox kernel: uart-pl011 fe201000.serial: no DMA platform data Aug 06 04:09:10 magnavox systemd[1]: Started File System Check Daemon to report status. Aug 06 04:09:10 magnavox kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Aug 06 04:09:10 magnavox systemd[1]: Starting Load/Save RF Kill Switch Status... Aug 06 04:09:10 magnavox systemd-fsck[509]: fsck.fat 4.1 (2017-01-24) Aug 06 04:09:10 magnavox systemd-fsck[509]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Aug 06 04:09:10 magnavox systemd-fsck[509]: Automatically removing dirty bit. Aug 06 04:09:10 magnavox systemd-fsck[509]: Performing changes. Aug 06 04:09:10 magnavox systemd-fsck[509]: /dev/mmcblk0p1: 379 files, 33710/46774 clusters Aug 06 04:09:10 magnavox kernel: Bluetooth: hci0: BCM: chip id 107 Aug 06 04:09:10 magnavox kernel: Bluetooth: hci0: BCM: features 0x2f Aug 06 04:09:10 magnavox kernel: Bluetooth: hci0: BCM4345C0 Aug 06 04:09:10 magnavox kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Aug 06 04:09:10 magnavox systemd-udevd[374]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 06 04:09:10 magnavox kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Aug 06 04:09:10 magnavox systemd[1]: Started File System Check on /dev/disk/by-uuid/B78E-8150. Aug 06 04:09:11 magnavox kernel: usb 1-1.1: 1:3 : unsupported format bits 0x100000000 Aug 06 04:09:11 magnavox kernel: usbcore: registered new interface driver snd-usb-audio Aug 06 04:09:11 magnavox systemd[1]: Started Load/Save RF Kill Switch Status. Aug 06 04:09:11 magnavox systemd[1]: Found device /sys/subsystem/net/devices/eth0. Aug 06 04:09:11 magnavox kernel: Bluetooth: hci0: BCM: features 0x2f Aug 06 04:09:11 magnavox kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Aug 06 04:09:11 magnavox kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Aug 06 04:09:11 magnavox systemd[1]: Created slice system-bthelper.slice. Aug 06 04:09:11 magnavox systemd[1]: Condition check resulted in Huge Pages File System being skipped. Aug 06 04:09:11 magnavox systemd[1]: Mounting /boot... Aug 06 04:09:11 magnavox systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Aug 06 04:09:11 magnavox systemd[1]: Starting Show Plymouth Boot Screen... Aug 06 04:09:11 magnavox systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Aug 06 04:09:11 magnavox systemd[1]: Mounted /boot. Aug 06 04:09:11 magnavox systemd[1]: plymouth-start.service: Succeeded. Aug 06 04:09:11 magnavox systemd[1]: Started Show Plymouth Boot Screen. Aug 06 04:09:11 magnavox systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 06 04:09:11 magnavox systemd[1]: Reached target Local File Systems. Aug 06 04:09:11 magnavox systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Aug 06 04:09:11 magnavox systemd[1]: Started ifup for eth0. Aug 06 04:09:11 magnavox systemd[1]: Starting Raise network interfaces... Aug 06 04:09:11 magnavox systemd[1]: Starting Create Volatile Files and Directories... Aug 06 04:09:11 magnavox systemd[1]: Starting Preprocess NFS configuration... Aug 06 04:09:11 magnavox systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Aug 06 04:09:11 magnavox systemd-udevd[369]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Aug 06 04:09:11 magnavox systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Aug 06 04:09:11 magnavox systemd[1]: nfs-config.service: Succeeded. Aug 06 04:09:11 magnavox systemd[1]: Started Preprocess NFS configuration. Aug 06 04:09:11 magnavox systemd[1]: plymouth-read-write.service: Succeeded. Aug 06 04:09:12 magnavox systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Aug 06 04:09:12 magnavox systemd[1]: Received SIGRTMIN+20 from PID 212 (plymouthd). Aug 06 04:09:12 magnavox systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Aug 06 04:09:12 magnavox systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Aug 06 04:09:12 magnavox systemd[1]: Reached target NFS client services. Aug 06 04:09:12 magnavox systemd[1]: Started ifup for wlan0. Aug 06 04:09:12 magnavox systemd[1]: Started Create Volatile Files and Directories. Aug 06 04:09:12 magnavox systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Aug 06 04:09:12 magnavox systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Aug 06 04:09:12 magnavox systemd[1]: Starting Update UTMP about System Boot/Shutdown... Aug 06 04:09:12 magnavox systemd[1]: Starting RPC bind portmap service... Aug 06 04:09:12 magnavox systemd[1]: Started Update UTMP about System Boot/Shutdown. Aug 06 04:09:12 magnavox systemd[1]: Started RPC bind portmap service. Aug 06 04:09:12 magnavox systemd[1]: Reached target RPC Port Mapper. Aug 06 04:09:12 magnavox systemd[1]: Reached target Remote File Systems (Pre). Aug 06 04:09:12 magnavox systemd[1]: Reached target Remote File Systems. Aug 06 04:09:12 magnavox systemd[1]: Reached target System Initialization. Aug 06 04:09:12 magnavox systemd[1]: Started Daily Cleanup of Temporary Directories. Aug 06 04:09:12 magnavox systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Aug 06 04:09:12 magnavox systemd[1]: apt-daily.timer: Not using persistent file timestamp Wed 2024-08-21 20:26:33 UTC as it is in the future. Aug 06 04:09:12 magnavox systemd[1]: Started Daily apt download activities. Aug 06 04:09:12 magnavox systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Wed 2024-08-21 06:44:33 UTC as it is in the future. Aug 06 04:09:12 magnavox systemd[1]: Started Daily apt upgrade and clean activities. Aug 06 04:09:12 magnavox systemd[1]: Reached target Timers. Aug 06 04:09:12 magnavox systemd[1]: Listening on triggerhappy.socket. Aug 06 04:09:12 magnavox systemd[1]: Listening on D-Bus System Message Bus Socket. Aug 06 04:09:12 magnavox systemd[1]: Reached target Sockets. Aug 06 04:09:12 magnavox systemd[1]: Reached target Basic System. Aug 06 04:09:12 magnavox kernel: 8021q: 802.1Q VLAN Support v1.8 Aug 06 04:09:12 magnavox systemd[1]: Started Volumio Iptables Module. Aug 06 04:09:12 magnavox systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Aug 06 04:09:12 magnavox systemd[1]: Starting dhcpcd on all interfaces... Aug 06 04:09:12 magnavox systemd[1]: Started Manage Sound Card State (restore and store). Aug 06 04:09:12 magnavox alsactl[617]: alsactl 1.1.8 daemon started Aug 06 04:09:12 magnavox systemd[1]: Starting Configure Bluetooth Modems connected by UART... Aug 06 04:09:12 magnavox systemd[1]: Started UPnP Renderer front-end to MPD. Aug 06 04:09:12 magnavox kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 06 04:09:12 magnavox systemd[1]: Started volumio-remote-updater.service. Aug 06 04:09:12 magnavox systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Aug 06 04:09:12 magnavox systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Aug 06 04:09:12 magnavox systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Aug 06 04:09:12 magnavox systemd[1]: Starting triggerhappy global hotkey daemon... Aug 06 04:09:12 magnavox dhcpcd[616]: Not running dhcpcd because /etc/network/interfaces Aug 06 04:09:12 magnavox dhcpcd[616]: defines some interfaces that will use a Aug 06 04:09:12 magnavox dhcpcd[616]: DHCP client or static address Aug 06 04:09:12 magnavox systemd[1]: Starting Save/Restore Sound Card State... Aug 06 04:09:12 magnavox systemd[1]: Started Volumio Log Rotation Service. Aug 06 04:09:12 magnavox systemd[1]: Starting Wireless Services... Aug 06 04:09:12 magnavox systemd[1]: Starting Login Service... Aug 06 04:09:12 magnavox thd[629]: Unable to parse trigger line: Aug 06 04:09:12 magnavox thd[629]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Aug 06 04:09:12 magnavox thd[629]: Unable to parse trigger line: Aug 06 04:09:12 magnavox thd[629]: Found socket passed from systemd Aug 06 04:09:12 magnavox systemd[1]: Started D-Bus System Message Bus. Aug 06 04:09:12 magnavox systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Aug 06 04:09:12 magnavox systemd[1]: Starting WPA supplicant... Aug 06 04:09:12 magnavox systemd[1]: Started triggerhappy global hotkey daemon. Aug 06 04:09:12 magnavox systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Aug 06 04:09:12 magnavox systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Aug 06 04:09:12 magnavox systemd[1]: Failed to start dhcpcd on all interfaces. Aug 06 04:09:12 magnavox systemd[1]: hciuart.service: Succeeded. Aug 06 04:09:12 magnavox systemd[1]: Started Configure Bluetooth Modems connected by UART. Aug 06 04:09:12 magnavox systemd[1]: Started Save/Restore Sound Card State. Aug 06 04:09:12 magnavox sh[538]: eth0: waiting for carrier Aug 06 04:09:12 magnavox dhcpcd[579]: eth0: waiting for carrier Aug 06 04:09:12 magnavox kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Aug 06 04:09:12 magnavox kernel: bcmgenet fd580000.ethernet eth0: Link is Down Aug 06 04:09:12 magnavox systemd-logind[647]: New seat seat0. Aug 06 04:09:12 magnavox systemd[1]: Reached target Sound Card. Aug 06 04:09:13 magnavox avahi-daemon[668]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Aug 06 04:09:13 magnavox avahi-daemon[668]: Successfully dropped root privileges. Aug 06 04:09:13 magnavox systemd[1]: Starting Raspberry Pi bluetooth helper... Aug 06 04:09:13 magnavox sh[558]: wlan0=wlan0 Aug 06 04:09:13 magnavox avahi-daemon[668]: avahi-daemon 0.7 starting up. Aug 06 04:09:13 magnavox volumio-remote-updater[621]: Error: No active session Aug 06 04:09:13 magnavox volumio-remote-updater[621]: [2024-08-06 04:09:13] [info] asio async_connect error: system:111 (Connection refused) Aug 06 04:09:13 magnavox volumio-remote-updater[621]: [2024-08-06 04:09:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 06 04:09:13 magnavox volumio-remote-updater[621]: [2024-08-06 04:09:13] [error] handle_connect error: Underlying Transport Error Aug 06 04:09:13 magnavox bthelper[680]: Raspberry Pi BDADDR already set Aug 06 04:09:13 magnavox volumio[620]: Could not open config: /tmp/upmpdcli.conf Aug 06 04:09:13 magnavox dbus-daemon[649]: [system] Successfully activated service 'org.freedesktop.systemd1' Aug 06 04:09:13 magnavox avahi-daemon[668]: Successfully called chroot(). Aug 06 04:09:13 magnavox avahi-daemon[668]: Successfully dropped remaining capabilities. Aug 06 04:09:13 magnavox systemd[1]: Started Login Service. Aug 06 04:09:13 magnavox avahi-daemon[668]: Loading service file /services/volumio.service. Aug 06 04:09:13 magnavox systemd[1]: Started Raise network interfaces. Aug 06 04:09:13 magnavox systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 06 04:09:13 magnavox systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 06 04:09:13 magnavox avahi-daemon[668]: Network interface enumeration completed. Aug 06 04:09:13 magnavox avahi-daemon[668]: Server startup complete. Host name is magnavox.local. Local service cookie is 2404197024. Aug 06 04:09:13 magnavox avahi-daemon[668]: Service "MAGNAVOX" (/services/volumio.service) successfully established. Aug 06 04:09:13 magnavox systemd[1]: Started Raspberry Pi bluetooth helper. Aug 06 04:09:13 magnavox wpa_supplicant[676]: Successfully initialized wpa_supplicant Aug 06 04:09:13 magnavox systemd[1]: Started Avahi mDNS/DNS-SD Stack. Aug 06 04:09:13 magnavox systemd[1]: Started WPA supplicant. Aug 06 04:09:13 magnavox systemd[1]: Starting Bluetooth service... Aug 06 04:09:13 magnavox systemd[1]: Reached target Network. Aug 06 04:09:13 magnavox systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Aug 06 04:09:13 magnavox systemd[1]: Starting OpenBSD Secure Shell server... Aug 06 04:09:13 magnavox systemd[1]: Starting Network Time Service... Aug 06 04:09:13 magnavox systemd[1]: Starting Permit User Sessions... Aug 06 04:09:13 magnavox systemd[1]: Reached target Network is Online. Aug 06 04:09:13 magnavox systemd[1]: Starting /etc/rc.local Compatibility... Aug 06 04:09:13 magnavox systemd[1]: Starting Samba NMB Daemon... Aug 06 04:09:13 magnavox systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 06 04:09:13 magnavox systemd[1]: Starting LSB: Brings up/down network automatically... Aug 06 04:09:13 magnavox systemd[1]: Started Permit User Sessions. Aug 06 04:09:13 magnavox systemd[1]: Started /etc/rc.local Compatibility. Aug 06 04:09:13 magnavox systemd[1]: Starting Hold until boot process finishes up... Aug 06 04:09:13 magnavox systemd[1]: Starting Terminate Plymouth Boot Screen... Aug 06 04:09:13 magnavox systemd[1]: Received SIGRTMIN+21 from PID 212 (plymouthd). Aug 06 04:09:13 magnavox systemd[1]: Received SIGRTMIN+21 from PID 212 (plymouthd). Aug 06 04:09:13 magnavox systemd[1]: plymouth-quit.service: Succeeded. Aug 06 04:09:13 magnavox systemd[1]: Started Terminate Plymouth Boot Screen. Aug 06 04:09:13 magnavox bluetoothd[733]: Bluetooth daemon 5.50 Aug 06 04:09:14 magnavox systemd[1]: plymouth-quit-wait.service: Succeeded. Aug 06 04:09:14 magnavox systemd[1]: Started Hold until boot process finishes up. Aug 06 04:09:14 magnavox loadcpufreq[628]: Loading cpufreq kernel modules...done (none). Aug 06 04:09:14 magnavox systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Aug 06 04:09:14 magnavox bluetoothd[733]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Aug 06 04:09:14 magnavox bluetoothd[733]: Starting SDP server Aug 06 04:09:14 magnavox bluetoothd[733]: Excluding (cli) sap Aug 06 04:09:14 magnavox systemd[1]: Starting LSB: set CPUFreq kernel parameters... Aug 06 04:09:14 magnavox systemd[1]: Started Getty on tty1. Aug 06 04:09:14 magnavox systemd[1]: Reached target Login Prompts. Aug 06 04:09:14 magnavox systemd[1]: Started Bluetooth service. Aug 06 04:09:14 magnavox ntpd[743]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 06 04:09:14 magnavox systemd[1]: Started Network Time Service. Aug 06 04:09:14 magnavox ntpd[743]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Aug 06 04:09:14 magnavox systemd[1]: iptables.service: Succeeded. Aug 06 04:09:14 magnavox ntpd[792]: proto: precision = 2.093 usec (-19) Aug 06 04:09:14 magnavox sshd[789]: Server listening on 0.0.0.0 port 22. Aug 06 04:09:14 magnavox sshd[789]: Server listening on :: port 22. Aug 06 04:09:14 magnavox ntpd[792]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 06 04:09:14 magnavox ntpd[792]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 06 04:09:14 magnavox ntpd[792]: Listen and drop on 0 v6wildcard [::]:123 Aug 06 04:09:14 magnavox ntpd[792]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 06 04:09:14 magnavox kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 06 04:09:14 magnavox kernel: Bluetooth: BNEP filters: protocol multicast Aug 06 04:09:14 magnavox kernel: Bluetooth: BNEP socket layer initialized Aug 06 04:09:14 magnavox ntpd[792]: Listen normally on 2 lo 127.0.0.1:123 Aug 06 04:09:14 magnavox ntpd[792]: Listening on routing socket on fd #19 for interface updates Aug 06 04:09:14 magnavox ntpd[792]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 06 04:09:14 magnavox ntpd[792]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 06 04:09:14 magnavox bluetoothd[733]: Bluetooth management interface 1.22 initialized Aug 06 04:09:14 magnavox kernel: Bluetooth: MGMT ver 1.22 Aug 06 04:09:14 magnavox dbus-daemon[649]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=733 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Aug 06 04:09:14 magnavox systemd[1]: Started OpenBSD Secure Shell server. Aug 06 04:09:14 magnavox bluetoothd[733]: Failed to set privacy: Rejected (0x0b) Aug 06 04:09:14 magnavox ifplugd(eth0)[810]: ifplugd 0.28 initializing. Aug 06 04:09:14 magnavox ifplugd(eth0)[810]: Using interface eth0/DC:A6:32:D8:E8:F9 with driver (version: 6.1.69-v7l+) Aug 06 04:09:14 magnavox ifplugd(eth0)[810]: Using detection mode: SIOCETHTOOL Aug 06 04:09:14 magnavox ifplugd(eth0)[810]: Initialization complete, link beat not detected. Aug 06 04:09:14 magnavox ifplugd[760]: Network Interface Plugging Daemon...start eth0...done. Aug 06 04:09:14 magnavox systemd[1]: Started LSB: Brings up/down network automatically. Aug 06 04:09:14 magnavox systemd[1]: Starting Hostname Service... Aug 06 04:09:14 magnavox systemd[1]: Reached target Bluetooth. Aug 06 04:09:14 magnavox cpufrequtils[775]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Aug 06 04:09:14 magnavox systemd[1]: Started LSB: set CPUFreq kernel parameters. Aug 06 04:09:14 magnavox dbus-daemon[649]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 06 04:09:14 magnavox systemd[1]: Started Hostname Service. Aug 06 04:09:15 magnavox nmbd[767]: [2024/08/06 04:09:15.025158, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Aug 06 04:09:15 magnavox nmbd[767]: started asyncdns process 822 Aug 06 04:09:15 magnavox nmbd[767]: [2024/08/06 04:09:15.028044, 0] ../lib/util/become_daemon.c:149(daemon_status) Aug 06 04:09:15 magnavox nmbd[767]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Aug 06 04:09:15 magnavox nmbd[767]: [2024/08/06 04:09:15.028511, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Aug 06 04:09:15 magnavox nmbd[767]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Aug 06 04:09:15 magnavox ntpd[792]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 06 04:09:15 magnavox wireless.js[645]: Cleaning previous... Aug 06 04:09:15 magnavox haveged[580]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Aug 06 04:09:15 magnavox haveged[580]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Aug 06 04:09:15 magnavox haveged[580]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00179 Aug 06 04:09:15 magnavox haveged[580]: haveged: fills: 0, generated: 0 Aug 06 04:09:15 magnavox sudo[830]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 06 04:09:15 magnavox sudo[830]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:15 magnavox sudo[830]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:15 magnavox sudo[832]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 06 04:09:15 magnavox sudo[832]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:15 magnavox sudo[832]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:15 magnavox wireless.js[645]: Stopped aP Aug 06 04:09:16 magnavox systemd[1]: systemd-rfkill.service: Succeeded. Aug 06 04:09:16 magnavox sudo[839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 06 04:09:16 magnavox sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:16 magnavox sudo[839]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:16 magnavox kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 06 04:09:16 magnavox sudo[841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 06 04:09:16 magnavox sudo[841]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:16 magnavox sudo[841]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:16 magnavox sudo[848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 06 04:09:16 magnavox sudo[848]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:16 magnavox sudo[848]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:16 magnavox sudo[850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 06 04:09:16 magnavox sudo[850]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:16 magnavox ntpd[792]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 06 04:09:17 magnavox ntpd[792]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 06 04:09:18 magnavox volumio-remote-updater[621]: [2024-08-06 04:09:18] [info] asio async_connect error: system:111 (Connection refused) Aug 06 04:09:18 magnavox volumio-remote-updater[621]: [2024-08-06 04:09:18] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 06 04:09:18 magnavox volumio-remote-updater[621]: [2024-08-06 04:09:18] [error] handle_connect error: Underlying Transport Error Aug 06 04:09:18 magnavox ntpd[792]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 06 04:09:18 magnavox bthelper[680]: Changing power off succeeded Aug 06 04:09:18 magnavox bthelper[680]: [CHG] Controller DC:A6:32:D8:E8:FB Class: 0x0000041c Aug 06 04:09:18 magnavox bthelper[680]: Changing power on succeeded Aug 06 04:09:18 magnavox sudo[850]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:18 magnavox wireless.js[645]: SETTING APPROPRIATE REG DOMAIN: US Aug 06 04:09:19 magnavox sudo[862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 06 04:09:19 magnavox sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:19 magnavox sudo[862]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:19 magnavox sudo[864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Aug 06 04:09:19 magnavox sudo[864]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:19 magnavox sudo[864]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:19 magnavox wireless.js[645]: SUCCESSFULLY SET NEW REGDOMAIN: US Aug 06 04:09:19 magnavox wireless.js[645]: Start wireless flow Aug 06 04:09:19 magnavox wireless.js[645]: Stopped hotspot (if there).. Aug 06 04:09:19 magnavox sudo[869]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 06 04:09:19 magnavox sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:19 magnavox sudo[869]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:19 magnavox sudo[871]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 06 04:09:19 magnavox sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:19 magnavox sudo[871]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:19 magnavox wireless.js[645]: DHCP IP Aug 06 04:09:19 magnavox wireless.js[645]: Start ap Aug 06 04:09:19 magnavox wpa_supplicant[873]: Successfully initialized wpa_supplicant Aug 06 04:09:19 magnavox sudo[874]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Aug 06 04:09:19 magnavox sudo[874]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:19 magnavox kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 06 04:09:19 magnavox dhcpcd[875]: dev: loaded udev Aug 06 04:09:19 magnavox dhcpcd-run-hooks[893]: wlan0: starting wpa_supplicant Aug 06 04:09:20 magnavox dhcpcd-run-hooks[898]: wlan0: failed to start wpa_supplicant Aug 06 04:09:20 magnavox dhcpcd-run-hooks[899]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Aug 06 04:09:20 magnavox dhcpcd[875]: wlan0: connected to Access Point `' Aug 06 04:09:20 magnavox dhcpcd[875]: no interfaces have a carrier Aug 06 04:09:20 magnavox dhcpcd[875]: forked to background, child pid 909 Aug 06 04:09:20 magnavox dhcpcd[909]: eth0: waiting for carrier Aug 06 04:09:20 magnavox dhcpcd[909]: wlan0: waiting for carrier Aug 06 04:09:20 magnavox sudo[874]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:20 magnavox wireless.js[645]: trying... Aug 06 04:09:20 magnavox sudo[911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 06 04:09:20 magnavox sudo[911]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:20 magnavox sudo[911]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:21 magnavox wireless.js[645]: trying... Aug 06 04:09:21 magnavox sudo[914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 06 04:09:21 magnavox sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:21 magnavox sudo[914]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:22 magnavox wpa_supplicant[897]: wlan0: Trying to associate with SSID 'BEAGLETOWN' Aug 06 04:09:22 magnavox wireless.js[645]: trying... Aug 06 04:09:22 magnavox sudo[917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 06 04:09:22 magnavox sudo[917]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:22 magnavox sudo[917]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:23 magnavox wireless.js[645]: trying... Aug 06 04:09:23 magnavox sudo[920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 06 04:09:23 magnavox sudo[920]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:23 magnavox sudo[920]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:24 magnavox wireless.js[645]: trying... Aug 06 04:09:24 magnavox sudo[923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 06 04:09:24 magnavox sudo[923]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:24 magnavox sudo[923]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:25 magnavox volumio-remote-updater[621]: [2024-08-06 04:09:25] [info] asio async_connect error: system:111 (Connection refused) Aug 06 04:09:25 magnavox volumio-remote-updater[621]: [2024-08-06 04:09:25] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 06 04:09:25 magnavox volumio-remote-updater[621]: [2024-08-06 04:09:25] [error] handle_connect error: Underlying Transport Error Aug 06 04:09:25 magnavox wireless.js[645]: trying... Aug 06 04:09:25 magnavox sudo[926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 06 04:09:25 magnavox sudo[926]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:25 magnavox sudo[926]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:25 magnavox dhcpcd[909]: wlan0: carrier acquired Aug 06 04:09:25 magnavox dhcpcd[909]: wlan0: connected to Access Point `BEAGLETOWN' Aug 06 04:09:25 magnavox wpa_supplicant[897]: wlan0: Associated with 28:80:88:44:12:8d Aug 06 04:09:25 magnavox wpa_supplicant[897]: wlan0: CTRL-EVENT-CONNECTED - Connection to 28:80:88:44:12:8d completed [id=0 id_str=] Aug 06 04:09:25 magnavox wpa_supplicant[897]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 06 04:09:25 magnavox wpa_supplicant[897]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Aug 06 04:09:25 magnavox dhcpcd[909]: DUID 00:01:00:01:29:aa:8b:03:dc:a6:32:d8:e8:fa Aug 06 04:09:25 magnavox dhcpcd[909]: wlan0: IAID 32:d8:e8:fa Aug 06 04:09:25 magnavox dhcpcd[909]: wlan0: adding address fe80::61b5:f62b:315a:26eb Aug 06 04:09:25 magnavox dhcpcd[909]: ipv6_addaddr1: Permission denied Aug 06 04:09:25 magnavox dhcpcd[909]: wlan0: carrier lost Aug 06 04:09:25 magnavox dhcpcd[909]: wlan0: carrier acquired Aug 06 04:09:25 magnavox dhcpcd[909]: wlan0: IAID 32:d8:e8:fa Aug 06 04:09:26 magnavox dhcpcd[909]: wlan0: soliciting an IPv6 router Aug 06 04:09:26 magnavox dhcpcd[909]: wlan0: rebinding lease of 192.168.1.6 Aug 06 04:09:26 magnavox dhcpcd[909]: wlan0: probing address 192.168.1.6/24 Aug 06 04:09:26 magnavox wireless.js[645]: trying... Aug 06 04:09:26 magnavox sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 06 04:09:26 magnavox sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:26 magnavox sudo[941]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:27 magnavox wireless.js[645]: trying... Aug 06 04:09:27 magnavox sudo[944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 06 04:09:27 magnavox sudo[944]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:27 magnavox sudo[944]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:28 magnavox wireless.js[645]: trying... Aug 06 04:09:28 magnavox sudo[947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 06 04:09:28 magnavox sudo[947]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:28 magnavox sudo[947]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:29 magnavox wireless.js[645]: trying... Aug 06 04:09:29 magnavox sudo[950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 06 04:09:29 magnavox sudo[950]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:29 magnavox sudo[950]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:30 magnavox wireless.js[645]: trying... Aug 06 04:09:30 magnavox sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 06 04:09:30 magnavox sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:30 magnavox sudo[953]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:31 magnavox dhcpcd[909]: wlan0: leased 192.168.1.6 for 86400 seconds Aug 06 04:09:31 magnavox avahi-daemon[668]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.6. Aug 06 04:09:31 magnavox dhcpcd[909]: wlan0: adding route to 192.168.1.0/24 Aug 06 04:09:31 magnavox avahi-daemon[668]: New relevant interface wlan0.IPv4 for mDNS. Aug 06 04:09:31 magnavox dhcpcd[909]: wlan0: adding default route via 192.168.1.1 Aug 06 04:09:31 magnavox avahi-daemon[668]: Registering new address record for 192.168.1.6 on wlan0.IPv4. Aug 06 04:09:31 magnavox wireless.js[645]: trying... Aug 06 04:09:31 magnavox sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 06 04:09:31 magnavox sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:31 magnavox sudo[976]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:31 magnavox wireless.js[645]: Connected to: ----BEAGLETOWN Aug 06 04:09:31 magnavox wireless.js[645]: ---- Aug 06 04:09:31 magnavox sudo[979]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 06 04:09:31 magnavox sudo[979]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:31 magnavox sudo[979]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:31 magnavox wireless.js[645]: ... joined AP, wlan0 IPv4 is 192.168.1.6, ipV6 is undefined Aug 06 04:09:31 magnavox wireless.js[645]: It's done! AP Aug 06 04:09:31 magnavox kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 06 04:09:31 magnavox systemd[1]: Started Wireless Services. Aug 06 04:09:31 magnavox systemd[1]: Started Volumio Backend Module. Aug 06 04:09:31 magnavox systemd[1]: Started Volumio Cpu Tweaker. Aug 06 04:09:31 magnavox volumio-cpu-tweak[985]: Setting RT Priority for mpd Aug 06 04:09:31 magnavox volumio-cpu-tweak[985]: pid 35's current scheduling policy: SCHED_OTHER Aug 06 04:09:31 magnavox volumio-cpu-tweak[985]: pid 35's current scheduling priority: 0 Aug 06 04:09:31 magnavox volumio-cpu-tweak[985]: Setting MPD Affinity Aug 06 04:09:31 magnavox volumio-cpu-tweak[985]: pid 3's current affinity mask: f Aug 06 04:09:31 magnavox volumio-cpu-tweak[985]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 06 04:09:31 magnavox volumio-cpu-tweak[985]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 06 04:09:31 magnavox systemd[1]: volumio_cpu_tweak.service: Succeeded. Aug 06 04:09:33 magnavox ntpd[792]: Listen normally on 3 wlan0 192.168.1.6:123 Aug 06 04:09:33 magnavox ntpd[792]: new interface(s) found: waking up resolver Aug 06 04:09:33 magnavox volumio[984]: info: ------------------------------------------- Aug 06 04:09:33 magnavox volumio[984]: info: ----- Volumio3 ---- Aug 06 04:09:33 magnavox volumio[984]: info: ------------------------------------------- Aug 06 04:09:33 magnavox volumio[984]: info: ----- System startup ---- Aug 06 04:09:33 magnavox volumio[984]: info: ------------------------------------------- Aug 06 04:09:34 magnavox volumio[984]: info: MYVOLUMIO Environment detected Aug 06 04:09:34 magnavox volumio[984]: info: Plugin folders cleanup Aug 06 04:09:34 magnavox volumio[984]: info: Scanning into folder /volumio/app/plugins/ Aug 06 04:09:34 magnavox volumio[984]: info: Scanning category audio_interface Aug 06 04:09:34 magnavox volumio[984]: info: Scanning category miscellanea Aug 06 04:09:34 magnavox volumio[984]: info: Scanning category music_service Aug 06 04:09:34 magnavox volumio[984]: info: Scanning category plugins.json Aug 06 04:09:34 magnavox volumio[984]: info: Scanning category system_controller Aug 06 04:09:34 magnavox volumio[984]: info: Scanning category user_interface Aug 06 04:09:34 magnavox volumio[984]: info: Scanning into folder /data/plugins/ Aug 06 04:09:34 magnavox volumio[984]: info: Scanning category music_service Aug 06 04:09:34 magnavox volumio[984]: info: Scanning category user_interface Aug 06 04:09:34 magnavox volumio[984]: info: Plugin folders cleanup completed Aug 06 04:09:34 magnavox volumio[984]: info: ------------------------------------------- Aug 06 04:09:34 magnavox volumio[984]: info: ----- Core plugins startup ---- Aug 06 04:09:34 magnavox volumio[984]: info: ------------------------------------------- Aug 06 04:09:34 magnavox volumio[984]: info: Loading plugins from folder /volumio/app/plugins/ Aug 06 04:09:34 magnavox volumio[984]: info: Adding plugin upnp to MyMusic Plugins Aug 06 04:09:34 magnavox volumio[984]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 06 04:09:34 magnavox volumio[984]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 06 04:09:34 magnavox volumio[984]: info: Loading plugins from folder /data/plugins/ Aug 06 04:09:34 magnavox volumio[984]: info: Loading plugin "system"... Aug 06 04:09:34 magnavox volumio[984]: info: Loading plugin "appearance"... Aug 06 04:09:35 magnavox nmbd[767]: [2024/08/06 04:09:35.032793, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 06 04:09:35 magnavox nmbd[767]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 06 04:09:35 magnavox systemd[1]: Started Samba NMB Daemon. Aug 06 04:09:35 magnavox systemd[1]: Starting Samba Winbind Daemon... Aug 06 04:09:35 magnavox nmbd[767]: [2024/08/06 04:09:35.232910, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 06 04:09:35 magnavox nmbd[767]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.6 for name WORKGROUP<1d>. Aug 06 04:09:35 magnavox nmbd[767]: This response was from IP 192.168.1.200, reporting an IP address of 192.168.1.200. Aug 06 04:09:35 magnavox winbindd[1014]: [2024/08/06 04:09:35.329722, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 06 04:09:35 magnavox winbindd[1014]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 06 04:09:35 magnavox winbindd[1014]: [2024/08/06 04:09:35.348590, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 06 04:09:35 magnavox systemd[1]: Started Samba Winbind Daemon. Aug 06 04:09:35 magnavox winbindd[1014]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 06 04:09:35 magnavox systemd[1]: Starting Samba SMB Daemon... Aug 06 04:09:35 magnavox volumio[984]: info: Loading plugin "network"... Aug 06 04:09:35 magnavox volumio[984]: info: Refreshing Cached IP Addresses Aug 06 04:09:35 magnavox sudo[1021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 06 04:09:35 magnavox sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:35 magnavox sudo[1021]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:35 magnavox volumio[984]: info: Loading plugin "services"... Aug 06 04:09:35 magnavox sudo[1023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 06 04:09:35 magnavox sudo[1023]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:35 magnavox volumio[984]: info: Loading plugin "alsa_controller"... Aug 06 04:09:35 magnavox sudo[1023]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:35 magnavox sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 06 04:09:35 magnavox sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:35 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 06 04:09:35 magnavox volumio[984]: info: Loading plugin "wizard"... Aug 06 04:09:35 magnavox volumio[984]: info: Loading plugin "networkfs"... Aug 06 04:09:35 magnavox volumio-remote-updater[621]: [2024-08-06 04:09:35] [connect] Successful connection Aug 06 04:09:35 magnavox volumio[984]: info: Starting Udev Watcher for removable devices Aug 06 04:09:35 magnavox sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=macipgw,password=macipgw,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.200/Music /mnt/NAS/Music Aug 06 04:09:35 magnavox sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:35 magnavox volumio[984]: info: Ignoring mount for partition: boot Aug 06 04:09:35 magnavox volumio[984]: info: Ignoring mount for partition: volumio Aug 06 04:09:35 magnavox volumio[984]: info: Ignoring mount for partition: volumio_data Aug 06 04:09:35 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 06 04:09:35 magnavox volumio[984]: info: Loading plugin "volumio_command_line_client"... Aug 06 04:09:35 magnavox volumio[984]: info: Loading plugin "upnp"... Aug 06 04:09:35 magnavox volumio[984]: info: [1722917375713] Starting Upmpd Daemon Aug 06 04:09:35 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 06 04:09:35 magnavox volumio[984]: info: Loading plugin "my_music"... Aug 06 04:09:35 magnavox volumio[984]: info: Loading plugin "mpd"... Aug 06 04:09:35 magnavox winbindd[1014]: [2024/08/06 04:09:35.740423, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Aug 06 04:09:35 magnavox winbindd[1014]: res_names->count = 3, expected 4 Aug 06 04:09:35 magnavox smbd[1019]: [2024/08/06 04:09:35.759662, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 06 04:09:35 magnavox smbd[1019]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 06 04:09:35 magnavox systemd[1]: Started Samba SMB Daemon. Aug 06 04:09:35 magnavox systemd[1]: Reached target Multi-User System. Aug 06 04:09:35 magnavox systemd[1]: Reached target Graphical Interface. Aug 06 04:09:35 magnavox systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 06 04:09:35 magnavox kernel: Key type cifs.spnego registered Aug 06 04:09:35 magnavox kernel: Key type cifs.idmap registered Aug 06 04:09:35 magnavox 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. Aug 06 04:09:35 magnavox kernel: CIFS: Attempting to mount \\192.168.1.200\Music Aug 06 04:09:35 magnavox systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 06 04:09:35 magnavox systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 06 04:09:35 magnavox systemd[1]: Startup finished in 15.631s (kernel) + 29.553s (userspace) = 45.184s. Aug 06 04:09:36 magnavox volumio[984]: info: Loading plugin "upnp_browser"... Aug 06 04:09:36 magnavox volumio[984]: info: Loading plugin "alarm-clock"... Aug 06 04:09:36 magnavox volumio[984]: info: Loading plugin "airplay_emulation"... Aug 06 04:09:36 magnavox volumio[984]: info: Starting Shairport Sync Aug 06 04:09:36 magnavox volumio[984]: info: Loading plugin "last_100"... Aug 06 04:09:36 magnavox volumio[984]: info: Loading plugin "webradio"... Aug 06 04:09:36 magnavox volumio[984]: info: Loading plugin "i2s_dacs"... Aug 06 04:09:36 magnavox volumio[984]: info: I2S DAC not set, start Auto-detection Aug 06 04:09:36 magnavox volumio[984]: info: Loading plugin "volumiodiscovery"... Aug 06 04:09:36 magnavox volumio[984]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 06 04:09:36 magnavox node[984]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 06 04:09:36 magnavox volumio[984]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 06 04:09:36 magnavox node[984]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 06 04:09:36 magnavox volumio[984]: *** WARNING *** For more information see Aug 06 04:09:36 magnavox node[984]: *** WARNING *** For more information see Aug 06 04:09:36 magnavox volumio[984]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 06 04:09:36 magnavox node[984]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 06 04:09:36 magnavox volumio[984]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 06 04:09:36 magnavox node[984]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 06 04:09:36 magnavox volumio[984]: *** WARNING *** For more information see Aug 06 04:09:36 magnavox node[984]: *** WARNING *** For more information see Aug 06 04:09:36 magnavox volumio[984]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 06 04:09:36 magnavox volumio[984]: info: Discovery: Started advertising with name: MAGNAVOX Aug 06 04:09:36 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 06 04:09:36 magnavox volumio[984]: info: Loading plugin "spop"... Aug 06 04:09:37 magnavox volumio[984]: info: Loading plugin "youtube2"... Aug 06 04:09:39 magnavox sudo[1026]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:39 magnavox sudo[1037]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:40 magnavox systemd[1]: systemd-fsckd.service: Succeeded. Aug 06 04:09:41 magnavox volumio[984]: info: Loading plugin "now_playing"... Aug 06 04:09:42 magnavox volumio[984]: info: Loading plugin "outputs"... Aug 06 04:09:42 magnavox volumio[984]: info: Loading plugin "albumart"... Aug 06 04:09:42 magnavox volumio[984]: info: Plugin example_plugin is not enabled Aug 06 04:09:42 magnavox volumio[984]: info: Loading plugin "inputs"... Aug 06 04:09:42 magnavox volumio[984]: info: Loading plugin "updater_comm"... Aug 06 04:09:42 magnavox volumio[984]: info: Plugin mpdemulation is not enabled Aug 06 04:09:42 magnavox volumio[984]: info: Loading plugin "rest_api"... Aug 06 04:09:42 magnavox volumio[984]: info: Loading plugin "websocket"... Aug 06 04:09:42 magnavox volumio[984]: info: Starting Socket.io Server version 2.3.0 Aug 06 04:09:42 magnavox volumio[984]: info: Loading plugin "80s80s"... Aug 06 04:09:42 magnavox volumio[984]: Forking 3 albumart workers Aug 06 04:09:42 magnavox sh[538]: timed out Aug 06 04:09:42 magnavox dhcpcd[579]: timed out Aug 06 04:09:42 magnavox sh[538]: dhcpcd exited Aug 06 04:09:42 magnavox dhcpcd[579]: dhcpcd exited Aug 06 04:09:42 magnavox sh[538]: ifup: failed to bring up eth0 Aug 06 04:09:42 magnavox systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Aug 06 04:09:42 magnavox systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Aug 06 04:09:42 magnavox volumio[984]: info: Applying required configuration parameters for plugin 80s80s Aug 06 04:09:42 magnavox volumio[984]: info: [1722917382893] [80s80s] API delay: 30 Aug 06 04:09:42 magnavox volumio[984]: info: Loading plugin "hotelradio"... Aug 06 04:09:43 magnavox volumio[984]: Starting albumart workers Aug 06 04:09:43 magnavox volumio[984]: Starting albumart workers Aug 06 04:09:43 magnavox volumio[984]: Starting albumart workers Aug 06 04:09:44 magnavox volumio[984]: info: Loading plugin "pandora"... Aug 06 04:09:44 magnavox systemd[1]: systemd-hostnamed.service: Succeeded. Aug 06 04:09:45 magnavox volumio[984]: info: Applying required configuration parameters for plugin pandora Aug 06 04:09:45 magnavox volumio[984]: info: Loading plugin "radio_paradise"... Aug 06 04:09:45 magnavox volumio[984]: info: Applying required configuration parameters for plugin radio_paradise Aug 06 04:09:45 magnavox volumio[984]: info: [1722917385454] [RadioParadise] API delay: 5 Aug 06 04:09:45 magnavox volumio[984]: info: Plugin volspotconnect2 is not enabled Aug 06 04:09:45 magnavox volumio[984]: info: Loading plugin "Systeminfo"... Aug 06 04:09:45 magnavox volumio[984]: info: Loading i18n strings for locale en Aug 06 04:09:45 magnavox volumio[984]: Updating browse sources language Aug 06 04:09:45 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::initPlayerControls Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 06 04:09:46 magnavox volumio[984]: Express server listening on port 3000 Aug 06 04:09:46 magnavox volumio[984]: [Metrics] WebUI: 13s 91.32ms Aug 06 04:09:46 magnavox volumio[984]: info: CoreStateMachine::resetVolumioState Aug 06 04:09:46 magnavox volumio[984]: info: CoreStateMachine::getcurrentVolume Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::volumioRetrievevolume Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 04:09:46 magnavox sudo[1130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 06 04:09:46 magnavox sudo[1130]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:46 magnavox sudo[1130]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:46 magnavox sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 06 04:09:46 magnavox sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:46 magnavox sudo[1133]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:46 magnavox volumio[984]: info: VolumeController:: Volume=48 Mute =false Aug 06 04:09:46 magnavox volumio[984]: info: CoreStateMachine::pushState Aug 06 04:09:46 magnavox volumio[984]: info: CorePlayQueue::getTrack 0 Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::volumioPushState Aug 06 04:09:46 magnavox volumio[984]: info: CoreStateMachine::updateTrackBlock Aug 06 04:09:46 magnavox volumio[984]: info: CorePlayQueue::getTrackBlock Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::volumioRetrievevolume Aug 06 04:09:46 magnavox volumio[984]: info: Reloading queue from file Aug 06 04:09:46 magnavox volumio[984]: info: CoreStateMachine::setRepeat true single undefined Aug 06 04:09:46 magnavox volumio[984]: info: CoreStateMachine::pushState Aug 06 04:09:46 magnavox volumio[984]: info: CorePlayQueue::getTrack 0 Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::volumioPushState Aug 06 04:09:46 magnavox volumio[984]: info: CoreStateMachine::setRandom null Aug 06 04:09:46 magnavox volumio[984]: info: CoreStateMachine::pushState Aug 06 04:09:46 magnavox volumio[984]: info: CorePlayQueue::getTrack 0 Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::volumioPushState Aug 06 04:09:46 magnavox volumio[984]: info: Setting Device type: Raspberry PI Aug 06 04:09:46 magnavox volumio[984]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 06 04:09:46 magnavox volumio[984]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Aug 06 04:09:46 magnavox volumio[984]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 06 04:09:46 magnavox volumio[984]: info: VolumeController:: Volume=48 Mute =false Aug 06 04:09:46 magnavox volumio[984]: info: CoreStateMachine::pushState Aug 06 04:09:46 magnavox volumio[984]: info: CorePlayQueue::getTrack 0 Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::volumioPushState Aug 06 04:09:46 magnavox volumio[984]: info: Completed loading Core Plugins Aug 06 04:09:46 magnavox volumio[984]: info: Preparing to generate the ALSA configuration file Aug 06 04:09:46 magnavox sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 06 04:09:46 magnavox sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:46 magnavox volumio[984]: info: Asound.conf file written Aug 06 04:09:46 magnavox systemd[1]: Started UPnP Renderer front-end to MPD. Aug 06 04:09:46 magnavox sudo[1144]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:46 magnavox sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 06 04:09:46 magnavox sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:46 magnavox sudo[1148]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:46 magnavox volumio[984]: info: Output device has changed, restarting MPD Aug 06 04:09:46 magnavox sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 06 04:09:46 magnavox sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:46 magnavox sudo[1153]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:46 magnavox volumio[984]: info: Output device has changed, restarting Shairport Sync Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 04:09:46 magnavox sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 06 04:09:46 magnavox sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:46 magnavox systemd[1]: Listening on mpd.socket. Aug 06 04:09:46 magnavox volumio[984]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 06 04:09:46 magnavox systemd[1]: Starting Music Player Daemon... Aug 06 04:09:46 magnavox volumio[984]: info: ___________ START PLUGINS ___________ Aug 06 04:09:46 magnavox volumio[984]: info: ControllerMpd::onStart: Initializing MPD Aug 06 04:09:46 magnavox volumio[984]: info: Creating MPD Configuration file Aug 06 04:09:46 magnavox sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 06 04:09:46 magnavox sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:46 magnavox sudo[1164]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 06 04:09:46 magnavox volumio[984]: info: [1722917386618] CoreMusicLibrary::Adding element Media Servers Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 06 04:09:46 magnavox sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 06 04:09:46 magnavox sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:46 magnavox sudo[1162]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 06 04:09:46 magnavox sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 04:09:46 magnavox sudo[1162]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:46 magnavox systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 06 04:09:46 magnavox systemd[1]: mpd.service: Succeeded. Aug 06 04:09:46 magnavox systemd[1]: Stopped Music Player Daemon. Aug 06 04:09:46 magnavox volumio[984]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 04:09:46 magnavox systemd[1]: Starting Music Player Daemon... Aug 06 04:09:46 magnavox volumio[984]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 06 04:09:46 magnavox volumio[984]: info: [1722917386730] CoreMusicLibrary::Adding element Last_100 Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 06 04:09:46 magnavox volumio[984]: info: [1722917386732] CoreMusicLibrary::Adding element Webradio Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 06 04:09:46 magnavox volumio[984]: info: Initializing BBC Radios Aug 06 04:09:46 magnavox sudo[1171]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 06 04:09:46 magnavox sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:46 magnavox sudo[1171]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 06 04:09:46 magnavox sudo[1171]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 04:09:46 magnavox volumio[984]: info: Checking for old spotify connect plugin installed Aug 06 04:09:46 magnavox volumio[984]: info: [youtube2-auth] Starting auth flow... Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 06 04:09:46 magnavox volumio[984]: info: [1722917386870] CoreMusicLibrary::Adding element YouTube2 Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 06 04:09:46 magnavox volumio[984]: Cannot find translation for source YouTube2 Aug 06 04:09:46 magnavox volumio[984]: info: [now-playing-config] Config is up to date. Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 06 04:09:46 magnavox volumio[984]: info: Discovery: Getting this device information Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::volumioGetState Aug 06 04:09:46 magnavox volumio[984]: info: CorePlayQueue::getTrack 0 Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 06 04:09:46 magnavox volumio[984]: info: [1722917386909] CoreMusicLibrary::Adding element 80s80s Radio Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 06 04:09:46 magnavox volumio[984]: Cannot find translation for source YouTube2 Aug 06 04:09:46 magnavox volumio[984]: Cannot find translation for source 80s80s Radio Aug 06 04:09:46 magnavox volumio[984]: info: Stopping AccessToken refresher cron for Hotel Radio Aug 06 04:09:46 magnavox volumio[984]: info: AccessToken refresher cron started for Hotel Radio Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 06 04:09:46 magnavox volumio[984]: info: [1722917386938] CoreMusicLibrary::Adding element Pandora Radio Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 06 04:09:46 magnavox volumio[984]: Cannot find translation for source YouTube2 Aug 06 04:09:46 magnavox volumio[984]: Cannot find translation for source 80s80s Radio Aug 06 04:09:46 magnavox volumio[984]: Cannot find translation for source Pandora Radio Aug 06 04:09:46 magnavox volumio[984]: info: [1722917386940] ControllerPandora::initializeMQTT Aug 06 04:09:46 magnavox volumio[984]: info: [1722917386941] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 06 04:09:46 magnavox volumio[984]: info: [1722917386948] CoreMusicLibrary::Adding element Radio Paradise Aug 06 04:09:46 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 06 04:09:46 magnavox volumio[984]: Cannot find translation for source YouTube2 Aug 06 04:09:46 magnavox volumio[984]: Cannot find translation for source 80s80s Radio Aug 06 04:09:46 magnavox volumio[984]: Cannot find translation for source Pandora Radio Aug 06 04:09:46 magnavox volumio[984]: Cannot find translation for source Radio Paradise Aug 06 04:09:46 magnavox volumio[984]: info: Volumio Calling Home Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 06 04:09:47 magnavox volumio[984]: [SpotifyConnect] Creating VLS config file Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: [SpotifyConnect] Starting metadata listener Aug 06 04:09:47 magnavox volumio[984]: info: [now-playing-app] App is listening on port 4004. Aug 06 04:09:47 magnavox volumio[984]: info: [1722917387060] ControllerPandora::validateAndSetAccountOptions Aug 06 04:09:47 magnavox volumio[984]: info: [1722917387060] ControllerPandora::initialSetup Aug 06 04:09:47 magnavox volumio[984]: info: [1722917387062] ControllerPandora::PandoraHandler::init Aug 06 04:09:47 magnavox volumio[984]: info: [1722917387062] ControllerPandora::PandoraHandler::loadErrorCodes Aug 06 04:09:47 magnavox sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Aug 06 04:09:47 magnavox volumio[984]: info: [1722917387073] [Pandora] PandoraHandler::setMaxStationTracks: 16 Aug 06 04:09:47 magnavox sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 06 04:09:47 magnavox volumio[984]: info: [1722917387074] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty Aug 06 04:09:47 magnavox volumio[984]: info: [1722917387076] ControllerPandora::PandoraHandler::setAccountOptions Aug 06 04:09:47 magnavox volumio[984]: info: [1722917387077] ControllerPandora::PreventAuthTimeout::fn Aug 06 04:09:47 magnavox volumio[984]: info: [1722917387078] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations Aug 06 04:09:47 magnavox volumio[984]: info: [1722917387090] [Pandora] PreventAuthTimeout::init: Timer loaded Aug 06 04:09:47 magnavox volumio[984]: info: [1722917387090] [Pandora] PreventAuthTimeout::init: Interval set to 10800000 ms Aug 06 04:09:47 magnavox volumio[984]: info: [1722917387091] [Pandora] ExpireOldTracks::init: Timer loaded Aug 06 04:09:47 magnavox volumio[984]: info: [1722917387091] [Pandora] ExpireOldTracks::init: Interval set to 300000 ms Aug 06 04:09:47 magnavox volumio[984]: info: [1722917387092] ControllerPandora::flushPandora Aug 06 04:09:47 magnavox volumio[984]: info: CorePlayQueue::getQueue Aug 06 04:09:47 magnavox volumio[984]: info: CorePlayQueue::clearPlayQueue Aug 06 04:09:47 magnavox volumio[984]: info: CorePlayQueue::saveQueue Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::volumioPushQueue Aug 06 04:09:47 magnavox volumio[984]: info: Discovery: adding 0d6022fd-c465-428d-a677-6f43a2c15049 Aug 06 04:09:47 magnavox volumio[984]: info: Discovery: Found device MAGNAVOX Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::volumioGetState Aug 06 04:09:47 magnavox volumio[984]: info: CorePlayQueue::getTrack 0 Aug 06 04:09:47 magnavox volumio[984]: info: MPD Permissions set Aug 06 04:09:47 magnavox volumio[984]: info: MPD Permissions set Aug 06 04:09:47 magnavox volumio[984]: info: Upmpdcli Daemon Started Aug 06 04:09:47 magnavox systemd[1]: Started Volspotconnect2 Daemon. Aug 06 04:09:47 magnavox sudo[1193]: pam_unix(sudo:session): session closed for user root Aug 06 04:09:47 magnavox volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 06 04:09:47 magnavox volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 06 04:09:47 magnavox volumio[984]: [SpotifyConnect] Vollibrespot Daemon service started! Aug 06 04:09:47 magnavox volumio[984]: [Metrics] SpotifyConnect: 0s 411.07ms Aug 06 04:09:47 magnavox volumio[984]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 06 04:09:47 magnavox volumio[984]: ReferenceError: deferred is not defined Aug 06 04:09:47 magnavox volumio[984]: at ClientRequest. (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) Aug 06 04:09:47 magnavox volumio[984]: at ClientRequest.emit (events.js:315:20) Aug 06 04:09:47 magnavox volumio[984]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Aug 06 04:09:47 magnavox volumio[984]: at TLSSocket.emit (events.js:315:20) Aug 06 04:09:47 magnavox volumio[984]: at emitErrorNT (internal/streams/destroy.js:106:8) Aug 06 04:09:47 magnavox volumio[984]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Aug 06 04:09:47 magnavox volumio[984]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 06 04:09:47 magnavox volumio[984]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 06 04:09:47 magnavox volumio[1195]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Aug 06 04:09:47 magnavox volumio[1195]: Reading Config from "/tmp/volspotify.toml" Aug 06 04:09:47 magnavox volumio[1195]: [Vollibrespot] : Using Alsa backend with device: volumio Aug 06 04:09:47 magnavox sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-06 04:08 Aug 06 04:09:47 magnavox sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"