-- Logs begin at Mon 2025-01-20 09:26:48 UTC, end at Mon 2025-01-20 09:28:09 UTC. -- Jan 20 09:27:00 volumio-home dhcpcd[626]: Not running dhcpcd because there is aleady an interface specific instance ... failed! Jan 20 09:27:00 volumio-home ifplugd(eth0)[675]: ifplugd 0.28 initializing. Jan 20 09:27:00 volumio-home ifplugd(eth0)[675]: Using interface eth0/B8:27:EB:5C:6C:1C with driver (version: 1.0.6) Jan 20 09:27:00 volumio-home dhcpcd[626]: /var/run/dhcpcd-eth0.pid ... failed! Jan 20 09:27:00 volumio-home ifplugd(eth0)[675]: Using detection mode: SIOCETHTOOL Jan 20 09:27:00 volumio-home ifplugd(eth0)[675]: Initialization complete, link beat detected. Jan 20 09:27:00 volumio-home kernel: ip_tables: (C) 2000-2006 Netfilter Core Team Jan 20 09:27:00 volumio-home kernel: lirc_dev: IR Remote Control driver registered, major 244 Jan 20 09:27:00 volumio-home loadcpufreq[610]: Loading cpufreq kernel modules...done (none). Jan 20 09:27:00 volumio-home ifplugd(eth0)[675]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 20 09:27:00 volumio-home kernel: lirc_rpi: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 09:27:00 volumio-home kernel: lirc_rpi: gpio chip not found! Jan 20 09:27:00 volumio-home kernel: ------------[ cut here ]------------ Jan 20 09:27:00 volumio-home kernel: WARNING: CPU: 2 PID: 681 at kernel/irq/manage.c:1531 __free_irq+0xd0/0x31c Jan 20 09:27:00 volumio-home kernel: Trying to free already-free IRQ 0 Jan 20 09:27:00 volumio-home kernel: Modules linked in: lirc_rpi(C+) lirc_dev ip_tables x_tables nfsd snd_usb_audio snd_hwdep snd_usbmidi_lib snd_seq_midi snd_seq_midi_event brcmfmac snd_rawmidi brcmutil cfg80211 aoide_dacii(O) sabre9018k2m(O) snd_soc_bcm2835_i2s snd_soc_core snd_bcm2835(C) rfkill snd_compress snd_pcm_dmaengine snd_seq snd_pcm snd_seq_device snd_timer snd i2c_bcm2835 uio_pdrv_genirq uio fixed i2c_dev fuse ipv6 squashfs overlay Jan 20 09:27:00 volumio-home kernel: CPU: 2 PID: 681 Comm: modprobe Tainted: G C O 4.14.56-v7+ #1128 Jan 20 09:27:00 volumio-home kernel: Hardware name: BCM2835 Jan 20 09:27:00 volumio-home kernel: [<8010ffd8>] (unwind_backtrace) from [<8010c240>] (show_stack+0x20/0x24) Jan 20 09:27:00 volumio-home kernel: [<8010c240>] (show_stack) from [<80786aa4>] (dump_stack+0xd4/0x118) Jan 20 09:27:00 volumio-home kernel: [<80786aa4>] (dump_stack) from [<8011da6c>] (__warn+0xf8/0x110) Jan 20 09:27:00 volumio-home kernel: [<8011da6c>] (__warn) from [<8011dacc>] (warn_slowpath_fmt+0x48/0x50) Jan 20 09:27:00 volumio-home kernel: [<8011dacc>] (warn_slowpath_fmt) from [<80176be4>] (__free_irq+0xd0/0x31c) Jan 20 09:27:00 volumio-home kernel: [<80176be4>] (__free_irq) from [<80176ed4>] (free_irq+0x4c/0x94) Jan 20 09:27:00 volumio-home kernel: [<80176ed4>] (free_irq) from [<7f74d978>] (lirc_rpi_exit+0x40/0xbc [lirc_rpi]) Jan 20 09:27:00 volumio-home kernel: [<7f74d978>] (lirc_rpi_exit [lirc_rpi]) from [<7f752480>] (lirc_rpi_init_module+0x480/0x1000 [lirc_rpi]) Jan 20 09:27:00 volumio-home kernel: [<7f752480>] (lirc_rpi_init_module [lirc_rpi]) from [<80101bf0>] (do_one_initcall+0x50/0x17c) Jan 20 09:27:00 volumio-home kernel: [<80101bf0>] (do_one_initcall) from [<801a94d4>] (do_init_module+0x74/0x20c) Jan 20 09:27:00 volumio-home kernel: [<801a94d4>] (do_init_module) from [<801a84f8>] (load_module+0x1c28/0x23a0) Jan 20 09:27:00 volumio-home kernel: [<801a84f8>] (load_module) from [<801a8e4c>] (SyS_finit_module+0x9c/0xac) Jan 20 09:27:00 volumio-home kernel: [<801a8e4c>] (SyS_finit_module) from [<80108060>] (ret_fast_syscall+0x0/0x28) Jan 20 09:27:00 volumio-home kernel: ---[ end trace 6b9bff5c9ebbf944 ]--- Jan 20 09:27:00 volumio-home ifplugd(eth0)[675]: client: /sbin/ifup: interface eth0 already configured Jan 20 09:27:00 volumio-home lirc[611]: Loading LIRC modules:. Jan 20 09:27:00 volumio-home ifplugd(eth0)[675]: Program executed successfully. Jan 20 09:27:00 volumio-home ifplugd[621]: Network Interface Plugging Daemon...start eth0...done. Jan 20 09:27:00 volumio-home lirc[611]: Unable to load LIRC kernel modules. Verify your ... failed! Jan 20 09:27:00 volumio-home avahi-daemon[631]: Found user 'avahi' (UID 104) and group 'avahi' (GID 105). Jan 20 09:27:00 volumio-home avahi-daemon[631]: Successfully dropped root privileges. Jan 20 09:27:00 volumio-home avahi-daemon[631]: avahi-daemon 0.6.31 starting up. Jan 20 09:27:00 volumio-home lirc[611]: selected kernel modules in /etc/lirc/hardware.conf ... failed! Jan 20 09:27:00 volumio-home kernel: nf_conntrack version 0.5.0 (16384 buckets, 65536 max) Jan 20 09:27:00 volumio-home lirc[611]: Starting execution daemon: irexec: failed! Jan 20 09:27:00 volumio-home ntpd[672]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) Jan 20 09:27:00 volumio-home ntp[608]: Starting NTP server: ntpd. Jan 20 09:27:00 volumio-home ntpd[713]: proto: precision = 1.041 usec Jan 20 09:27:00 volumio-home ntpd[713]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Jan 20 09:27:00 volumio-home ntpd[713]: Listen and drop on 1 v6wildcard :: UDP 123 Jan 20 09:27:00 volumio-home ntpd[713]: Listen normally on 2 lo 127.0.0.1 UDP 123 Jan 20 09:27:00 volumio-home ntpd[713]: Listen normally on 3 eth0 192.168.123.106 UDP 123 Jan 20 09:27:00 volumio-home ntpd[713]: peers refreshed Jan 20 09:27:00 volumio-home ntpd[713]: Listening on routing socket on fd #20 for interface updates Jan 20 09:27:00 volumio-home sudo[612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh Jan 20 09:27:00 volumio-home sudo[604]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Jan 20 09:27:00 volumio-home sudo[612]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:00 volumio-home sudo[604]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:00 volumio-home sudo[604]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:00 volumio-home avahi-daemon[631]: Successfully called chroot(). Jan 20 09:27:00 volumio-home avahi-daemon[631]: Successfully dropped remaining capabilities. Jan 20 09:27:00 volumio-home dbus[634]: [system] Successfully activated service 'org.freedesktop.systemd1' Jan 20 09:27:00 volumio-home systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 20 09:27:00 volumio-home avahi-daemon[631]: Loading service file /services/volumio.service. Jan 20 09:27:00 volumio-home systemd[1]: Starting Permit User Sessions... Jan 20 09:27:00 volumio-home systemd[1]: Started Restore Sound Card State. Jan 20 09:27:00 volumio-home systemd[1]: Started /etc/rc.local Compatibility. Jan 20 09:27:00 volumio-home systemd[1]: Started LSB: Start NTP daemon. Jan 20 09:27:00 volumio-home systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 20 09:27:00 volumio-home systemd[1]: Started LSB: Starts LIRC daemon.. Jan 20 09:27:00 volumio-home systemd[1]: Started LSB: Brings up/down network automatically. Jan 20 09:27:00 volumio-home avahi-daemon[631]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.123.106. Jan 20 09:27:00 volumio-home avahi-daemon[631]: New relevant interface eth0.IPv4 for mDNS. Jan 20 09:27:00 volumio-home avahi-daemon[631]: Network interface enumeration completed. Jan 20 09:27:00 volumio-home avahi-daemon[631]: Registering new address record for 192.168.123.106 on eth0.IPv4. Jan 20 09:27:00 volumio-home dbus[634]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Jan 20 09:27:00 volumio-home avahi-daemon[631]: Registering HINFO record with values 'ARMV7L'/'LINUX'. Jan 20 09:27:00 volumio-home systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Jan 20 09:27:00 volumio-home systemd[1]: dhcpcd.service: control process exited, code=exited status=6 Jan 20 09:27:00 volumio-home systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. Jan 20 09:27:00 volumio-home systemd[1]: Unit dhcpcd.service entered failed state. Jan 20 09:27:00 volumio-home systemd[1]: Started Permit User Sessions. Jan 20 09:27:01 volumio-home volumio-remote-updater[599]: Error: No active session Jan 20 09:27:01 volumio-home volumio-remote-updater[599]: [2025-01-20 09:27:01] [info] asio async_connect error: system:111 (Connection refused) Jan 20 09:27:01 volumio-home volumio-remote-updater[599]: [2025-01-20 09:27:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 20 09:27:01 volumio-home volumio-remote-updater[599]: [2025-01-20 09:27:01] [error] handle_connect error: Underlying Transport Error Jan 20 09:27:01 volumio-home systemd-logind[605]: New seat seat0. Jan 20 09:27:01 volumio-home volumio[598]: Could not open config: /tmp/upmpdcli.conf Jan 20 09:27:01 volumio-home systemd[1]: Started Login Service. Jan 20 09:27:01 volumio-home systemd[1]: Starting Disk Manager (legacy version)... Jan 20 09:27:01 volumio-home systemd[1]: Starting OpenBSD Secure Shell server... Jan 20 09:27:01 volumio-home systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 20 09:27:01 volumio-home systemd[1]: Starting Hold until boot process finishes up... Jan 20 09:27:01 volumio-home systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 20 09:27:01 volumio-home systemd[1]: Received SIGRTMIN+21 from PID 229 (plymouthd). Jan 20 09:27:01 volumio-home systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Jan 20 09:27:01 volumio-home systemd[1]: Unit upmpdcli.service entered failed state. Jan 20 09:27:01 volumio-home systemd[1]: Started Hold until boot process finishes up. Jan 20 09:27:01 volumio-home systemd[1]: Started Terminate Plymouth Boot Screen. Jan 20 09:27:01 volumio-home systemd[1]: Starting Getty on tty1... Jan 20 09:27:01 volumio-home systemd[1]: Started Getty on tty1. Jan 20 09:27:01 volumio-home systemd[1]: Starting Login Prompts. Jan 20 09:27:01 volumio-home systemd[1]: Reached target Login Prompts. Jan 20 09:27:01 volumio-home dbus[634]: [system] Successfully activated service 'org.freedesktop.UDisks' Jan 20 09:27:01 volumio-home systemd[1]: Started Disk Manager (legacy version). Jan 20 09:27:01 volumio-home cpufrequtils[739]: CPUFreq Utilities: Setting performance CPUFreq governor...error: unexpectedly disconnected from boot status daemon Jan 20 09:27:01 volumio-home cpufrequtils[739]: CPU0...CPU1...CPU2...CPU3...done. Jan 20 09:27:01 volumio-home systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 20 09:27:01 volumio-home systemd[1]: Started OpenBSD Secure Shell server. Jan 20 09:27:01 volumio-home sudo[612]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:01 volumio-home sshd[763]: Server listening on 0.0.0.0 port 22. Jan 20 09:27:01 volumio-home sshd[763]: Server listening on :: port 22. Jan 20 09:27:01 volumio-home dbus[634]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Jan 20 09:27:01 volumio-home avahi-daemon[631]: Server startup complete. Host name is volumio-home.local. Local service cookie is 2400737933. Jan 20 09:27:01 volumio-home systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Jan 20 09:27:01 volumio-home polkitd[769]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 20 09:27:01 volumio-home dbus[634]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 20 09:27:01 volumio-home systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Jan 20 09:27:02 volumio-home udisks-glue[597]: Device file /dev/mmcblk0 inserted Jan 20 09:27:02 volumio-home udisks-glue[597]: Device file /dev/mmcblk0p3 inserted Jan 20 09:27:02 volumio-home avahi-daemon[631]: Service "Volumio-Home" (/services/volumio.service) successfully established. Jan 20 09:27:02 volumio-home haveged[595]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K Jan 20 09:27:02 volumio-home haveged[595]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 Jan 20 09:27:02 volumio-home haveged[595]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00076 Jan 20 09:27:02 volumio-home haveged[595]: haveged: fills: 0, generated: 0 Jan 20 09:27:03 volumio-home kernel: Under-voltage detected! (0x00050005) Jan 20 09:27:05 volumio-home systemd[1]: Started Music Player Daemon. Jan 20 09:27:05 volumio-home wireless.js[731]: WIRELESS DAEMON: start Jan 20 09:27:05 volumio-home wireless.js[731]: WIRELESS: Loaded configuration Jan 20 09:27:05 volumio-home wireless.js[731]: Cleaning previous... Jan 20 09:27:05 volumio-home systemd[1]: Stopped hotspot.service. Jan 20 09:27:05 volumio-home sudo[791]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 20 09:27:05 volumio-home sudo[791]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:05 volumio-home sudo[791]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:05 volumio-home sudo[799]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 20 09:27:05 volumio-home sudo[799]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:06 volumio-home volumio-remote-updater[599]: [2025-01-20 09:27:06] [info] asio async_connect error: system:111 (Connection refused) Jan 20 09:27:06 volumio-home volumio-remote-updater[599]: [2025-01-20 09:27:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 20 09:27:06 volumio-home volumio-remote-updater[599]: [2025-01-20 09:27:06] [error] handle_connect error: Underlying Transport Error Jan 20 09:27:06 volumio-home sudo[799]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:06 volumio-home systemd[1]: Started LSB: start Samba daemons for the AD DC. Jan 20 09:27:06 volumio-home wireless.js[731]: Stopped aP Jan 20 09:27:06 volumio-home wireless.js[731]: Start wireless flow Jan 20 09:27:06 volumio-home wireless.js[731]: Stopped hotspot (if there).. Jan 20 09:27:06 volumio-home sudo[820]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 20 09:27:06 volumio-home sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:06 volumio-home sudo[820]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:06 volumio-home sudo[829]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 20 09:27:06 volumio-home sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:06 volumio-home sudo[829]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:06 volumio-home wireless.js[731]: DHCP IP Jan 20 09:27:06 volumio-home wireless.js[731]: Start ap Jan 20 09:27:06 volumio-home sudo[842]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 20 09:27:06 volumio-home sudo[842]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:06 volumio-home dhcpcd[846]: version 6.0.5 starting Jan 20 09:27:06 volumio-home kernel: brcmfmac: power management disabled Jan 20 09:27:06 volumio-home winbind[606]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Jan 20 09:27:06 volumio-home winbind[606]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jan 20 09:27:06 volumio-home winbind[606]: Unable to setup corepath for winbindd: No such file or directory Jan 20 09:27:06 volumio-home winbind[606]: mkdir failed on directory /var/log/samba/cores: No such file or directory Jan 20 09:27:06 volumio-home winbind[606]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jan 20 09:27:06 volumio-home winbind[606]: Unable to setup corepath for winbindd: No such file or directory Jan 20 09:27:06 volumio-home winbind[606]: [2025/01/20 09:27:06, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:06 volumio-home winbind[606]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jan 20 09:27:06 volumio-home winbind[606]: [2025/01/20 09:27:06, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:06 volumio-home winbind[606]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jan 20 09:27:06 volumio-home winbind[606]: [2025/01/20 09:27:06, 0] ../source3/winbindd/winbindd.c:1549(main) Jan 20 09:27:06 volumio-home winbind[606]: winbindd version 4.2.14-Debian started. Jan 20 09:27:06 volumio-home winbind[606]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Jan 20 09:27:06 volumio-home winbind[606]: [2025/01/20 09:27:06, 0] ../lib/util/util.c:220(directory_create_or_exist) Jan 20 09:27:06 volumio-home winbind[606]: mkdir failed on directory /var/log/samba/cores: No such file or directory Jan 20 09:27:06 volumio-home wpa_supplicant[838]: Successfully initialized wpa_supplicant Jan 20 09:27:06 volumio-home winbindd[821]: [2025/01/20 09:27:06.673185, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:06 volumio-home winbindd[821]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jan 20 09:27:06 volumio-home winbindd[821]: [2025/01/20 09:27:06.675265, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:06 volumio-home winbindd[821]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jan 20 09:27:06 volumio-home winbind[606]: [2025/01/20 09:27:06, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Jan 20 09:27:06 volumio-home winbind[606]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jan 20 09:27:06 volumio-home winbind[606]: [2025/01/20 09:27:06, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Jan 20 09:27:06 volumio-home winbind[606]: Unable to setup corepath for winbindd: No such file or directory Jan 20 09:27:06 volumio-home winbind[606]: [2025/01/20 09:27:06.673185, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:06 volumio-home winbind[606]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jan 20 09:27:06 volumio-home winbind[606]: [2025/01/20 09:27:06.675265, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:06 volumio-home winbind[606]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jan 20 09:27:06 volumio-home winbind[606]: . Jan 20 09:27:06 volumio-home systemd[1]: Started LSB: start Winbind daemon. Jan 20 09:27:06 volumio-home winbindd[862]: [2025/01/20 09:27:06.707525, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Jan 20 09:27:06 volumio-home winbindd[862]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 20 09:27:06 volumio-home nmbd[609]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Jan 20 09:27:06 volumio-home nmbd[609]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jan 20 09:27:06 volumio-home nmbd[609]: Unable to setup corepath for nmbd: No such file or directory Jan 20 09:27:06 volumio-home nmbd[609]: [2025/01/20 09:27:06, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:06 volumio-home nmbd[609]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jan 20 09:27:06 volumio-home nmbd[841]: [2025/01/20 09:27:06.738940, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:06 volumio-home nmbd[841]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jan 20 09:27:06 volumio-home nmbd[841]: [2025/01/20 09:27:06.742137, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:06 volumio-home nmbd[841]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jan 20 09:27:06 volumio-home nmbd[870]: [2025/01/20 09:27:06.748052, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Jan 20 09:27:06 volumio-home nmbd[870]: started asyncdns process 871 Jan 20 09:27:06 volumio-home systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Jan 20 09:27:06 volumio-home systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Jan 20 09:27:06 volumio-home winbindd[862]: [2025/01/20 09:27:06.760309, 0] ../lib/util/become_daemon.c:124(daemon_ready) Jan 20 09:27:06 volumio-home winbindd[862]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 20 09:27:06 volumio-home nmbd[870]: [2025/01/20 09:27:06.769434, 0] ../lib/util/become_daemon.c:124(daemon_ready) Jan 20 09:27:06 volumio-home nmbd[870]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 20 09:27:06 volumio-home nmbd[609]: [2025/01/20 09:27:06, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:06 volumio-home nmbd[609]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jan 20 09:27:06 volumio-home nmbd[609]: [2025/01/20 09:27:06, 0] ../source3/nmbd/nmbd.c:908(main) Jan 20 09:27:06 volumio-home nmbd[609]: nmbd version 4.2.14-Debian started. Jan 20 09:27:06 volumio-home nmbd[609]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Jan 20 09:27:06 volumio-home nmbd[609]: [2025/01/20 09:27:06.738940, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:06 volumio-home nmbd[609]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jan 20 09:27:06 volumio-home nmbd[609]: [2025/01/20 09:27:06.742137, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:06 volumio-home nmbd[609]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jan 20 09:27:06 volumio-home winbindd[873]: [2025/01/20 09:27:06.779267, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:06 volumio-home winbindd[873]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO-HOME': No such file or directory Jan 20 09:27:06 volumio-home nmbd[609]: . Jan 20 09:27:06 volumio-home dhcpcd[846]: eth0: soliciting an IPv6 router Jan 20 09:27:06 volumio-home dhcpcd[846]: eth0: rebinding lease of 192.168.123.106 Jan 20 09:27:06 volumio-home dhcpcd[846]: wlan0: waiting for carrier Jan 20 09:27:06 volumio-home dhcpcd[846]: eth0: leased 192.168.123.106 for 21600 seconds Jan 20 09:27:06 volumio-home dhcpcd[507]: eth0: removing route to 192.168.123.0/24 Jan 20 09:27:06 volumio-home dhcpcd[846]: eth0: adding host route to 192.168.123.106 via 127.0.0.1 Jan 20 09:27:06 volumio-home dhcpcd[846]: eth0: adding route to 192.168.123.0/24 Jan 20 09:27:06 volumio-home dhcpcd[846]: eth0: adding default route via 192.168.123.1 Jan 20 09:27:06 volumio-home dhcpcd[507]: eth0: removing default route via 192.168.123.1 Jan 20 09:27:07 volumio-home dhcpcd[846]: forked to background, child pid 907 Jan 20 09:27:07 volumio-home sudo[842]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:07 volumio-home dhcpcd[907]: eth0: removing route to 192.168.123.0/24 Jan 20 09:27:07 volumio-home dhcpcd[907]: eth0: removing default route via 192.168.123.1 Jan 20 09:27:07 volumio-home wireless.js[731]: trying... Jan 20 09:27:07 volumio-home sudo[915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 09:27:07 volumio-home sudo[915]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:07 volumio-home sudo[915]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:08 volumio-home smbd[872]: Starting SMB/CIFS daemon: smbd[2025/01/20 09:27:08, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:08 volumio-home smbd[872]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 20 09:27:08 volumio-home smbd[872]: [2025/01/20 09:27:08, 0] ../lib/util/util.c:220(directory_create_or_exist) Jan 20 09:27:08 volumio-home smbd[872]: mkdir failed on directory /var/log/samba/cores: No such file or directory Jan 20 09:27:08 volumio-home smbd[872]: [2025/01/20 09:27:08, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Jan 20 09:27:08 volumio-home smbd[872]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jan 20 09:27:08 volumio-home smbd[872]: [2025/01/20 09:27:08, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Jan 20 09:27:08 volumio-home smbd[872]: Unable to setup corepath for smbd: No such file or directory Jan 20 09:27:08 volumio-home smbd[872]: [2025/01/20 09:27:08, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:08 volumio-home smbd[872]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 20 09:27:08 volumio-home smbd[872]: [2025/01/20 09:27:08, 0] ../source3/smbd/server.c:1241(main) Jan 20 09:27:08 volumio-home smbd[872]: smbd version 4.2.14-Debian started. Jan 20 09:27:08 volumio-home smbd[872]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Jan 20 09:27:08 volumio-home smbd[924]: [2025/01/20 09:27:08.388765, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:08 volumio-home smbd[924]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 20 09:27:08 volumio-home smbd[924]: [2025/01/20 09:27:08.391272, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:08 volumio-home smbd[924]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 20 09:27:08 volumio-home smbd[924]: [2025/01/20 09:27:08.391543, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:08 volumio-home smbd[924]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 20 09:27:08 volumio-home smbd[872]: [2025/01/20 09:27:08.388765, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:08 volumio-home smbd[872]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 20 09:27:08 volumio-home smbd[872]: [2025/01/20 09:27:08.391272, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:08 volumio-home smbd[872]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 20 09:27:08 volumio-home smbd[872]: [2025/01/20 09:27:08.391543, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:08 volumio-home smbd[872]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 20 09:27:08 volumio-home smbd[872]: . Jan 20 09:27:08 volumio-home systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Jan 20 09:27:08 volumio-home winbindd[928]: [2025/01/20 09:27:08.472767, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:08 volumio-home winbindd[928]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Jan 20 09:27:08 volumio-home winbindd[929]: [2025/01/20 09:27:08.592018, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:08 volumio-home winbindd[929]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Jan 20 09:27:08 volumio-home smbd[925]: [2025/01/20 09:27:08.646572, 0] ../lib/util/become_daemon.c:124(daemon_ready) Jan 20 09:27:08 volumio-home smbd[925]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 20 09:27:08 volumio-home wireless.js[731]: trying... Jan 20 09:27:08 volumio-home smbd[931]: [2025/01/20 09:27:08.654267, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 20 09:27:08 volumio-home smbd[931]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 20 09:27:08 volumio-home sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 09:27:08 volumio-home sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:08 volumio-home sudo[932]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:09 volumio-home wireless.js[731]: trying... Jan 20 09:27:09 volumio-home sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 09:27:09 volumio-home sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:09 volumio-home sudo[942]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:10 volumio-home wpa_supplicant[911]: wlan0: Trying to associate with 80:ca:4b:37:a4:2f (SSID='U+NetA42C_5G' freq=5745 MHz) Jan 20 09:27:10 volumio-home wpa_supplicant[911]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jan 20 09:27:10 volumio-home wireless.js[731]: trying... Jan 20 09:27:10 volumio-home sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 09:27:10 volumio-home sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:10 volumio-home sudo[951]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:11 volumio-home wireless.js[731]: trying... Jan 20 09:27:11 volumio-home sudo[960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 09:27:11 volumio-home sudo[960]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:11 volumio-home sudo[960]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:12 volumio-home wireless.js[731]: trying... Jan 20 09:27:12 volumio-home sudo[969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 09:27:12 volumio-home sudo[969]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:13 volumio-home sudo[969]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:13 volumio-home wpa_supplicant[911]: wlan0: Trying to associate with 80:ca:4b:37:a4:2f (SSID='U+NetA42C_5G' freq=5745 MHz) Jan 20 09:27:13 volumio-home wpa_supplicant[911]: wlan0: Associated with 80:ca:4b:37:a4:2f Jan 20 09:27:13 volumio-home wpa_supplicant[911]: wlan0: WPA: Key negotiation completed with 80:ca:4b:37:a4:2f [PTK=CCMP GTK=CCMP] Jan 20 09:27:13 volumio-home wpa_supplicant[911]: wlan0: CTRL-EVENT-CONNECTED - Connection to 80:ca:4b:37:a4:2f completed [id=0 id_str=] Jan 20 09:27:13 volumio-home dhcpcd[907]: wlan0: carrier acquired Jan 20 09:27:13 volumio-home wpa_supplicant[911]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=KR Jan 20 09:27:13 volumio-home dhcpcd[907]: wlan0: soliciting an IPv6 router Jan 20 09:27:13 volumio-home dhcpcd[907]: wlan0: using static address 192.168.123.107 Jan 20 09:27:13 volumio-home avahi-daemon[631]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.123.107. Jan 20 09:27:13 volumio-home avahi-daemon[631]: New relevant interface wlan0.IPv4 for mDNS. Jan 20 09:27:13 volumio-home avahi-daemon[631]: Registering new address record for 192.168.123.107 on wlan0.IPv4. Jan 20 09:27:13 volumio-home dhcpcd[907]: eth0: adding route to 192.168.123.0/24 Jan 20 09:27:13 volumio-home dhcpcd[907]: eth0: adding default route via 192.168.123.1 Jan 20 09:27:13 volumio-home dhcpcd[907]: wlan0: router 192.168.1.1 requires a host route Jan 20 09:27:13 volumio-home dhcpcd[907]: wlan0: adding host route to 192.168.123.107 via 127.0.0.1 Jan 20 09:27:13 volumio-home dhcpcd[907]: wlan0: adding route to 192.168.123.0/24 Jan 20 09:27:13 volumio-home dhcpcd[907]: wlan0: adding host route to 192.168.1.1 Jan 20 09:27:13 volumio-home dhcpcd[907]: wlan0: adding default route via 192.168.1.1 Jan 20 09:27:13 volumio-home volumio-remote-updater[599]: [2025-01-20 09:27:13] [info] asio async_connect error: system:111 (Connection refused) Jan 20 09:27:13 volumio-home volumio-remote-updater[599]: [2025-01-20 09:27:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 20 09:27:13 volumio-home volumio-remote-updater[599]: [2025-01-20 09:27:13] [error] handle_connect error: Underlying Transport Error Jan 20 09:27:32 volumio-home systemd[1]: Time has been changed Jan 20 09:27:32 volumio-home dhcpcd[907]: eth0: removing route to 192.168.123.0/24 Jan 20 09:27:32 volumio-home dhcpcd[907]: eth0: removing default route via 192.168.123.1 Jan 20 09:27:32 volumio-home wireless.js[731]: trying... Jan 20 09:27:32 volumio-home sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 20 09:27:32 volumio-home sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:32 volumio-home sudo[1016]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:32 volumio-home wireless.js[731]: Connected to: ----U+NetA42C_5G Jan 20 09:27:32 volumio-home wireless.js[731]: ---- Jan 20 09:27:32 volumio-home wireless.js[731]: ... joined AP, wlan0 IPv4 is 192.168.123.107, ipV6 is undefined Jan 20 09:27:32 volumio-home wireless.js[731]: It's done! AP Jan 20 09:27:32 volumio-home systemd[1]: Started Wireless Services. Jan 20 09:27:32 volumio-home systemd[1]: Starting Volumio Backend Module... Jan 20 09:27:32 volumio-home systemd[1]: Started Volumio Backend Module. Jan 20 09:27:32 volumio-home systemd[1]: Starting Multi-User System. Jan 20 09:27:32 volumio-home systemd[1]: Reached target Multi-User System. Jan 20 09:27:32 volumio-home systemd[1]: Starting Graphical Interface. Jan 20 09:27:32 volumio-home systemd[1]: Reached target Graphical Interface. Jan 20 09:27:32 volumio-home systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 20 09:27:32 volumio-home systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 20 09:27:32 volumio-home systemd[1]: Startup finished in 9.154s (kernel) + 26.866s (userspace) = 36.021s. Jan 20 09:27:33 volumio-home ntpd[713]: Listen normally on 4 wlan0 192.168.123.107 UDP 123 Jan 20 09:27:33 volumio-home ntpd[713]: peers refreshed Jan 20 09:27:36 volumio-home volumio[1027]: info: ------------------------------------------- Jan 20 09:27:36 volumio-home volumio[1027]: info: ----- Volumio2 ---- Jan 20 09:27:36 volumio-home volumio[1027]: info: ------------------------------------------- Jan 20 09:27:36 volumio-home volumio[1027]: info: ----- System startup ---- Jan 20 09:27:36 volumio-home volumio[1027]: info: ------------------------------------------- Jan 20 09:27:36 volumio-home volumio[1027]: info: Plugin folders cleanup Jan 20 09:27:36 volumio-home volumio[1027]: info: Scanning into folder /volumio/app/plugins/ Jan 20 09:27:36 volumio-home volumio[1027]: info: Scanning category audio_interface Jan 20 09:27:36 volumio-home volumio[1027]: info: Scanning category miscellanea Jan 20 09:27:36 volumio-home volumio[1027]: info: Scanning category music_service Jan 20 09:27:36 volumio-home volumio[1027]: info: Scanning category plugins.json Jan 20 09:27:36 volumio-home volumio[1027]: info: Scanning category system_controller Jan 20 09:27:36 volumio-home volumio[1027]: info: Scanning category user_interface Jan 20 09:27:36 volumio-home volumio[1027]: info: Scanning into folder /data/plugins/ Jan 20 09:27:36 volumio-home volumio[1027]: info: Scanning category miscellanea Jan 20 09:27:36 volumio-home volumio[1027]: info: Scanning category music_service Jan 20 09:27:36 volumio-home volumio[1027]: info: Plugin folders cleanup completed Jan 20 09:27:36 volumio-home volumio[1027]: info: ------------------------------------------- Jan 20 09:27:36 volumio-home volumio[1027]: info: ----- Core plugins startup ---- Jan 20 09:27:36 volumio-home volumio[1027]: info: ------------------------------------------- Jan 20 09:27:36 volumio-home volumio[1027]: info: Loading plugins from folder /volumio/app/plugins/ Jan 20 09:27:36 volumio-home volumio[1027]: info: Loading plugins from folder /data/plugins/ Jan 20 09:27:36 volumio-home volumio[1027]: info: Loading plugin "system"... Jan 20 09:27:37 volumio-home volumio[1027]: info: Loading plugin "appearance"... Jan 20 09:27:38 volumio-home volumio[1027]: info: Loading plugin "network"... Jan 20 09:27:38 volumio-home kernel: Voltage normalised (0x00000000) Jan 20 09:27:38 volumio-home volumio[1027]: info: Loading plugin "services"... Jan 20 09:27:38 volumio-home volumio[1027]: info: Loading plugin "alsa_controller"... Jan 20 09:27:38 volumio-home volumio[1027]: info: Loading plugin "wizard"... Jan 20 09:27:38 volumio-home volumio[1027]: info: Loading plugin "volumio_command_line_client"... Jan 20 09:27:38 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:38 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:38 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:38 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:38 volumio-home volumio[1027]: info: Loading plugin "upnp"... Jan 20 09:27:38 volumio-home volumio[1027]: info: [1737365258723] Starting Upmpd Daemon Jan 20 09:27:38 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 09:27:38 volumio-home volumio[1027]: info: Loading plugin "my_music"... Jan 20 09:27:38 volumio-home volumio[1027]: info: Loading plugin "mpd"... Jan 20 09:27:38 volumio-home volumio[1027]: info: Loading plugin "upnp_browser"... Jan 20 09:27:40 volumio-home volumio[1027]: info: Loading plugin "networkfs"... Jan 20 09:27:40 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 09:27:40 volumio-home volumio[1027]: info: Loading plugin "alarm-clock"... Jan 20 09:27:40 volumio-home kernel: Under-voltage detected! (0x00050005) Jan 20 09:27:40 volumio-home volumio[1027]: info: Loading plugin "airplay_emulation"... Jan 20 09:27:40 volumio-home volumio[1027]: info: [1737365260816] Starting Shairport Sync Jan 20 09:27:40 volumio-home volumio[1027]: info: Loading plugin "last_100"... Jan 20 09:27:40 volumio-home volumio[1027]: info: Loading plugin "webradio"... Jan 20 09:27:40 volumio-home volumio[1027]: info: Loading plugin "i2s_dacs"... Jan 20 09:27:40 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 09:27:40 volumio-home volumio[1027]: info: Loading plugin "volumiodiscovery"... Jan 20 09:27:41 volumio-home volumio[1027]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 20 09:27:41 volumio-home node[1027]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 20 09:27:41 volumio-home node[1027]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 09:27:41 volumio-home node[1027]: *** WARNING *** For more information see Jan 20 09:27:41 volumio-home node[1027]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 20 09:27:41 volumio-home node[1027]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 09:27:41 volumio-home node[1027]: *** WARNING *** For more information see Jan 20 09:27:41 volumio-home volumio[1027]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 09:27:41 volumio-home volumio[1027]: *** WARNING *** For more information see Jan 20 09:27:41 volumio-home volumio[1027]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 20 09:27:41 volumio-home volumio[1027]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 09:27:41 volumio-home volumio[1027]: *** WARNING *** For more information see Jan 20 09:27:41 volumio-home volumio[1027]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 20 09:27:41 volumio-home volumio[1027]: Discovery: StartAdv! undefined Jan 20 09:27:41 volumio-home volumio[1027]: Discovery: Started advertising... Volumio-home - undefined Jan 20 09:27:41 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 09:27:41 volumio-home volumio[1027]: info: Loading plugin "autostart"... Jan 20 09:27:41 volumio-home volumio[1027]: info: Applying required configuration parameters for plugin autostart Jan 20 09:27:41 volumio-home volumio[1027]: info: Loading plugin "youtube"... Jan 20 09:27:42 volumio-home volumio-remote-updater[599]: [2025-01-20 09:27:42] [connect] Successful connection Jan 20 09:27:44 volumio-home volumio[1027]: info: Loading plugin "albumart"... Jan 20 09:27:44 volumio-home volumio[1027]: info: Plugin example_plugin is not enabled Jan 20 09:27:44 volumio-home volumio[1027]: info: Loading plugin "updater_comm"... Jan 20 09:27:44 volumio-home volumio[1027]: info: Plugin mpdemulation is not enabled Jan 20 09:27:44 volumio-home volumio[1027]: info: Loading plugin "rest_api"... Jan 20 09:27:44 volumio-home volumio[1027]: info: Loading plugin "websocket"... Jan 20 09:27:44 volumio-home volumio[1027]: info: Loading plugin "lastfm"... Jan 20 09:27:44 volumio-home volumio[1027]: Forking 3 albumart workers Jan 20 09:27:46 volumio-home volumio[1027]: Starting albumart workers Jan 20 09:27:46 volumio-home volumio[1027]: Starting albumart workers Jan 20 09:27:46 volumio-home volumio[1027]: Starting albumart workers Jan 20 09:27:46 volumio-home volumio[1027]: info: [LastFM] scrobbler initiated! Jan 20 09:27:46 volumio-home volumio[1027]: info: [LastFM] extended logging: false Jan 20 09:27:46 volumio-home volumio[1027]: info: [LastFM] try scrobble stream/radio plays: true Jan 20 09:27:46 volumio-home volumio[1027]: info: Loading plugin "minidlna"... Jan 20 09:27:47 volumio-home volumio[1027]: info: Applying required configuration parameters for plugin minidlna Jan 20 09:27:47 volumio-home volumio[1027]: info: Plugin 80s80s is not enabled Jan 20 09:27:47 volumio-home volumio[1027]: info: Loading plugin "personal_radio"... Jan 20 09:27:49 volumio-home volumio[1027]: info: ControllerPersonalRadio::constructor Jan 20 09:27:49 volumio-home volumio[1027]: info: Loading plugin "spop"... Jan 20 09:27:51 volumio-home volumio[1027]: info: ___________ START PLUGINS ___________ Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 09:27:51 volumio-home volumio[1027]: info: [1737365271253] CoreMusicLibrary::Adding element Media Servers Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 09:27:51 volumio-home volumio[1027]: info: [1737365271283] CoreMusicLibrary::Adding element Last_100 Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 09:27:51 volumio-home volumio[1027]: info: [1737365271288] CoreMusicLibrary::Adding element Webradio Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 09:27:51 volumio-home volumio[1027]: info: Youtube::onStart Adding to browse sources Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 09:27:51 volumio-home volumio[1027]: info: [1737365271297] CoreMusicLibrary::Adding element Youtube Jan 20 09:27:51 volumio-home volumio[1027]: info: Performing onStart action Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 09:27:51 volumio-home volumio[1027]: info: [1737365271304] CoreMusicLibrary::Adding element LastFM Jan 20 09:27:51 volumio-home volumio[1027]: info: Loading i18n strings for locale ko Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 09:27:51 volumio-home volumio[1027]: info: [1737365271391] CoreMusicLibrary::Adding element 개인 라디오 Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 09:27:51 volumio-home volumio[1027]: [SpotifyConnect] Creating VLS config file Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:51 volumio-home sudo[1077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jan 20 09:27:51 volumio-home volumio[1027]: [SpotifyConnect] Starting metadata listener Jan 20 09:27:51 volumio-home sudo[1077]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:51 volumio-home sudo[1077]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:51 volumio-home volumio[1027]: info: ------------------------------------------- Jan 20 09:27:51 volumio-home volumio[1027]: info: ----- MyVolumio plugins startup ---- Jan 20 09:27:51 volumio-home volumio[1027]: info: ------------------------------------------- Jan 20 09:27:51 volumio-home volumio[1027]: info: Loading plugins from folder /myvolumio/plugins Jan 20 09:27:51 volumio-home volumio[1027]: info: Loading plugins from folder /data/myvolumio/plugins Jan 20 09:27:51 volumio-home volumio[1027]: info: Loading i18n strings for locale ko Jan 20 09:27:51 volumio-home volumio[1027]: Updating browse sources language Jan 20 09:27:51 volumio-home volumio[1027]: Cannot find translation for sourceMedia Servers Jan 20 09:27:51 volumio-home volumio[1027]: Cannot find translation for sourceYoutube Jan 20 09:27:51 volumio-home volumio[1027]: Cannot find translation for sourceLastFM Jan 20 09:27:51 volumio-home volumio[1027]: Cannot find translation for source개인 라디오 Jan 20 09:27:51 volumio-home sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jan 20 09:27:51 volumio-home sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 09:27:51 volumio-home systemd[1]: Starting Volspotconnect2 Daemon... Jan 20 09:27:51 volumio-home systemd[1]: Started Volspotconnect2 Daemon. Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::initPlayerControls Jan 20 09:27:51 volumio-home sudo[1086]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 09:27:51 volumio-home volumio[1027]: info: BOOT COMPLETED Jan 20 09:27:51 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 09:27:51 volumio-home volumio[1091]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Jan 20 09:27:51 volumio-home volumio[1091]: Reading Config from "/tmp/volspotify.toml" Jan 20 09:27:51 volumio-home volumio[1091]: Malformed config key: missing field `Authentication` Jan 20 09:27:53 volumio-home systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Jan 20 09:27:53 volumio-home systemd[1]: Stopping Volspotconnect2 Daemon... Jan 20 09:27:53 volumio-home systemd[1]: Starting Volspotconnect2 Daemon... Jan 20 09:27:53 volumio-home systemd[1]: Started Volspotconnect2 Daemon. Jan 20 09:27:53 volumio-home volumio[1101]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Jan 20 09:27:53 volumio-home volumio[1101]: Reading Config from "/tmp/volspotify.toml" Jan 20 09:27:53 volumio-home volumio[1101]: Malformed config key: missing field `Authentication` Jan 20 09:27:56 volumio-home systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Jan 20 09:27:56 volumio-home systemd[1]: Stopping Volspotconnect2 Daemon... Jan 20 09:27:56 volumio-home systemd[1]: Starting Volspotconnect2 Daemon... Jan 20 09:27:56 volumio-home systemd[1]: Started Volspotconnect2 Daemon. Jan 20 09:27:56 volumio-home volumio[1104]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Jan 20 09:27:56 volumio-home volumio[1104]: Reading Config from "/tmp/volspotify.toml" Jan 20 09:27:56 volumio-home volumio[1104]: Malformed config key: missing field `Authentication` Jan 20 09:27:57 volumio-home volumio[1027]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jan 20 09:27:57 volumio-home volumio[1027]: info: CoreCommandRouter::Close All Modals sent Jan 20 09:27:57 volumio-home volumio[1027]: info: CoreCommandRouter::Close All Modals sent Jan 20 09:27:57 volumio-home volumio[1027]: Express server listening on port 3000 Jan 20 09:27:57 volumio-home volumio[1027]: Volumio Calling Home Jan 20 09:27:57 volumio-home volumio[1027]: info: Starting minidlna.service Jan 20 09:27:57 volumio-home volumio[1027]: info: CoreStateMachine::resetVolumioState Jan 20 09:27:57 volumio-home volumio[1027]: info: CoreStateMachine::getcurrentVolume Jan 20 09:27:57 volumio-home volumio[1027]: info: CoreCommandRouter::volumioRetrievevolume Jan 20 09:27:57 volumio-home sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jan 20 09:27:57 volumio-home sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:57 volumio-home volumio[1027]: info: CoreStateMachine::updateTrackBlock Jan 20 09:27:57 volumio-home volumio[1027]: info: CorePlayQueue::getTrackBlock Jan 20 09:27:57 volumio-home volumio-remote-updater[599]: [2025-01-20 09:27:57] [connect] Successful connection Jan 20 09:27:57 volumio-home systemd[1]: Starting MiniDLNA UPnP-A/V and DLNA media server... Jan 20 09:27:57 volumio-home systemd[1]: Started MiniDLNA UPnP-A/V and DLNA media server. Jan 20 09:27:57 volumio-home sudo[1115]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:57 volumio-home volumio[1027]: info: Setting Device type: Raspberry PI Jan 20 09:27:57 volumio-home volumio[1027]: [SpotifyConnect] Vollibrespot Daemon service started! Jan 20 09:27:57 volumio-home volumio[1027]: SpotifyConnect: 6174.273ms Jan 20 09:27:57 volumio-home volumio[1027]: info: MPD running with PID633 ,establishing connection Jan 20 09:27:57 volumio-home volumio-remote-updater[599]: [2025-01-20 09:27:57] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1737365277 101 Jan 20 09:27:57 volumio-home volumio[1027]: info: VolumeController:: Volume=40 Mute =false Jan 20 09:27:57 volumio-home volumio[1027]: info: CoreStateMachine::pushState Jan 20 09:27:57 volumio-home volumio[1027]: info: CoreStateMachine::getState Jan 20 09:27:57 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 20 09:27:57 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 20 09:27:57 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 09:27:57 volumio-home volumio[1027]: info: start of minidlna.service succeeded. Jan 20 09:27:57 volumio-home volumio[1027]: info: Jan 20 09:27:58 volumio-home volumio[1027]: ---------------------------- Client requests Volume 40 Jan 20 09:27:58 volumio-home volumio[1027]: info: VolumeController::SetAlsaVolume40 Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 20 09:27:58 volumio-home volumio[1027]: info: Reloading queue from file Jan 20 09:27:58 volumio-home systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Jan 20 09:27:58 volumio-home systemd[1]: Stopping Volspotconnect2 Daemon... Jan 20 09:27:58 volumio-home systemd[1]: Starting Volspotconnect2 Daemon... Jan 20 09:27:58 volumio-home systemd[1]: Started Volspotconnect2 Daemon. Jan 20 09:27:58 volumio-home volumio[1140]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Jan 20 09:27:58 volumio-home volumio[1140]: Reading Config from "/tmp/volspotify.toml" Jan 20 09:27:58 volumio-home volumio[1027]: info: Jan 20 09:27:58 volumio-home volumio[1027]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Jan 20 09:27:58 volumio-home volumio[1027]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Jan 20 09:27:58 volumio-home volumio[1027]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Jan 20 09:27:58 volumio-home volumio[1027]: at emitOne (events.js:116:13) Jan 20 09:27:58 volumio-home volumio[1027]: at Socket.emit (events.js:211:7) Jan 20 09:27:58 volumio-home volumio[1027]: at addChunk (_stream_readable.js:263:12) Jan 20 09:27:58 volumio-home volumio[1027]: at readableAddChunk (_stream_readable.js:246:13) Jan 20 09:27:58 volumio-home volumio[1027]: at Socket.Readable.push (_stream_readable.js:208:10) Jan 20 09:27:58 volumio-home volumio[1027]: at Pipe.onread (net.js:607:20) Jan 20 09:27:58 volumio-home volumio[1140]: [Vollibrespot] : Using Alsa backend with device: plughw:5 Jan 20 09:27:58 volumio-home volumio[1140]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Jan 20 09:27:58 volumio-home volumio[1027]: null Jan 20 09:27:58 volumio-home volumio[1027]: info: VolumeController::Volume 40 Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreStateMachine::pushState Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreStateMachine::getState Jan 20 09:27:58 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 09:27:58 volumio-home volumio[1027]: info: Setting volume on startup at 40 Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreStateMachine::setRepeat true single undefined Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreStateMachine::pushState Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreStateMachine::getState Jan 20 09:27:58 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreStateMachine::setRandom false Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreStateMachine::pushState Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreStateMachine::getState Jan 20 09:27:58 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 20 09:27:58 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 09:27:58 volumio-home sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 20 09:27:58 volumio-home sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:58 volumio-home sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Jan 20 09:27:58 volumio-home systemd[1]: Starting UPnP Renderer front-end to MPD... Jan 20 09:27:58 volumio-home systemd[1]: Started UPnP Renderer front-end to MPD. Jan 20 09:27:58 volumio-home sudo[1144]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:58 volumio-home sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 09:27:58 volumio-home systemd[1]: Starting ShairportSync AirTunes receiver... Jan 20 09:27:58 volumio-home systemd[1]: Started ShairportSync AirTunes receiver. Jan 20 09:27:59 volumio-home sudo[1147]: pam_unix(sudo:session): session closed for user root Jan 20 09:27:59 volumio-home shairport-sync[1159]: Successful Startup Jan 20 09:27:59 volumio-home volumio[1027]: Discovery: adding 82fd70b1-be72-4c2a-9c12-b70e12e22616 Jan 20 09:27:59 volumio-home volumio[1027]: info: mDNS: Found device Volumio-home Jan 20 09:27:59 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 20 09:27:59 volumio-home volumio[1027]: info: CoreStateMachine::getState Jan 20 09:27:59 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 20 09:27:59 volumio-home volumio[1027]: info: Upmpdcli Daemon Started Jan 20 09:27:59 volumio-home volumio[1027]: Discovery: this is already registered, 82fd70b1-be72-4c2a-9c12-b70e12e22616 Jan 20 09:27:59 volumio-home volumio[1027]: info: mDNS: Found device Volumio-home Jan 20 09:27:59 volumio-home volumio[1027]: info: [1737365279396] Shairport-Sync Started Jan 20 09:27:59 volumio-home volumio[1027]: Error adding Membership: Error: addMembership EINVAL Jan 20 09:27:59 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetState Jan 20 09:27:59 volumio-home volumio[1027]: info: CoreStateMachine::getState Jan 20 09:27:59 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 20 09:27:59 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetBrowseSources Jan 20 09:27:59 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 20 09:27:59 volumio-home volumio[1027]: Plugin multiroom or method getMultiroom not found Jan 20 09:27:59 volumio-home volumio[1027]: info: Listing playlists Jan 20 09:27:59 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetQueue Jan 20 09:27:59 volumio-home volumio[1027]: info: CoreStateMachine::getQueue Jan 20 09:27:59 volumio-home volumio[1027]: info: CorePlayQueue::getQueue Jan 20 09:28:01 volumio-home volumio[1027]: info: AutoStart - getting queue Jan 20 09:28:01 volumio-home volumio[1027]: info: CoreCommandRouter::volumioGetQueue Jan 20 09:28:01 volumio-home volumio[1027]: info: CoreStateMachine::getQueue Jan 20 09:28:01 volumio-home volumio[1027]: info: CorePlayQueue::getQueue Jan 20 09:28:01 volumio-home volumio[1027]: info: AutoStart - start playing -> queue is not empty Jan 20 09:28:01 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPlay Jan 20 09:28:01 volumio-home volumio[1027]: UNSET VOLATILE Jan 20 09:28:01 volumio-home volumio[1027]: info: CoreStateMachine::play index undefined Jan 20 09:28:01 volumio-home volumio[1027]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 09:28:01 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 20 09:28:01 volumio-home volumio[1027]: info: CoreStateMachine::startPlaybackTimer Jan 20 09:28:01 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 20 09:28:01 volumio-home volumio[1027]: info: ControllerMpd::sendMpdCommand stop Jan 20 09:28:01 volumio-home volumio[1027]: info: sending command... Jan 20 09:28:01 volumio-home volumio[1027]: info: parsing response... Jan 20 09:28:01 volumio-home volumio[1027]: info: ControllerMpd::sendMpdCommand clear Jan 20 09:28:01 volumio-home volumio[1027]: info: sending command... Jan 20 09:28:01 volumio-home volumio[1027]: info: Jan 20 09:28:01 volumio-home volumio[1027]: ---------------------------- MPD announces system playlist update Jan 20 09:28:01 volumio-home volumio[1027]: info: Ignoring MPD Status Update Jan 20 09:28:01 volumio-home volumio[1027]: info: parsing response... Jan 20 09:28:01 volumio-home volumio[1027]: info: ControllerMpd::sendMpdCommand add "http://radiolive.sbs.co.kr/sbsdmb/sbsdmb.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3Mzc0MDgxMDksInBhdGgiOiIvc2JzZG1iLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiMTY4YmZjZDgtODMxZi00OWFiLWJkNWQtMDFjOTNlZTFhMWZiIiwiaWF0IjoxNzM3MzY0OTA5fQ.3ljqwLO0vTYWtsWXxpiWvrM5frVCmBe7SFTDzcKLM1U" Jan 20 09:28:01 volumio-home volumio[1027]: info: sending command... Jan 20 09:28:01 volumio-home volumio[1027]: info: Jan 20 09:28:01 volumio-home volumio[1027]: info: Jan 20 09:28:01 volumio-home volumio[1027]: ---------------------------- MPD announces system playlist update Jan 20 09:28:01 volumio-home volumio[1027]: info: Ignoring MPD Status Update Jan 20 09:28:01 volumio-home volumio[1027]: info: ------------------------------ 17ms Jan 20 09:28:01 volumio-home volumio[1027]: info: parsing response... Jan 20 09:28:01 volumio-home volumio[1027]: info: ControllerMpd::sendMpdCommand play Jan 20 09:28:01 volumio-home volumio[1027]: info: sending command... Jan 20 09:28:01 volumio-home volumio[1027]: info: ------------------------------ 22ms Jan 20 09:28:01 volumio-home volumio[1027]: info: parsing response... Jan 20 09:28:01 volumio-home volumio[1027]: info: ControllerMpd::getState Jan 20 09:28:01 volumio-home volumio[1027]: info: ControllerMpd::sendMpdCommand status Jan 20 09:28:01 volumio-home volumio[1027]: info: sending command... Jan 20 09:28:01 volumio-home volumio[1027]: info: parsing response... Jan 20 09:28:01 volumio-home volumio[1027]: info: ControllerMpd::parseState Jan 20 09:28:01 volumio-home volumio[1027]: info: ControllerMpd::sendMpdCommand playlistinfo Jan 20 09:28:01 volumio-home volumio[1027]: info: sending command... Jan 20 09:28:01 volumio-home volumio[1027]: info: parsing response... Jan 20 09:28:01 volumio-home volumio[1027]: info: ControllerMpd::parseTrackInfo Jan 20 09:28:01 volumio-home volumio[1027]: info: CoreStateMachine::syncState Jan 20 09:28:01 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 20 09:28:01 volumio-home volumio[1027]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3Mzc0MDgxMDksInBhdGgiOiIvc2JzZG1iLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiMTY4YmZjZDgtODMxZi00OWFiLWJkNWQtMDFjOTNlZTFhMWZiIiwiaWF0IjoxNzM3MzY0OTA5fQ.3ljqwLO0vTYWtsWXxpiWvrM5frVCmBe7SFTDzcKLM1U","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/sbsdmb/sbsdmb.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3Mzc0MDgxMDksInBhdGgiOiIvc2JzZG1iLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiMTY4YmZjZDgtODMxZi00OWFiLWJkNWQtMDFjOTNlZTFhMWZiIiwiaWF0IjoxNzM3MzY0OTA5fQ.3ljqwLO0vTYWtsWXxpiWvrM5frVCmBe7SFTDzcKLM1U","trackType":"3ljqwLO0vTYWtsWXxpiWvrM5frVCmBe7SFTDzcKLM1U"} Jan 20 09:28:01 volumio-home volumio[1027]: info: CURRENT POSITION 0 Jan 20 09:28:01 volumio-home volumio[1027]: info: CoreStateMachine::syncState stateService play Jan 20 09:28:01 volumio-home volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Jan 20 09:28:01 volumio-home volumio[1027]: info: CoreStateMachine::pushState Jan 20 09:28:01 volumio-home volumio[1027]: info: CoreStateMachine::getState Jan 20 09:28:01 volumio-home volumio[1027]: info: CorePlayQueue::getTrack 0 Jan 20 09:28:01 volumio-home volumio[1027]: info: CoreCommandRouter::volumioPushState Jan 20 09:28:01 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 09:28:01 volumio-home volumio[1027]: info: Checking Spotify Web API Jan 20 09:28:01 volumio-home volumio[1027]: info: [LastFM] configuration error; "API_KEY" is not set. Jan 20 09:28:01 volumio-home volumio[1027]: info: [LastFM] configuration error; "API_SECRET" is not set. Jan 20 09:28:01 volumio-home volumio[1027]: info: [LastFM] configuration error; "username" is not set. Jan 20 09:28:01 volumio-home volumio[1027]: info: [LastFM] configuration error; "authToken" is not set. Jan 20 09:28:05 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jan 20 09:28:07 volumio-home volumio[1027]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jan 20 09:28:07 volumio-home volumio[1027]: info: CoreCommandRouter::volumioAddQueueItems Jan 20 09:28:07 volumio-home volumio[1027]: info: CoreStateMachine::addQueueItems Jan 20 09:28:07 volumio-home volumio[1027]: info: CorePlayQueue::addQueueItems Jan 20 09:28:07 volumio-home volumio[1027]: info: Adding Item to queue: webkbs/0 Jan 20 09:28:07 volumio-home volumio[1027]: info: Exploding uri webkbs/0 in service personal_radio Jan 20 09:28:08 volumio-home volumio[1027]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 09:28:08 volumio-home volumio[1027]: ReferenceError: self is not defined Jan 20 09:28:08 volumio-home volumio[1027]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Jan 20 09:28:08 volumio-home volumio[1027]: at /data/plugins/music_service/personal_radio/index.js:487:12 Jan 20 09:28:08 volumio-home volumio[1027]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Jan 20 09:28:08 volumio-home volumio[1027]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Jan 20 09:28:08 volumio-home volumio[1027]: at emitOne (events.js:116:13) Jan 20 09:28:08 volumio-home volumio[1027]: at Request.emit (events.js:211:7) Jan 20 09:28:08 volumio-home volumio[1027]: at Request.onRequestError (/data/plugins/music_service/personal_radio/node_modules/request/request.js:813:8) Jan 20 09:28:08 volumio-home volumio[1027]: at emitOne (events.js:116:13) Jan 20 09:28:08 volumio-home volumio[1027]: at ClientRequest.emit (events.js:211:7) Jan 20 09:28:08 volumio-home volumio[1027]: at Socket.socketErrorListener (_http_client.js:387:9) Jan 20 09:28:08 volumio-home volumio[1027]: at emitOne (events.js:116:13) Jan 20 09:28:08 volumio-home volumio[1027]: at Socket.emit (events.js:211:7) Jan 20 09:28:08 volumio-home volumio[1027]: at emitErrorNT (internal/streams/destroy.js:64:8) Jan 20 09:28:08 volumio-home volumio[1027]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Jan 20 09:28:08 volumio-home volumio[1027]: at process._tickCallback (internal/process/next_tick.js:180:9) Jan 20 09:28:08 volumio-home volumio[1027]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 09:28:09 volumio-home sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-20 09:27 Jan 20 09:28:09 volumio-home sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="3686e5c749983c2b52f621497566574399fc1a6c" VOLUMIO_FE_VERSION="f027ce75738c64189018057558e04cba7a74a080" VOLUMIO_BE_VERSION="00a16140148367a337eb80a05ab0638fd10cd06e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="2018年 08月 13日 星期一 07:01:39 CST" VOLUMIO_VERSION="2.444" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="d41d8cd98f00b204e9800998ecf8427e"