-- Logs begin at Thu 2019-02-14 13:12:00 MSK, end at Fri 2024-04-05 15:25:05 MSK. -- Apr 05 15:24:22 speaker fake-hwclock[192]: Fri Apr 5 12:24:22 UTC 2024 Apr 05 15:24:22 speaker systemd[1]: Started Restore / save the current clock. Apr 05 15:24:22 speaker systemd[1]: Started Apply Kernel Variables. Apr 05 15:24:22 speaker systemd[1]: dynamicswap.service: Succeeded. Apr 05 15:24:22 speaker systemd[1]: Started Load/Save Random Seed. Apr 05 15:24:22 speaker systemd[1]: Started Create System Users. Apr 05 15:24:22 speaker systemd[1]: Starting Create Static Device Nodes in /dev... Apr 05 15:24:22 speaker systemd[1]: Started Create Static Device Nodes in /dev. Apr 05 15:24:22 speaker systemd[1]: Reached target Local File Systems (Pre). Apr 05 15:24:22 speaker systemd[1]: Mounting /var/spool/cups... Apr 05 15:24:22 speaker systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Apr 05 15:24:22 speaker systemd[1]: Mounting /tmp... Apr 05 15:24:22 speaker systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Apr 05 15:24:22 speaker systemd[1]: Mounting /var/log... Apr 05 15:24:22 speaker systemd[1]: Starting udev Kernel Device Manager... Apr 05 15:24:22 speaker systemd[1]: Started udev Coldplug all Devices. Apr 05 15:24:22 speaker systemd[1]: Mounted /var/spool/cups. Apr 05 15:24:22 speaker systemd[1]: Mounted /tmp. Apr 05 15:24:22 speaker systemd[1]: Mounted /var/log. Apr 05 15:24:22 speaker systemd[1]: Starting Flush Journal to Persistent Storage... Apr 05 15:24:22 speaker systemd[1]: Mounting /var/spool/cups/tmp... Apr 05 15:24:22 speaker systemd[1]: Starting Helper to synchronize boot up for ifupdown... Apr 05 15:24:22 speaker systemd[1]: Mounted /var/spool/cups/tmp. Apr 05 15:24:22 speaker systemd-udevd[227]: Network interface NamePolicy= disabled on kernel command line, ignoring. Apr 05 15:24:22 speaker systemd-journald[195]: Runtime journal (/run/log/journal/c1d531165394c79721acb5a765d65e88) is 7.5M, max 30.0M, 22.5M free. Apr 05 15:24:22 speaker systemd[1]: Started Flush Journal to Persistent Storage. Apr 05 15:24:22 speaker systemd[1]: Started udev Kernel Device Manager. Apr 05 15:24:22 speaker systemd[1]: Starting Show Plymouth Boot Screen... Apr 05 15:24:22 speaker systemd[1]: Received SIGRTMIN+20 from PID 250 (plymouthd). Apr 05 15:24:22 speaker systemd[1]: Started Show Plymouth Boot Screen. Apr 05 15:24:22 speaker systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Apr 05 15:24:22 speaker systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 05 15:24:22 speaker systemd[1]: Reached target Paths. Apr 05 15:24:22 speaker systemd[1]: Reached target Local Encrypted Volumes. Apr 05 15:24:23 speaker kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Apr 05 15:24:23 speaker kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Apr 05 15:24:23 speaker kernel: [vc_sm_connected_init]: start Apr 05 15:24:23 speaker kernel: [vc_sm_connected_init]: installed successfully Apr 05 15:24:23 speaker kernel: mc: Linux media interface: v0.10 Apr 05 15:24:23 speaker kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Apr 05 15:24:23 speaker kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Apr 05 15:24:23 speaker systemd[1]: Found device /dev/ttyAMA0. Apr 05 15:24:23 speaker kernel: videodev: Linux video capture interface: v2.00 Apr 05 15:24:23 speaker kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Apr 05 15:24:23 speaker kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Apr 05 15:24:23 speaker kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Apr 05 15:24:23 speaker kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Apr 05 15:24:23 speaker kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Apr 05 15:24:23 speaker kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Apr 05 15:24:23 speaker kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Apr 05 15:24:23 speaker kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Apr 05 15:24:23 speaker kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Apr 05 15:24:23 speaker systemd-udevd[240]: Using default interface naming scheme 'v240'. Apr 05 15:24:23 speaker kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Apr 05 15:24:23 speaker kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Apr 05 15:24:24 speaker kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Apr 05 15:24:24 speaker kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Apr 05 15:24:24 speaker kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Apr 05 15:24:24 speaker kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Apr 05 15:24:24 speaker systemd-udevd[240]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 05 15:24:24 speaker systemd-udevd[247]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 05 15:24:24 speaker kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Apr 05 15:24:24 speaker kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Apr 05 15:24:24 speaker kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Apr 05 15:24:24 speaker kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Apr 05 15:24:24 speaker kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Apr 05 15:24:24 speaker kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Apr 05 15:24:24 speaker kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Apr 05 15:24:24 speaker kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Apr 05 15:24:25 speaker systemd[1]: Found device /dev/mmcblk0p1. Apr 05 15:24:25 speaker kernel: usb 1-1.5: reset high-speed USB device number 4 using dwc_otg Apr 05 15:24:25 speaker kernel: ieee80211 phy0: rt2x00_set_rt: Info - RT chipset 5390, rev 0502 detected Apr 05 15:24:25 speaker kernel: ieee80211 phy0: rt2x00_set_rf: Info - RF chipset 5370 detected Apr 05 15:24:25 speaker kernel: ieee80211 phy0: Selected rate control algorithm 'minstrel_ht' Apr 05 15:24:25 speaker kernel: usbcore: registered new interface driver rt2800usb Apr 05 15:24:25 speaker systemd-udevd[247]: Using default interface naming scheme 'v240'. Apr 05 15:24:25 speaker systemd-udevd[247]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 05 15:24:26 speaker systemd[1]: Started Helper to synchronize boot up for ifupdown. Apr 05 15:24:26 speaker systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Apr 05 15:24:26 speaker systemd[1]: Condition check resulted in RT5370 Wireless Adapter being skipped. Apr 05 15:24:26 speaker systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 05 15:24:26 speaker systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 05 15:24:26 speaker systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Apr 05 15:24:26 speaker systemd[1]: Condition check resulted in FUSE Control File System being skipped. Apr 05 15:24:26 speaker systemd[1]: Condition check resulted in Huge Pages File System being skipped. Apr 05 15:24:26 speaker systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Apr 05 15:24:26 speaker systemd[1]: Starting File System Check on /dev/mmcblk0p1... Apr 05 15:24:26 speaker systemd[1]: Starting Load/Save RF Kill Switch Status... Apr 05 15:24:27 speaker systemd[1]: Started File System Check Daemon to report status. Apr 05 15:24:27 speaker systemd[1]: Started Load/Save RF Kill Switch Status. Apr 05 15:24:27 speaker systemd-fsck[341]: fsck.fat 4.1 (2017-01-24) Apr 05 15:24:27 speaker systemd-fsck[341]: /dev/mmcblk0p1: 365 files, 33347/46774 clusters Apr 05 15:24:27 speaker systemd[1]: Started File System Check on /dev/mmcblk0p1. Apr 05 15:24:27 speaker systemd[1]: Mounting /boot... Apr 05 15:24:27 speaker systemd[1]: Mounted /boot. Apr 05 15:24:27 speaker systemd[1]: Reached target Local File Systems. Apr 05 15:24:27 speaker systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Apr 05 15:24:27 speaker systemd[1]: Starting Create Volatile Files and Directories... Apr 05 15:24:27 speaker systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Apr 05 15:24:27 speaker systemd[1]: Started ifup for eth0. Apr 05 15:24:27 speaker systemd[1]: Starting Preprocess NFS configuration... Apr 05 15:24:27 speaker systemd[1]: Starting Raise network interfaces... Apr 05 15:24:27 speaker systemd[1]: Started ifup for wlan0. Apr 05 15:24:27 speaker systemd[1]: plymouth-read-write.service: Succeeded. Apr 05 15:24:27 speaker systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Apr 05 15:24:27 speaker systemd[1]: Received SIGRTMIN+20 from PID 250 (plymouthd). Apr 05 15:24:27 speaker systemd[1]: nfs-config.service: Succeeded. Apr 05 15:24:27 speaker systemd[1]: Started Preprocess NFS configuration. Apr 05 15:24:27 speaker systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Apr 05 15:24:27 speaker systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Apr 05 15:24:27 speaker systemd[1]: Reached target NFS client services. Apr 05 15:24:27 speaker systemd[1]: Started Create Volatile Files and Directories. Apr 05 15:24:27 speaker systemd[1]: Starting RPC bind portmap service... Apr 05 15:24:27 speaker systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Apr 05 15:24:27 speaker systemd[1]: Starting Update UTMP about System Boot/Shutdown... Apr 05 15:24:27 speaker kernel: ieee80211 phy0: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2870.bin' Apr 05 15:24:27 speaker kernel: ieee80211 phy0: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.36 Apr 05 15:24:28 speaker systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Apr 05 15:24:28 speaker systemd[1]: Started Update UTMP about System Boot/Shutdown. Apr 05 15:24:28 speaker systemd[1]: Reached target System Initialization. Apr 05 15:24:28 speaker systemd[1]: Listening on D-Bus System Message Bus Socket. Apr 05 15:24:28 speaker systemd[1]: Listening on triggerhappy.socket. Apr 05 15:24:28 speaker systemd[1]: Started Volumio Splash. Apr 05 15:24:28 speaker systemd[414]: volumiosplash.service: Failed to execute command: No such file or directory Apr 05 15:24:28 speaker systemd[414]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Apr 05 15:24:28 speaker systemd[1]: Started Daily apt download activities. Apr 05 15:24:28 speaker systemd[1]: Started Daily apt upgrade and clean activities. Apr 05 15:24:28 speaker systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Apr 05 15:24:28 speaker systemd[1]: Reached target Sockets. Apr 05 15:24:28 speaker systemd[1]: Reached target Basic System. Apr 05 15:24:28 speaker systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Apr 05 15:24:28 speaker systemd[1]: Starting Wireless Services... Apr 05 15:24:28 speaker systemd[1]: Started Manage Sound Card State (restore and store). Apr 05 15:24:28 speaker systemd[1]: Starting Save/Restore Sound Card State... Apr 05 15:24:28 speaker systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Apr 05 15:24:28 speaker alsactl[417]: alsactl 1.1.8 daemon started Apr 05 15:24:28 speaker systemd[1]: Starting Login Service... Apr 05 15:24:28 speaker systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Apr 05 15:24:28 speaker systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Apr 05 15:24:28 speaker systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Apr 05 15:24:28 speaker systemd[1]: Started UPnP Renderer front-end to MPD. Apr 05 15:24:28 speaker systemd[1]: Started volumio-remote-updater.service. Apr 05 15:24:28 speaker systemd[1]: Started D-Bus System Message Bus. Apr 05 15:24:28 speaker systemd[1]: Starting WPA supplicant... Apr 05 15:24:28 speaker systemd[1]: Started Volumio Log Rotation Service. Apr 05 15:24:28 speaker systemd[1]: Started Volumio Iptables Module. Apr 05 15:24:28 speaker systemd[1]: Starting triggerhappy global hotkey daemon... Apr 05 15:24:28 speaker avahi-daemon[415]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Apr 05 15:24:28 speaker avahi-daemon[415]: Successfully dropped root privileges. Apr 05 15:24:28 speaker avahi-daemon[415]: avahi-daemon 0.7 starting up. Apr 05 15:24:28 speaker systemd[1]: Starting dhcpcd on all interfaces... Apr 05 15:24:28 speaker systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 05 15:24:28 speaker systemd[1]: Reached target Timers. Apr 05 15:24:28 speaker kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Apr 05 15:24:28 speaker kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Apr 05 15:24:28 speaker systemd[1]: Started RPC bind portmap service. Apr 05 15:24:28 speaker systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Apr 05 15:24:28 speaker systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Apr 05 15:24:28 speaker systemd[1]: Reached target RPC Port Mapper. Apr 05 15:24:28 speaker systemd[1]: Reached target Remote File Systems (Pre). Apr 05 15:24:28 speaker systemd[1]: Reached target Remote File Systems. Apr 05 15:24:28 speaker systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Apr 05 15:24:28 speaker thd[426]: Unable to parse trigger line: Apr 05 15:24:28 speaker thd[426]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Apr 05 15:24:28 speaker thd[426]: Unable to parse trigger line: Apr 05 15:24:28 speaker thd[426]: Found socket passed from systemd Apr 05 15:24:28 speaker systemd-logind[419]: New seat seat0. Apr 05 15:24:29 speaker dhcpcd[430]: dev: loaded udev Apr 05 15:24:29 speaker kernel: 8021q: 802.1Q VLAN Support v1.8 Apr 05 15:24:29 speaker dhcpcd[430]: forked to background, child pid 498 Apr 05 15:24:30 speaker systemd[1]: Started triggerhappy global hotkey daemon. Apr 05 15:24:30 speaker systemd[1]: Started Save/Restore Sound Card State. Apr 05 15:24:30 speaker systemd[1]: Started dhcpcd on all interfaces. Apr 05 15:24:30 speaker avahi-daemon[415]: Successfully called chroot(). Apr 05 15:24:30 speaker avahi-daemon[415]: Successfully dropped remaining capabilities. Apr 05 15:24:30 speaker wpa_supplicant[423]: Successfully initialized wpa_supplicant Apr 05 15:24:30 speaker avahi-daemon[415]: Loading service file /services/volumio.service. Apr 05 15:24:30 speaker avahi-daemon[415]: Network interface enumeration completed. Apr 05 15:24:30 speaker avahi-daemon[415]: Server startup complete. Host name is speaker.local. Local service cookie is 4166161936. Apr 05 15:24:30 speaker avahi-daemon[415]: Service "speaker" (/services/volumio.service) successfully established. Apr 05 15:24:30 speaker dhcpcd-run-hooks[512]: wlan0: ctrl_interface not defined in /etc/wpa_supplicant/wpa_supplicant.conf Apr 05 15:24:30 speaker dhcpcd-run-hooks[513]: wlan0: not interacting with wpa_supplicant(8) Apr 05 15:24:30 speaker dhcpcd[498]: wlan0: connected to Access Point `' Apr 05 15:24:30 speaker ifup[352]: ifup: waiting for lock on /run/network/ifstate.wlan0 Apr 05 15:24:30 speaker sh[349]: eth0=eth0 Apr 05 15:24:30 speaker sh[354]: wlan0=wlan0 Apr 05 15:24:30 speaker volumio-remote-updater[421]: Error: No active session Apr 05 15:24:30 speaker volumio-remote-updater[421]: [2024-04-05 15:24:30] [info] asio async_connect error: system:111 (Connection refused) Apr 05 15:24:30 speaker volumio-remote-updater[421]: [2024-04-05 15:24:30] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 05 15:24:30 speaker volumio-remote-updater[421]: [2024-04-05 15:24:30] [error] handle_connect error: Underlying Transport Error Apr 05 15:24:30 speaker dhcpcd[498]: DUID 00:01:00:01:2d:69:1c:d1:b8:27:eb:f0:b9:6f Apr 05 15:24:30 speaker dhcpcd[498]: eth0: IAID eb:f0:b9:6f Apr 05 15:24:30 speaker dhcpcd[498]: eth0: adding address fe80::ba27:ebff:fef0:b96f Apr 05 15:24:30 speaker dhcpcd[498]: ipv6_addaddr1: Permission denied Apr 05 15:24:30 speaker dhcpcd[498]: eth0: probing address 192.168.30.101/24 Apr 05 15:24:30 speaker dhcpcd[498]: wlan0: waiting for carrier Apr 05 15:24:30 speaker volumio[420]: Could not open config: /tmp/upmpdcli.conf Apr 05 15:24:30 speaker systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 05 15:24:30 speaker systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 05 15:24:30 speaker systemd[1]: iptables.service: Succeeded. Apr 05 15:24:31 speaker systemd[1]: Started Login Service. Apr 05 15:24:31 speaker systemd[1]: Started Avahi mDNS/DNS-SD Stack. Apr 05 15:24:31 speaker systemd[1]: Started WPA supplicant. Apr 05 15:24:31 speaker systemd[1]: Reached target Sound Card. Apr 05 15:24:31 speaker systemd[1]: Started Raise network interfaces. Apr 05 15:24:31 speaker systemd[1]: Reached target Network. Apr 05 15:24:31 speaker systemd[1]: Starting Network Time Service... Apr 05 15:24:31 speaker systemd[1]: Reached target Network is Online. Apr 05 15:24:31 speaker systemd[1]: Starting LSB: Brings up/down network automatically... Apr 05 15:24:31 speaker systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 05 15:24:31 speaker systemd[1]: Starting /etc/rc.local Compatibility... Apr 05 15:24:31 speaker systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Apr 05 15:24:31 speaker systemd[1]: Starting Samba NMB Daemon... Apr 05 15:24:31 speaker systemd[1]: Starting Permit User Sessions... Apr 05 15:24:31 speaker systemd[1]: Started /etc/rc.local Compatibility. Apr 05 15:24:31 speaker loadcpufreq[440]: Loading cpufreq kernel modules...done (none). Apr 05 15:24:31 speaker systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Apr 05 15:24:31 speaker systemd[1]: Started Permit User Sessions. Apr 05 15:24:31 speaker ntpd[565]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 05 15:24:31 speaker ntpd[565]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Apr 05 15:24:31 speaker ntpd[607]: proto: precision = 1.667 usec (-19) Apr 05 15:24:31 speaker dhcpcd[498]: eth0: soliciting an IPv6 router Apr 05 15:24:31 speaker systemd[1]: Starting Hold until boot process finishes up... Apr 05 15:24:31 speaker ifplugd(eth0)[616]: ifplugd 0.28 initializing. Apr 05 15:24:31 speaker systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 05 15:24:31 speaker ifplugd(eth0)[616]: Using interface eth0/B8:27:EB:F0:B9:6F with driver (version: 6.1.69-v7+) Apr 05 15:24:31 speaker systemd[1]: Starting LSB: set CPUFreq kernel parameters... Apr 05 15:24:31 speaker ifplugd(eth0)[616]: Using detection mode: SIOCETHTOOL Apr 05 15:24:31 speaker ifplugd(eth0)[616]: Initialization complete, link beat detected. Apr 05 15:24:31 speaker ntpd[607]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 05 15:24:31 speaker ntpd[607]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 05 15:24:31 speaker systemd[1]: Started Network Time Service. Apr 05 15:24:31 speaker systemd[1]: Received SIGRTMIN+21 from PID 250 (plymouthd). Apr 05 15:24:31 speaker ntpd[607]: Listen and drop on 0 v6wildcard [::]:123 Apr 05 15:24:31 speaker ntpd[607]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 05 15:24:31 speaker ntpd[607]: Listen normally on 2 lo 127.0.0.1:123 Apr 05 15:24:31 speaker ntpd[607]: Listening on routing socket on fd #19 for interface updates Apr 05 15:24:31 speaker ntpd[607]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 05 15:24:31 speaker ntpd[607]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 05 15:24:31 speaker systemd[1]: Received SIGRTMIN+21 from PID 250 (plymouthd). Apr 05 15:24:31 speaker systemd[1]: plymouth-quit.service: Succeeded. Apr 05 15:24:31 speaker ifplugd(eth0)[616]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 05 15:24:31 speaker systemd[1]: Started Terminate Plymouth Boot Screen. Apr 05 15:24:31 speaker systemd[1]: plymouth-start.service: Succeeded. Apr 05 15:24:31 speaker systemd[1]: plymouth-quit-wait.service: Succeeded. Apr 05 15:24:31 speaker systemd[1]: Started Hold until boot process finishes up. Apr 05 15:24:32 speaker ifplugd(eth0)[616]: client: ifup: interface eth0 already configured Apr 05 15:24:32 speaker systemd[1]: Started Getty on tty1. Apr 05 15:24:32 speaker systemd[1]: Started Serial Getty on ttyAMA0. Apr 05 15:24:32 speaker systemd[1]: Reached target Login Prompts. Apr 05 15:24:32 speaker systemd[1]: systemd-rfkill.service: Succeeded. Apr 05 15:24:32 speaker ifplugd(eth0)[616]: Program executed successfully. Apr 05 15:24:32 speaker ifplugd[576]: Network Interface Plugging Daemon...start eth0...done. Apr 05 15:24:32 speaker systemd[1]: Started LSB: Brings up/down network automatically. Apr 05 15:24:32 speaker cpufrequtils[618]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Apr 05 15:24:32 speaker systemd[1]: Started LSB: set CPUFreq kernel parameters. Apr 05 15:24:32 speaker haveged[406]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Apr 05 15:24:32 speaker haveged[406]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Apr 05 15:24:32 speaker haveged[406]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99674 Apr 05 15:24:32 speaker haveged[406]: haveged: fills: 0, generated: 0 Apr 05 15:24:32 speaker ntpd[607]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 05 15:24:32 speaker nmbd[599]: [2024/04/05 15:24:32.994906, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 05 15:24:32 speaker nmbd[599]: started asyncdns process 641 Apr 05 15:24:33 speaker nmbd[599]: [2024/04/05 15:24:32.999827, 0] ../lib/util/become_daemon.c:149(daemon_status) Apr 05 15:24:33 speaker nmbd[599]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Apr 05 15:24:33 speaker nmbd[599]: [2024/04/05 15:24:33.000315, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Apr 05 15:24:33 speaker nmbd[599]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Apr 05 15:24:33 speaker ntpd[607]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 05 15:24:33 speaker wireless.js[416]: Cleaning previous... Apr 05 15:24:34 speaker sudo[649]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 05 15:24:34 speaker sudo[649]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:34 speaker sudo[649]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:34 speaker sudo[651]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 05 15:24:34 speaker sudo[651]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:34 speaker sudo[651]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:34 speaker wireless.js[416]: Stopped aP Apr 05 15:24:34 speaker sudo[658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 05 15:24:34 speaker sudo[658]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:34 speaker ntpd[607]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 05 15:24:34 speaker sudo[658]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:34 speaker sudo[660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 05 15:24:34 speaker sudo[660]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:34 speaker sudo[660]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:34 speaker sudo[667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 05 15:24:34 speaker sudo[667]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:34 speaker sudo[667]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:34 speaker sudo[669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 05 15:24:34 speaker sudo[669]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:35 speaker dhcpcd[498]: eth0: using static address 192.168.30.101/24 Apr 05 15:24:35 speaker avahi-daemon[415]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.30.101. Apr 05 15:24:35 speaker avahi-daemon[415]: New relevant interface eth0.IPv4 for mDNS. Apr 05 15:24:35 speaker dhcpcd[498]: eth0: adding route to 192.168.30.0/24 Apr 05 15:24:35 speaker avahi-daemon[415]: Registering new address record for 192.168.30.101 on eth0.IPv4. Apr 05 15:24:35 speaker dhcpcd[498]: eth0: adding default route via 192.168.30.100 Apr 05 15:24:35 speaker volumio-remote-updater[421]: [2024-04-05 15:24:35] [info] asio async_connect error: system:111 (Connection refused) Apr 05 15:24:35 speaker volumio-remote-updater[421]: [2024-04-05 15:24:35] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 05 15:24:35 speaker volumio-remote-updater[421]: [2024-04-05 15:24:35] [error] handle_connect error: Underlying Transport Error Apr 05 15:24:35 speaker ntpd[607]: Soliciting pool server 194.190.168.1 Apr 05 15:24:36 speaker sudo[669]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:36 speaker sudo[695]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 05 15:24:36 speaker sudo[695]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:36 speaker sudo[695]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:36 speaker sudo[697]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 05 15:24:36 speaker sudo[697]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:36 speaker sudo[697]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:36 speaker avahi-daemon[415]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 05 15:24:36 speaker avahi-daemon[415]: New relevant interface wlan0.IPv4 for mDNS. Apr 05 15:24:36 speaker avahi-daemon[415]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Apr 05 15:24:37 speaker systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Apr 05 15:24:37 speaker systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Apr 05 15:24:37 speaker dnsmasq[704]: dnsmasq: syntax check OK. Apr 05 15:24:37 speaker hostapd[703]: Configuration file: /etc/hostapd/hostapd.conf Apr 05 15:24:37 speaker avahi-daemon[415]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 05 15:24:37 speaker avahi-daemon[415]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 05 15:24:37 speaker avahi-daemon[415]: Withdrawing address record for 192.168.211.1 on wlan0. Apr 05 15:24:37 speaker avahi-daemon[415]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 05 15:24:37 speaker avahi-daemon[415]: New relevant interface wlan0.IPv4 for mDNS. Apr 05 15:24:37 speaker avahi-daemon[415]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Apr 05 15:24:37 speaker hostapd[703]: Using interface wlan0 with hwaddr 00:19:86:81:dc:2e and ssid "spSCC" Apr 05 15:24:37 speaker dnsmasq[710]: started, version 2.80 cachesize 150 Apr 05 15:24:37 speaker dnsmasq[710]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Apr 05 15:24:37 speaker dnsmasq-dhcp[710]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Apr 05 15:24:37 speaker dnsmasq[710]: reading /etc/resolv.conf Apr 05 15:24:37 speaker dnsmasq[710]: using nameserver 192.168.100.3#53 Apr 05 15:24:37 speaker dnsmasq[710]: using nameserver 8.8.8.8#53 Apr 05 15:24:37 speaker dnsmasq[710]: using nameserver 192.168.30.100#53 Apr 05 15:24:37 speaker dnsmasq[710]: using nameserver 208.67.222.222#53 Apr 05 15:24:37 speaker dnsmasq[710]: read /etc/hosts - 1 addresses Apr 05 15:24:37 speaker dhcpcd[498]: wlan0: carrier acquired Apr 05 15:24:37 speaker dhcpcd[498]: wlan0: IAID 86:81:dc:2e Apr 05 15:24:37 speaker dhcpcd[498]: wlan0: adding address fe80::219:86ff:fe81:dc2e Apr 05 15:24:37 speaker dhcpcd[498]: ipv6_addaddr1: Permission denied Apr 05 15:24:37 speaker systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Apr 05 15:24:37 speaker systemd[1]: Reached target Host and Network Name Lookups. Apr 05 15:24:37 speaker kernel: cryptd: max_cpu_qlen set to 1000 Apr 05 15:24:38 speaker systemd[1]: Started Samba NMB Daemon. Apr 05 15:24:38 speaker nmbd[599]: [2024/04/05 15:24:38.015655, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 05 15:24:38 speaker nmbd[599]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 05 15:24:38 speaker dhcpcd[498]: wlan0: soliciting a DHCP lease Apr 05 15:24:38 speaker systemd[1]: Starting Samba Winbind Daemon... Apr 05 15:24:38 speaker hostapd[703]: wlan0: interface state UNINITIALIZED->ENABLED Apr 05 15:24:38 speaker hostapd[703]: wlan0: AP-ENABLED Apr 05 15:24:38 speaker systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Apr 05 15:24:38 speaker iwconfig[746]: Error for wireless request "Set Power Management" (8B2C) : Apr 05 15:24:38 speaker iwconfig[746]: SET failed on device wlan0 ; Invalid argument. Apr 05 15:24:38 speaker systemd[1]: wireless.service: Succeeded. Apr 05 15:24:38 speaker systemd[1]: Started Wireless Services. Apr 05 15:24:38 speaker systemd[1]: Started Volumio Backend Module. Apr 05 15:24:38 speaker systemd[1]: Started Volumio Cpu Tweaker. Apr 05 15:24:38 speaker volumio-cpu-tweak[748]: Setting RT Priority for mpd Apr 05 15:24:38 speaker volumio-cpu-tweak[748]: pid 35's current scheduling policy: SCHED_OTHER Apr 05 15:24:38 speaker volumio-cpu-tweak[748]: pid 35's current scheduling priority: 0 Apr 05 15:24:38 speaker volumio-cpu-tweak[748]: Setting MPD Affinity Apr 05 15:24:38 speaker volumio-cpu-tweak[748]: pid 3's current affinity mask: f Apr 05 15:24:38 speaker volumio-cpu-tweak[748]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 05 15:24:38 speaker volumio-cpu-tweak[748]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 05 15:24:38 speaker dhcpcd[498]: wlan0: soliciting an IPv6 router Apr 05 15:24:38 speaker systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 05 15:24:38 speaker winbindd[742]: [2024/04/05 15:24:38.696387, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 05 15:24:38 speaker winbindd[742]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 05 15:24:38 speaker winbindd[742]: [2024/04/05 15:24:38.728038, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 05 15:24:38 speaker winbindd[742]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 05 15:24:38 speaker systemd[1]: Started Samba Winbind Daemon. Apr 05 15:24:38 speaker systemd[1]: Starting Samba SMB Daemon... Apr 05 15:24:39 speaker ntpd[607]: Listen normally on 3 eth0 192.168.30.101:123 Apr 05 15:24:39 speaker ntpd[607]: Listen normally on 4 wlan0 192.168.211.1:123 Apr 05 15:24:39 speaker ntpd[607]: new interface(s) found: waking up resolver Apr 05 15:24:39 speaker smbd[767]: [2024/04/05 15:24:39.709280, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 05 15:24:39 speaker smbd[767]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 05 15:24:39 speaker systemd[1]: Started Samba SMB Daemon. Apr 05 15:24:39 speaker systemd[1]: Reached target Multi-User System. Apr 05 15:24:39 speaker systemd[1]: Reached target Graphical Interface. Apr 05 15:24:39 speaker systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 05 15:24:39 speaker systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 05 15:24:39 speaker systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 05 15:24:39 speaker systemd[1]: Startup finished in 10.585s (kernel) + 20.527s (userspace) = 31.112s. Apr 05 15:24:43 speaker dhcpcd[498]: wlan0: probing for an IPv4LL address Apr 05 15:24:43 speaker volumio-remote-updater[421]: [2024-04-05 15:24:43] [connect] Successful connection Apr 05 15:24:43 speaker volumio[747]: info: ------------------------------------------- Apr 05 15:24:43 speaker volumio[747]: info: ----- Volumio3 ---- Apr 05 15:24:43 speaker volumio[747]: info: ------------------------------------------- Apr 05 15:24:43 speaker volumio[747]: info: ----- System startup ---- Apr 05 15:24:43 speaker volumio[747]: info: ------------------------------------------- Apr 05 15:24:45 speaker volumio[747]: info: MYVOLUMIO Environment detected Apr 05 15:24:46 speaker volumio[747]: info: Plugin folders cleanup Apr 05 15:24:46 speaker volumio[747]: info: Scanning into folder /volumio/app/plugins/ Apr 05 15:24:46 speaker volumio[747]: info: Scanning category audio_interface Apr 05 15:24:46 speaker volumio[747]: info: Scanning category miscellanea Apr 05 15:24:46 speaker volumio[747]: info: Scanning category music_service Apr 05 15:24:46 speaker volumio[747]: info: Scanning category plugins.json Apr 05 15:24:46 speaker volumio[747]: info: Scanning category system_controller Apr 05 15:24:46 speaker volumio[747]: info: Scanning category user_interface Apr 05 15:24:46 speaker volumio[747]: info: Scanning into folder /data/plugins/ Apr 05 15:24:46 speaker volumio[747]: info: Plugin folders cleanup completed Apr 05 15:24:46 speaker volumio[747]: info: ------------------------------------------- Apr 05 15:24:46 speaker volumio[747]: info: ----- Core plugins startup ---- Apr 05 15:24:46 speaker volumio[747]: info: ------------------------------------------- Apr 05 15:24:46 speaker volumio[747]: info: Loading plugins from folder /volumio/app/plugins/ Apr 05 15:24:46 speaker volumio[747]: info: Adding plugin upnp to MyMusic Plugins Apr 05 15:24:46 speaker volumio[747]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 05 15:24:46 speaker volumio[747]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 05 15:24:46 speaker volumio[747]: info: Loading plugins from folder /data/plugins/ Apr 05 15:24:46 speaker volumio[747]: info: Loading plugin "system"... Apr 05 15:24:46 speaker volumio[747]: info: Loading plugin "appearance"... Apr 05 15:24:47 speaker dhcpcd[498]: wlan0: using IPv4LL address 169.254.232.167 Apr 05 15:24:47 speaker dhcpcd[498]: wlan0: adding route to 169.254.0.0/16 Apr 05 15:24:47 speaker avahi-daemon[415]: Registering new address record for 169.254.232.167 on wlan0.IPv4. Apr 05 15:24:48 speaker ntpd[607]: Listen normally on 5 wlan0 169.254.232.167:123 Apr 05 15:24:48 speaker ntpd[607]: new interface(s) found: waking up resolver Apr 05 15:24:49 speaker volumio[747]: info: Loading plugin "network"... Apr 05 15:24:49 speaker volumio[747]: info: Refreshing Cached IP Addresses Apr 05 15:24:49 speaker sudo[807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 05 15:24:49 speaker sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:49 speaker volumio[747]: info: Loading plugin "services"... Apr 05 15:24:49 speaker sudo[807]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:49 speaker sudo[809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 05 15:24:49 speaker sudo[809]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:49 speaker volumio[747]: info: Loading plugin "alsa_controller"... Apr 05 15:24:49 speaker sudo[809]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:49 speaker sudo[812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 05 15:24:49 speaker sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:49 speaker sudo[812]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:49 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 05 15:24:49 speaker volumio[747]: info: Loading plugin "wizard"... Apr 05 15:24:49 speaker volumio[747]: info: Loading plugin "networkfs"... Apr 05 15:24:49 speaker volumio[747]: info: Starting Udev Watcher for removable devices Apr 05 15:24:49 speaker sudo[820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=speaker,password=volumioscc,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.100.132/speaker /mnt/NAS/music-share Apr 05 15:24:49 speaker sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:49 speaker volumio[747]: info: Ignoring mount for partition: boot Apr 05 15:24:49 speaker volumio[747]: info: Ignoring mount for partition: volumio Apr 05 15:24:49 speaker volumio[747]: info: Ignoring mount for partition: volumio_data Apr 05 15:24:49 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 05 15:24:49 speaker volumio[747]: info: Loading plugin "volumio_command_line_client"... Apr 05 15:24:49 speaker volumio[747]: info: Loading plugin "upnp"... Apr 05 15:24:49 speaker volumio[747]: info: [1712319889785] Starting Upmpd Daemon Apr 05 15:24:49 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 05 15:24:49 speaker volumio[747]: info: Loading plugin "my_music"... Apr 05 15:24:49 speaker volumio[747]: info: Loading plugin "mpd"... Apr 05 15:24:50 speaker kernel: Key type cifs.spnego registered Apr 05 15:24:50 speaker kernel: Key type cifs.idmap registered Apr 05 15:24:50 speaker kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Apr 05 15:24:50 speaker kernel: CIFS: Attempting to mount \\192.168.100.132\speaker Apr 05 15:24:50 speaker sudo[820]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:50 speaker volumio[747]: info: Loading plugin "upnp_browser"... Apr 05 15:24:51 speaker volumio[747]: info: Loading plugin "alarm-clock"... Apr 05 15:24:52 speaker volumio[747]: info: Alarm: Scheduling Playlist alarm at 8:25 Apr 05 15:24:52 speaker volumio[747]: info: Loading plugin "airplay_emulation"... Apr 05 15:24:52 speaker volumio[747]: info: Starting Shairport Sync Apr 05 15:24:52 speaker volumio[747]: info: Loading plugin "last_100"... Apr 05 15:24:52 speaker volumio[747]: info: Loading plugin "webradio"... Apr 05 15:24:52 speaker volumio[747]: info: Loading plugin "i2s_dacs"... Apr 05 15:24:52 speaker volumio[747]: info: I2S DAC not set, start Auto-detection Apr 05 15:24:52 speaker volumio[747]: info: Loading plugin "volumiodiscovery"... Apr 05 15:24:52 speaker volumio[747]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 05 15:24:52 speaker node[747]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 05 15:24:52 speaker volumio[747]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 15:24:52 speaker node[747]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 15:24:52 speaker volumio[747]: *** WARNING *** For more information see Apr 05 15:24:52 speaker node[747]: *** WARNING *** For more information see Apr 05 15:24:52 speaker volumio[747]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 05 15:24:52 speaker node[747]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 05 15:24:52 speaker volumio[747]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 15:24:52 speaker node[747]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 05 15:24:52 speaker volumio[747]: *** WARNING *** For more information see Apr 05 15:24:52 speaker node[747]: *** WARNING *** For more information see Apr 05 15:24:52 speaker volumio[747]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 05 15:24:52 speaker volumio[747]: info: Discovery: Started advertising with name: speaker Apr 05 15:24:52 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 05 15:24:52 speaker volumio[747]: info: Loading plugin "outputs"... Apr 05 15:24:52 speaker volumio[747]: info: Loading plugin "albumart"... Apr 05 15:24:52 speaker volumio[747]: info: Plugin example_plugin is not enabled Apr 05 15:24:52 speaker volumio[747]: info: Loading plugin "inputs"... Apr 05 15:24:52 speaker volumio[747]: info: Loading plugin "updater_comm"... Apr 05 15:24:53 speaker volumio[747]: info: Plugin mpdemulation is not enabled Apr 05 15:24:53 speaker volumio[747]: info: Loading plugin "rest_api"... Apr 05 15:24:53 speaker volumio[747]: info: Loading plugin "websocket"... Apr 05 15:24:53 speaker volumio[747]: info: Loading i18n strings for locale ru Apr 05 15:24:53 speaker volumio[747]: Updating browse sources language Apr 05 15:24:53 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 15:24:53 speaker volumio[747]: Forking 3 albumart workers Apr 05 15:24:54 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 15:24:54 speaker volumio[747]: info: CoreCommandRouter::initPlayerControls Apr 05 15:24:54 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 15:24:54 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 15:24:54 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 15:24:54 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 15:24:54 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 15:24:54 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 15:24:54 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 15:24:54 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 15:24:54 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 15:24:54 speaker volumio[747]: Express server listening on port 3000 Apr 05 15:24:54 speaker volumio[747]: [Metrics] WebUI: 12s 213.74ms Apr 05 15:24:54 speaker volumio[747]: info: CoreStateMachine::resetVolumioState Apr 05 15:24:54 speaker volumio[747]: info: CoreStateMachine::getcurrentVolume Apr 05 15:24:54 speaker volumio[747]: info: CoreCommandRouter::volumioRetrievevolume Apr 05 15:24:54 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 15:24:54 speaker volumio[747]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 05 15:24:54 speaker volumio[747]: wlan0 Interface doesn't support scanning : Operation not supported Apr 05 15:24:54 speaker volumio[747]: info: Cannot use regular scanning, forcing with ap-force Apr 05 15:24:54 speaker sudo[885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 05 15:24:54 speaker sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:55 speaker volumio[747]: Starting albumart workers Apr 05 15:24:55 speaker volumio[747]: Starting albumart workers Apr 05 15:24:55 speaker volumio[747]: Starting albumart workers Apr 05 15:24:55 speaker sudo[885]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 1 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 2 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 3 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 4 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 5 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 6 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 7 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 8 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 9 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 10 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 11 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 12 Apr 05 15:24:56 speaker volumio[747]: info: Reloading queue from file Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 13 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 14 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 15 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 16 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 17 Apr 05 15:24:56 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 18 Apr 05 15:24:56 speaker volumio[747]: info: VolumeController:: Volume=50 Mute =false Apr 05 15:24:56 speaker volumio[747]: info: CoreStateMachine::pushState Apr 05 15:24:57 speaker volumio[747]: info: CorePlayQueue::getTrack 0 Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::volumioPushState Apr 05 15:24:57 speaker volumio[747]: info: CoreStateMachine::updateTrackBlock Apr 05 15:24:57 speaker volumio[747]: info: CorePlayQueue::getTrackBlock Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::volumioRetrievevolume Apr 05 15:24:57 speaker volumio[747]: info: CoreStateMachine::setRepeat null single undefined Apr 05 15:24:57 speaker volumio[747]: info: CoreStateMachine::pushState Apr 05 15:24:57 speaker volumio[747]: info: CorePlayQueue::getTrack 0 Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::volumioPushState Apr 05 15:24:57 speaker volumio[747]: info: CoreStateMachine::setRandom null Apr 05 15:24:57 speaker volumio[747]: info: CoreStateMachine::pushState Apr 05 15:24:57 speaker volumio[747]: info: CorePlayQueue::getTrack 0 Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::volumioPushState Apr 05 15:24:57 speaker volumio[747]: info: Setting Device type: Raspberry PI Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 19 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 20 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 21 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 22 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 23 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 24 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.68 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 25 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 26 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 27 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 28 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 29 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 30 Apr 05 15:24:57 speaker systemd[1]: systemd-fsckd.service: Succeeded. Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 31 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 32 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 33 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 34 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 35 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 36 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 37 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 38 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 39 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 40 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 41 Apr 05 15:24:57 speaker volumio[747]: verbose: New Socket.io Connection to 192.168.30.101 from 192.168.3.55 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 42 Apr 05 15:24:57 speaker volumio[747]: info: VolumeController:: Volume=50 Mute =false Apr 05 15:24:57 speaker volumio[747]: info: CoreStateMachine::pushState Apr 05 15:24:57 speaker volumio[747]: info: CorePlayQueue::getTrack 0 Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::volumioPushState Apr 05 15:24:57 speaker volumio[747]: info: Discovery: adding 8ee36925-00c0-470b-85b4-4d0fd182fc11 Apr 05 15:24:57 speaker volumio[747]: info: Discovery: Found device speaker Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::volumioGetState Apr 05 15:24:57 speaker volumio[747]: info: CorePlayQueue::getTrack 0 Apr 05 15:24:57 speaker volumio[747]: info: Discovery: this is already registered, 8ee36925-00c0-470b-85b4-4d0fd182fc11 Apr 05 15:24:57 speaker volumio[747]: info: Discovery: Found device speaker Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::volumioGetState Apr 05 15:24:57 speaker volumio[747]: info: CorePlayQueue::getTrack 0 Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::volumioGetState Apr 05 15:24:57 speaker volumio[747]: info: CorePlayQueue::getTrack 0 Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::volumioGetVisibleSources Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 15:24:57 speaker volumio[747]: info: Listing playlists Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::volumioGetState Apr 05 15:24:57 speaker volumio[747]: info: CorePlayQueue::getTrack 0 Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::volumioGetVisibleSources Apr 05 15:24:57 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 05 15:24:58 speaker volumio-remote-updater[421]: [2024-04-05 15:24:58] [connect] Successful connection Apr 05 15:24:58 speaker volumio-remote-updater[421]: [2024-04-05 15:24:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712319898 101 Apr 05 15:24:58 speaker volumio[747]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 43 Apr 05 15:24:59 speaker sudo[921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 05 15:24:59 speaker sudo[921]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:59 speaker sudo[921]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:59 speaker sudo[923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 05 15:24:59 speaker sudo[923]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:24:59 speaker sudo[923]: pam_unix(sudo:session): session closed for user root Apr 05 15:24:59 speaker sudo[927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 05 15:24:59 speaker sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 15:25:00 speaker systemd[1]: Started UPnP Renderer front-end to MPD. Apr 05 15:25:00 speaker sudo[927]: pam_unix(sudo:session): session closed for user root Apr 05 15:25:00 speaker volumio[747]: info: Upmpdcli Daemon Started Apr 05 15:25:00 speaker volumio[747]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 05 15:25:00 speaker volumio[747]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 05 15:25:00 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 05 15:25:00 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 05 15:25:00 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 05 15:25:00 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 05 15:25:00 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 15:25:00 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 15:25:00 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 15:25:00 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 05 15:25:00 speaker volumio[747]: info: CoreCommandRouter::volumioGetBrowseSources Apr 05 15:25:00 speaker volumio[747]: info: CoreCommandRouter::volumioGetBrowseSources Apr 05 15:25:00 speaker volumio[747]: info: CoreCommandRouter::volumioGetBrowseSources Apr 05 15:25:00 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 05 15:25:00 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 05 15:25:04 speaker volumio[747]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 05 15:25:04 speaker volumio[747]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 05 15:25:04 speaker volumio[747]: verbose: ControllerMpd::sendMpdCommand update Apr 05 15:25:04 speaker volumio[747]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 15:25:04 speaker volumio[747]: TypeError: Cannot read property 'then' of undefined Apr 05 15:25:04 speaker volumio[747]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) Apr 05 15:25:04 speaker volumio[747]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1898:15) Apr 05 15:25:04 speaker volumio[747]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Apr 05 15:25:04 speaker volumio[747]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1049:26) Apr 05 15:25:04 speaker volumio[747]: at Socket.emit (events.js:315:20) Apr 05 15:25:04 speaker volumio[747]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 05 15:25:04 speaker volumio[747]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 05 15:25:04 speaker volumio[747]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 15:25:05 speaker sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-05 15:24 Apr 05 15:25:05 speaker sudo[942]: 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"