-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Sun 2024-03-24 18:54:37 JST. -- Mar 24 18:53:55 volumiogg fake-hwclock[213]: Sun Mar 24 09:53:55 UTC 2024 Mar 24 18:53:55 volumiogg systemd[1]: Starting Apply Kernel Variables... Mar 24 18:53:55 volumiogg systemd[1]: Mounting Kernel Configuration File System... Mar 24 18:53:55 volumiogg systemd[1]: Condition check resulted in FUSE Control File System being skipped. Mar 24 18:53:55 volumiogg systemd[1]: Started Restore / save the current clock. Mar 24 18:53:55 volumiogg systemd[1]: dynamicswap.service: Succeeded. Mar 24 18:53:55 volumiogg systemd[1]: Started Create System Users. Mar 24 18:53:55 volumiogg systemd[1]: Started Load/Save Random Seed. Mar 24 18:53:55 volumiogg systemd[1]: Mounted Kernel Configuration File System. Mar 24 18:53:55 volumiogg systemd[1]: Starting Create Static Device Nodes in /dev... Mar 24 18:53:55 volumiogg systemd[1]: Started Apply Kernel Variables. Mar 24 18:53:55 volumiogg systemd[1]: Started Create Static Device Nodes in /dev. Mar 24 18:53:55 volumiogg systemd[1]: Starting udev Kernel Device Manager... Mar 24 18:53:55 volumiogg systemd[1]: Reached target Local File Systems (Pre). Mar 24 18:53:55 volumiogg systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Mar 24 18:53:55 volumiogg systemd[1]: Mounting /var/log... Mar 24 18:53:55 volumiogg systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Mar 24 18:53:55 volumiogg systemd[1]: Mounting /tmp... Mar 24 18:53:55 volumiogg systemd[1]: Mounting /var/spool/cups... Mar 24 18:53:55 volumiogg systemd[1]: Mounted /var/log. Mar 24 18:53:55 volumiogg systemd[1]: Starting Flush Journal to Persistent Storage... Mar 24 18:53:55 volumiogg systemd[1]: Mounted /var/spool/cups. Mar 24 18:53:55 volumiogg systemd[1]: Mounted /tmp. Mar 24 18:53:55 volumiogg systemd[1]: Mounting /var/spool/cups/tmp... Mar 24 18:53:55 volumiogg systemd-udevd[233]: Network interface NamePolicy= disabled on kernel command line, ignoring. Mar 24 18:53:55 volumiogg systemd[1]: Mounted /var/spool/cups/tmp. Mar 24 18:53:55 volumiogg systemd-journald[199]: Runtime journal (/run/log/journal/ba41094955353179f46b1ad365cba63d) is 7.5M, max 30.0M, 22.5M free. Mar 24 18:53:55 volumiogg systemd[1]: Started Flush Journal to Persistent Storage. Mar 24 18:53:55 volumiogg systemd[1]: Started udev Coldplug all Devices. Mar 24 18:53:55 volumiogg systemd[1]: Starting Helper to synchronize boot up for ifupdown... Mar 24 18:53:55 volumiogg systemd[1]: Started udev Kernel Device Manager. Mar 24 18:53:55 volumiogg systemd[1]: Starting Show Plymouth Boot Screen... Mar 24 18:53:55 volumiogg systemd[1]: Started Helper to synchronize boot up for ifupdown. Mar 24 18:53:55 volumiogg systemd[1]: Received SIGRTMIN+20 from PID 262 (plymouthd). Mar 24 18:53:55 volumiogg systemd[1]: Started Show Plymouth Boot Screen. Mar 24 18:53:55 volumiogg systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Mar 24 18:53:55 volumiogg systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 24 18:53:55 volumiogg systemd[1]: Reached target Paths. Mar 24 18:53:55 volumiogg systemd[1]: Reached target Local Encrypted Volumes. Mar 24 18:53:55 volumiogg systemd-udevd[266]: Using default interface naming scheme 'v240'. Mar 24 18:53:55 volumiogg systemd-udevd[266]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 24 18:53:55 volumiogg kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Mar 24 18:53:55 volumiogg kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Mar 24 18:53:55 volumiogg kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Mar 24 18:53:55 volumiogg kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Mar 24 18:53:55 volumiogg kernel: [vc_sm_connected_init]: start Mar 24 18:53:55 volumiogg kernel: [vc_sm_connected_init]: installed successfully Mar 24 18:53:55 volumiogg kernel: mc: Linux media interface: v0.10 Mar 24 18:53:55 volumiogg kernel: input: raspberrypi-ts as /devices/platform/soc/soc:firmware/soc:firmware:touchscreen/input/input0 Mar 24 18:53:56 volumiogg kernel: videodev: Linux video capture interface: v2.00 Mar 24 18:53:56 volumiogg kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Mar 24 18:53:56 volumiogg systemd-udevd[240]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 24 18:53:56 volumiogg kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Mar 24 18:53:56 volumiogg kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Mar 24 18:53:56 volumiogg kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 24 18:53:56 volumiogg kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Mar 24 18:53:56 volumiogg kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Mar 24 18:53:56 volumiogg kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Mar 24 18:53:56 volumiogg kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Mar 24 18:53:56 volumiogg kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Mar 24 18:53:56 volumiogg kernel: rpivid feb10000.codec: Device registered as /dev/video19 Mar 24 18:53:56 volumiogg kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Mar 24 18:53:56 volumiogg kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Mar 24 18:53:56 volumiogg kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Mar 24 18:53:56 volumiogg kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Mar 24 18:53:56 volumiogg kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Mar 24 18:53:56 volumiogg kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Mar 24 18:53:56 volumiogg kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Mar 24 18:53:56 volumiogg kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Mar 24 18:53:56 volumiogg kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Mar 24 18:53:56 volumiogg kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Mar 24 18:53:56 volumiogg kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Mar 24 18:53:56 volumiogg kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Mar 24 18:53:56 volumiogg kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Mar 24 18:53:57 volumiogg kernel: bcm2835_audio bcm2835_audio: card created with 8 channels Mar 24 18:53:57 volumiogg kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Mar 24 18:53:57 volumiogg kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Mar 24 18:53:57 volumiogg kernel: usbcore: registered new interface driver brcmfmac Mar 24 18:53:57 volumiogg kernel: Bluetooth: Core ver 2.22 Mar 24 18:53:57 volumiogg kernel: NET: Registered PF_BLUETOOTH protocol family Mar 24 18:53:57 volumiogg kernel: Bluetooth: HCI device and connection manager initialized Mar 24 18:53:57 volumiogg kernel: Bluetooth: HCI socket layer initialized Mar 24 18:53:57 volumiogg kernel: Bluetooth: L2CAP socket layer initialized Mar 24 18:53:57 volumiogg kernel: Bluetooth: SCO socket layer initialized Mar 24 18:53:57 volumiogg kernel: Bluetooth: HCI UART driver ver 2.3 Mar 24 18:53:57 volumiogg kernel: Bluetooth: HCI UART protocol H4 registered Mar 24 18:53:57 volumiogg kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Mar 24 18:53:57 volumiogg kernel: Bluetooth: HCI UART protocol Broadcom registered Mar 24 18:53:57 volumiogg kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Mar 24 18:53:57 volumiogg kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Mar 24 18:53:57 volumiogg kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Mar 24 18:53:57 volumiogg kernel: usb 1-1.4: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Mar 24 18:53:57 volumiogg kernel: usb 1-1.4: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Mar 24 18:53:57 volumiogg kernel: usb 1-1.4: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Mar 24 18:53:57 volumiogg kernel: usb 1-1.4: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Mar 24 18:53:57 volumiogg kernel: usbcore: registered new interface driver snd-usb-audio Mar 24 18:53:57 volumiogg kernel: uart-pl011 fe201000.serial: no DMA platform data Mar 24 18:53:57 volumiogg 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 Mar 24 18:53:57 volumiogg systemd-udevd[319]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 24 18:53:57 volumiogg systemd-udevd[319]: Using default interface naming scheme 'v240'. Mar 24 18:53:57 volumiogg systemd-udevd[319]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 24 18:53:57 volumiogg kernel: Bluetooth: hci0: BCM: chip id 107 Mar 24 18:53:57 volumiogg kernel: Bluetooth: hci0: BCM: features 0x2f Mar 24 18:53:57 volumiogg kernel: Bluetooth: hci0: BCM4345C0 Mar 24 18:53:57 volumiogg kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Mar 24 18:53:57 volumiogg kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Mar 24 18:53:57 volumiogg systemd-udevd[244]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 24 18:53:58 volumiogg systemd-udevd[260]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Mar 24 18:53:58 volumiogg systemd[1]: Found device /dev/mmcblk0p1. Mar 24 18:53:58 volumiogg kernel: Bluetooth: hci0: BCM: features 0x2f Mar 24 18:53:58 volumiogg kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Mar 24 18:53:58 volumiogg kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Mar 24 18:53:59 volumiogg systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Mar 24 18:53:59 volumiogg systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Mar 24 18:53:59 volumiogg systemd[1]: Created slice system-bthelper.slice. Mar 24 18:53:59 volumiogg systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 24 18:53:59 volumiogg systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 24 18:53:59 volumiogg systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 24 18:53:59 volumiogg systemd[1]: Condition check resulted in Huge Pages File System being skipped. Mar 24 18:53:59 volumiogg systemd[1]: Condition check resulted in FUSE Control File System being skipped. Mar 24 18:53:59 volumiogg systemd[1]: Created slice system-systemd\x2dbacklight.slice. Mar 24 18:53:59 volumiogg systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:rpi_backlight... Mar 24 18:53:59 volumiogg systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Mar 24 18:53:59 volumiogg systemd[1]: Starting File System Check on /dev/mmcblk0p1... Mar 24 18:53:59 volumiogg systemd[1]: Starting Load/Save RF Kill Switch Status... Mar 24 18:53:59 volumiogg systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:rpi_backlight. Mar 24 18:53:59 volumiogg systemd[1]: Started File System Check Daemon to report status. Mar 24 18:53:59 volumiogg systemd[1]: Started Load/Save RF Kill Switch Status. Mar 24 18:53:59 volumiogg systemd-fsck[410]: fsck.fat 4.1 (2017-01-24) Mar 24 18:53:59 volumiogg systemd-fsck[410]: /dev/mmcblk0p1: 366 files, 33348/46774 clusters Mar 24 18:53:59 volumiogg systemd[1]: Started File System Check on /dev/mmcblk0p1. Mar 24 18:53:59 volumiogg systemd[1]: Mounting /boot... Mar 24 18:53:59 volumiogg systemd[1]: Mounted /boot. Mar 24 18:53:59 volumiogg systemd[1]: Reached target Local File Systems. Mar 24 18:53:59 volumiogg systemd[1]: Started ifup for eth0. Mar 24 18:53:59 volumiogg systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Mar 24 18:53:59 volumiogg systemd[1]: Starting Create Volatile Files and Directories... Mar 24 18:53:59 volumiogg systemd[1]: Starting Raise network interfaces... Mar 24 18:53:59 volumiogg systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Mar 24 18:53:59 volumiogg systemd[1]: Starting Preprocess NFS configuration... Mar 24 18:53:59 volumiogg systemd[1]: Started ifup for wlan0. Mar 24 18:53:59 volumiogg systemd[1]: plymouth-read-write.service: Succeeded. Mar 24 18:53:59 volumiogg systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Mar 24 18:53:59 volumiogg systemd[1]: Received SIGRTMIN+20 from PID 262 (plymouthd). Mar 24 18:53:59 volumiogg systemd[1]: nfs-config.service: Succeeded. Mar 24 18:53:59 volumiogg systemd[1]: Started Preprocess NFS configuration. Mar 24 18:53:59 volumiogg systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Mar 24 18:53:59 volumiogg systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Mar 24 18:53:59 volumiogg systemd[1]: Reached target NFS client services. Mar 24 18:53:59 volumiogg systemd[1]: Started Create Volatile Files and Directories. Mar 24 18:53:59 volumiogg systemd[1]: Starting RPC bind portmap service... Mar 24 18:53:59 volumiogg systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Mar 24 18:53:59 volumiogg systemd[1]: Starting Update UTMP about System Boot/Shutdown... Mar 24 18:53:59 volumiogg systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Mar 24 18:53:59 volumiogg kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 24 18:53:59 volumiogg systemd[1]: Started RPC bind portmap service. Mar 24 18:53:59 volumiogg systemd[1]: Reached target Remote File Systems (Pre). Mar 24 18:53:59 volumiogg systemd[1]: Reached target Remote File Systems. Mar 24 18:53:59 volumiogg systemd[1]: Reached target RPC Port Mapper. Mar 24 18:53:59 volumiogg kernel: 8021q: 802.1Q VLAN Support v1.8 Mar 24 18:53:59 volumiogg systemd[1]: Started Update UTMP about System Boot/Shutdown. Mar 24 18:53:59 volumiogg systemd[1]: Reached target System Initialization. Mar 24 18:53:59 volumiogg systemd[1]: Started Volumio Splash. Mar 24 18:53:59 volumiogg systemd[500]: volumiosplash.service: Failed to execute command: No such file or directory Mar 24 18:53:59 volumiogg systemd[500]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Mar 24 18:53:59 volumiogg systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 24 18:53:59 volumiogg systemd[1]: apt-daily.timer: Not using persistent file timestamp Sat 2024-04-27 05:07:42 JST as it is in the future. Mar 24 18:53:59 volumiogg systemd[1]: Started Daily apt download activities. Mar 24 18:53:59 volumiogg systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Sat 2024-04-27 05:07:42 JST as it is in the future. Mar 24 18:53:59 volumiogg systemd[1]: Started Daily apt upgrade and clean activities. Mar 24 18:53:59 volumiogg systemd[1]: Listening on triggerhappy.socket. Mar 24 18:53:59 volumiogg systemd[1]: man-db.timer: Not using persistent file timestamp Sat 2024-04-27 05:07:42 JST as it is in the future. Mar 24 18:53:59 volumiogg systemd[1]: Started Daily man-db regeneration. Mar 24 18:53:59 volumiogg systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 24 18:53:59 volumiogg systemd[1]: Reached target Timers. Mar 24 18:53:59 volumiogg systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Mar 24 18:53:59 volumiogg systemd[1]: Reached target Sockets. Mar 24 18:53:59 volumiogg systemd[1]: Reached target Basic System. Mar 24 18:53:59 volumiogg systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Mar 24 18:53:59 volumiogg systemd[1]: Reached target Login Prompts. Mar 24 18:53:59 volumiogg systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Mar 24 18:53:59 volumiogg systemd[1]: Started Manage Sound Card State (restore and store). Mar 24 18:53:59 volumiogg alsactl[522]: alsactl 1.1.8 daemon started Mar 24 18:53:59 volumiogg systemd[1]: Starting Save/Restore Sound Card State... Mar 24 18:53:59 volumiogg systemd[1]: Started volumio-remote-updater.service. Mar 24 18:54:00 volumiogg systemd[1]: Started Volumio Log Rotation Service. Mar 24 18:54:00 volumiogg systemd[1]: Started D-Bus System Message Bus. Mar 24 18:54:00 volumiogg sh[418]: eth0: waiting for carrier Mar 24 18:54:00 volumiogg kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Mar 24 18:54:00 volumiogg kernel: bcmgenet fd580000.ethernet eth0: Link is Down Mar 24 18:54:00 volumiogg dhcpcd[469]: eth0: waiting for carrier Mar 24 18:54:00 volumiogg systemd[1]: Starting triggerhappy global hotkey daemon... Mar 24 18:54:00 volumiogg systemd[1]: Starting Configure Bluetooth Modems connected by UART... Mar 24 18:54:00 volumiogg systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Mar 24 18:54:00 volumiogg systemd[1]: Starting WPA supplicant... Mar 24 18:54:00 volumiogg systemd[1]: Started Volumio Iptables Module. Mar 24 18:54:00 volumiogg systemd[1]: Started UPnP Renderer front-end to MPD. Mar 24 18:54:00 volumiogg systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Mar 24 18:54:00 volumiogg systemd[1]: Starting dhcpcd on all interfaces... Mar 24 18:54:00 volumiogg systemd[1]: Starting Wireless Services... Mar 24 18:54:00 volumiogg thd[536]: Unable to parse trigger line: Mar 24 18:54:00 volumiogg thd[536]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Mar 24 18:54:00 volumiogg thd[536]: Unable to parse trigger line: Mar 24 18:54:00 volumiogg thd[536]: Found socket passed from systemd Mar 24 18:54:00 volumiogg systemd[1]: Starting Login Service... Mar 24 18:54:00 volumiogg systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Mar 24 18:54:00 volumiogg systemd[1]: Started triggerhappy global hotkey daemon. Mar 24 18:54:00 volumiogg systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Mar 24 18:54:00 volumiogg systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Mar 24 18:54:00 volumiogg dhcpcd[541]: Not running dhcpcd because /etc/network/interfaces Mar 24 18:54:00 volumiogg dhcpcd[541]: defines some interfaces that will use a Mar 24 18:54:00 volumiogg dhcpcd[541]: DHCP client or static address Mar 24 18:54:00 volumiogg systemd[1]: Started Save/Restore Sound Card State. Mar 24 18:54:00 volumiogg systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Mar 24 18:54:00 volumiogg systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Mar 24 18:54:00 volumiogg systemd[1]: Failed to start dhcpcd on all interfaces. Mar 24 18:54:00 volumiogg systemd-logind[548]: New seat seat0. Mar 24 18:54:00 volumiogg sh[428]: wlan0=wlan0 Mar 24 18:54:00 volumiogg systemd[1]: hciuart.service: Succeeded. Mar 24 18:54:00 volumiogg systemd[1]: Started Configure Bluetooth Modems connected by UART. Mar 24 18:54:00 volumiogg avahi-daemon[550]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Mar 24 18:54:00 volumiogg avahi-daemon[550]: Successfully dropped root privileges. Mar 24 18:54:00 volumiogg avahi-daemon[550]: avahi-daemon 0.7 starting up. Mar 24 18:54:00 volumiogg volumio-remote-updater[528]: Error: No active session Mar 24 18:54:00 volumiogg volumio-remote-updater[528]: [2024-03-24 18:54:00] [info] asio async_connect error: system:111 (Connection refused) Mar 24 18:54:00 volumiogg volumio-remote-updater[528]: [2024-03-24 18:54:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 24 18:54:00 volumiogg volumio-remote-updater[528]: [2024-03-24 18:54:00] [error] handle_connect error: Underlying Transport Error Mar 24 18:54:00 volumiogg systemd[1]: Starting Raspberry Pi bluetooth helper... Mar 24 18:54:00 volumiogg systemd[1]: Reached target Sound Card. Mar 24 18:54:00 volumiogg volumio[540]: Could not open config: /tmp/upmpdcli.conf Mar 24 18:54:00 volumiogg bthelper[590]: Raspberry Pi BDADDR already set Mar 24 18:54:00 volumiogg systemd[1]: Started Login Service. Mar 24 18:54:00 volumiogg systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:54:00 volumiogg systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 24 18:54:01 volumiogg systemd[1]: Started Raspberry Pi bluetooth helper. Mar 24 18:54:01 volumiogg avahi-daemon[550]: Successfully called chroot(). Mar 24 18:54:01 volumiogg avahi-daemon[550]: Successfully dropped remaining capabilities. Mar 24 18:54:01 volumiogg avahi-daemon[550]: Loading service file /services/volumio.service. Mar 24 18:54:01 volumiogg wpa_supplicant[538]: Successfully initialized wpa_supplicant Mar 24 18:54:01 volumiogg systemd[1]: Started Raise network interfaces. Mar 24 18:54:01 volumiogg avahi-daemon[550]: Network interface enumeration completed. Mar 24 18:54:01 volumiogg avahi-daemon[550]: Server startup complete. Host name is volumiogg.local. Local service cookie is 785128992. Mar 24 18:54:01 volumiogg avahi-daemon[550]: Service "VolumioGG" (/services/volumio.service) successfully established. Mar 24 18:54:01 volumiogg systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 24 18:54:01 volumiogg systemd[1]: Started WPA supplicant. Mar 24 18:54:01 volumiogg systemd[1]: Reached target Network. Mar 24 18:54:01 volumiogg loadcpufreq[521]: Loading cpufreq kernel modules...done (none). Mar 24 18:54:01 volumiogg systemd[1]: Starting Permit User Sessions... Mar 24 18:54:01 volumiogg systemd[1]: Starting Network Time Service... Mar 24 18:54:01 volumiogg systemd[1]: Starting OpenBSD Secure Shell server... Mar 24 18:54:01 volumiogg systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Mar 24 18:54:01 volumiogg systemd[1]: Reached target Network is Online. Mar 24 18:54:01 volumiogg systemd[1]: Starting LSB: Brings up/down network automatically... Mar 24 18:54:01 volumiogg systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 24 18:54:01 volumiogg systemd[1]: Starting Samba NMB Daemon... Mar 24 18:54:01 volumiogg systemd[1]: Starting /etc/rc.local Compatibility... Mar 24 18:54:01 volumiogg systemd[1]: Starting Bluetooth service... Mar 24 18:54:01 volumiogg systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Mar 24 18:54:01 volumiogg systemd[1]: Started Permit User Sessions. Mar 24 18:54:01 volumiogg systemd[1]: Started /etc/rc.local Compatibility. Mar 24 18:54:01 volumiogg systemd[1]: Starting Hold until boot process finishes up... Mar 24 18:54:01 volumiogg systemd[1]: Starting Terminate Plymouth Boot Screen... Mar 24 18:54:01 volumiogg systemd[1]: Starting LSB: set CPUFreq kernel parameters... Mar 24 18:54:01 volumiogg systemd[1]: plymouth-start.service: Succeeded. Mar 24 18:54:01 volumiogg systemd[1]: plymouth-quit-wait.service: Succeeded. Mar 24 18:54:01 volumiogg systemd[1]: Started Hold until boot process finishes up. Mar 24 18:54:01 volumiogg systemd[1]: plymouth-quit.service: Succeeded. Mar 24 18:54:01 volumiogg systemd[1]: Started Terminate Plymouth Boot Screen. Mar 24 18:54:01 volumiogg systemd[1]: Received SIGRTMIN+21 from PID 262 (n/a). Mar 24 18:54:01 volumiogg systemd[1]: Received SIGRTMIN+21 from PID 262 (n/a). Mar 24 18:54:01 volumiogg ntpd[631]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 24 18:54:01 volumiogg ntpd[631]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Mar 24 18:54:01 volumiogg systemd[1]: Started Network Time Service. Mar 24 18:54:01 volumiogg bluetoothd[643]: Bluetooth daemon 5.50 Mar 24 18:54:01 volumiogg ntpd[655]: proto: precision = 2.093 usec (-19) Mar 24 18:54:01 volumiogg bluetoothd[643]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Mar 24 18:54:01 volumiogg ifplugd(eth0)[669]: ifplugd 0.28 initializing. Mar 24 18:54:01 volumiogg ifplugd(eth0)[669]: Using interface eth0/D8:3A:DD:88:60:9B with driver (version: 6.1.69-v7l+) Mar 24 18:54:01 volumiogg ifplugd(eth0)[669]: Using detection mode: SIOCETHTOOL Mar 24 18:54:01 volumiogg ifplugd(eth0)[669]: Initialization complete, link beat not detected. Mar 24 18:54:01 volumiogg systemd[1]: Started Bluetooth service. Mar 24 18:54:01 volumiogg systemd[1]: Reached target Bluetooth. Mar 24 18:54:01 volumiogg ntpd[655]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 24 18:54:01 volumiogg ntpd[655]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 24 18:54:01 volumiogg bluetoothd[643]: Starting SDP server Mar 24 18:54:01 volumiogg bluetoothd[643]: Excluding (cli) sap Mar 24 18:54:01 volumiogg ntpd[655]: Listen and drop on 0 v6wildcard [::]:123 Mar 24 18:54:01 volumiogg ntpd[655]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 24 18:54:01 volumiogg ntpd[655]: Listen normally on 2 lo 127.0.0.1:123 Mar 24 18:54:01 volumiogg ntpd[655]: Listening on routing socket on fd #19 for interface updates Mar 24 18:54:01 volumiogg ntpd[655]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 24 18:54:01 volumiogg ntpd[655]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 24 18:54:01 volumiogg sshd[662]: Server listening on 0.0.0.0 port 22. Mar 24 18:54:01 volumiogg sshd[662]: Server listening on :: port 22. Mar 24 18:54:01 volumiogg systemd[1]: Started OpenBSD Secure Shell server. Mar 24 18:54:01 volumiogg ifplugd[637]: Network Interface Plugging Daemon...start eth0...done. Mar 24 18:54:01 volumiogg systemd[1]: Started LSB: Brings up/down network automatically. Mar 24 18:54:01 volumiogg cpufrequtils[650]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Mar 24 18:54:01 volumiogg kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 24 18:54:01 volumiogg kernel: Bluetooth: BNEP filters: protocol multicast Mar 24 18:54:01 volumiogg kernel: Bluetooth: BNEP socket layer initialized Mar 24 18:54:01 volumiogg kernel: Bluetooth: MGMT ver 1.22 Mar 24 18:54:01 volumiogg bluetoothd[643]: Bluetooth management interface 1.22 initialized Mar 24 18:54:01 volumiogg dbus-daemon[534]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=643 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Mar 24 18:54:01 volumiogg systemd[1]: Started LSB: set CPUFreq kernel parameters. Mar 24 18:54:02 volumiogg bluetoothd[643]: Failed to set privacy: Rejected (0x0b) Mar 24 18:54:02 volumiogg systemd[1]: iptables.service: Succeeded. Mar 24 18:54:02 volumiogg systemd[1]: Starting Hostname Service... Mar 24 18:54:02 volumiogg dbus-daemon[534]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 24 18:54:02 volumiogg systemd[1]: Started Hostname Service. Mar 24 18:54:02 volumiogg nmbd[645]: [2024/03/24 18:54:02.416722, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 24 18:54:02 volumiogg nmbd[645]: started asyncdns process 689 Mar 24 18:54:02 volumiogg nmbd[645]: [2024/03/24 18:54:02.418647, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 24 18:54:02 volumiogg nmbd[645]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 24 18:54:02 volumiogg nmbd[645]: [2024/03/24 18:54:02.418772, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 24 18:54:02 volumiogg nmbd[645]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 24 18:54:02 volumiogg haveged[479]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Mar 24 18:54:02 volumiogg haveged[479]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Mar 24 18:54:02 volumiogg haveged[479]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99781 Mar 24 18:54:02 volumiogg haveged[479]: haveged: fills: 0, generated: 0 Mar 24 18:54:02 volumiogg ntpd[655]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 24 18:54:02 volumiogg wireless.js[547]: Cleaning previous... Mar 24 18:54:02 volumiogg sudo[697]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 24 18:54:02 volumiogg sudo[697]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:02 volumiogg sudo[697]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:02 volumiogg sudo[699]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 24 18:54:02 volumiogg sudo[699]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:03 volumiogg sudo[699]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:03 volumiogg wireless.js[547]: Stopped aP Mar 24 18:54:03 volumiogg sudo[706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 24 18:54:03 volumiogg sudo[706]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:03 volumiogg sudo[706]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:03 volumiogg kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 24 18:54:03 volumiogg sudo[708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 24 18:54:03 volumiogg sudo[708]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:03 volumiogg sudo[708]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:03 volumiogg sudo[715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 24 18:54:03 volumiogg sudo[715]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:03 volumiogg sudo[715]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:03 volumiogg sudo[717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 24 18:54:03 volumiogg sudo[717]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:03 volumiogg ntpd[655]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 24 18:54:04 volumiogg systemd[1]: systemd-rfkill.service: Succeeded. Mar 24 18:54:04 volumiogg ntpd[655]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 24 18:54:05 volumiogg ntpd[655]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 24 18:54:05 volumiogg volumio-remote-updater[528]: [2024-03-24 18:54:05] [info] asio async_connect error: system:111 (Connection refused) Mar 24 18:54:05 volumiogg volumio-remote-updater[528]: [2024-03-24 18:54:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 24 18:54:05 volumiogg volumio-remote-updater[528]: [2024-03-24 18:54:05] [error] handle_connect error: Underlying Transport Error Mar 24 18:54:06 volumiogg bthelper[590]: Changing power off succeeded Mar 24 18:54:06 volumiogg sudo[717]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:06 volumiogg wireless.js[547]: SETTING APPROPRIATE REG DOMAIN: JP Mar 24 18:54:06 volumiogg sudo[723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 24 18:54:06 volumiogg sudo[723]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:06 volumiogg sudo[723]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:06 volumiogg sudo[725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP Mar 24 18:54:06 volumiogg sudo[725]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:06 volumiogg sudo[725]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:06 volumiogg wireless.js[547]: SUCCESSFULLY SET NEW REGDOMAIN: JP Mar 24 18:54:06 volumiogg wireless.js[547]: Start wireless flow Mar 24 18:54:06 volumiogg wireless.js[547]: Stopped hotspot (if there).. Mar 24 18:54:06 volumiogg sudo[730]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 24 18:54:06 volumiogg sudo[730]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:06 volumiogg sudo[730]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:06 volumiogg sudo[736]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 24 18:54:06 volumiogg sudo[736]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:06 volumiogg bthelper[590]: [CHG] Controller D8:3A:DD:88:60:9D Class: 0x0000041c Mar 24 18:54:06 volumiogg bthelper[590]: Changing power on succeeded Mar 24 18:54:06 volumiogg sudo[736]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:06 volumiogg wireless.js[547]: DHCP IP Mar 24 18:54:06 volumiogg wireless.js[547]: Start ap Mar 24 18:54:06 volumiogg wpa_supplicant[740]: Successfully initialized wpa_supplicant Mar 24 18:54:06 volumiogg sudo[741]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 24 18:54:06 volumiogg sudo[741]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:06 volumiogg kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 24 18:54:06 volumiogg dhcpcd[742]: dev: loaded udev Mar 24 18:54:06 volumiogg dhcpcd-run-hooks[760]: wlan0: starting wpa_supplicant Mar 24 18:54:07 volumiogg dhcpcd-run-hooks[765]: wlan0: failed to start wpa_supplicant Mar 24 18:54:07 volumiogg dhcpcd-run-hooks[766]: 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 Mar 24 18:54:07 volumiogg dhcpcd[742]: wlan0: connected to Access Point `' Mar 24 18:54:07 volumiogg dhcpcd[742]: no interfaces have a carrier Mar 24 18:54:07 volumiogg dhcpcd[742]: forked to background, child pid 776 Mar 24 18:54:07 volumiogg dhcpcd[776]: eth0: waiting for carrier Mar 24 18:54:07 volumiogg dhcpcd[776]: wlan0: waiting for carrier Mar 24 18:54:07 volumiogg sudo[741]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:07 volumiogg wireless.js[547]: trying... Mar 24 18:54:07 volumiogg sudo[778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 18:54:07 volumiogg sudo[778]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:07 volumiogg sudo[778]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:08 volumiogg wireless.js[547]: trying... Mar 24 18:54:08 volumiogg sudo[781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 18:54:08 volumiogg sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:08 volumiogg sudo[781]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:09 volumiogg wpa_supplicant[764]: wlan0: Trying to associate with SSID 'Buffalo-A-5558' Mar 24 18:54:09 volumiogg wireless.js[547]: trying... Mar 24 18:54:09 volumiogg sudo[784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 18:54:09 volumiogg sudo[784]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:09 volumiogg sudo[784]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:10 volumiogg wireless.js[547]: trying... Mar 24 18:54:10 volumiogg sudo[787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 18:54:10 volumiogg sudo[787]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:10 volumiogg sudo[787]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:11 volumiogg wireless.js[547]: trying... Mar 24 18:54:11 volumiogg sudo[790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 18:54:11 volumiogg sudo[790]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:11 volumiogg sudo[790]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:12 volumiogg dhcpcd[776]: wlan0: carrier acquired Mar 24 18:54:12 volumiogg dhcpcd[776]: wlan0: connected to Access Point `Buffalo-A-5558' Mar 24 18:54:12 volumiogg wpa_supplicant[764]: wlan0: Associated with 90:96:f3:69:cf:6f Mar 24 18:54:12 volumiogg wpa_supplicant[764]: wlan0: CTRL-EVENT-CONNECTED - Connection to 90:96:f3:69:cf:6f completed [id=0 id_str=] Mar 24 18:54:12 volumiogg wpa_supplicant[764]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 24 18:54:12 volumiogg wpa_supplicant[764]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Mar 24 18:54:12 volumiogg dhcpcd[776]: DUID 00:01:00:01:2d:5e:65:61:d8:3a:dd:88:60:9c Mar 24 18:54:12 volumiogg dhcpcd[776]: wlan0: IAID dd:88:60:9c Mar 24 18:54:12 volumiogg dhcpcd[776]: wlan0: adding address fe80::ad67:7e85:5c81:ccfe Mar 24 18:54:12 volumiogg dhcpcd[776]: ipv6_addaddr1: Permission denied Mar 24 18:54:12 volumiogg dhcpcd[776]: wlan0: carrier lost Mar 24 18:54:12 volumiogg dhcpcd[776]: wlan0: carrier acquired Mar 24 18:54:12 volumiogg dhcpcd[776]: wlan0: IAID dd:88:60:9c Mar 24 18:54:12 volumiogg dhcpcd[776]: wlan0: soliciting an IPv6 router Mar 24 18:54:12 volumiogg dhcpcd[776]: wlan0: rebinding lease of 192.168.11.27 Mar 24 18:54:12 volumiogg dhcpcd[776]: wlan0: probing address 192.168.11.27/24 Mar 24 18:54:12 volumiogg wireless.js[547]: trying... Mar 24 18:54:12 volumiogg sudo[805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 18:54:12 volumiogg sudo[805]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:12 volumiogg sudo[805]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:13 volumiogg volumio-remote-updater[528]: [2024-03-24 18:54:13] [info] asio async_connect error: system:111 (Connection refused) Mar 24 18:54:13 volumiogg volumio-remote-updater[528]: [2024-03-24 18:54:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 24 18:54:13 volumiogg volumio-remote-updater[528]: [2024-03-24 18:54:13] [error] handle_connect error: Underlying Transport Error Mar 24 18:54:13 volumiogg wireless.js[547]: trying... Mar 24 18:54:13 volumiogg sudo[808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 18:54:13 volumiogg sudo[808]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:13 volumiogg sudo[808]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:14 volumiogg wireless.js[547]: trying... Mar 24 18:54:14 volumiogg sudo[811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 18:54:14 volumiogg sudo[811]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:14 volumiogg sudo[811]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:15 volumiogg wireless.js[547]: trying... Mar 24 18:54:15 volumiogg sudo[814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 18:54:15 volumiogg sudo[814]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:15 volumiogg sudo[814]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:16 volumiogg wireless.js[547]: trying... Mar 24 18:54:16 volumiogg sudo[817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 18:54:16 volumiogg sudo[817]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:16 volumiogg sudo[817]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:17 volumiogg dhcpcd[776]: wlan0: leased 192.168.11.27 for 172800 seconds Mar 24 18:54:17 volumiogg avahi-daemon[550]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.11.27. Mar 24 18:54:17 volumiogg avahi-daemon[550]: New relevant interface wlan0.IPv4 for mDNS. Mar 24 18:54:17 volumiogg avahi-daemon[550]: Registering new address record for 192.168.11.27 on wlan0.IPv4. Mar 24 18:54:17 volumiogg dhcpcd[776]: wlan0: adding route to 192.168.11.0/24 Mar 24 18:54:17 volumiogg dhcpcd[776]: wlan0: adding default route via 192.168.11.1 Mar 24 18:54:17 volumiogg nmbd[645]: [2024/03/24 18:54:17.425472, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 24 18:54:17 volumiogg systemd[1]: Started Samba NMB Daemon. Mar 24 18:54:17 volumiogg nmbd[645]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 24 18:54:17 volumiogg systemd[1]: Starting Samba Winbind Daemon... Mar 24 18:54:17 volumiogg winbindd[839]: [2024/03/24 18:54:17.678160, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 24 18:54:17 volumiogg winbindd[839]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 24 18:54:17 volumiogg winbindd[839]: [2024/03/24 18:54:17.695965, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 24 18:54:17 volumiogg winbindd[839]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 24 18:54:17 volumiogg systemd[1]: Started Samba Winbind Daemon. Mar 24 18:54:17 volumiogg systemd[1]: Starting Samba SMB Daemon... Mar 24 18:54:17 volumiogg wireless.js[547]: trying... Mar 24 18:54:17 volumiogg sudo[845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 24 18:54:17 volumiogg sudo[845]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:17 volumiogg sudo[845]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:17 volumiogg wireless.js[547]: Connected to: ----Buffalo-A-5558 Mar 24 18:54:17 volumiogg wireless.js[547]: ---- Mar 24 18:54:17 volumiogg sudo[849]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 18:54:17 volumiogg sudo[849]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:17 volumiogg sudo[849]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:17 volumiogg wireless.js[547]: ... joined AP, wlan0 IPv4 is 192.168.11.27, ipV6 is undefined Mar 24 18:54:17 volumiogg wireless.js[547]: It's done! AP Mar 24 18:54:17 volumiogg kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 24 18:54:17 volumiogg systemd[1]: Started Wireless Services. Mar 24 18:54:17 volumiogg systemd[1]: Started Volumio Backend Module. Mar 24 18:54:17 volumiogg systemd[1]: Started Volumio Cpu Tweaker. Mar 24 18:54:17 volumiogg volumio-cpu-tweak[855]: Setting RT Priority for mpd Mar 24 18:54:17 volumiogg volumio-cpu-tweak[855]: pid 35's current scheduling policy: SCHED_OTHER Mar 24 18:54:17 volumiogg volumio-cpu-tweak[855]: pid 35's current scheduling priority: 0 Mar 24 18:54:17 volumiogg volumio-cpu-tweak[855]: Setting MPD Affinity Mar 24 18:54:17 volumiogg volumio-cpu-tweak[855]: pid 3's current affinity mask: f Mar 24 18:54:17 volumiogg volumio-cpu-tweak[855]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 24 18:54:17 volumiogg volumio-cpu-tweak[855]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 24 18:54:18 volumiogg systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 24 18:54:18 volumiogg smbd[846]: [2024/03/24 18:54:18.130975, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 24 18:54:18 volumiogg smbd[846]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 24 18:54:18 volumiogg systemd[1]: Started Samba SMB Daemon. Mar 24 18:54:18 volumiogg systemd[1]: Reached target Multi-User System. Mar 24 18:54:18 volumiogg systemd[1]: Reached target Graphical Interface. Mar 24 18:54:18 volumiogg systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 24 18:54:18 volumiogg systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 24 18:54:18 volumiogg systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 24 18:54:18 volumiogg systemd[1]: Startup finished in 9.157s (kernel) + 25.043s (userspace) = 34.201s. Mar 24 18:54:18 volumiogg ntpd[655]: Listen normally on 3 wlan0 192.168.11.27:123 Mar 24 18:54:18 volumiogg ntpd[655]: new interface(s) found: waking up resolver Mar 24 18:54:19 volumiogg volumio[854]: info: ------------------------------------------- Mar 24 18:54:19 volumiogg volumio[854]: info: ----- Volumio3 ---- Mar 24 18:54:19 volumiogg volumio[854]: info: ------------------------------------------- Mar 24 18:54:19 volumiogg volumio[854]: info: ----- System startup ---- Mar 24 18:54:19 volumiogg volumio[854]: info: ------------------------------------------- Mar 24 18:54:20 volumiogg volumio[854]: info: MYVOLUMIO Environment detected Mar 24 18:54:20 volumiogg volumio[854]: info: Plugin folders cleanup Mar 24 18:54:20 volumiogg volumio[854]: info: Scanning into folder /volumio/app/plugins/ Mar 24 18:54:20 volumiogg volumio[854]: info: Scanning category audio_interface Mar 24 18:54:20 volumiogg volumio[854]: info: Scanning category miscellanea Mar 24 18:54:20 volumiogg volumio[854]: info: Scanning category music_service Mar 24 18:54:20 volumiogg volumio[854]: info: Scanning category plugins.json Mar 24 18:54:20 volumiogg volumio[854]: info: Scanning category system_controller Mar 24 18:54:20 volumiogg volumio[854]: info: Scanning category user_interface Mar 24 18:54:20 volumiogg volumio[854]: info: Scanning into folder /data/plugins/ Mar 24 18:54:20 volumiogg volumio[854]: info: Scanning category audio_interface Mar 24 18:54:20 volumiogg volumio[854]: info: Scanning category music_service Mar 24 18:54:20 volumiogg volumio[854]: info: Scanning category user_interface Mar 24 18:54:20 volumiogg volumio[854]: info: Plugin folders cleanup completed Mar 24 18:54:20 volumiogg volumio[854]: info: ------------------------------------------- Mar 24 18:54:20 volumiogg volumio[854]: info: ----- Core plugins startup ---- Mar 24 18:54:20 volumiogg volumio[854]: info: ------------------------------------------- Mar 24 18:54:20 volumiogg volumio[854]: info: Loading plugins from folder /volumio/app/plugins/ Mar 24 18:54:20 volumiogg volumio[854]: info: Adding plugin upnp to MyMusic Plugins Mar 24 18:54:20 volumiogg volumio[854]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 24 18:54:20 volumiogg volumio[854]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 24 18:54:20 volumiogg volumio[854]: info: Loading plugins from folder /data/plugins/ Mar 24 18:54:20 volumiogg volumio[854]: info: Loading plugin "system"... Mar 24 18:54:20 volumiogg volumio[854]: info: Loading plugin "appearance"... Mar 24 18:54:21 volumiogg volumio[854]: info: Loading plugin "network"... Mar 24 18:54:21 volumiogg volumio[854]: info: Refreshing Cached IP Addresses Mar 24 18:54:21 volumiogg sudo[893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 18:54:21 volumiogg sudo[893]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:21 volumiogg volumio[854]: info: Loading plugin "services"... Mar 24 18:54:21 volumiogg sudo[895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 18:54:21 volumiogg sudo[893]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:21 volumiogg sudo[895]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:21 volumiogg volumio[854]: info: Loading plugin "alsa_controller"... Mar 24 18:54:21 volumiogg sudo[895]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:21 volumiogg sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 24 18:54:21 volumiogg sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:21 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 18:54:21 volumiogg volumio[854]: info: Loading plugin "wizard"... Mar 24 18:54:21 volumiogg volumio[854]: info: Loading plugin "networkfs"... Mar 24 18:54:21 volumiogg volumio[854]: info: Starting Udev Watcher for removable devices Mar 24 18:54:21 volumiogg sudo[906]: 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.11/disk1 /mnt/NAS/disk1 Mar 24 18:54:21 volumiogg sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:21 volumiogg volumio[854]: info: Ignoring mount for partition: boot Mar 24 18:54:21 volumiogg volumio[854]: info: Ignoring mount for partition: volumio Mar 24 18:54:21 volumiogg volumio[854]: info: Ignoring mount for partition: volumio_data Mar 24 18:54:21 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 18:54:21 volumiogg volumio[854]: info: Loading plugin "volumio_command_line_client"... Mar 24 18:54:21 volumiogg volumio[854]: info: Loading plugin "upnp"... Mar 24 18:54:21 volumiogg volumio[854]: info: [1711274061689] Starting Upmpd Daemon Mar 24 18:54:21 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 18:54:21 volumiogg volumio[854]: info: Loading plugin "my_music"... Mar 24 18:54:21 volumiogg volumio[854]: info: Loading plugin "mpd"... Mar 24 18:54:21 volumiogg kernel: Key type cifs.spnego registered Mar 24 18:54:21 volumiogg kernel: Key type cifs.idmap registered Mar 24 18:54:21 volumiogg 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. Mar 24 18:54:21 volumiogg kernel: CIFS: Attempting to mount \\192.168.11.11\disk1 Mar 24 18:54:22 volumiogg volumio[854]: info: Loading plugin "upnp_browser"... Mar 24 18:54:22 volumiogg sudo[906]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:22 volumiogg volumio[854]: info: Loading plugin "alarm-clock"... Mar 24 18:54:22 volumiogg volumio[854]: info: Loading plugin "airplay_emulation"... Mar 24 18:54:22 volumiogg volumio[854]: info: Starting Shairport Sync Mar 24 18:54:22 volumiogg volumio[854]: info: Loading plugin "last_100"... Mar 24 18:54:22 volumiogg volumio[854]: info: Loading plugin "webradio"... Mar 24 18:54:22 volumiogg volumio[854]: info: Loading plugin "i2s_dacs"... Mar 24 18:54:22 volumiogg volumio[854]: info: I2S DAC not set, start Auto-detection Mar 24 18:54:22 volumiogg volumio[854]: info: Loading plugin "volumiodiscovery"... Mar 24 18:54:22 volumiogg volumio[854]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 24 18:54:22 volumiogg volumio[854]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 18:54:22 volumiogg node[854]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 24 18:54:22 volumiogg volumio[854]: *** WARNING *** For more information see Mar 24 18:54:22 volumiogg volumio[854]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 24 18:54:22 volumiogg volumio[854]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 18:54:22 volumiogg volumio[854]: *** WARNING *** For more information see Mar 24 18:54:22 volumiogg node[854]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 18:54:22 volumiogg node[854]: *** WARNING *** For more information see Mar 24 18:54:22 volumiogg node[854]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 24 18:54:22 volumiogg node[854]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 18:54:22 volumiogg node[854]: *** WARNING *** For more information see Mar 24 18:54:22 volumiogg volumio[854]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 24 18:54:22 volumiogg volumio[854]: info: Discovery: Started advertising with name: VolumioGG Mar 24 18:54:22 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 18:54:22 volumiogg volumio[854]: info: Plugin ytcr is not enabled Mar 24 18:54:22 volumiogg volumio[854]: info: Loading plugin "outputs"... Mar 24 18:54:22 volumiogg volumio[854]: info: Loading plugin "albumart"... Mar 24 18:54:22 volumiogg volumio[854]: info: Plugin example_plugin is not enabled Mar 24 18:54:22 volumiogg volumio[854]: info: Loading plugin "inputs"... Mar 24 18:54:22 volumiogg volumio[854]: info: Loading plugin "updater_comm"... Mar 24 18:54:22 volumiogg volumio[854]: info: Plugin mpdemulation is not enabled Mar 24 18:54:22 volumiogg volumio[854]: info: Loading plugin "rest_api"... Mar 24 18:54:22 volumiogg volumio[854]: info: Loading plugin "websocket"... Mar 24 18:54:22 volumiogg volumio[854]: info: Loading plugin "fusiondsp"... Mar 24 18:54:22 volumiogg volumio[854]: Forking 3 albumart workers Mar 24 18:54:23 volumiogg volumio-remote-updater[528]: [2024-03-24 18:54:23] [connect] Successful connection Mar 24 18:54:23 volumiogg volumio[854]: info: Applying required configuration parameters for plugin fusiondsp Mar 24 18:54:23 volumiogg volumio[854]: info: Loading plugin "lms"... Mar 24 18:54:23 volumiogg volumio[854]: Starting albumart workers Mar 24 18:54:23 volumiogg volumio[854]: Starting albumart workers Mar 24 18:54:23 volumiogg volumio[854]: Starting albumart workers Mar 24 18:54:23 volumiogg volumio[854]: info: Loading plugin "Systeminfo"... Mar 24 18:54:24 volumiogg volumio[854]: info: Loading plugin "touch_display"... Mar 24 18:54:24 volumiogg volumio[854]: info: Applying required configuration parameters for plugin touch_display Mar 24 18:54:24 volumiogg volumio[854]: info: Loading i18n strings for locale ja Mar 24 18:54:24 volumiogg volumio[854]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 24 18:54:24 volumiogg volumio[854]: Updating browse sources language Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::initPlayerControls Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:54:24 volumiogg volumio[854]: Express server listening on port 3000 Mar 24 18:54:24 volumiogg volumio[854]: [Metrics] WebUI: 5s 614.04ms Mar 24 18:54:24 volumiogg volumio[854]: info: CoreStateMachine::resetVolumioState Mar 24 18:54:24 volumiogg volumio[854]: info: CoreStateMachine::getcurrentVolume Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::volumioRetrievevolume Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 18:54:24 volumiogg volumio-remote-updater[528]: [2024-03-24 18:54:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1711274063 101 Mar 24 18:54:24 volumiogg volumio[854]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Mar 24 18:54:24 volumiogg volumio[854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Mar 24 18:54:24 volumiogg volumio[854]: info: VolumeController:: Volume=100 Mute =false Mar 24 18:54:24 volumiogg volumio[854]: info: CoreStateMachine::pushState Mar 24 18:54:24 volumiogg volumio[854]: info: CorePlayQueue::getTrack 0 Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::volumioPushState Mar 24 18:54:24 volumiogg volumio[854]: info: CoreStateMachine::updateTrackBlock Mar 24 18:54:24 volumiogg volumio[854]: info: CorePlayQueue::getTrackBlock Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::volumioRetrievevolume Mar 24 18:54:24 volumiogg volumio[854]: info: Reloading queue from file Mar 24 18:54:24 volumiogg volumio[854]: info: CoreStateMachine::setRepeat true single undefined Mar 24 18:54:24 volumiogg volumio[854]: info: CoreStateMachine::pushState Mar 24 18:54:24 volumiogg volumio[854]: info: CorePlayQueue::getTrack 0 Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::volumioPushState Mar 24 18:54:24 volumiogg volumio[854]: info: CoreStateMachine::setRandom true Mar 24 18:54:24 volumiogg volumio[854]: info: CoreStateMachine::pushState Mar 24 18:54:24 volumiogg volumio[854]: info: CorePlayQueue::getTrack 0 Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::volumioPushState Mar 24 18:54:24 volumiogg volumio[854]: info: Setting Device type: Raspberry PI Mar 24 18:54:24 volumiogg volumio[854]: info: VolumeController:: Volume=100 Mute =false Mar 24 18:54:24 volumiogg volumio[854]: info: CoreStateMachine::pushState Mar 24 18:54:24 volumiogg volumio[854]: info: CorePlayQueue::getTrack 0 Mar 24 18:54:24 volumiogg volumio[854]: info: CoreCommandRouter::volumioPushState Mar 24 18:54:25 volumiogg volumio[854]: info: Discovery: adding febcb725-c201-4aab-a45b-b3ee5b32d042 Mar 24 18:54:25 volumiogg volumio[854]: info: Discovery: Found device VolumioGG Mar 24 18:54:25 volumiogg volumio[854]: info: CoreCommandRouter::volumioGetState Mar 24 18:54:25 volumiogg volumio[854]: info: CorePlayQueue::getTrack 0 Mar 24 18:54:25 volumiogg sudo[898]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:27 volumiogg sudo[998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 18:54:27 volumiogg sudo[998]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:27 volumiogg sudo[998]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:27 volumiogg sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 18:54:27 volumiogg sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:27 volumiogg sudo[1000]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:27 volumiogg volumio[854]: verbose: New Socket.io Connection to 192.168.11.27 from 192.168.11.6 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/16.2 Mobile/15E148 Safari/604.1 Total Clients: 3 Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::volumioGetState Mar 24 18:54:27 volumiogg volumio[854]: info: CorePlayQueue::getTrack 0 Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 18:54:27 volumiogg volumio[854]: info: Received Get System Info Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 18:54:27 volumiogg volumio[854]: info: Discovery: Getting this device information Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::volumioGetState Mar 24 18:54:27 volumiogg volumio[854]: info: CorePlayQueue::getTrack 0 Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::volumioGetState Mar 24 18:54:27 volumiogg volumio[854]: info: CorePlayQueue::getTrack 0 Mar 24 18:54:27 volumiogg volumio[854]: info: Listing playlists Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 18:54:27 volumiogg volumio[854]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 18:54:29 volumiogg systemd[1]: systemd-fsckd.service: Succeeded. Mar 24 18:54:29 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 18:54:29 volumiogg volumio[854]: info: Received Get System Info Mar 24 18:54:29 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 18:54:29 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 18:54:29 volumiogg volumio[854]: info: Discovery: Getting this device information Mar 24 18:54:29 volumiogg volumio[854]: info: CoreCommandRouter::volumioGetState Mar 24 18:54:29 volumiogg volumio[854]: info: CorePlayQueue::getTrack 0 Mar 24 18:54:29 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 18:54:30 volumiogg sh[418]: timed out Mar 24 18:54:30 volumiogg dhcpcd[469]: timed out Mar 24 18:54:30 volumiogg sh[418]: dhcpcd exited Mar 24 18:54:30 volumiogg dhcpcd[469]: dhcpcd exited Mar 24 18:54:30 volumiogg sh[418]: ifup: failed to bring up eth0 Mar 24 18:54:30 volumiogg systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:54:30 volumiogg systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 24 18:54:30 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 18:54:30 volumiogg volumio[854]: info: Received Get System Info Mar 24 18:54:30 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 18:54:30 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 18:54:30 volumiogg volumio[854]: info: Discovery: Getting this device information Mar 24 18:54:30 volumiogg volumio[854]: info: CoreCommandRouter::volumioGetState Mar 24 18:54:30 volumiogg volumio[854]: info: CorePlayQueue::getTrack 0 Mar 24 18:54:30 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 18:54:31 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 24 18:54:31 volumiogg volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 24 18:54:31 volumiogg sudo[1005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 18:54:31 volumiogg sudo[1005]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:31 volumiogg sudo[1005]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:31 volumiogg sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 18:54:31 volumiogg sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:31 volumiogg sudo[1007]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:31 volumiogg sudo[1010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 24 18:54:31 volumiogg sudo[1010]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 18:54:31 volumiogg systemd[1]: Started UPnP Renderer front-end to MPD. Mar 24 18:54:31 volumiogg sudo[1010]: pam_unix(sudo:session): session closed for user root Mar 24 18:54:31 volumiogg volumio[854]: info: Upmpdcli Daemon Started Mar 24 18:54:31 volumiogg volumio[854]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Mar 24 18:54:32 volumiogg systemd[1]: systemd-hostnamed.service: Succeeded. Mar 24 18:54:33 volumiogg volumio[854]: info: CoreCommandRouter::volumioGetQueue Mar 24 18:54:33 volumiogg volumio[854]: info: CoreStateMachine::getQueue Mar 24 18:54:33 volumiogg volumio[854]: info: CorePlayQueue::getQueue Mar 24 18:54:35 volumiogg volumio[854]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Mar 24 18:54:37 volumiogg volumio[854]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 18:54:37 volumiogg volumio[854]: info: CoreStateMachine::ClearQueue Mar 24 18:54:37 volumiogg volumio[854]: info: CoreStateMachine::stop Mar 24 18:54:37 volumiogg volumio[854]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 18:54:37 volumiogg volumio[854]: info: CorePlayQueue::clearPlayQueue Mar 24 18:54:37 volumiogg volumio[854]: info: CorePlayQueue::saveQueue Mar 24 18:54:37 volumiogg volumio[854]: info: CoreCommandRouter::volumioPushQueue Mar 24 18:54:37 volumiogg volumio[854]: info: Play playlist Ado Mar 24 18:54:37 volumiogg volumio[854]: info: CoreCommandRouter::volumioClearQueue Mar 24 18:54:37 volumiogg volumio[854]: info: CoreStateMachine::ClearQueue Mar 24 18:54:37 volumiogg volumio[854]: info: CoreStateMachine::stop Mar 24 18:54:37 volumiogg volumio[854]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 18:54:37 volumiogg volumio[854]: info: CorePlayQueue::clearPlayQueue Mar 24 18:54:37 volumiogg volumio[854]: info: CorePlayQueue::saveQueue Mar 24 18:54:37 volumiogg volumio[854]: info: CoreCommandRouter::volumioPushState Mar 24 18:54:37 volumiogg volumio[854]: info: CoreCommandRouter::volumioPushQueue Mar 24 18:54:37 volumiogg volumio[854]: info: CoreCommandRouter::volumioAddQueueItems Mar 24 18:54:37 volumiogg volumio[854]: info: CoreStateMachine::addQueueItems Mar 24 18:54:37 volumiogg volumio[854]: info: CorePlayQueue::addQueueItems Mar 24 18:54:37 volumiogg volumio[854]: info: Preload queue cleared Mar 24 18:54:37 volumiogg volumio[854]: info: Adding Item to queue: NAS/disk1/e-onkyo/Ado/Adoの歌ってみたアルバム/01_ドライフラワー.dsf Mar 24 18:54:37 volumiogg volumio[854]: info: Exploding uri NAS/disk1/e-onkyo/Ado/Adoの歌ってみたアルバム/01_ドライフラワー.dsf in service mpd Mar 24 18:54:37 volumiogg volumio[854]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 18:54:37 volumiogg volumio[854]: TypeError: Cannot read property 'then' of undefined Mar 24 18:54:37 volumiogg volumio[854]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Mar 24 18:54:37 volumiogg volumio[854]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Mar 24 18:54:37 volumiogg volumio[854]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Mar 24 18:54:37 volumiogg volumio[854]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Mar 24 18:54:37 volumiogg volumio[854]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Mar 24 18:54:37 volumiogg volumio[854]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Mar 24 18:54:37 volumiogg volumio[854]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Mar 24 18:54:37 volumiogg volumio[854]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:447:28) Mar 24 18:54:37 volumiogg volumio[854]: at /volumio/app/playlistManager.js:726:30 Mar 24 18:54:37 volumiogg volumio[854]: at /volumio/node_modules/jsonfile/index.js:46:5 Mar 24 18:54:37 volumiogg volumio[854]: at /volumio/node_modules/graceful-fs/graceful-fs.js:123:16 Mar 24 18:54:37 volumiogg volumio[854]: at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:63:3) Mar 24 18:54:37 volumiogg volumio[854]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 18:54:37 volumiogg sudo[1025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-24 18:53 Mar 24 18:54:37 volumiogg sudo[1025]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"