-- Logs begin at Thu 2019-02-14 19:12:00 JST, end at Thu 2024-02-22 05:44:04 JST. -- Feb 22 05:43:00 volumio ntpd[585]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 22 05:43:00 volumio wireless.js[447]: Cleaning previous... Feb 22 05:43:00 volumio sudo[631]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 22 05:43:00 volumio sudo[631]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:00 volumio sudo[631]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:00 volumio sudo[633]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 22 05:43:00 volumio sudo[633]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:01 volumio sudo[633]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:01 volumio wireless.js[447]: Stopped aP Feb 22 05:43:01 volumio sudo[640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 22 05:43:01 volumio sudo[640]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:01 volumio kernel: rtl8192cu: MAC auto ON okay! Feb 22 05:43:01 volumio ntpd[585]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 22 05:43:01 volumio kernel: rtl8192cu: Tx queue select: 0x05 Feb 22 05:43:02 volumio sh[358]: eth0: leased 192.168.0.202 for infinity Feb 22 05:43:02 volumio dhcpcd[406]: eth0: leased 192.168.0.202 for infinity Feb 22 05:43:02 volumio avahi-daemon[444]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.202. Feb 22 05:43:02 volumio sh[358]: eth0: adding route to 192.168.0.0/24 Feb 22 05:43:02 volumio sh[358]: eth0: adding default route via 192.168.0.1 Feb 22 05:43:02 volumio volumio-remote-updater[472]: [2024-02-22 05:43:02] [info] asio async_connect error: system:111 (Connection refused) Feb 22 05:43:02 volumio volumio-remote-updater[472]: [2024-02-22 05:43:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 22 05:43:02 volumio volumio-remote-updater[472]: [2024-02-22 05:43:02] [error] handle_connect error: Underlying Transport Error Feb 22 05:43:02 volumio avahi-daemon[444]: New relevant interface eth0.IPv4 for mDNS. Feb 22 05:43:02 volumio avahi-daemon[444]: Registering new address record for 192.168.0.202 on eth0.IPv4. Feb 22 05:43:02 volumio dhcpcd[406]: eth0: adding route to 192.168.0.0/24 Feb 22 05:43:02 volumio ntpd[585]: Listen normally on 3 eth0 192.168.0.202:123 Feb 22 05:43:02 volumio dhcpcd[406]: eth0: adding default route via 192.168.0.1 Feb 22 05:43:02 volumio ntpd[585]: new interface(s) found: waking up resolver Feb 22 05:43:02 volumio ntpd[585]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 22 05:43:02 volumio sudo[640]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:03 volumio sudo[643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 22 05:43:03 volumio sudo[643]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:03 volumio sudo[643]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:03 volumio sh[358]: forked to background, child pid 670 Feb 22 05:43:03 volumio dhcpcd[406]: forked to background, child pid 670 Feb 22 05:43:03 volumio sudo[667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 22 05:43:03 volumio sudo[667]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:03 volumio sudo[667]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:03 volumio sudo[672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 22 05:43:03 volumio sudo[672]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:03 volumio ifplugd(eth0)[605]: client: ifup: interface eth0 already configured Feb 22 05:43:03 volumio sh[358]: eth0=eth0 Feb 22 05:43:03 volumio ifplugd(eth0)[605]: Program executed successfully. Feb 22 05:43:03 volumio ifplugd[535]: Network Interface Plugging Daemon...start eth0...done. Feb 22 05:43:03 volumio systemd[1]: Started LSB: Brings up/down network automatically. Feb 22 05:43:04 volumio sudo[672]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:04 volumio wireless.js[447]: SETTING APPROPRIATE REG DOMAIN: JP Feb 22 05:43:04 volumio sudo[702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 22 05:43:04 volumio sudo[702]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:04 volumio sudo[702]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:04 volumio sudo[704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP Feb 22 05:43:04 volumio sudo[704]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:04 volumio sudo[704]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:04 volumio wireless.js[447]: SUCCESSFULLY SET NEW REGDOMAIN: JP Feb 22 05:43:04 volumio wireless.js[447]: Start wireless flow Feb 22 05:43:04 volumio wireless.js[447]: Stopped hotspot (if there).. Feb 22 05:43:04 volumio sudo[709]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 22 05:43:04 volumio sudo[709]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:04 volumio sudo[709]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:04 volumio nmbd[560]: [2024/02/22 05:43:04.720801, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 22 05:43:04 volumio systemd[1]: Started Samba NMB Daemon. Feb 22 05:43:04 volumio nmbd[560]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 22 05:43:04 volumio sudo[711]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 22 05:43:04 volumio sudo[711]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:04 volumio systemd[1]: Starting Samba Winbind Daemon... Feb 22 05:43:04 volumio sudo[711]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:04 volumio wireless.js[447]: DHCP IP Feb 22 05:43:04 volumio wireless.js[447]: Start ap Feb 22 05:43:04 volumio wpa_supplicant[715]: Successfully initialized wpa_supplicant Feb 22 05:43:04 volumio kernel: rtl8192cu: MAC auto ON okay! Feb 22 05:43:04 volumio sudo[716]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 22 05:43:04 volumio sudo[716]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:04 volumio dhcpcd[717]: dev: loaded udev Feb 22 05:43:04 volumio kernel: rtl8192cu: Tx queue select: 0x05 Feb 22 05:43:05 volumio wireless.js[447]: trying... Feb 22 05:43:06 volumio sudo[719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 05:43:06 volumio sudo[719]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:06 volumio winbindd[712]: [2024/02/22 05:43:06.614080, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 22 05:43:06 volumio winbindd[712]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 22 05:43:06 volumio sudo[719]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:06 volumio winbindd[712]: [2024/02/22 05:43:06.679179, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 22 05:43:06 volumio winbindd[712]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 22 05:43:06 volumio systemd[1]: Started Samba Winbind Daemon. Feb 22 05:43:06 volumio dhcpcd[717]: wlan0: connected to Access Point `' Feb 22 05:43:06 volumio systemd[1]: Starting Samba SMB Daemon... Feb 22 05:43:06 volumio wireless.js[447]: trying... Feb 22 05:43:06 volumio dhcpcd[717]: DUID 00:01:00:01:2d:69:1c:d0:b8:27:eb:2d:a1:ec Feb 22 05:43:06 volumio dhcpcd[717]: eth0: IAID eb:2d:a1:ec Feb 22 05:43:06 volumio dhcpcd[717]: eth0: adding address fe80::fee0:fa83:4d19:a2a3 Feb 22 05:43:06 volumio dhcpcd[717]: ipv6_addaddr1: Permission denied Feb 22 05:43:06 volumio dhcpcd[717]: wlan0: waiting for carrier Feb 22 05:43:06 volumio sudo[751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 05:43:06 volumio sudo[751]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:06 volumio sudo[751]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:06 volumio dhcpcd[717]: eth0: soliciting an IPv6 router Feb 22 05:43:07 volumio systemd[1]: Started Samba SMB Daemon. Feb 22 05:43:07 volumio smbd[741]: [2024/02/22 05:43:07.680189, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 22 05:43:07 volumio smbd[741]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 22 05:43:07 volumio dhcpcd[717]: eth0: rebinding lease of 192.168.0.202 Feb 22 05:43:07 volumio wireless.js[447]: trying... Feb 22 05:43:07 volumio sudo[761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 05:43:07 volumio sudo[761]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:07 volumio sudo[761]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:07 volumio dhcpcd[717]: eth0: leased 192.168.0.202 for infinity Feb 22 05:43:07 volumio dhcpcd[717]: eth0: adding route to 192.168.0.0/24 Feb 22 05:43:07 volumio dhcpcd[717]: eth0: adding default route via 192.168.0.1 Feb 22 05:43:08 volumio wpa_supplicant[732]: wlan0: SME: Trying to authenticate with 6c:5a:b0:1d:bf:70 (SSID='4CE676C43020_A' freq=2417 MHz) Feb 22 05:43:08 volumio kernel: wlan0: authenticate with 6c:5a:b0:1d:bf:70 Feb 22 05:43:08 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Feb 22 05:43:08 volumio kernel: wlan0: send auth to 6c:5a:b0:1d:bf:70 (try 1/3) Feb 22 05:43:08 volumio dhcpcd[717]: forked to background, child pid 783 Feb 22 05:43:08 volumio kernel: wlan0: authenticated Feb 22 05:43:08 volumio wpa_supplicant[732]: wlan0: Trying to associate with 6c:5a:b0:1d:bf:70 (SSID='4CE676C43020_A' freq=2417 MHz) Feb 22 05:43:08 volumio kernel: wlan0: associate with 6c:5a:b0:1d:bf:70 (try 1/3) Feb 22 05:43:08 volumio sudo[716]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:08 volumio kernel: wlan0: RX AssocResp from 6c:5a:b0:1d:bf:70 (capab=0x1411 status=0 aid=6) Feb 22 05:43:08 volumio wpa_supplicant[732]: wlan0: Associated with 6c:5a:b0:1d:bf:70 Feb 22 05:43:08 volumio wpa_supplicant[732]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 22 05:43:08 volumio wpa_supplicant[732]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Feb 22 05:43:08 volumio kernel: wlan0: associated Feb 22 05:43:08 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 6c:5a:b0:1d:bf:70 Feb 22 05:43:08 volumio kernel: cryptd: max_cpu_qlen set to 1000 Feb 22 05:43:08 volumio wpa_supplicant[732]: wlan0: WPA: Key negotiation completed with 6c:5a:b0:1d:bf:70 [PTK=CCMP GTK=CCMP] Feb 22 05:43:08 volumio wpa_supplicant[732]: wlan0: CTRL-EVENT-CONNECTED - Connection to 6c:5a:b0:1d:bf:70 completed [id=0 id_str=] Feb 22 05:43:08 volumio dhcpcd[783]: wlan0: carrier acquired Feb 22 05:43:08 volumio dhcpcd[783]: wlan0: connected to Access Point `4CE676C43020_A' Feb 22 05:43:08 volumio dhcpcd[783]: wlan0: IAID cf:f6:4e:60 Feb 22 05:43:08 volumio dhcpcd[783]: wlan0: adding address fe80::a6e7:b1e:60ab:86ef Feb 22 05:43:08 volumio dhcpcd[783]: ipv6_addaddr1: Permission denied Feb 22 05:43:08 volumio wireless.js[447]: trying... Feb 22 05:43:08 volumio sudo[811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 05:43:08 volumio sudo[811]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:08 volumio sudo[811]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:08 volumio dhcpcd[783]: wlan0: soliciting a DHCP lease Feb 22 05:43:09 volumio dhcpcd[783]: wlan0: soliciting an IPv6 router Feb 22 05:43:09 volumio wireless.js[447]: trying... Feb 22 05:43:09 volumio sudo[814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 05:43:09 volumio sudo[814]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:09 volumio sudo[814]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:10 volumio volumio-remote-updater[472]: [2024-02-22 05:43:10] [info] asio async_connect error: system:111 (Connection refused) Feb 22 05:43:10 volumio volumio-remote-updater[472]: [2024-02-22 05:43:10] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 22 05:43:10 volumio volumio-remote-updater[472]: [2024-02-22 05:43:10] [error] handle_connect error: Underlying Transport Error Feb 22 05:43:10 volumio wireless.js[447]: trying... Feb 22 05:43:10 volumio sudo[817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 05:43:10 volumio sudo[817]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:10 volumio sudo[817]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:11 volumio wireless.js[447]: trying... Feb 22 05:43:11 volumio sudo[820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 05:43:11 volumio sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:11 volumio sudo[820]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:12 volumio wireless.js[447]: trying... Feb 22 05:43:12 volumio sudo[823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 05:43:12 volumio sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:12 volumio sudo[823]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:13 volumio wireless.js[447]: trying... Feb 22 05:43:13 volumio sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 05:43:13 volumio sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:13 volumio sudo[826]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:13 volumio dhcpcd[783]: wlan0: probing for an IPv4LL address Feb 22 05:43:14 volumio wireless.js[447]: trying... Feb 22 05:43:14 volumio sudo[829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 05:43:14 volumio sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:14 volumio sudo[829]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:15 volumio wireless.js[447]: trying... Feb 22 05:43:15 volumio sudo[832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 05:43:15 volumio sudo[832]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:15 volumio sudo[832]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:16 volumio wireless.js[447]: trying... Feb 22 05:43:16 volumio sudo[835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 05:43:16 volumio sudo[835]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:16 volumio sudo[835]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:17 volumio wireless.js[447]: trying... Feb 22 05:43:17 volumio sudo[838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 05:43:17 volumio sudo[838]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:17 volumio sudo[838]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:18 volumio wireless.js[447]: trying... Feb 22 05:43:18 volumio dhcpcd[783]: wlan0: using IPv4LL address 169.254.31.109 Feb 22 05:43:18 volumio dhcpcd[783]: wlan0: adding route to 169.254.0.0/16 Feb 22 05:43:18 volumio avahi-daemon[444]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.31.109. Feb 22 05:43:18 volumio avahi-daemon[444]: New relevant interface wlan0.IPv4 for mDNS. Feb 22 05:43:18 volumio avahi-daemon[444]: Registering new address record for 169.254.31.109 on wlan0.IPv4. Feb 22 05:43:18 volumio sudo[842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 22 05:43:18 volumio sudo[842]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:18 volumio sudo[842]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:18 volumio wireless.js[447]: Connected to: ----4CE676C43020_A Feb 22 05:43:18 volumio wireless.js[447]: ---- Feb 22 05:43:18 volumio sudo[857]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 05:43:19 volumio sudo[857]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:19 volumio sudo[857]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:19 volumio wireless.js[447]: ... joined AP, wlan0 IPv4 is 169.254.31.109, ipV6 is undefined Feb 22 05:43:19 volumio wireless.js[447]: It's done! AP Feb 22 05:43:19 volumio iwconfig[866]: Error for wireless request "Set Power Management" (8B2C) : Feb 22 05:43:19 volumio iwconfig[866]: SET failed on device wlan0 ; Operation not supported. Feb 22 05:43:19 volumio systemd[1]: Started Wireless Services. Feb 22 05:43:19 volumio systemd[1]: Started Volumio Backend Module. Feb 22 05:43:19 volumio systemd[1]: Started Volumio Cpu Tweaker. Feb 22 05:43:19 volumio systemd[1]: Reached target Multi-User System. Feb 22 05:43:19 volumio systemd[1]: Reached target Graphical Interface. Feb 22 05:43:19 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 22 05:43:19 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 22 05:43:19 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 22 05:43:19 volumio volumio-cpu-tweak[868]: Setting RT Priority for mpd Feb 22 05:43:19 volumio systemd[1]: Startup finished in 11.157s (kernel) + 34.990s (userspace) = 46.148s. Feb 22 05:43:19 volumio volumio-cpu-tweak[868]: pid 35's current scheduling policy: SCHED_OTHER Feb 22 05:43:19 volumio volumio-cpu-tweak[868]: pid 35's current scheduling priority: 0 Feb 22 05:43:19 volumio volumio-cpu-tweak[868]: Setting MPD Affinity Feb 22 05:43:19 volumio volumio-cpu-tweak[868]: pid 3's current affinity mask: f Feb 22 05:43:19 volumio volumio-cpu-tweak[868]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 22 05:43:19 volumio volumio-cpu-tweak[868]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 22 05:43:19 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 22 05:43:20 volumio ntpd[585]: Listen normally on 4 wlan0 169.254.31.109:123 Feb 22 05:43:20 volumio ntpd[585]: new interface(s) found: waking up resolver Feb 22 05:43:20 volumio volumio-remote-updater[472]: [2024-02-22 05:43:20] [info] asio async_connect error: system:111 (Connection refused) Feb 22 05:43:20 volumio volumio-remote-updater[472]: [2024-02-22 05:43:20] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 22 05:43:20 volumio volumio-remote-updater[472]: [2024-02-22 05:43:20] [error] handle_connect error: Underlying Transport Error Feb 22 05:43:23 volumio systemd[1]: systemd-fsckd.service: Succeeded. Feb 22 05:43:28 volumio volumio[867]: info: ------------------------------------------- Feb 22 05:43:28 volumio volumio[867]: info: ----- Volumio3 ---- Feb 22 05:43:28 volumio volumio[867]: info: ------------------------------------------- Feb 22 05:43:28 volumio volumio[867]: info: ----- System startup ---- Feb 22 05:43:28 volumio volumio[867]: info: ------------------------------------------- Feb 22 05:43:30 volumio volumio[867]: info: MYVOLUMIO Environment detected Feb 22 05:43:30 volumio volumio[867]: info: Plugin folders cleanup Feb 22 05:43:30 volumio volumio[867]: info: Scanning into folder /volumio/app/plugins/ Feb 22 05:43:30 volumio volumio[867]: info: Scanning category audio_interface Feb 22 05:43:30 volumio volumio[867]: info: Scanning category miscellanea Feb 22 05:43:30 volumio volumio[867]: info: Scanning category music_service Feb 22 05:43:30 volumio volumio[867]: info: Scanning category plugins.json Feb 22 05:43:30 volumio volumio[867]: info: Scanning category system_controller Feb 22 05:43:30 volumio volumio[867]: info: Scanning category user_interface Feb 22 05:43:30 volumio volumio[867]: info: Scanning into folder /data/plugins/ Feb 22 05:43:30 volumio volumio[867]: info: Plugin folders cleanup completed Feb 22 05:43:30 volumio volumio[867]: info: ------------------------------------------- Feb 22 05:43:30 volumio volumio[867]: info: ----- Core plugins startup ---- Feb 22 05:43:30 volumio volumio[867]: info: ------------------------------------------- Feb 22 05:43:30 volumio volumio[867]: info: Loading plugins from folder /volumio/app/plugins/ Feb 22 05:43:30 volumio volumio[867]: info: Adding plugin upnp to MyMusic Plugins Feb 22 05:43:30 volumio volumio[867]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 22 05:43:30 volumio volumio[867]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 22 05:43:30 volumio volumio[867]: info: Loading plugins from folder /data/plugins/ Feb 22 05:43:30 volumio volumio[867]: info: Loading plugin "system"... Feb 22 05:43:30 volumio volumio-remote-updater[472]: [2024-02-22 05:43:30] [connect] Successful connection Feb 22 05:43:30 volumio volumio[867]: info: Loading plugin "appearance"... Feb 22 05:43:33 volumio volumio[867]: info: Loading plugin "network"... Feb 22 05:43:33 volumio volumio[867]: info: Refreshing Cached IP Addresses Feb 22 05:43:33 volumio sudo[899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 05:43:33 volumio sudo[899]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:33 volumio sudo[899]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:33 volumio volumio[867]: info: Loading plugin "services"... Feb 22 05:43:33 volumio sudo[901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 05:43:33 volumio sudo[901]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:33 volumio volumio[867]: info: Loading plugin "alsa_controller"... Feb 22 05:43:33 volumio sudo[901]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:33 volumio sudo[904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 22 05:43:33 volumio sudo[904]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:33 volumio volumio[867]: warn: Unable to locate the audio output device HiFiBerry DAC+ Pro. Please configure a valid output device. Feb 22 05:43:33 volumio volumio[867]: Invalid card number. Feb 22 05:43:33 volumio volumio[867]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Feb 22 05:43:33 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 05:43:33 volumio volumio[867]: Invalid card number. Feb 22 05:43:33 volumio volumio[867]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 22 05:43:33 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 05:43:33 volumio volumio[867]: info: Loading plugin "wizard"... Feb 22 05:43:33 volumio volumio[867]: info: Loading plugin "networkfs"... Feb 22 05:43:34 volumio volumio[867]: info: Starting Udev Watcher for removable devices Feb 22 05:43:34 volumio volumio[867]: info: Ignoring mount for partition: boot Feb 22 05:43:34 volumio volumio[867]: info: Ignoring mount for partition: volumio Feb 22 05:43:34 volumio volumio[867]: info: Ignoring mount for partition: volumio_data Feb 22 05:43:34 volumio volumio[867]: info: Mounting Device クラシック・ジャズ・ハイレゾ・落語 Feb 22 05:43:34 volumio sudo[921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/クラシック・ジャズ・ハイレゾ・落語 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 22 05:43:34 volumio sudo[921]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:34 volumio kernel: fuse: init (API version 7.37) Feb 22 05:43:34 volumio systemd[1]: Mounting FUSE Control File System... Feb 22 05:43:34 volumio systemd[1]: Mounted FUSE Control File System. Feb 22 05:43:34 volumio ntfs-3g[928]: Version 2017.3.23AR.3 integrated FUSE 28 Feb 22 05:43:34 volumio ntfs-3g[928]: Mounted /dev/sdb1 (Read-Write, label "クラシック・ジャズ・ハイレゾ・落語", NTFS 3.1) Feb 22 05:43:34 volumio ntfs-3g[928]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 22 05:43:34 volumio ntfs-3g[928]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb1,blkdev,blksize=4096 Feb 22 05:43:34 volumio ntfs-3g[928]: Global ownership and permissions enforced, configuration type 7 Feb 22 05:43:34 volumio sudo[921]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:34 volumio volumio[867]: info: Mounting Device Music Feb 22 05:43:34 volumio sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Music -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 22 05:43:34 volumio sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:35 volumio ntfs-3g[936]: Version 2017.3.23AR.3 integrated FUSE 28 Feb 22 05:43:35 volumio ntfs-3g[936]: Mounted /dev/sda1 (Read-Write, label "Music", NTFS 3.1) Feb 22 05:43:35 volumio ntfs-3g[936]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 22 05:43:35 volumio ntfs-3g[936]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Feb 22 05:43:35 volumio ntfs-3g[936]: Global ownership and permissions enforced, configuration type 7 Feb 22 05:43:35 volumio sudo[933]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:35 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 05:43:35 volumio volumio[867]: info: Loading plugin "volumio_command_line_client"... Feb 22 05:43:35 volumio volumio[867]: info: Loading plugin "upnp"... Feb 22 05:43:35 volumio volumio[867]: info: [1708548215844] Starting Upmpd Daemon Feb 22 05:43:35 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 05:43:35 volumio volumio[867]: info: Loading plugin "my_music"... Feb 22 05:43:35 volumio volumio[867]: info: Loading plugin "mpd"... Feb 22 05:43:36 volumio volumio[867]: info: Loading plugin "upnp_browser"... Feb 22 05:43:37 volumio sudo[904]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:37 volumio volumio[867]: info: Loading plugin "alarm-clock"... Feb 22 05:43:38 volumio volumio[867]: info: Loading plugin "airplay_emulation"... Feb 22 05:43:38 volumio volumio[867]: info: Starting Shairport Sync Feb 22 05:43:38 volumio volumio[867]: info: Loading plugin "last_100"... Feb 22 05:43:38 volumio volumio[867]: info: Loading plugin "webradio"... Feb 22 05:43:38 volumio volumio[867]: info: Loading plugin "i2s_dacs"... Feb 22 05:43:38 volumio volumio[867]: info: Loading plugin "volumiodiscovery"... Feb 22 05:43:38 volumio volumio[867]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 22 05:43:38 volumio volumio[867]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 05:43:38 volumio node[867]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 22 05:43:38 volumio volumio[867]: *** WARNING *** For more information see Feb 22 05:43:38 volumio volumio[867]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 22 05:43:38 volumio volumio[867]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 05:43:38 volumio volumio[867]: *** WARNING *** For more information see Feb 22 05:43:38 volumio node[867]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 05:43:38 volumio node[867]: *** WARNING *** For more information see Feb 22 05:43:38 volumio node[867]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 22 05:43:38 volumio node[867]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 05:43:38 volumio node[867]: *** WARNING *** For more information see Feb 22 05:43:38 volumio volumio[867]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 22 05:43:38 volumio volumio[867]: info: Discovery: Started advertising with name: Volumio Feb 22 05:43:38 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 05:43:38 volumio volumio[867]: info: Loading plugin "outputs"... Feb 22 05:43:38 volumio volumio[867]: info: Loading plugin "albumart"... Feb 22 05:43:38 volumio volumio[867]: info: Plugin example_plugin is not enabled Feb 22 05:43:38 volumio volumio[867]: info: Loading plugin "inputs"... Feb 22 05:43:38 volumio volumio[867]: info: Loading plugin "updater_comm"... Feb 22 05:43:39 volumio volumio[867]: info: Plugin mpdemulation is not enabled Feb 22 05:43:39 volumio volumio[867]: info: Loading plugin "rest_api"... Feb 22 05:43:39 volumio volumio[867]: info: Loading plugin "websocket"... Feb 22 05:43:39 volumio volumio[867]: info: Loading i18n strings for locale ja Feb 22 05:43:39 volumio volumio[867]: Updating browse sources language Feb 22 05:43:39 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 05:43:39 volumio volumio[867]: Forking 3 albumart workers Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::initPlayerControls Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 05:43:40 volumio volumio[867]: Express server listening on port 3000 Feb 22 05:43:40 volumio volumio[867]: [Metrics] WebUI: 17s 342.15ms Feb 22 05:43:40 volumio volumio[867]: info: CoreStateMachine::resetVolumioState Feb 22 05:43:40 volumio volumio[867]: info: CoreStateMachine::getcurrentVolume Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 22 05:43:40 volumio volumio[867]: info: CoreStateMachine::getcurrentVolume Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 05:43:40 volumio volumio[867]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 22 05:43:40 volumio volumio[867]: info: VolumeController:: Volume=undefined Mute =false Feb 22 05:43:40 volumio volumio[867]: info: CoreStateMachine::pushState Feb 22 05:43:40 volumio volumio[867]: info: CorePlayQueue::getTrack 0 Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Feb 22 05:43:40 volumio volumio[867]: info: CoreStateMachine::updateTrackBlock Feb 22 05:43:40 volumio volumio[867]: info: CorePlayQueue::getTrackBlock Feb 22 05:43:40 volumio volumio[867]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 05:43:40 volumio volumio[867]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 22 05:43:41 volumio volumio[867]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 22 05:43:41 volumio volumio[867]: MPD error: Cannot assign requested address Feb 22 05:43:41 volumio volumio[867]: MPD error: Cannot assign requested address Feb 22 05:43:41 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 05:43:41 volumio volumio[867]: info: VolumeController:: Volume=undefined Mute =false Feb 22 05:43:41 volumio volumio[867]: info: CoreStateMachine::pushState Feb 22 05:43:41 volumio volumio[867]: info: CorePlayQueue::getTrack 0 Feb 22 05:43:41 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Feb 22 05:43:41 volumio volumio[867]: info: CoreStateMachine::updateTrackBlock Feb 22 05:43:41 volumio volumio[867]: info: CorePlayQueue::getTrackBlock Feb 22 05:43:41 volumio volumio[867]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 05:43:41 volumio volumio[867]: info: CoreStateMachine::setRepeat null single undefined Feb 22 05:43:41 volumio volumio[867]: info: CoreStateMachine::pushState Feb 22 05:43:41 volumio volumio[867]: info: CorePlayQueue::getTrack 0 Feb 22 05:43:41 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Feb 22 05:43:41 volumio volumio[867]: info: CoreStateMachine::setRandom null Feb 22 05:43:41 volumio volumio[867]: info: CoreStateMachine::pushState Feb 22 05:43:41 volumio volumio[867]: info: CorePlayQueue::getTrack 0 Feb 22 05:43:41 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Feb 22 05:43:41 volumio volumio[867]: info: Setting Device type: Raspberry PI Feb 22 05:43:41 volumio volumio[867]: Starting albumart workers Feb 22 05:43:41 volumio volumio[867]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 22 05:43:41 volumio volumio[867]: info: VolumeController:: Volume=undefined Mute =false Feb 22 05:43:41 volumio volumio[867]: info: CoreStateMachine::pushState Feb 22 05:43:41 volumio volumio[867]: info: CorePlayQueue::getTrack 0 Feb 22 05:43:41 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Feb 22 05:43:41 volumio volumio[867]: Starting albumart workers Feb 22 05:43:41 volumio volumio[867]: info: VolumeController:: Volume=undefined Mute =false Feb 22 05:43:41 volumio volumio[867]: info: CoreStateMachine::pushState Feb 22 05:43:41 volumio volumio[867]: info: CorePlayQueue::getTrack 0 Feb 22 05:43:41 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Feb 22 05:43:41 volumio volumio[867]: Starting albumart workers Feb 22 05:43:43 volumio sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 05:43:43 volumio sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:43 volumio sudo[1014]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:43 volumio sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 05:43:43 volumio sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:43 volumio sudo[1016]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:43 volumio volumio[867]: verbose: New Socket.io Connection to 192.168.0.202 from 192.168.0.248 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Edg/123.0.0.0 Total Clients: 1 Feb 22 05:43:44 volumio sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 05:43:44 volumio sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:44 volumio sudo[1020]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:44 volumio sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 05:43:44 volumio sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:44 volumio sudo[1022]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:44 volumio volumio[867]: verbose: New Socket.io Connection to 192.168.0.202 from 192.168.0.248 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Edg/123.0.0.0 Total Clients: 2 Feb 22 05:43:44 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 05:43:44 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 05:43:44 volumio volumio[867]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 05:43:44 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 05:43:44 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Feb 22 05:43:44 volumio volumio[867]: info: CorePlayQueue::getTrack 0 Feb 22 05:43:44 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 05:43:44 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 05:43:44 volumio volumio[867]: info: Received Get System Info Feb 22 05:43:44 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 05:43:44 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 05:43:44 volumio volumio[867]: info: Discovery: Getting this device information Feb 22 05:43:44 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Feb 22 05:43:44 volumio volumio[867]: info: CorePlayQueue::getTrack 0 Feb 22 05:43:44 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 05:43:44 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Feb 22 05:43:44 volumio volumio[867]: info: CorePlayQueue::getTrack 0 Feb 22 05:43:44 volumio volumio[867]: info: Listing playlists Feb 22 05:43:44 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 05:43:44 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 05:43:45 volumio volumio-remote-updater[472]: [2024-02-22 05:43:45] [connect] Successful connection Feb 22 05:43:45 volumio volumio-remote-updater[472]: [2024-02-22 05:43:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1708548225 101 Feb 22 05:43:45 volumio volumio[867]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Feb 22 05:43:45 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Feb 22 05:43:45 volumio volumio[867]: info: CorePlayQueue::getTrack 0 Feb 22 05:43:45 volumio sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 05:43:45 volumio sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:45 volumio sudo[1026]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:45 volumio sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 05:43:45 volumio sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:45 volumio sudo[1028]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:45 volumio sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 22 05:43:45 volumio sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 05:43:46 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 22 05:43:46 volumio sudo[1031]: pam_unix(sudo:session): session closed for user root Feb 22 05:43:46 volumio volumio[867]: info: Upmpdcli Daemon Started Feb 22 05:43:46 volumio volumio[867]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 22 05:43:46 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 05:43:46 volumio volumio[867]: info: Received Get System Info Feb 22 05:43:46 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 05:43:46 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 05:43:46 volumio volumio[867]: info: Discovery: Getting this device information Feb 22 05:43:46 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Feb 22 05:43:46 volumio volumio[867]: info: CorePlayQueue::getTrack 0 Feb 22 05:43:46 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 05:43:50 volumio volumio[867]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 22 05:43:52 volumio volumio[867]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 05:43:52 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 05:43:52 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 22 05:43:52 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 22 05:43:52 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 22 05:43:52 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 05:43:52 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 05:43:52 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 05:43:52 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 05:43:52 volumio volumio[867]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 05:43:52 volumio volumio[867]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 05:43:52 volumio volumio[867]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 05:43:52 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 22 05:43:52 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 22 05:43:56 volumio volumio[867]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 22 05:43:56 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 22 05:43:59 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Feb 22 05:43:59 volumio volumio[867]: info: CorePlayQueue::getTrack 0 Feb 22 05:44:01 volumio volumio[867]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 05:44:01 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 05:44:01 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 22 05:44:01 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 22 05:44:01 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 22 05:44:01 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 05:44:01 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 05:44:01 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 05:44:01 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 05:44:01 volumio volumio[867]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 05:44:01 volumio volumio[867]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 05:44:01 volumio volumio[867]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 05:44:01 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 22 05:44:01 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 22 05:44:03 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 05:44:03 volumio volumio[867]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 22 05:44:03 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 22 05:44:03 volumio volumio[867]: info: Received Get System Version Feb 22 05:44:03 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 05:44:03 volumio volumio[867]: info: Received Get System Info Feb 22 05:44:03 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 05:44:03 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 05:44:03 volumio volumio[867]: info: Discovery: Getting this device information Feb 22 05:44:03 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Feb 22 05:44:03 volumio volumio[867]: info: CorePlayQueue::getTrack 0 Feb 22 05:44:03 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 05:44:03 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 22 05:44:03 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand update Feb 22 05:44:03 volumio volumio[867]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 05:44:03 volumio volumio[867]: TypeError: Cannot read property 'then' of undefined Feb 22 05:44:03 volumio volumio[867]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) Feb 22 05:44:03 volumio volumio[867]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1898:15) Feb 22 05:44:03 volumio volumio[867]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Feb 22 05:44:03 volumio volumio[867]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1049:26) Feb 22 05:44:03 volumio volumio[867]: at Socket.emit (events.js:315:20) Feb 22 05:44:03 volumio volumio[867]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 22 05:44:03 volumio volumio[867]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 22 05:44:03 volumio volumio[867]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 05:44:04 volumio sudo[1079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-02-22 05:43 Feb 22 05:44:04 volumio sudo[1079]: 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"