-- Logs begin at Tue 2025-03-18 15:40:02 CST, end at Tue 2025-03-18 15:52:12 CST. -- Mar 18 15:51:12 volumio volumio[4130]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::volumioGetState Mar 18 15:51:12 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Mar 18 15:51:12 volumio sudo[4597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 18 15:51:12 volumio sudo[4597]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:12 volumio sudo[4597]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:12 volumio volumio[4130]: info: Restoring Previous Volume level: 100 false false Mar 18 15:51:12 volumio volumio[4130]: info: Volume configurations have been set Mar 18 15:51:12 volumio sudo[4600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 18 15:51:12 volumio sudo[4600]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:12 volumio systemd[1]: Stopping Music Player Daemon... Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 18 15:51:12 volumio volumio[4130]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 18 15:51:12 volumio volumio[4130]: info: Disabling external Volume Control Mar 18 15:51:12 volumio systemd[1]: mpd.service: Succeeded. Mar 18 15:51:12 volumio systemd[1]: Stopped Music Player Daemon. Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 18 15:51:12 volumio volumio[4130]: info: MPD Permissions set Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 15:51:12 volumio systemd[1]: Starting Music Player Daemon... Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 15:51:12 volumio sudo[4605]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 18 15:51:12 volumio sudo[4605]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:12 volumio sudo[4605]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 18 15:51:12 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 18 15:51:13 volumio volumio[4130]: info: VolumeController:: Volume=100 Mute =false Mar 18 15:51:13 volumio volumio[4130]: info: CoreStateMachine::pushState Mar 18 15:51:13 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:51:13 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 15:51:13 volumio volumio[4130]: info: CoreCommandRouter::volumioPushState Mar 18 15:51:13 volumio mpd[4615]: Mar 18 15:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 18 15:51:13 volumio systemd[1]: Started Music Player Daemon. Mar 18 15:51:13 volumio sudo[4600]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:13 volumio volumio[4130]: error: updateQueue error: null Mar 18 15:51:17 volumio volumio[4130]: info: VolumeController::SetAlsaVolume100 Mar 18 15:51:17 volumio volumio[4130]: info: CoreStateMachine::pushState Mar 18 15:51:17 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:51:17 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 15:51:17 volumio volumio[4130]: info: CoreCommandRouter::volumioPushState Mar 18 15:51:19 volumio volumio[4130]: info: CoreCommandRouter::volumioGetState Mar 18 15:51:19 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:51:23 volumio volumio[4130]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 18 15:51:23 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 18 15:51:23 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Mar 18 15:51:23 volumio sudo[4645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Mar 18 15:51:23 volumio sudo[4645]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:23 volumio sudo[4651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 18 15:51:23 volumio sudo[4651]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:23 volumio sudo[4645]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:23 volumio sudo[4651]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:23 volumio sudo[4658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 18 15:51:23 volumio sudo[4658]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:23 volumio sudo[4658]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:23 volumio sudo[4665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 18 15:51:23 volumio sudo[4665]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:23 volumio sudo[4665]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:23 volumio sudo[4671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 18 15:51:23 volumio sudo[4671]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:23 volumio sudo[4671]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:23 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 18 15:51:23 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 18 15:51:23 volumio sudo[4674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 18 15:51:23 volumio sudo[4674]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:23 volumio sudo[4674]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:23 volumio sudo[4677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 18 15:51:23 volumio sudo[4677]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:23 volumio sudo[4677]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:23 volumio volumio[4130]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 18 15:51:23 volumio volumio[4130]: wlan0 Interface doesn't support scanning : Operation not supported Mar 18 15:51:23 volumio volumio[4130]: info: Cannot use regular scanning, forcing with ap-force Mar 18 15:51:23 volumio sudo[4680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 18 15:51:23 volumio sudo[4680]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:23 volumio sudo[4680]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:23 volumio volumio[4130]: command failed: Operation not supported (-95) Mar 18 15:51:23 volumio volumio[4130]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 18 15:51:23 volumio volumio[4130]: command failed: Operation not supported (-95) Mar 18 15:51:54 volumio volumio[4130]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Mar 18 15:51:54 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Mar 18 15:51:56 volumio sudo[4768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Mar 18 15:51:56 volumio sudo[4768]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:56 volumio sudo[4768]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:56 volumio volumio[4130]: info: Permissions for /etc/hostapd/hostapd.conf Mar 18 15:51:56 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Mar 18 15:51:56 volumio volumio[4130]: info: Discovery: Restarting Advertising due to device name change Mar 18 15:51:56 volumio sudo[4771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Mar 18 15:51:56 volumio sudo[4771]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:56 volumio systemd[1]: Starting Wireless Services... Mar 18 15:51:56 volumio wireless.js[4773]: Cleaning previous... Mar 18 15:51:56 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Mar 18 15:51:56 volumio dnsmasq[1232]: exiting on receipt of SIGTERM Mar 18 15:51:56 volumio systemd[1]: dnsmasq.service: Succeeded. Mar 18 15:51:56 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Mar 18 15:51:56 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 18 15:51:56 volumio dhcpcd[963]: wlan0: carrier lost Mar 18 15:51:56 volumio dhcpcd[963]: wlan0: deleting route to 169.254.0.0/16 Mar 18 15:51:56 volumio avahi-daemon[883]: Withdrawing address record for 169.254.139.173 on wlan0. Mar 18 15:51:56 volumio avahi-daemon[883]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 18 15:51:56 volumio avahi-daemon[883]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 18 15:51:56 volumio avahi-daemon[883]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 18 15:51:56 volumio avahi-daemon[883]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 18 15:51:56 volumio avahi-daemon[883]: New relevant interface wlan0.IPv4 for mDNS. Mar 18 15:51:56 volumio avahi-daemon[883]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Mar 18 15:51:56 volumio systemd[1]: hostapd.service: Succeeded. Mar 18 15:51:56 volumio volumio[4130]: info: Discovery: A device disappeared from network Mar 18 15:51:56 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 18 15:51:56 volumio volumio[4130]: info: Discovery: Device volumio disappeared from network Mar 18 15:51:56 volumio sudo[4834]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 18 15:51:56 volumio sudo[4834]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:56 volumio avahi-daemon[883]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 18 15:51:56 volumio avahi-daemon[883]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 18 15:51:56 volumio avahi-daemon[883]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 18 15:51:56 volumio sudo[4834]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:56 volumio sudo[4836]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 18 15:51:56 volumio sudo[4836]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:56 volumio sudo[4836]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:56 volumio wireless.js[4773]: Stopped aP Mar 18 15:51:56 volumio sudo[4844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 18 15:51:56 volumio sudo[4844]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:56 volumio sudo[4844]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:56 volumio sudo[4846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 18 15:51:56 volumio sudo[4846]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:56 volumio sudo[4846]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:56 volumio sudo[4853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 18 15:51:56 volumio sudo[4853]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:56 volumio sudo[4853]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:56 volumio sudo[4855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 18 15:51:56 volumio sudo[4855]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:57 volumio volumio[4130]: info: Discovery: A device disappeared from network Mar 18 15:51:58 volumio ntpd[1657]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=704 secs Mar 18 15:51:58 volumio ntpd[1657]: Deleting interface #5 wlan0, 169.254.139.173#123, interface stats: received=0, sent=0, dropped=0, active_time=699 secs Mar 18 15:51:58 volumio sudo[4855]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:58 volumio sudo[4865]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 18 15:51:58 volumio sudo[4865]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:58 volumio sudo[4865]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:58 volumio sudo[4867]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 18 15:51:58 volumio sudo[4867]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:51:58 volumio sudo[4867]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:58 volumio volumio[4130]: info: CoreCommandRouter::volumioGetState Mar 18 15:51:58 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:51:58 volumio avahi-daemon[883]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 18 15:51:58 volumio avahi-daemon[883]: New relevant interface wlan0.IPv4 for mDNS. Mar 18 15:51:58 volumio avahi-daemon[883]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Mar 18 15:51:59 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 18 15:51:59 volumio hostapd[4874]: Configuration file: /etc/hostapd/hostapd.conf Mar 18 15:51:59 volumio avahi-daemon[883]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 18 15:51:59 volumio avahi-daemon[883]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 18 15:51:59 volumio avahi-daemon[883]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 18 15:51:59 volumio avahi-daemon[883]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 18 15:51:59 volumio avahi-daemon[883]: New relevant interface wlan0.IPv4 for mDNS. Mar 18 15:51:59 volumio avahi-daemon[883]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Mar 18 15:51:59 volumio hostapd[4874]: Using interface wlan0 with hwaddr c8:94:02:f8:ce:73 and ssid "Volumio" Mar 18 15:51:59 volumio dhcpcd[963]: wlan0: carrier acquired Mar 18 15:51:59 volumio hostapd[4874]: wlan0: interface state UNINITIALIZED->ENABLED Mar 18 15:51:59 volumio hostapd[4874]: wlan0: AP-ENABLED Mar 18 15:51:59 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 18 15:51:59 volumio dhcpcd[963]: wlan0: IAID 02:f8:ce:73 Mar 18 15:51:59 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Mar 18 15:51:59 volumio dnsmasq[4892]: dnsmasq: syntax check OK. Mar 18 15:51:59 volumio iwconfig[4894]: Error for wireless request "Set Power Management" (8B2C) : Mar 18 15:51:59 volumio iwconfig[4894]: SET failed on device wlan0 ; Invalid argument. Mar 18 15:51:59 volumio systemd[1]: wireless.service: Succeeded. Mar 18 15:51:59 volumio systemd[1]: Started Wireless Services. Mar 18 15:51:59 volumio sudo[4771]: pam_unix(sudo:session): session closed for user root Mar 18 15:51:59 volumio dnsmasq[4900]: started, version 2.80 cachesize 150 Mar 18 15:51:59 volumio dnsmasq[4900]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Mar 18 15:51:59 volumio dnsmasq-dhcp[4900]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Mar 18 15:51:59 volumio dnsmasq[4900]: reading /etc/resolv.conf Mar 18 15:51:59 volumio dnsmasq[4900]: using nameserver 192.168.112.1#53 Mar 18 15:51:59 volumio dnsmasq[4900]: using nameserver 208.67.222.222#53 Mar 18 15:51:59 volumio dnsmasq[4900]: using nameserver 208.67.220.220#53 Mar 18 15:51:59 volumio dnsmasq[4900]: read /etc/hosts - 5 addresses Mar 18 15:51:59 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Mar 18 15:51:59 volumio dhcpcd[963]: wlan0: soliciting a DHCP lease Mar 18 15:51:59 volumio dhcpcd[963]: wlan0: soliciting an IPv6 router Mar 18 15:52:00 volumio ntpd[1657]: Listen normally on 6 wlan0 192.168.211.1:123 Mar 18 15:52:00 volumio ntpd[1657]: new interface(s) found: waking up resolver Mar 18 15:52:01 volumio volumio[4130]: info: Discovery: Started advertising with name: Volumio Mar 18 15:52:01 volumio volumio[4130]: info: Discovery: adding d38b327e-36c0-4a9f-94be-98c24f61e055 Mar 18 15:52:01 volumio volumio[4130]: info: Discovery: Found device Volumio Mar 18 15:52:01 volumio volumio[4130]: info: CoreCommandRouter::volumioGetState Mar 18 15:52:01 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:52:01 volumio volumio[4130]: info: Discovery: this is already registered, d38b327e-36c0-4a9f-94be-98c24f61e055 Mar 18 15:52:01 volumio volumio[4130]: info: Discovery: Found device Volumio Mar 18 15:52:01 volumio volumio[4130]: info: CoreCommandRouter::volumioGetState Mar 18 15:52:01 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:52:02 volumio volumio[4130]: info: CoreCommandRouter::volumioGetQueue Mar 18 15:52:02 volumio volumio[4130]: info: CoreStateMachine::getQueue Mar 18 15:52:02 volumio volumio[4130]: info: CorePlayQueue::getQueue Mar 18 15:52:04 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Mar 18 15:52:04 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Mar 18 15:52:04 volumio sudo[4916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 18 15:52:04 volumio sudo[4916]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:52:04 volumio sudo[4916]: pam_unix(sudo:session): session closed for user root Mar 18 15:52:04 volumio dhcpcd[963]: wlan0: probing for an IPv4LL address Mar 18 15:52:06 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 18 15:52:06 volumio volumio[4130]: info: CURURI: genres:// Mar 18 15:52:06 volumio volumio[4130]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Mar 18 15:52:06 volumio volumio[4130]: info: Preload queue cleared Mar 18 15:52:09 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Mar 18 15:52:09 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Mar 18 15:52:09 volumio volumio[4130]: info: Refreshing Cached IP Addresses Mar 18 15:52:09 volumio sudo[4984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Mar 18 15:52:09 volumio sudo[4984]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:52:09 volumio sudo[4984]: pam_unix(sudo:session): session closed for user root Mar 18 15:52:09 volumio systemd[1]: upmpdcli.service: Succeeded. Mar 18 15:52:09 volumio sudo[4987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 18 15:52:09 volumio sudo[4987]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:52:09 volumio sudo[4987]: pam_unix(sudo:session): session closed for user root Mar 18 15:52:09 volumio sudo[4990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 18 15:52:09 volumio sudo[4990]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 15:52:09 volumio sudo[4990]: pam_unix(sudo:session): session closed for user root Mar 18 15:52:09 volumio dhcpcd[963]: wlan0: using IPv4LL address 169.254.139.173 Mar 18 15:52:09 volumio dhcpcd[963]: wlan0: adding route to 169.254.0.0/16 Mar 18 15:52:09 volumio avahi-daemon[883]: Registering new address record for 169.254.139.173 on wlan0.IPv4. Mar 18 15:52:10 volumio volumio[4130]: info: Preload queue cleared Mar 18 15:52:10 volumio volumio[4130]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::ClearQueue Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::stop Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::stPlaybackTimer Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::updateTrackBlock Mar 18 15:52:10 volumio volumio[4130]: info: CorePlayQueue::getTrackBlock Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::pushState Mar 18 15:52:10 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:52:10 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 15:52:10 volumio volumio[4130]: info: CoreCommandRouter::volumioPushState Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::serviceStop Mar 18 15:52:10 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:52:10 volumio volumio[4130]: info: CoreCommandRouter::serviceStop Mar 18 15:52:10 volumio volumio[4130]: info: ControllerMpd::stop Mar 18 15:52:10 volumio volumio[4130]: verbose: ControllerMpd::sendMpdCommand stop Mar 18 15:52:10 volumio volumio[4130]: info: CorePlayQueue::clearPlayQueue Mar 18 15:52:10 volumio volumio[4130]: info: CorePlayQueue::saveQueue Mar 18 15:52:10 volumio volumio[4130]: info: CoreCommandRouter::volumioPushQueue Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::addQueueItems Mar 18 15:52:10 volumio volumio[4130]: info: CorePlayQueue::addQueueItems Mar 18 15:52:10 volumio volumio[4130]: info: Preload queue cleared Mar 18 15:52:10 volumio volumio[4130]: info: Adding Item to queue: genres://Popular%20New%20Age Mar 18 15:52:10 volumio volumio[4130]: info: Exploding uri genres://Popular%20New%20Age in service mpd Mar 18 15:52:10 volumio volumio[4130]: info: sendMpdCommand stop took 15 milliseconds Mar 18 15:52:10 volumio volumio[4130]: info: CoreCommandRouter::volumioPushQueue Mar 18 15:52:10 volumio volumio[4130]: info: CorePlayQueue::saveQueue Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::updateTrackBlock Mar 18 15:52:10 volumio volumio[4130]: info: CorePlayQueue::getTrackBlock Mar 18 15:52:10 volumio volumio[4130]: info: CoreCommandRouter::volumioPlay Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::play index 0 Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::stop Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::play index undefined Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 18 15:52:10 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::startPlaybackTimer Mar 18 15:52:10 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:52:10 volumio volumio[4130]: verbose: ControllerMpd::clearAddPlayTracks NAS/server/红音堂 - 響壹 发烧示范碟 2010 北全/01-鄧偉標-《傾國傾城》 西施情殤-驚變.wav Mar 18 15:52:10 volumio volumio[4130]: verbose: ControllerMpd::sendMpdCommand stop Mar 18 15:52:10 volumio volumio[4130]: info: sendMpdCommand stop took 2 milliseconds Mar 18 15:52:10 volumio volumio[4130]: verbose: ControllerMpd::sendMpdCommand clear Mar 18 15:52:10 volumio volumio[4130]: info: Mar 18 15:52:10 volumio volumio[4130]: ---------------------------- MPD announces system playlist update Mar 18 15:52:10 volumio volumio[4130]: info: Ignoring MPD Status Update Mar 18 15:52:10 volumio volumio[4130]: info: sendMpdCommand clear took 1 milliseconds Mar 18 15:52:10 volumio volumio[4130]: verbose: ControllerMpd::sendMpdCommand add "NAS/server/红音堂 - 響壹 发烧示范碟 2010 北全/01-鄧偉標-《傾國傾城》 西施情殤-驚變.wav" Mar 18 15:52:10 volumio volumio[4130]: error: updateQueue error: null Mar 18 15:52:10 volumio volumio[4130]: info: Mar 18 15:52:10 volumio volumio[4130]: ---------------------------- MPD announces system playlist update Mar 18 15:52:10 volumio volumio[4130]: info: Ignoring MPD Status Update Mar 18 15:52:10 volumio volumio[4130]: info: ------------------------------ 2ms Mar 18 15:52:10 volumio volumio[4130]: info: sendMpdCommand add "NAS/server/红音堂 - 響壹 发烧示范碟 2010 北全/01-鄧偉標-《傾國傾城》 西施情殤-驚變.wav" took 2 milliseconds Mar 18 15:52:10 volumio volumio[4130]: verbose: ControllerMpd::sendMpdCommand play Mar 18 15:52:10 volumio volumio[4130]: info: ------------------------------ 2ms Mar 18 15:52:10 volumio volumio[4130]: info: sendMpdCommand play took 1 milliseconds Mar 18 15:52:10 volumio volumio[4130]: info: Mar 18 15:52:10 volumio volumio[4130]: ---------------------------- MPD announces state update: player Mar 18 15:52:10 volumio volumio[4130]: info: ControllerMpd::getState Mar 18 15:52:10 volumio volumio[4130]: verbose: ControllerMpd::sendMpdCommand status Mar 18 15:52:10 volumio volumio[4130]: info: Mar 18 15:52:10 volumio volumio[4130]: ---------------------------- MPD announces state update: player Mar 18 15:52:10 volumio volumio[4130]: info: sendMpdCommand status took 2 milliseconds Mar 18 15:52:10 volumio volumio[4130]: info: ControllerMpd::getState Mar 18 15:52:10 volumio volumio[4130]: verbose: ControllerMpd::sendMpdCommand status Mar 18 15:52:10 volumio volumio[4130]: verbose: ControllerMpd::parseState Mar 18 15:52:10 volumio volumio[4130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 15:52:10 volumio volumio[4130]: info: sendMpdCommand status took 2 milliseconds Mar 18 15:52:10 volumio volumio[4130]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 18 15:52:10 volumio volumio[4130]: verbose: ControllerMpd::parseState Mar 18 15:52:10 volumio volumio[4130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 15:52:10 volumio volumio[4130]: verbose: ControllerMpd::parseTrackInfo Mar 18 15:52:10 volumio volumio[4130]: info: ControllerMpd::pushState Mar 18 15:52:10 volumio volumio[4130]: info: CoreCommandRouter::servicePushState Mar 18 15:52:10 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:52:10 volumio volumio[4130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":363,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"《傾國傾城》 西施情殤-驚變","artist":"鄧偉標","album":"红音堂 - 響壹 发烧示范碟 2010 北全","uri":"NAS/server/红音堂 - 響壹 发烧示范碟 2010 北全/01-鄧偉標-《傾國傾城》 西施情殤-驚變.wav","trackType":"wav"} Mar 18 15:52:10 volumio volumio[4130]: verbose: CURRENT POSITION 0 Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::syncState stateService play Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::syncState currentStatus stop Mar 18 15:52:10 volumio volumio[4130]: info: ------------------------------ 8ms Mar 18 15:52:10 volumio volumio[4130]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 18 15:52:10 volumio volumio[4130]: verbose: ControllerMpd::parseTrackInfo Mar 18 15:52:10 volumio volumio[4130]: info: ControllerMpd::pushState Mar 18 15:52:10 volumio volumio[4130]: info: CoreCommandRouter::servicePushState Mar 18 15:52:10 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:52:10 volumio volumio[4130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":363,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"《傾國傾城》 西施情殤-驚變","artist":"鄧偉標","album":"红音堂 - 響壹 发烧示范碟 2010 北全","uri":"NAS/server/红音堂 - 響壹 发烧示范碟 2010 北全/01-鄧偉標-《傾國傾城》 西施情殤-驚變.wav","trackType":"wav"} Mar 18 15:52:10 volumio volumio[4130]: verbose: CURRENT POSITION 0 Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::syncState stateService play Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::syncState currentStatus play Mar 18 15:52:10 volumio volumio[4130]: info: Received an update from plugin. extracting info from payload Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::pushState Mar 18 15:52:10 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:52:10 volumio volumio[4130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 15:52:10 volumio volumio[4130]: info: CoreCommandRouter::volumioPushState Mar 18 15:52:10 volumio volumio[4130]: info: CoreStateMachine::pushState Mar 18 15:52:10 volumio volumio[4130]: info: CorePlayQueue::getTrack 0 Mar 18 15:52:10 volumio volumio[4130]: info: CoreCommandRouter::volumioPushState Mar 18 15:52:10 volumio volumio[4130]: info: ------------------------------ 27ms Mar 18 15:52:11 volumio ntpd[1657]: Listen normally on 7 wlan0 169.254.139.173:123 Mar 18 15:52:11 volumio ntpd[1657]: new interface(s) found: waking up resolver Mar 18 15:52:12 volumio volumio[4130]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 18 15:52:12 volumio volumio[4130]: Error: connect ETIMEDOUT 199.16.158.182:80 Mar 18 15:52:12 volumio volumio[4130]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 18 15:52:12 volumio volumio[4130]: errno: -110, Mar 18 15:52:12 volumio volumio[4130]: code: 'ETIMEDOUT', Mar 18 15:52:12 volumio volumio[4130]: syscall: 'connect', Mar 18 15:52:12 volumio volumio[4130]: address: '199.16.158.182', Mar 18 15:52:12 volumio volumio[4130]: port: 80 Mar 18 15:52:12 volumio volumio[4130]: } Mar 18 15:52:12 volumio volumio[4130]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 18 15:52:12 volumio sudo[5021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-18 15:51 Mar 18 15:52:12 volumio sudo[5021]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"