-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Mon 2024-05-13 22:08:35 JST. --
May 13 22:07:24 volumio kernel: usb usb1: SerialNumber: 3f980000.usb
May 13 22:07:24 volumio kernel: hub 1-0:1.0: USB hub found
May 13 22:07:24 volumio kernel: hub 1-0:1.0: 1 port detected
May 13 22:07:24 volumio kernel: dwc_otg: FIQ enabled
May 13 22:07:24 volumio kernel: dwc_otg: NAK holdoff enabled
May 13 22:07:24 volumio kernel: dwc_otg: FIQ split-transaction FSM enabled
May 13 22:07:24 volumio kernel: Module dwc_common_port init
May 13 22:07:24 volumio kernel: usbcore: registered new interface driver usb-storage
May 13 22:07:24 volumio kernel: mousedev: PS/2 mouse device common for all mice
May 13 22:07:24 volumio kernel: sdhci: Secure Digital Host Controller Interface driver
May 13 22:07:24 volumio kernel: sdhci: Copyright(c) Pierre Ossman
May 13 22:07:24 volumio kernel: sdhci-pltfm: SDHCI platform and OF driver helper
May 13 22:07:24 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs
May 13 22:07:24 volumio kernel: hid: raw HID events driver (C) Jiri Kosina
May 13 22:07:24 volumio kernel: usbcore: registered new interface driver usbhid
May 13 22:07:24 volumio kernel: usbhid: USB HID core driver
May 13 22:07:24 volumio kernel: Initializing XFRM netlink socket
May 13 22:07:24 volumio kernel: NET: Registered PF_PACKET protocol family
May 13 22:07:24 volumio kernel: Key type dns_resolver registered
May 13 22:07:24 volumio kernel: Registering SWP/SWPB emulation handler
May 13 22:07:24 volumio kernel: registered taskstats version 1
May 13 22:07:24 volumio kernel: Loading compiled-in X.509 certificates
May 13 22:07:24 volumio kernel: Key type .fscrypt registered
May 13 22:07:24 volumio kernel: Key type fscrypt-provisioning registered
May 13 22:07:24 volumio kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled
May 13 22:07:24 volumio kernel: 3f201000.serial: ttyAMA1 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2
May 13 22:07:24 volumio kernel: serial serial0: tty port ttyAMA1 registered
May 13 22:07:24 volumio kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
May 13 22:07:24 volumio kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
May 13 22:07:24 volumio kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
May 13 22:07:24 volumio kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
May 13 22:07:24 volumio kernel: sdhost: log_buf @ 6c681618 (f4113000)
May 13 22:07:24 volumio kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
May 13 22:07:24 volumio kernel: of_cfs_init
May 13 22:07:24 volumio kernel: of_cfs_init: OK
May 13 22:07:24 volumio kernel: Freeing unused kernel image (initmem) memory: 1024K
May 13 22:07:24 volumio kernel: Indeed it is in host mode hprt0 = 00021501
May 13 22:07:24 volumio kernel: mmc0: host does not support reading read-only switch, assuming write-enable
May 13 22:07:24 volumio kernel: mmc0: new high speed SDHC card at address 0001
May 13 22:07:24 volumio kernel: mmcblk0: mmc0:0001 ASTC 29.1 GiB
May 13 22:07:24 volumio kernel: mmcblk0: p1 p2 p3
May 13 22:07:24 volumio kernel: mmcblk0: mmc0:0001 ASTC 29.1 GiB
May 13 22:07:24 volumio kernel: mmc1: new high speed SDIO card at address 0001
May 13 22:07:24 volumio kernel: Run /init as init process
May 13 22:07:24 volumio kernel: with arguments:
May 13 22:07:24 volumio kernel: /init
May 13 22:07:24 volumio kernel: splash
May 13 22:07:24 volumio kernel: with environment:
May 13 22:07:24 volumio kernel: HOME=/
May 13 22:07:24 volumio kernel: TERM=linux
May 13 22:07:24 volumio kernel: imgpart=/dev/mmcblk0p2
May 13 22:07:24 volumio kernel: imgfile=/volumio_current.sqsh
May 13 22:07:24 volumio kernel: bootdelay=5
May 13 22:07:24 volumio kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg
May 13 22:07:24 volumio kernel: Indeed it is in host mode hprt0 = 00001101
May 13 22:07:24 volumio initramfs: Booting Volumio for BCM2835
May 13 22:07:24 volumio initramfs: This script mounts rootfs RO with an overlay RW layer.
May 13 22:07:24 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
May 13 22:07:24 volumio initramfs: IMGPART=/dev/mmcblk0p2
May 13 22:07:24 volumio initramfs: IMGFILE=/volumio_current.sqsh
May 13 22:07:24 volumio initramfs: Boot delay (except first time) will be 5 seconds
May 13 22:07:24 volumio initramfs: /dev/mmcblk0p2:
May 13 22:07:24 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
May 13 22:07:24 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
May 13 22:07:24 volumio kernel: hub 1-1:1.0: USB hub found
May 13 22:07:24 volumio kernel: hub 1-1:1.0: 4 ports detected
May 13 22:07:24 volumio kernel: EXT4-fs (mmcblk0p2): recovery complete
May 13 22:07:24 volumio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none.
May 13 22:07:24 volumio initramfs: Doing a 5 second delay here to give kernel load a headstart
May 13 22:07:24 volumio kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg
May 13 22:07:24 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
May 13 22:07:24 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
May 13 22:07:24 volumio kernel: hub 1-1.1:1.0: USB hub found
May 13 22:07:24 volumio kernel: hub 1-1.1:1.0: 3 ports detected
May 13 22:07:24 volumio kernel: dwc_otg_handle_wakeup_detected_intr lxstate = 2
May 13 22:07:24 volumio kernel: usb 1-1.1.1: new high-speed USB device number 4 using dwc_otg
May 13 22:07:24 volumio kernel: usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00
May 13 22:07:24 volumio kernel: usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
May 13 22:07:24 volumio kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed
May 13 22:07:24 volumio kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64
May 13 22:07:24 volumio initramfs: Checking for USB updates
May 13 22:07:24 volumio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline)
May 13 22:07:24 volumio kernel: loop0: detected capacity change from 0 to 976664
May 13 22:07:24 volumio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
May 13 22:07:24 volumio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
May 13 22:07:24 volumio kernel: EXT4-fs (mmcblk0p3): recovery complete
May 13 22:07:24 volumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none.
May 13 22:07:24 volumio initramfs: With Option:
May 13 22:07:24 volumio initramfs: VOLUMIO_VERSION="3.661"
May 13 22:07:24 volumio initramfs: Finish initramfs, continue booting Volumio
May 13 22:07:24 volumio systemd[1]: System time before build time, advancing clock.
May 13 22:07:24 volumio kernel: NET: Registered PF_INET6 protocol family
May 13 22:07:24 volumio kernel: Segment Routing with IPv6
May 13 22:07:24 volumio kernel: In-situ OAM (IOAM) with IPv6
May 13 22:07:24 volumio 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)
May 13 22:07:24 volumio systemd[1]: Detected architecture arm.
May 13 22:07:24 volumio systemd[1]: Set hostname to .
May 13 22:07:24 volumio 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.
May 13 22:07:24 volumio systemd[1]: Listening on Journal Audit Socket.
May 13 22:07:24 volumio systemd[1]: Created slice User and Session Slice.
May 13 22:07:24 volumio systemd[1]: Created slice system-getty.slice.
May 13 22:07:24 volumio systemd[1]: Created slice system-systemd\x2dfsck.slice.
May 13 22:07:24 volumio systemd[1]: Listening on Journal Socket.
May 13 22:07:24 volumio systemd[1]: Starting Create list of required static device nodes for the current kernel...
May 13 22:07:24 volumio systemd-journald[201]: Journal started
May 13 22:07:24 volumio systemd-journald[201]: Runtime journal (/run/log/journal/32836d460d421189c56ead0a6628f62c) is 7.5M, max 30.0M, 22.5M free.
May 13 22:07:24 volumio fake-hwclock[191]: Mon May 13 13:07:24 UTC 2024
May 13 22:07:24 volumio systemd-modules-load[204]: Inserted module 'i2c_dev'
May 13 22:07:24 volumio kernel: i2c_dev: i2c /dev entries driver
May 13 22:07:24 volumio systemd[1]: Started Load Kernel Modules.
May 13 22:07:24 volumio systemd[1]: Condition check resulted in FUSE Control File System being skipped.
May 13 22:07:24 volumio systemd[1]: Mounting Kernel Configuration File System...
May 13 22:07:24 volumio systemd[1]: Starting Apply Kernel Variables...
May 13 22:07:24 volumio systemd[1]: Started Load/Save Random Seed.
May 13 22:07:24 volumio systemd[1]: Mounted Kernel Configuration File System.
May 13 22:07:24 volumio systemd[1]: Started Create System Users.
May 13 22:07:24 volumio systemd[1]: Starting Create Static Device Nodes in /dev...
May 13 22:07:24 volumio systemd[1]: dynamicswap.service: Succeeded.
May 13 22:07:24 volumio systemd[1]: Started Apply Kernel Variables.
May 13 22:07:24 volumio systemd[1]: Started udev Coldplug all Devices.
May 13 22:07:24 volumio systemd[1]: Starting Helper to synchronize boot up for ifupdown...
May 13 22:07:24 volumio systemd[1]: Started Create Static Device Nodes in /dev.
May 13 22:07:24 volumio systemd[1]: Starting udev Kernel Device Manager...
May 13 22:07:24 volumio systemd[1]: Reached target Local File Systems (Pre).
May 13 22:07:24 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway.
May 13 22:07:24 volumio systemd[1]: Mounting /tmp...
May 13 22:07:24 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway.
May 13 22:07:24 volumio systemd[1]: Mounting /var/log...
May 13 22:07:24 volumio systemd[1]: Mounting /var/spool/cups...
May 13 22:07:24 volumio systemd[1]: Mounted /tmp.
May 13 22:07:24 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown.
May 13 22:07:24 volumio systemd[1]: Mounted /var/log.
May 13 22:07:24 volumio systemd[1]: Mounted /var/spool/cups.
May 13 22:07:24 volumio systemd[1]: Mounting /var/spool/cups/tmp...
May 13 22:07:24 volumio systemd[1]: Starting Flush Journal to Persistent Storage...
May 13 22:07:24 volumio systemd[1]: Mounted /var/spool/cups/tmp.
May 13 22:07:24 volumio systemd-udevd[223]: Network interface NamePolicy= disabled on kernel command line, ignoring.
May 13 22:07:24 volumio systemd[1]: Started udev Kernel Device Manager.
May 13 22:07:24 volumio systemd[1]: Starting Show Plymouth Boot Screen...
May 13 22:07:24 volumio systemd[1]: Received SIGRTMIN+20 from PID 240 (plymouthd).
May 13 22:07:24 volumio systemd-journald[201]: Runtime journal (/run/log/journal/32836d460d421189c56ead0a6628f62c) is 7.5M, max 30.0M, 22.5M free.
May 13 22:07:24 volumio systemd[1]: Started Flush Journal to Persistent Storage.
May 13 22:07:24 volumio systemd[1]: Started Show Plymouth Boot Screen.
May 13 22:07:24 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
May 13 22:07:24 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped.
May 13 22:07:24 volumio systemd[1]: Reached target Local Encrypted Volumes.
May 13 22:07:24 volumio systemd[1]: Reached target Paths.
May 13 22:07:24 volumio kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
May 13 22:07:24 volumio kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver
May 13 22:07:24 volumio kernel: [vc_sm_connected_init]: start
May 13 22:07:24 volumio kernel: [vc_sm_connected_init]: installed successfully
May 13 22:07:24 volumio kernel: mc: Linux media interface: v0.10
May 13 22:07:24 volumio kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000
May 13 22:07:24 volumio kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem
May 13 22:07:24 volumio kernel: videodev: Linux video capture interface: v2.00
May 13 22:07:25 volumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
May 13 22:07:25 volumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
May 13 22:07:25 volumio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
May 13 22:07:25 volumio kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
May 13 22:07:25 volumio kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
May 13 22:07:25 volumio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
May 13 22:07:25 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10
May 13 22:07:25 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode
May 13 22:07:25 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11
May 13 22:07:25 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode
May 13 22:07:25 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12
May 13 22:07:25 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp
May 13 22:07:25 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18
May 13 22:07:25 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
May 13 22:07:25 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31
May 13 22:07:25 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
May 13 22:07:25 volumio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
May 13 22:07:25 volumio kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored
May 13 22:07:25 volumio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels
May 13 22:07:25 volumio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels
May 13 22:07:25 volumio systemd[1]: Found device /dev/mmcblk0p1.
May 13 22:07:25 volumio systemd-udevd[235]: Using default interface naming scheme 'v240'.
May 13 22:07:25 volumio systemd-udevd[235]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
May 13 22:07:25 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database
May 13 22:07:25 volumio kernel: Bluetooth: Core ver 2.22
May 13 22:07:25 volumio kernel: NET: Registered PF_BLUETOOTH protocol family
May 13 22:07:25 volumio kernel: Bluetooth: HCI device and connection manager initialized
May 13 22:07:25 volumio kernel: Bluetooth: HCI socket layer initialized
May 13 22:07:25 volumio kernel: Bluetooth: L2CAP socket layer initialized
May 13 22:07:25 volumio kernel: Bluetooth: SCO socket layer initialized
May 13 22:07:26 volumio kernel: Bluetooth: HCI UART driver ver 2.3
May 13 22:07:26 volumio kernel: Bluetooth: HCI UART protocol H4 registered
May 13 22:07:26 volumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered
May 13 22:07:26 volumio kernel: Bluetooth: HCI UART protocol Broadcom registered
May 13 22:07:26 volumio kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator
May 13 22:07:26 volumio kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator
May 13 22:07:26 volumio kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf'
May 13 22:07:26 volumio kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328'
May 13 22:07:26 volumio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
May 13 22:07:26 volumio kernel: uart-pl011 3f201000.serial: no DMA platform data
May 13 22:07:26 volumio systemd-udevd[230]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
May 13 22:07:26 volumio kernel: Bluetooth: hci0: BCM: chip id 107
May 13 22:07:26 volumio kernel: Bluetooth: hci0: BCM: features 0x2f
May 13 22:07:26 volumio kernel: Bluetooth: hci0: BCM4345C0
May 13 22:07:26 volumio kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000
May 13 22:07:26 volumio kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch
May 13 22:07:26 volumio kernel: brcmfmac: F1 signature read @0x18000000=0x15264345
May 13 22:07:26 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
May 13 22:07:26 volumio kernel: usbcore: registered new interface driver brcmfmac
May 13 22:07:26 volumio 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
May 13 22:07:26 volumio systemd-udevd[237]: Using default interface naming scheme 'v240'.
May 13 22:07:26 volumio systemd-udevd[237]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
May 13 22:07:27 volumio kernel: Bluetooth: hci0: BCM: features 0x2f
May 13 22:07:27 volumio kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+
May 13 22:07:27 volumio kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342
May 13 22:07:27 volumio kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac)
May 13 22:07:27 volumio systemd-udevd[229]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 1' failed with exit code 17.
May 13 22:07:27 volumio kernel: hwmon hwmon1: Undervoltage detected!
May 13 22:07:28 volumio systemd[1]: Condition check resulted in 7800 being skipped.
May 13 22:07:28 volumio systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped.
May 13 22:07:28 volumio systemd[1]: Created slice system-bthelper.slice.
May 13 22:07:28 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
May 13 22:07:28 volumio systemd[1]: Condition check resulted in FUSE Control File System being skipped.
May 13 22:07:28 volumio systemd[1]: Condition check resulted in Huge Pages File System being skipped.
May 13 22:07:28 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
May 13 22:07:28 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped.
May 13 22:07:28 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
May 13 22:07:28 volumio systemd[1]: Starting File System Check on /dev/mmcblk0p1...
May 13 22:07:28 volumio systemd[1]: Starting Load/Save RF Kill Switch Status...
May 13 22:07:28 volumio systemd[1]: Started File System Check Daemon to report status.
May 13 22:07:28 volumio systemd[1]: Started Load/Save RF Kill Switch Status.
May 13 22:07:28 volumio systemd-fsck[359]: fsck.fat 4.1 (2017-01-24)
May 13 22:07:28 volumio systemd-fsck[359]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt.
May 13 22:07:28 volumio systemd-fsck[359]: Automatically removing dirty bit.
May 13 22:07:28 volumio systemd-fsck[359]: Performing changes.
May 13 22:07:28 volumio systemd-fsck[359]: /dev/mmcblk0p1: 367 files, 33363/46774 clusters
May 13 22:07:28 volumio systemd[1]: Started File System Check on /dev/mmcblk0p1.
May 13 22:07:28 volumio systemd[1]: Mounting /boot...
May 13 22:07:28 volumio systemd[1]: Mounted /boot.
May 13 22:07:28 volumio systemd[1]: Reached target Local File Systems.
May 13 22:07:28 volumio systemd[1]: Starting Raise network interfaces...
May 13 22:07:28 volumio systemd[1]: Started ifup for wlan0.
May 13 22:07:28 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped.
May 13 22:07:28 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
May 13 22:07:28 volumio systemd[1]: Starting Preprocess NFS configuration...
May 13 22:07:28 volumio systemd[1]: Starting Create Volatile Files and Directories...
May 13 22:07:28 volumio systemd[1]: Started ifup for eth0.
May 13 22:07:28 volumio systemd[1]: plymouth-read-write.service: Succeeded.
May 13 22:07:28 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
May 13 22:07:28 volumio systemd[1]: Received SIGRTMIN+20 from PID 240 (plymouthd).
May 13 22:07:28 volumio systemd[1]: nfs-config.service: Succeeded.
May 13 22:07:28 volumio systemd[1]: Started Preprocess NFS configuration.
May 13 22:07:28 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped.
May 13 22:07:28 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped.
May 13 22:07:28 volumio systemd[1]: Reached target NFS client services.
May 13 22:07:28 volumio systemd[1]: Started Create Volatile Files and Directories.
May 13 22:07:28 volumio systemd[1]: Starting RPC bind portmap service...
May 13 22:07:28 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm.
May 13 22:07:28 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped.
May 13 22:07:28 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown...
May 13 22:07:28 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
May 13 22:07:28 volumio kernel: 8021q: 802.1Q VLAN Support v1.8
May 13 22:07:29 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown.
May 13 22:07:29 volumio systemd[1]: Started RPC bind portmap service.
May 13 22:07:29 volumio systemd[1]: Reached target Remote File Systems (Pre).
May 13 22:07:29 volumio systemd[1]: Reached target Remote File Systems.
May 13 22:07:29 volumio systemd[1]: Reached target RPC Port Mapper.
May 13 22:07:29 volumio systemd[1]: Reached target System Initialization.
May 13 22:07:29 volumio systemd[1]: Listening on D-Bus System Message Bus Socket.
May 13 22:07:29 volumio systemd[1]: Started Volumio Splash.
May 13 22:07:29 volumio systemd[474]: volumiosplash.service: Failed to execute command: No such file or directory
May 13 22:07:29 volumio systemd[474]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory
May 13 22:07:29 volumio systemd[1]: Started Daily apt download activities.
May 13 22:07:29 volumio systemd[1]: Started Daily apt upgrade and clean activities.
May 13 22:07:29 volumio systemd[1]: Started Daily Cleanup of Temporary Directories.
May 13 22:07:29 volumio systemd[1]: Reached target Timers.
May 13 22:07:29 volumio systemd[1]: Listening on triggerhappy.socket.
May 13 22:07:29 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
May 13 22:07:29 volumio systemd[1]: Reached target Sockets.
May 13 22:07:29 volumio systemd[1]: Reached target Basic System.
May 13 22:07:29 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped.
May 13 22:07:29 volumio systemd[1]: Starting triggerhappy global hotkey daemon...
May 13 22:07:29 volumio systemd[1]: Starting Configure Bluetooth Modems connected by UART...
May 13 22:07:29 volumio systemd[1]: Started volumio-remote-updater.service.
May 13 22:07:29 volumio systemd[1]: Starting Wireless Services...
May 13 22:07:29 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped.
May 13 22:07:29 volumio sh[377]: eth0: waiting for carrier
May 13 22:07:29 volumio kernel: 8021q: adding VLAN 0 to HW filter on device eth0
May 13 22:07:29 volumio kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down
May 13 22:07:29 volumio dhcpcd[417]: eth0: waiting for carrier
May 13 22:07:29 volumio systemd[1]: Starting dhcpcd on all interfaces...
May 13 22:07:29 volumio systemd[1]: Started Volumio Iptables Module.
May 13 22:07:29 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped.
May 13 22:07:29 volumio systemd[1]: Starting Login Service...
May 13 22:07:29 volumio systemd[1]: Started D-Bus System Message Bus.
May 13 22:07:29 volumio thd[495]: Unable to parse trigger line:
May 13 22:07:29 volumio thd[495]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle
May 13 22:07:29 volumio thd[495]: Unable to parse trigger line:
May 13 22:07:29 volumio thd[495]: Unable to parse trigger line:
May 13 22:07:29 volumio thd[495]: Found socket passed from systemd
May 13 22:07:29 volumio dhcpcd[501]: Not running dhcpcd because /etc/network/interfaces
May 13 22:07:29 volumio dhcpcd[501]: defines some interfaces that will use a
May 13 22:07:29 volumio dhcpcd[501]: DHCP client or static address
May 13 22:07:29 volumio systemd[1]: Starting WPA supplicant...
May 13 22:07:29 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
May 13 22:07:29 volumio systemd[1]: Started Manage Sound Card State (restore and store).
May 13 22:07:29 volumio alsactl[511]: alsactl 1.1.8 daemon started
May 13 22:07:29 volumio systemd[1]: Starting Save/Restore Sound Card State...
May 13 22:07:29 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
May 13 22:07:29 volumio systemd[1]: Started Volumio Log Rotation Service.
May 13 22:07:29 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling...
May 13 22:07:29 volumio systemd[1]: Started triggerhappy global hotkey daemon.
May 13 22:07:29 volumio systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC
May 13 22:07:29 volumio systemd[1]: volumiosplash.service: Failed with result 'exit-code'.
May 13 22:07:29 volumio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED
May 13 22:07:29 volumio systemd[1]: dhcpcd.service: Failed with result 'exit-code'.
May 13 22:07:29 volumio systemd[1]: Failed to start dhcpcd on all interfaces.
May 13 22:07:29 volumio sh[367]: wlan0=wlan0
May 13 22:07:29 volumio systemd-logind[505]: New seat seat0.
May 13 22:07:29 volumio avahi-daemon[522]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113).
May 13 22:07:29 volumio avahi-daemon[522]: Successfully dropped root privileges.
May 13 22:07:29 volumio avahi-daemon[522]: avahi-daemon 0.7 starting up.
May 13 22:07:30 volumio avahi-daemon[522]: Successfully called chroot().
May 13 22:07:30 volumio avahi-daemon[522]: Successfully dropped remaining capabilities.
May 13 22:07:30 volumio avahi-daemon[522]: No service file found in /etc/avahi/services.
May 13 22:07:30 volumio systemd[1]: Started Login Service.
May 13 22:07:30 volumio avahi-daemon[522]: Network interface enumeration completed.
May 13 22:07:30 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack.
May 13 22:07:30 volumio avahi-daemon[522]: Server startup complete. Host name is volumio.local. Local service cookie is 4065745108.
May 13 22:07:30 volumio systemd[1]: Started Raise network interfaces.
May 13 22:07:30 volumio wpa_supplicant[509]: Successfully initialized wpa_supplicant
May 13 22:07:30 volumio systemd[1]: hciuart.service: Succeeded.
May 13 22:07:30 volumio systemd[1]: Started Configure Bluetooth Modems connected by UART.
May 13 22:07:30 volumio systemd[1]: Started Save/Restore Sound Card State.
May 13 22:07:30 volumio systemd[1]: Started WPA supplicant.
May 13 22:07:30 volumio systemd[1]: Reached target Sound Card.
May 13 22:07:30 volumio systemd[1]: Starting Raspberry Pi bluetooth helper...
May 13 22:07:30 volumio systemd[1]: Reached target Network.
May 13 22:07:30 volumio systemd[1]: Starting Permit User Sessions...
May 13 22:07:30 volumio systemd[1]: Starting Network Time Service...
May 13 22:07:30 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
May 13 22:07:30 volumio systemd[1]: Reached target Network is Online.
May 13 22:07:30 volumio systemd[1]: Starting /etc/rc.local Compatibility...
May 13 22:07:30 volumio systemd[1]: Starting LSB: Brings up/down network automatically...
May 13 22:07:30 volumio systemd[1]: Starting Samba NMB Daemon...
May 13 22:07:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 13 22:07:30 volumio systemd[1]: Started /etc/rc.local Compatibility.
May 13 22:07:31 volumio systemd[1]: Started Permit User Sessions.
May 13 22:07:31 volumio systemd[1]: Starting Hold until boot process finishes up...
May 13 22:07:31 volumio bthelper[558]: Raspberry Pi BDADDR already set
May 13 22:07:31 volumio systemd[1]: Starting Terminate Plymouth Boot Screen...
May 13 22:07:31 volumio systemd[1]: Started Raspberry Pi bluetooth helper.
May 13 22:07:31 volumio volumio-remote-updater[497]: Error: No active session
May 13 22:07:31 volumio volumio-remote-updater[497]: [2024-05-13 22:07:31] [info] asio async_connect error: system:111 (Connection refused)
May 13 22:07:31 volumio volumio-remote-updater[497]: [2024-05-13 22:07:31] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
May 13 22:07:31 volumio volumio-remote-updater[497]: [2024-05-13 22:07:31] [error] handle_connect error: Underlying Transport Error
May 13 22:07:31 volumio systemd[1]: Received SIGRTMIN+21 from PID 240 (plymouthd).
May 13 22:07:31 volumio systemd[1]: Starting Bluetooth service...
May 13 22:07:31 volumio systemd[1]: plymouth-start.service: Succeeded.
May 13 22:07:31 volumio systemd[1]: plymouth-quit-wait.service: Succeeded.
May 13 22:07:31 volumio systemd[1]: Started Hold until boot process finishes up.
May 13 22:07:31 volumio systemd[1]: plymouth-quit.service: Succeeded.
May 13 22:07:31 volumio systemd[1]: Started Terminate Plymouth Boot Screen.
May 13 22:07:31 volumio systemd[1]: Received SIGRTMIN+21 from PID 240 (n/a).
May 13 22:07:31 volumio volumio[510]: Could not open config: /tmp/upmpdcli.conf
May 13 22:07:31 volumio systemd[1]: Started Getty on tty1.
May 13 22:07:31 volumio systemd[1]: Reached target Login Prompts.
May 13 22:07:31 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
May 13 22:07:31 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
May 13 22:07:31 volumio systemd[1]: iptables.service: Succeeded.
May 13 22:07:31 volumio loadcpufreq[526]: Loading cpufreq kernel modules...done (none).
May 13 22:07:31 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling.
May 13 22:07:31 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters...
May 13 22:07:31 volumio ifplugd(eth0)[626]: ifplugd 0.28 initializing.
May 13 22:07:31 volumio ifplugd(eth0)[626]: Using interface eth0/B8:27:EB:7C:E6:A9 with driver (version: 6.1.69-v7+)
May 13 22:07:31 volumio ifplugd(eth0)[626]: Using detection mode: SIOCETHTOOL
May 13 22:07:31 volumio ifplugd(eth0)[626]: Initialization complete, link beat not detected.
May 13 22:07:31 volumio ntpd[562]: ntpd 4.2.8p12@1.3728-o (1): Starting
May 13 22:07:31 volumio ntpd[562]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103
May 13 22:07:31 volumio systemd[1]: Started Network Time Service.
May 13 22:07:31 volumio ifplugd[568]: Network Interface Plugging Daemon...start eth0...done.
May 13 22:07:31 volumio systemd[1]: Started LSB: Brings up/down network automatically.
May 13 22:07:31 volumio bluetoothd[595]: Bluetooth daemon 5.50
May 13 22:07:31 volumio ntpd[628]: proto: precision = 1.406 usec (-19)
May 13 22:07:31 volumio bluetoothd[595]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf
May 13 22:07:31 volumio ntpd[628]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
May 13 22:07:31 volumio ntpd[628]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
May 13 22:07:31 volumio ntpd[628]: Listen and drop on 0 v6wildcard [::]:123
May 13 22:07:31 volumio ntpd[628]: Listen and drop on 1 v4wildcard 0.0.0.0:123
May 13 22:07:31 volumio systemd[1]: Started Bluetooth service.
May 13 22:07:31 volumio ntpd[628]: Listen normally on 2 lo 127.0.0.1:123
May 13 22:07:31 volumio ntpd[628]: Listening on routing socket on fd #19 for interface updates
May 13 22:07:31 volumio ntpd[628]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
May 13 22:07:31 volumio ntpd[628]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
May 13 22:07:31 volumio bluetoothd[595]: Starting SDP server
May 13 22:07:31 volumio bluetoothd[595]: Excluding (cli) sap
May 13 22:07:31 volumio systemd[1]: Started BluezAlsa proxy.
May 13 22:07:31 volumio systemd[1]: Reached target Bluetooth.
May 13 22:07:31 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
May 13 22:07:31 volumio kernel: Bluetooth: BNEP filters: protocol multicast
May 13 22:07:31 volumio kernel: Bluetooth: BNEP socket layer initialized
May 13 22:07:32 volumio bluetoothd[595]: Bluetooth management interface 1.22 initialized
May 13 22:07:32 volumio kernel: Bluetooth: MGMT ver 1.22
May 13 22:07:32 volumio dbus-daemon[507]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=595 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ")
May 13 22:07:32 volumio cpufrequtils[622]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done.
May 13 22:07:32 volumio haveged[427]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K
May 13 22:07:32 volumio haveged[427]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488
May 13 22:07:32 volumio haveged[427]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00377
May 13 22:07:32 volumio haveged[427]: haveged: fills: 0, generated: 0
May 13 22:07:32 volumio bluetoothd[595]: Failed to set privacy: Rejected (0x0b)
May 13 22:07:32 volumio bluetoothd[595]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/Source/1
May 13 22:07:32 volumio bluetoothd[595]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/Source/2
May 13 22:07:32 volumio kernel: Bluetooth: RFCOMM TTY layer initialized
May 13 22:07:32 volumio kernel: Bluetooth: RFCOMM socket layer initialized
May 13 22:07:32 volumio kernel: Bluetooth: RFCOMM ver 1.11
May 13 22:07:32 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters.
May 13 22:07:32 volumio systemd[1]: Starting Hostname Service...
May 13 22:07:32 volumio ntpd[628]: error resolving pool 0.debian.pool.ntp.org: System error (-11)
May 13 22:07:32 volumio dbus-daemon[507]: [system] Successfully activated service 'org.freedesktop.hostname1'
May 13 22:07:32 volumio systemd[1]: Started Hostname Service.
May 13 22:07:33 volumio systemd[1]: systemd-rfkill.service: Succeeded.
May 13 22:07:33 volumio nmbd[580]: [2024/05/13 22:07:33.270122, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns)
May 13 22:07:33 volumio nmbd[580]: started asyncdns process 656
May 13 22:07:33 volumio nmbd[580]: [2024/05/13 22:07:33.274378, 0] ../lib/util/become_daemon.c:149(daemon_status)
May 13 22:07:33 volumio nmbd[580]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...
May 13 22:07:33 volumio nmbd[580]: [2024/05/13 22:07:33.274687, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets)
May 13 22:07:33 volumio nmbd[580]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6).
May 13 22:07:33 volumio ntpd[628]: error resolving pool 1.debian.pool.ntp.org: System error (-11)
May 13 22:07:33 volumio wireless.js[498]: Cleaning previous...
May 13 22:07:34 volumio sudo[664]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
May 13 22:07:34 volumio sudo[664]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:34 volumio sudo[664]: pam_unix(sudo:session): session closed for user root
May 13 22:07:34 volumio sudo[666]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
May 13 22:07:34 volumio sudo[666]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:34 volumio ntpd[628]: error resolving pool 2.debian.pool.ntp.org: System error (-11)
May 13 22:07:34 volumio sudo[666]: pam_unix(sudo:session): session closed for user root
May 13 22:07:34 volumio wireless.js[498]: Stopped aP
May 13 22:07:34 volumio sudo[673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
May 13 22:07:34 volumio sudo[673]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:35 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
May 13 22:07:35 volumio sudo[673]: pam_unix(sudo:session): session closed for user root
May 13 22:07:35 volumio sudo[675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get
May 13 22:07:35 volumio sudo[675]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:35 volumio sudo[675]: pam_unix(sudo:session): session closed for user root
May 13 22:07:35 volumio sudo[682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
May 13 22:07:35 volumio sudo[682]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:35 volumio sudo[682]: pam_unix(sudo:session): session closed for user root
May 13 22:07:35 volumio sudo[684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan
May 13 22:07:35 volumio sudo[684]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:35 volumio ntpd[628]: error resolving pool 3.debian.pool.ntp.org: System error (-11)
May 13 22:07:36 volumio volumio-remote-updater[497]: [2024-05-13 22:07:36] [info] asio async_connect error: system:111 (Connection refused)
May 13 22:07:36 volumio volumio-remote-updater[497]: [2024-05-13 22:07:36] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
May 13 22:07:36 volumio volumio-remote-updater[497]: [2024-05-13 22:07:36] [error] handle_connect error: Underlying Transport Error
May 13 22:07:36 volumio bthelper[558]: Changing power off succeeded
May 13 22:07:36 volumio bthelper[558]: [CHG] Controller B8:27:EB:D6:4C:03 Class: 0x0048041c
May 13 22:07:36 volumio bthelper[558]: Changing power on succeeded
May 13 22:07:37 volumio sudo[684]: pam_unix(sudo:session): session closed for user root
May 13 22:07:37 volumio wireless.js[498]: SETTING APPROPRIATE REG DOMAIN: JP
May 13 22:07:37 volumio sudo[696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
May 13 22:07:37 volumio sudo[696]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:37 volumio sudo[696]: pam_unix(sudo:session): session closed for user root
May 13 22:07:37 volumio sudo[698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP
May 13 22:07:37 volumio sudo[698]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:37 volumio sudo[698]: pam_unix(sudo:session): session closed for user root
May 13 22:07:37 volumio wireless.js[498]: SUCCESSFULLY SET NEW REGDOMAIN: JP
May 13 22:07:37 volumio wireless.js[498]: Start wireless flow
May 13 22:07:37 volumio wireless.js[498]: Stopped hotspot (if there)..
May 13 22:07:37 volumio sudo[703]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
May 13 22:07:37 volumio sudo[703]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:37 volumio sudo[703]: pam_unix(sudo:session): session closed for user root
May 13 22:07:38 volumio sudo[705]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
May 13 22:07:38 volumio sudo[705]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:38 volumio sudo[705]: pam_unix(sudo:session): session closed for user root
May 13 22:07:38 volumio wireless.js[498]: DHCP IP
May 13 22:07:38 volumio wireless.js[498]: Start ap
May 13 22:07:38 volumio wpa_supplicant[707]: Successfully initialized wpa_supplicant
May 13 22:07:38 volumio sudo[708]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd
May 13 22:07:38 volumio sudo[708]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:38 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
May 13 22:07:38 volumio dhcpcd[709]: dev: loaded udev
May 13 22:07:38 volumio dhcpcd-run-hooks[727]: wlan0: starting wpa_supplicant
May 13 22:07:39 volumio dhcpcd-run-hooks[732]: wlan0: failed to start wpa_supplicant
May 13 22:07:39 volumio dhcpcd-run-hooks[733]: 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
May 13 22:07:39 volumio dhcpcd[709]: wlan0: connected to Access Point `'
May 13 22:07:39 volumio dhcpcd[709]: no interfaces have a carrier
May 13 22:07:39 volumio dhcpcd[709]: forked to background, child pid 743
May 13 22:07:39 volumio dhcpcd[743]: eth0: waiting for carrier
May 13 22:07:39 volumio dhcpcd[743]: wlan0: waiting for carrier
May 13 22:07:39 volumio sudo[708]: pam_unix(sudo:session): session closed for user root
May 13 22:07:39 volumio wireless.js[498]: trying...
May 13 22:07:39 volumio sudo[745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 13 22:07:39 volumio sudo[745]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:39 volumio sudo[745]: pam_unix(sudo:session): session closed for user root
May 13 22:07:40 volumio wireless.js[498]: trying...
May 13 22:07:40 volumio sudo[748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 13 22:07:40 volumio sudo[748]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:40 volumio sudo[748]: pam_unix(sudo:session): session closed for user root
May 13 22:07:41 volumio wpa_supplicant[731]: wlan0: Trying to associate with SSID 'Buffalo-A-E500-tak5'
May 13 22:07:41 volumio wireless.js[498]: trying...
May 13 22:07:41 volumio sudo[751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 13 22:07:41 volumio sudo[751]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:41 volumio sudo[751]: pam_unix(sudo:session): session closed for user root
May 13 22:07:42 volumio wireless.js[498]: trying...
May 13 22:07:42 volumio sudo[754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 13 22:07:42 volumio sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:42 volumio sudo[754]: pam_unix(sudo:session): session closed for user root
May 13 22:07:43 volumio wireless.js[498]: trying...
May 13 22:07:43 volumio sudo[757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 13 22:07:43 volumio sudo[757]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:43 volumio sudo[757]: pam_unix(sudo:session): session closed for user root
May 13 22:07:43 volumio volumio-remote-updater[497]: [2024-05-13 22:07:43] [info] asio async_connect error: system:111 (Connection refused)
May 13 22:07:43 volumio volumio-remote-updater[497]: [2024-05-13 22:07:43] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
May 13 22:07:43 volumio volumio-remote-updater[497]: [2024-05-13 22:07:43] [error] handle_connect error: Underlying Transport Error
May 13 22:07:44 volumio dhcpcd[743]: wlan0: carrier acquired
May 13 22:07:44 volumio dhcpcd[743]: wlan0: connected to Access Point `Buffalo-A-E500-tak5'
May 13 22:07:44 volumio wpa_supplicant[731]: wlan0: Associated with 00:e5:f1:b6:2e:ce
May 13 22:07:44 volumio wpa_supplicant[731]: wlan0: CTRL-EVENT-CONNECTED - Connection to 00:e5:f1:b6:2e:ce completed [id=0 id_str=]
May 13 22:07:44 volumio wpa_supplicant[731]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
May 13 22:07:44 volumio wpa_supplicant[731]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP
May 13 22:07:44 volumio dhcpcd[743]: DUID 00:01:00:01:2d:bb:b4:89:b8:27:eb:7c:e6:a9
May 13 22:07:44 volumio dhcpcd[743]: wlan0: IAID eb:29:b3:fc
May 13 22:07:44 volumio dhcpcd[743]: wlan0: adding address fe80::822a:7e86:5e67:586d
May 13 22:07:44 volumio dhcpcd[743]: ipv6_addaddr1: Permission denied
May 13 22:07:44 volumio dhcpcd[743]: wlan0: carrier lost
May 13 22:07:44 volumio dhcpcd[743]: wlan0: carrier acquired
May 13 22:07:44 volumio dhcpcd[743]: wlan0: IAID eb:29:b3:fc
May 13 22:07:44 volumio dhcpcd[743]: wlan0: soliciting an IPv6 router
May 13 22:07:44 volumio wireless.js[498]: trying...
May 13 22:07:44 volumio dhcpcd[743]: wlan0: rebinding lease of 192.168.11.149
May 13 22:07:44 volumio sudo[772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 13 22:07:44 volumio sudo[772]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:44 volumio sudo[772]: pam_unix(sudo:session): session closed for user root
May 13 22:07:44 volumio dhcpcd[743]: wlan0: probing address 192.168.11.149/24
May 13 22:07:45 volumio wireless.js[498]: trying...
May 13 22:07:45 volumio sudo[775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 13 22:07:45 volumio sudo[775]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:45 volumio sudo[775]: pam_unix(sudo:session): session closed for user root
May 13 22:07:46 volumio wireless.js[498]: trying...
May 13 22:07:46 volumio sudo[778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 13 22:07:46 volumio sudo[778]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:46 volumio sudo[778]: pam_unix(sudo:session): session closed for user root
May 13 22:07:47 volumio wpa_supplicant[731]: RRM: Ignoring radio measurement request: Not RRM network
May 13 22:07:47 volumio wireless.js[498]: trying...
May 13 22:07:47 volumio sudo[781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 13 22:07:47 volumio sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:47 volumio sudo[781]: pam_unix(sudo:session): session closed for user root
May 13 22:07:47 volumio wpa_supplicant[731]: RRM: Ignoring radio measurement request: Not RRM network
May 13 22:07:48 volumio wireless.js[498]: trying...
May 13 22:07:48 volumio sudo[784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 13 22:07:48 volumio sudo[784]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:48 volumio sudo[784]: pam_unix(sudo:session): session closed for user root
May 13 22:07:49 volumio dhcpcd[743]: wlan0: leased 192.168.11.149 for 172800 seconds
May 13 22:07:49 volumio avahi-daemon[522]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.11.149.
May 13 22:07:49 volumio dhcpcd[743]: wlan0: adding route to 192.168.11.0/24
May 13 22:07:49 volumio dhcpcd[743]: wlan0: adding default route via 192.168.11.1
May 13 22:07:49 volumio avahi-daemon[522]: New relevant interface wlan0.IPv4 for mDNS.
May 13 22:07:49 volumio avahi-daemon[522]: Registering new address record for 192.168.11.149 on wlan0.IPv4.
May 13 22:07:49 volumio wireless.js[498]: trying...
May 13 22:07:49 volumio sudo[807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
May 13 22:07:49 volumio sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:49 volumio sudo[807]: pam_unix(sudo:session): session closed for user root
May 13 22:07:49 volumio wireless.js[498]: Connected to: ----Buffalo-A-E500-tak5
May 13 22:07:49 volumio wireless.js[498]: ----
May 13 22:07:49 volumio sudo[810]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 13 22:07:49 volumio sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:07:49 volumio sudo[810]: pam_unix(sudo:session): session closed for user root
May 13 22:07:49 volumio wireless.js[498]: ... joined AP, wlan0 IPv4 is 192.168.11.149, ipV6 is undefined
May 13 22:07:49 volumio wireless.js[498]: It's done! AP
May 13 22:07:49 volumio systemd[1]: Started Wireless Services.
May 13 22:07:49 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
May 13 22:07:49 volumio systemd[1]: Started Volumio Backend Module.
May 13 22:07:49 volumio systemd[1]: Started Volumio Cpu Tweaker.
May 13 22:07:49 volumio volumio-cpu-tweak[816]: Setting RT Priority for mpd
May 13 22:07:49 volumio volumio-cpu-tweak[816]: pid 35's current scheduling policy: SCHED_OTHER
May 13 22:07:49 volumio volumio-cpu-tweak[816]: pid 35's current scheduling priority: 0
May 13 22:07:49 volumio volumio-cpu-tweak[816]: Setting MPD Affinity
May 13 22:07:49 volumio volumio-cpu-tweak[816]: pid 3's current affinity mask: f
May 13 22:07:49 volumio volumio-cpu-tweak[816]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input
May 13 22:07:49 volumio volumio-cpu-tweak[816]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance
May 13 22:07:50 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded.
May 13 22:07:50 volumio ntpd[628]: Listen normally on 3 wlan0 192.168.11.149:123
May 13 22:07:50 volumio ntpd[628]: new interface(s) found: waking up resolver
May 13 22:07:51 volumio dhcpcd[743]: wlan0: carrier lost
May 13 22:07:51 volumio wpa_supplicant[731]: wlan0: Associated with d6:2c:46:66:e5:0a
May 13 22:07:51 volumio wpa_supplicant[731]: wlan0: CTRL-EVENT-CONNECTED - Connection to d6:2c:46:66:e5:0a completed [id=0 id_str=]
May 13 22:07:51 volumio wpa_supplicant[731]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
May 13 22:07:51 volumio avahi-daemon[522]: Withdrawing address record for 192.168.11.149 on wlan0.
May 13 22:07:51 volumio avahi-daemon[522]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.11.149.
May 13 22:07:51 volumio avahi-daemon[522]: Interface wlan0.IPv4 no longer relevant for mDNS.
May 13 22:07:51 volumio dhcpcd[743]: wlan0: deleting route to 192.168.11.0/24
May 13 22:07:51 volumio dhcpcd[743]: wlan0: deleting default route via 192.168.11.1
May 13 22:07:51 volumio dhcpcd[743]: wlan0: carrier acquired
May 13 22:07:51 volumio dhcpcd[743]: wlan0: IAID eb:29:b3:fc
May 13 22:07:52 volumio dhcpcd[743]: wlan0: soliciting an IPv6 router
May 13 22:07:52 volumio dhcpcd[743]: wlan0: rebinding lease of 192.168.11.149
May 13 22:07:52 volumio dhcpcd[743]: wlan0: probing address 192.168.11.149/24
May 13 22:07:53 volumio ntpd[628]: Deleting interface #3 wlan0, 192.168.11.149#123, interface stats: received=0, sent=0, dropped=0, active_time=3 secs
May 13 22:07:53 volumio volumio-remote-updater[497]: [2024-05-13 22:07:53] [connect] Successful connection
May 13 22:07:54 volumio volumio[815]: info: -------------------------------------------
May 13 22:07:54 volumio volumio[815]: info: ----- Volumio3 ----
May 13 22:07:54 volumio volumio[815]: info: -------------------------------------------
May 13 22:07:54 volumio volumio[815]: info: ----- System startup ----
May 13 22:07:54 volumio volumio[815]: info: -------------------------------------------
May 13 22:07:57 volumio volumio[815]: info: MYVOLUMIO Environment detected
May 13 22:07:57 volumio volumio[815]: info: Plugin folders cleanup
May 13 22:07:57 volumio volumio[815]: info: Scanning into folder /volumio/app/plugins/
May 13 22:07:57 volumio volumio[815]: info: Scanning category audio_interface
May 13 22:07:57 volumio volumio[815]: info: Scanning category miscellanea
May 13 22:07:57 volumio volumio[815]: info: Scanning category music_service
May 13 22:07:57 volumio volumio[815]: info: Scanning category plugins.json
May 13 22:07:57 volumio volumio[815]: info: Scanning category system_controller
May 13 22:07:57 volumio volumio[815]: info: Scanning category user_interface
May 13 22:07:57 volumio volumio[815]: info: Scanning into folder /data/plugins/
May 13 22:07:57 volumio volumio[815]: info: Scanning category audio_interface
May 13 22:07:57 volumio volumio[815]: info: Plugin folders cleanup completed
May 13 22:07:57 volumio volumio[815]: info: -------------------------------------------
May 13 22:07:57 volumio volumio[815]: info: ----- Core plugins startup ----
May 13 22:07:57 volumio volumio[815]: info: -------------------------------------------
May 13 22:07:57 volumio volumio[815]: info: Loading plugins from folder /volumio/app/plugins/
May 13 22:07:57 volumio volumio[815]: info: Adding plugin upnp to MyMusic Plugins
May 13 22:07:57 volumio volumio[815]: info: Adding plugin airplay_emulation to MyMusic Plugins
May 13 22:07:57 volumio volumio[815]: info: Adding plugin upnp_browser to MyMusic Plugins
May 13 22:07:57 volumio volumio[815]: info: Loading plugins from folder /data/plugins/
May 13 22:07:57 volumio volumio[815]: info: Loading plugin "system"...
May 13 22:07:57 volumio volumio[815]: info: Loading plugin "bluetooth_controller"...
May 13 22:07:57 volumio dhcpcd[743]: wlan0: leased 192.168.11.149 for 172800 seconds
May 13 22:07:57 volumio avahi-daemon[522]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.11.149.
May 13 22:07:57 volumio dhcpcd[743]: wlan0: adding route to 192.168.11.0/24
May 13 22:07:57 volumio dhcpcd[743]: wlan0: adding default route via 192.168.11.1
May 13 22:07:57 volumio avahi-daemon[522]: New relevant interface wlan0.IPv4 for mDNS.
May 13 22:07:57 volumio avahi-daemon[522]: Registering new address record for 192.168.11.149 on wlan0.IPv4.
May 13 22:07:58 volumio systemd[1]: systemd-fsckd.service: Succeeded.
May 13 22:07:58 volumio volumio[815]: info: Loading plugin "appearance"...
May 13 22:07:58 volumio nmbd[580]: [2024/05/13 22:07:58.547377, 0] ../lib/util/become_daemon.c:138(daemon_ready)
May 13 22:07:58 volumio nmbd[580]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
May 13 22:07:58 volumio systemd[1]: Started Samba NMB Daemon.
May 13 22:07:58 volumio nmbd[580]: [2024/05/13 22:07:58.573780, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
May 13 22:07:58 volumio nmbd[580]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.11.149 for name WORKGROUP<1d>.
May 13 22:07:58 volumio nmbd[580]: This response was from IP 192.168.11.103, reporting an IP address of 192.168.11.103.
May 13 22:07:58 volumio systemd[1]: Starting Samba Winbind Daemon...
May 13 22:07:59 volumio sh[377]: timed out
May 13 22:07:59 volumio dhcpcd[417]: timed out
May 13 22:07:59 volumio sh[377]: dhcpcd exited
May 13 22:07:59 volumio dhcpcd[417]: dhcpcd exited
May 13 22:07:59 volumio sh[377]: ifup: failed to bring up eth0
May 13 22:07:59 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE
May 13 22:07:59 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'.
May 13 22:07:59 volumio winbindd[899]: [2024/05/13 22:07:59.295569, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
May 13 22:07:59 volumio winbindd[899]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
May 13 22:07:59 volumio winbindd[899]: [2024/05/13 22:07:59.329500, 0] ../lib/util/become_daemon.c:138(daemon_ready)
May 13 22:07:59 volumio winbindd[899]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
May 13 22:07:59 volumio systemd[1]: Started Samba Winbind Daemon.
May 13 22:07:59 volumio systemd[1]: Starting Samba SMB Daemon...
May 13 22:07:59 volumio ntpd[628]: Listen normally on 4 wlan0 192.168.11.149:123
May 13 22:07:59 volumio ntpd[628]: new interface(s) found: waking up resolver
May 13 22:08:00 volumio winbindd[899]: [2024/05/13 22:08:00.334304, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv)
May 13 22:08:00 volumio winbindd[899]: res_names->count = 3, expected 4
May 13 22:08:00 volumio smbd[906]: [2024/05/13 22:08:00.384591, 0] ../lib/util/become_daemon.c:138(daemon_ready)
May 13 22:08:00 volumio systemd[1]: Started Samba SMB Daemon.
May 13 22:08:00 volumio smbd[906]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
May 13 22:08:00 volumio systemd[1]: Reached target Multi-User System.
May 13 22:08:00 volumio systemd[1]: Reached target Graphical Interface.
May 13 22:08:00 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
May 13 22:08:00 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
May 13 22:08:00 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
May 13 22:08:00 volumio systemd[1]: Startup finished in 10.056s (kernel) + 38.581s (userspace) = 48.638s.
May 13 22:08:01 volumio volumio[815]: info: Loading plugin "network"...
May 13 22:08:01 volumio volumio[815]: info: Refreshing Cached IP Addresses
May 13 22:08:01 volumio sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 13 22:08:01 volumio sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:08:01 volumio sudo[916]: pam_unix(sudo:session): session closed for user root
May 13 22:08:01 volumio volumio[815]: info: Loading plugin "services"...
May 13 22:08:01 volumio sudo[918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 13 22:08:01 volumio sudo[918]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:08:01 volumio volumio[815]: info: Loading plugin "alsa_controller"...
May 13 22:08:01 volumio sudo[918]: pam_unix(sudo:session): session closed for user root
May 13 22:08:01 volumio sudo[921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
May 13 22:08:01 volumio sudo[921]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:08:02 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 13 22:08:02 volumio volumio[815]: info: Loading plugin "wizard"...
May 13 22:08:02 volumio volumio[815]: info: Loading plugin "networkfs"...
May 13 22:08:02 volumio volumio[815]: info: Starting Udev Watcher for removable devices
May 13 22:08:02 volumio sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.11.103/share\\shared music /mnt/NAS/NAS
May 13 22:08:02 volumio sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:08:02 volumio volumio[815]: info: Ignoring mount for partition: boot
May 13 22:08:02 volumio volumio[815]: info: Ignoring mount for partition: volumio
May 13 22:08:02 volumio volumio[815]: info: Ignoring mount for partition: volumio_data
May 13 22:08:02 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 13 22:08:02 volumio volumio[815]: info: Loading plugin "volumio_command_line_client"...
May 13 22:08:02 volumio volumio[815]: info: Loading plugin "upnp"...
May 13 22:08:02 volumio volumio[815]: info: [1715605682428] Starting Upmpd Daemon
May 13 22:08:02 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 13 22:08:02 volumio volumio[815]: info: Loading plugin "my_music"...
May 13 22:08:02 volumio volumio[815]: info: Loading plugin "mpd"...
May 13 22:08:02 volumio kernel: Key type cifs.spnego registered
May 13 22:08:02 volumio kernel: Key type cifs.idmap registered
May 13 22:08:02 volumio 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.
May 13 22:08:02 volumio kernel: CIFS: Attempting to mount \\192.168.11.103\share
May 13 22:08:02 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
May 13 22:08:03 volumio sudo[929]: pam_unix(sudo:session): session closed for user root
May 13 22:08:03 volumio volumio[815]: info: Loading plugin "upnp_browser"...
May 13 22:08:04 volumio volumio[815]: info: Loading plugin "alarm-clock"...
May 13 22:08:04 volumio volumio[815]: info: Loading plugin "airplay_emulation"...
May 13 22:08:04 volumio volumio[815]: info: Starting Shairport Sync
May 13 22:08:04 volumio volumio[815]: info: Loading plugin "last_100"...
May 13 22:08:04 volumio volumio[815]: info: Loading plugin "webradio"...
May 13 22:08:05 volumio volumio[815]: info: Loading plugin "i2s_dacs"...
May 13 22:08:05 volumio volumio[815]: info: I2S DAC not set, start Auto-detection
May 13 22:08:05 volumio volumio[815]: info: Loading plugin "volumiodiscovery"...
May 13 22:08:05 volumio volumio[815]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
May 13 22:08:05 volumio volumio[815]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 13 22:08:05 volumio volumio[815]: *** WARNING *** For more information see
May 13 22:08:05 volumio node[815]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
May 13 22:08:05 volumio volumio[815]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
May 13 22:08:05 volumio volumio[815]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 13 22:08:05 volumio volumio[815]: *** WARNING *** For more information see
May 13 22:08:05 volumio node[815]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 13 22:08:05 volumio node[815]: *** WARNING *** For more information see
May 13 22:08:05 volumio node[815]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
May 13 22:08:05 volumio node[815]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 13 22:08:05 volumio node[815]: *** WARNING *** For more information see
May 13 22:08:05 volumio volumio[815]: info: Applying required configuration parameters for plugin volumiodiscovery
May 13 22:08:05 volumio volumio[815]: info: Discovery: Started advertising with name: Volumio
May 13 22:08:05 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 13 22:08:05 volumio volumio[815]: info: Loading plugin "outputs"...
May 13 22:08:05 volumio volumio[815]: info: Loading plugin "albumart"...
May 13 22:08:05 volumio volumio[815]: info: Plugin example_plugin is not enabled
May 13 22:08:05 volumio volumio[815]: info: Loading plugin "inputs"...
May 13 22:08:05 volumio volumio[815]: info: Loading plugin "updater_comm"...
May 13 22:08:05 volumio sudo[921]: pam_unix(sudo:session): session closed for user root
May 13 22:08:05 volumio volumio[815]: info: Plugin mpdemulation is not enabled
May 13 22:08:05 volumio volumio[815]: info: Loading plugin "rest_api"...
May 13 22:08:05 volumio volumio[815]: info: Loading plugin "websocket"...
May 13 22:08:06 volumio volumio[815]: info: Loading i18n strings for locale ja
May 13 22:08:06 volumio volumio[815]: Updating browse sources language
May 13 22:08:06 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 13 22:08:06 volumio volumio[815]: Forking 3 albumart workers
May 13 22:08:06 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 22:08:06 volumio volumio[815]: info: CoreCommandRouter::initPlayerControls
May 13 22:08:06 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 22:08:06 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 22:08:06 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 22:08:06 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 22:08:06 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 22:08:06 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 22:08:06 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 22:08:06 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 22:08:06 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 22:08:06 volumio volumio[815]: Express server listening on port 3000
May 13 22:08:06 volumio volumio[815]: [Metrics] WebUI: 13s 518.41ms
May 13 22:08:06 volumio volumio[815]: info: CoreStateMachine::resetVolumioState
May 13 22:08:06 volumio volumio[815]: info: CoreStateMachine::getcurrentVolume
May 13 22:08:06 volumio volumio[815]: info: CoreCommandRouter::volumioRetrievevolume
May 13 22:08:06 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 22:08:06 volumio volumio[815]: bluetooth controller exists
May 13 22:08:07 volumio volumio[815]: adding device 28:73:F6:83:50:13
May 13 22:08:07 volumio volumio[815]: adding device 64:31:39:06:C5:41
May 13 22:08:07 volumio volumio[815]: info: Reloading queue from file
May 13 22:08:07 volumio volumio[815]: info: Setting Device type: Raspberry PI
May 13 22:08:07 volumio volumio[815]: info: CoreStateMachine::setRepeat null single undefined
May 13 22:08:07 volumio volumio[815]: info: CoreStateMachine::pushState
May 13 22:08:07 volumio volumio[815]: info: CorePlayQueue::getTrack 0
May 13 22:08:07 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 13 22:08:07 volumio volumio[815]: info: CoreCommandRouter::volumioPushState
May 13 22:08:07 volumio volumio[815]: info: CoreStateMachine::setRandom true
May 13 22:08:07 volumio volumio[815]: info: CoreStateMachine::pushState
May 13 22:08:07 volumio volumio[815]: info: CorePlayQueue::getTrack 0
May 13 22:08:07 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 13 22:08:07 volumio volumio[815]: info: CoreCommandRouter::volumioPushState
May 13 22:08:07 volumio volumio[815]: info: VolumeController:: Volume=70 Mute =false
May 13 22:08:07 volumio volumio[815]: info: CoreStateMachine::pushState
May 13 22:08:07 volumio volumio[815]: info: CorePlayQueue::getTrack 0
May 13 22:08:07 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 13 22:08:07 volumio volumio[815]: info: CoreCommandRouter::volumioPushState
May 13 22:08:07 volumio volumio[815]: info: CoreStateMachine::updateTrackBlock
May 13 22:08:07 volumio volumio[815]: info: CorePlayQueue::getTrackBlock
May 13 22:08:07 volumio volumio[815]: info: CoreCommandRouter::volumioRetrievevolume
May 13 22:08:07 volumio volumio[815]: info: Discovery: adding 945f5b49-6fcb-4199-94d0-fe5b1f94e11c
May 13 22:08:07 volumio volumio[815]: info: Discovery: Found device Volumio
May 13 22:08:07 volumio volumio[815]: info: CoreCommandRouter::volumioGetState
May 13 22:08:07 volumio volumio[815]: info: CorePlayQueue::getTrack 0
May 13 22:08:07 volumio volumio[815]: info: VolumeController:: Volume=70 Mute =false
May 13 22:08:07 volumio volumio[815]: info: CoreStateMachine::pushState
May 13 22:08:07 volumio volumio[815]: info: CorePlayQueue::getTrack 0
May 13 22:08:07 volumio volumio[815]: info: CoreCommandRouter::volumioPushState
May 13 22:08:08 volumio volumio[815]: Starting albumart workers
May 13 22:08:08 volumio volumio[815]: Starting albumart workers
May 13 22:08:08 volumio volumio[815]: Starting albumart workers
May 13 22:08:08 volumio sudo[1001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 13 22:08:08 volumio sudo[1001]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:08:08 volumio sudo[1001]: pam_unix(sudo:session): session closed for user root
May 13 22:08:08 volumio sudo[1003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 13 22:08:08 volumio sudo[1003]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:08:08 volumio sudo[1003]: pam_unix(sudo:session): session closed for user root
May 13 22:08:08 volumio volumio[815]: verbose: New Socket.io Connection to 192.168.11.149 from 192.168.11.15 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Edg/124.0.0.0 Total Clients: 1
May 13 22:08:08 volumio volumio-remote-updater[497]: [2024-05-13 22:08:08] [connect] Successful connection
May 13 22:08:08 volumio volumio-remote-updater[497]: [2024-05-13 22:08:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715605688 101
May 13 22:08:08 volumio volumio[815]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2
May 13 22:08:09 volumio sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 13 22:08:09 volumio sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:08:09 volumio sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 13 22:08:09 volumio sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:08:09 volumio sudo[1007]: pam_unix(sudo:session): session closed for user root
May 13 22:08:09 volumio sudo[1009]: pam_unix(sudo:session): session closed for user root
May 13 22:08:09 volumio volumio[815]: verbose: New Socket.io Connection to 192.168.11.149 from 192.168.11.15 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Edg/124.0.0.0 Total Clients: 3
May 13 22:08:09 volumio volumio[815]: info: CoreCommandRouter::volumioGetState
May 13 22:08:09 volumio volumio[815]: info: CorePlayQueue::getTrack 0
May 13 22:08:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 13 22:08:09 volumio volumio[815]: info: CoreCommandRouter::volumioGetVisibleSources
May 13 22:08:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 13 22:08:09 volumio volumio[815]: info: Listing playlists
May 13 22:08:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 13 22:08:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
May 13 22:08:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
May 13 22:08:09 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 13 22:08:12 volumio volumio[815]: checking info of 28:73:F6:83:50:13
May 13 22:08:12 volumio volumio[815]: checking info of 64:31:39:06:C5:41
May 13 22:08:12 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 13 22:08:12 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 13 22:08:12 volumio sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 13 22:08:12 volumio sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:08:12 volumio sudo[1037]: pam_unix(sudo:session): session closed for user root
May 13 22:08:12 volumio sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 13 22:08:12 volumio sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:08:12 volumio sudo[1039]: pam_unix(sudo:session): session closed for user root
May 13 22:08:12 volumio sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
May 13 22:08:12 volumio sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 22:08:12 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
May 13 22:08:12 volumio sudo[1043]: pam_unix(sudo:session): session closed for user root
May 13 22:08:12 volumio volumio[815]: info: Upmpdcli Daemon Started
May 13 22:08:12 volumio volumio[815]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
May 13 22:08:13 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
May 13 22:08:13 volumio volumio[815]: info: CoreCommandRouter::Close All Modals sent
May 13 22:08:16 volumio volumio[815]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
May 13 22:08:17 volumio volumio[815]: checking info of 28:73:F6:83:50:13
May 13 22:08:17 volumio volumio[815]: checking info of 64:31:39:06:C5:41
May 13 22:08:18 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
May 13 22:08:18 volumio volumio[815]: info: CURURI: music-library
May 13 22:08:18 volumio volumio[815]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined
May 13 22:08:18 volumio volumio[815]: info: CoreCommandRouter::volumioGetQueue
May 13 22:08:18 volumio volumio[815]: info: CoreStateMachine::getQueue
May 13 22:08:18 volumio volumio[815]: info: CorePlayQueue::getQueue
May 13 22:08:22 volumio volumio[815]: checking info of 28:73:F6:83:50:13
May 13 22:08:22 volumio volumio[815]: checking info of 64:31:39:06:C5:41
May 13 22:08:22 volumio volumio[815]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
May 13 22:08:24 volumio volumio[815]: info: Preload queue cleared
May 13 22:08:27 volumio volumio[815]: checking info of 28:73:F6:83:50:13
May 13 22:08:27 volumio volumio[815]: checking info of 64:31:39:06:C5:41
May 13 22:08:27 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 13 22:08:27 volumio volumio[815]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
May 13 22:08:27 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
May 13 22:08:27 volumio volumio[815]: info: Received Get System Version
May 13 22:08:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 13 22:08:28 volumio volumio[815]: info: Received Get System Info
May 13 22:08:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 13 22:08:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 13 22:08:28 volumio volumio[815]: info: Discovery: Getting this device information
May 13 22:08:28 volumio volumio[815]: info: CoreCommandRouter::volumioGetState
May 13 22:08:28 volumio volumio[815]: info: CorePlayQueue::getTrack 0
May 13 22:08:28 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 13 22:08:29 volumio volumio[815]: info: Preload queue cleared
May 13 22:08:30 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
May 13 22:08:30 volumio volumio[815]: info: CURURI: playlists
May 13 22:08:30 volumio volumio[815]: info: Listing playlists
May 13 22:08:30 volumio volumio[815]: info: Preload queue cleared
May 13 22:08:32 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
May 13 22:08:32 volumio volumio[815]: info: CURURI: music-library
May 13 22:08:32 volumio volumio[815]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined
May 13 22:08:32 volumio volumio[815]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
May 13 22:08:34 volumio volumio[815]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
May 13 22:08:34 volumio volumio[815]: info: CURURI: albums://
May 13 22:08:34 volumio volumio[815]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 13 22:08:34 volumio volumio[815]: TypeError: Cannot read property 'sendCommand' of undefined
May 13 22:08:34 volumio volumio[815]: at /volumio/app/plugins/music_service/mpd/index.js:2986:22
May 13 22:08:34 volumio volumio[815]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
May 13 22:08:34 volumio volumio[815]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 13 22:08:35 volumio sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-13 22:07
May 13 22:08:35 volumio sudo[1090]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d"
VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee"
VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623"
VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST"
VOLUMIO_VERSION="3.661"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"