-- Logs begin at Thu 2025-04-24 19:01:18 CST, end at Thu 2025-04-24 19:48:41 CST. -- Apr 24 19:47:07 thinkpadhome volumio[8885]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 19:47:07 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 24 19:47:07 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 24 19:47:07 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 24 19:47:07 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 24 19:47:07 thinkpadhome volumio[8885]: info: Received Get System Version Apr 24 19:47:07 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 24 19:47:25 thinkpadhome volumio[8885]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 24 19:47:25 thinkpadhome volumio[8885]: info: Retrying Login Due to Network Error Apr 24 19:47:26 thinkpadhome volumio-remote-updater[772]: No test mode Apr 24 19:47:26 thinkpadhome volumio-remote-updater[772]: No alpha test mode Apr 24 19:47:27 thinkpadhome volumio[8885]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 24 19:47:27 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 24 19:47:30 thinkpadhome volumio[8885]: info: CoreCommandRouter::Close All Modals sent Apr 24 19:47:35 thinkpadhome volumio[8885]: info: MyVolumio login type: Token Apr 24 19:47:44 thinkpadhome wpa_supplicant[1042]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 24 19:47:44 thinkpadhome dhcpcd[861]: wlan0: carrier lost Apr 24 19:47:44 thinkpadhome avahi-daemon[773]: Withdrawing address record for 192.168.3.98 on wlan0. Apr 24 19:47:44 thinkpadhome avahi-daemon[773]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.98. Apr 24 19:47:44 thinkpadhome avahi-daemon[773]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 24 19:47:44 thinkpadhome wpa_supplicant[1042]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c0:b4:7d:45:91:f4 reason=4 locally_generated=1 Apr 24 19:47:44 thinkpadhome wpa_supplicant[1042]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Apr 24 19:47:44 thinkpadhome wpa_supplicant[1042]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Apr 24 19:47:44 thinkpadhome dhcpcd[861]: wlan0: deleting route to 192.168.3.0/24 Apr 24 19:47:44 thinkpadhome volumio[8885]: info: Discovery: A device disappeared from network Apr 24 19:47:44 thinkpadhome volumio[8885]: info: Discovery: Device thinkpadhome disappeared from network Apr 24 19:47:44 thinkpadhome dhcpcd[861]: wlan0: deleting default route via 192.168.3.1 Apr 24 19:47:44 thinkpadhome dhcpcd[861]: wlan0: dhcp_envoption 213: Operation not supported Apr 24 19:47:44 thinkpadhome dhcpcd[861]: wlan0: dhcp_envoption 213: Operation not supported Apr 24 19:47:45 thinkpadhome wpa_supplicant[1042]: wlan0: SME: Trying to authenticate with c0:b4:7d:45:91:f0 (SSID='HUAWEI-10GO5H' freq=2462 MHz) Apr 24 19:47:45 thinkpadhome kernel: wlan0: authenticate with c0:b4:7d:45:91:f0 Apr 24 19:47:45 thinkpadhome kernel: wlan0: 80 MHz not supported, disabling VHT Apr 24 19:47:45 thinkpadhome wpa_supplicant[1042]: wlan0: Trying to associate with c0:b4:7d:45:91:f0 (SSID='HUAWEI-10GO5H' freq=2462 MHz) Apr 24 19:47:45 thinkpadhome kernel: wlan0: send auth to c0:b4:7d:45:91:f0 (try 1/3) Apr 24 19:47:45 thinkpadhome kernel: wlan0: authenticated Apr 24 19:47:45 thinkpadhome kernel: wlan0: associate with c0:b4:7d:45:91:f0 (try 1/3) Apr 24 19:47:45 thinkpadhome kernel: wlan0: associate with c0:b4:7d:45:91:f0 (try 2/3) Apr 24 19:47:45 thinkpadhome kernel: wlan0: associate with c0:b4:7d:45:91:f0 (try 3/3) Apr 24 19:47:45 thinkpadhome kernel: wlan0: association with c0:b4:7d:45:91:f0 timed out Apr 24 19:47:47 thinkpadhome ntpd[1274]: Deleting interface #4 wlan0, 192.168.3.98#123, interface stats: received=0, sent=0, dropped=0, active_time=2757 secs Apr 24 19:47:47 thinkpadhome wpa_supplicant[1042]: wlan0: SME: Trying to authenticate with c0:b4:7d:45:91:f4 (SSID='HUAWEI-10GO5H' freq=5745 MHz) Apr 24 19:47:47 thinkpadhome kernel: wlan0: authenticate with c0:b4:7d:45:91:f4 Apr 24 19:47:47 thinkpadhome kernel: wlan0: send auth to c0:b4:7d:45:91:f4 (try 1/3) Apr 24 19:47:47 thinkpadhome kernel: wlan0: send auth to c0:b4:7d:45:91:f4 (try 2/3) Apr 24 19:47:47 thinkpadhome kernel: wlan0: send auth to c0:b4:7d:45:91:f4 (try 3/3) Apr 24 19:47:47 thinkpadhome kernel: wlan0: authentication with c0:b4:7d:45:91:f4 timed out Apr 24 19:47:48 thinkpadhome nmbd[915]: [2025/04/24 19:47:48.554516, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 24 19:47:48 thinkpadhome nmbd[915]: Packet send failed to 192.168.3.255(138) ERRNO=Network is unreachable Apr 24 19:47:51 thinkpadhome volumio[8885]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 19:47:51 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 24 19:47:51 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 19:47:51 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 19:47:51 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 19:47:51 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 19:47:51 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 19:47:51 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 19:47:51 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 19:47:51 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 19:47:51 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 24 19:47:51 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 24 19:47:53 thinkpadhome volumio[8885]: info: Volumio Network Manager: Network status updated: 1 Apr 24 19:47:54 thinkpadhome wpa_supplicant[1042]: wlan0: SME: Trying to authenticate with c0:b4:7d:45:91:f4 (SSID='HUAWEI-10GO5H' freq=5745 MHz) Apr 24 19:47:54 thinkpadhome kernel: wlan0: authenticate with c0:b4:7d:45:91:f4 Apr 24 19:47:54 thinkpadhome kernel: wlan0: send auth to c0:b4:7d:45:91:f4 (try 1/3) Apr 24 19:47:54 thinkpadhome wpa_supplicant[1042]: wlan0: Trying to associate with c0:b4:7d:45:91:f4 (SSID='HUAWEI-10GO5H' freq=5745 MHz) Apr 24 19:47:54 thinkpadhome kernel: wlan0: authenticated Apr 24 19:47:54 thinkpadhome kernel: wlan0: waiting for beacon from c0:b4:7d:45:91:f4 Apr 24 19:47:54 thinkpadhome kernel: wlan0: associate with c0:b4:7d:45:91:f4 (try 1/3) Apr 24 19:47:54 thinkpadhome kernel: wlan0: RX AssocResp from c0:b4:7d:45:91:f4 (capab=0x1531 status=0 aid=3) Apr 24 19:47:54 thinkpadhome kernel: wlan0: associated Apr 24 19:47:54 thinkpadhome wpa_supplicant[1042]: wlan0: Associated with c0:b4:7d:45:91:f4 Apr 24 19:47:54 thinkpadhome wpa_supplicant[1042]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 24 19:47:54 thinkpadhome wpa_supplicant[1042]: wlan0: WPA: Key negotiation completed with c0:b4:7d:45:91:f4 [PTK=CCMP GTK=TKIP] Apr 24 19:47:54 thinkpadhome wpa_supplicant[1042]: wlan0: CTRL-EVENT-CONNECTED - Connection to c0:b4:7d:45:91:f4 completed [id=0 id_str=] Apr 24 19:47:54 thinkpadhome dhcpcd[861]: wlan0: carrier acquired Apr 24 19:47:54 thinkpadhome dhcpcd[861]: wlan0: IAID c6:7b:64:30 Apr 24 19:47:55 thinkpadhome dhcpcd[861]: wlan0: rebinding lease of 192.168.3.98 Apr 24 19:47:55 thinkpadhome dhcpcd[861]: wlan0: NAK: Invalid Request Message from 192.168.3.1 Apr 24 19:47:55 thinkpadhome dhcpcd[861]: wlan0: message: Invalid Request Message Apr 24 19:47:55 thinkpadhome dhcpcd[861]: wlan0: soliciting a DHCP lease Apr 24 19:47:55 thinkpadhome dhcpcd[861]: wlan0: offered 192.168.3.248 from 192.168.3.1 Apr 24 19:47:55 thinkpadhome dhcpcd[861]: wlan0: probing address 192.168.3.248/24 Apr 24 19:47:55 thinkpadhome dhcpcd[861]: wlan0: soliciting an IPv6 router Apr 24 19:48:00 thinkpadhome dhcpcd[861]: wlan0: leased 192.168.3.248 for 86400 seconds Apr 24 19:48:00 thinkpadhome dhcpcd[861]: wlan0: adding route to 192.168.3.0/24 Apr 24 19:48:00 thinkpadhome dhcpcd[861]: wlan0: adding default route via 192.168.3.1 Apr 24 19:48:00 thinkpadhome dhcpcd[861]: wlan0: dhcp_envoption 213: Operation not supported Apr 24 19:48:00 thinkpadhome dhcpcd[861]: wlan0: dhcp_envoption 213: Operation not supported Apr 24 19:48:00 thinkpadhome avahi-daemon[773]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.248. Apr 24 19:48:00 thinkpadhome avahi-daemon[773]: New relevant interface wlan0.IPv4 for mDNS. Apr 24 19:48:00 thinkpadhome avahi-daemon[773]: Registering new address record for 192.168.3.248 on wlan0.IPv4. Apr 24 19:48:00 thinkpadhome ntpd[1274]: ntpd exiting on signal 15 (Terminated) Apr 24 19:48:00 thinkpadhome systemd[1]: Stopping Network Time Service... Apr 24 19:48:00 thinkpadhome ntpd[1274]: 162.159.200.1 local addr 192.168.3.247 -> Apr 24 19:48:00 thinkpadhome ntpd[1274]: 162.159.200.123 local addr 192.168.3.247 -> Apr 24 19:48:00 thinkpadhome ntpd[1274]: 193.182.111.12 local addr 192.168.3.247 -> Apr 24 19:48:00 thinkpadhome ntpd[1274]: 193.182.111.14 local addr 192.168.3.247 -> Apr 24 19:48:00 thinkpadhome ntpd[1274]: 193.182.111.143 local addr 192.168.3.247 -> Apr 24 19:48:00 thinkpadhome ntpd[1274]: 193.182.111.141 local addr 192.168.3.247 -> Apr 24 19:48:00 thinkpadhome systemd[1]: ntp.service: Succeeded. Apr 24 19:48:00 thinkpadhome systemd[1]: Stopped Network Time Service. Apr 24 19:48:00 thinkpadhome systemd[1]: Starting Network Time Service... Apr 24 19:48:00 thinkpadhome ntpd[10121]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 24 19:48:00 thinkpadhome ntpd[10121]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 24 19:48:00 thinkpadhome systemd[1]: Started Network Time Service. Apr 24 19:48:00 thinkpadhome ntpd[10127]: proto: precision = 0.113 usec (-23) Apr 24 19:48:00 thinkpadhome ntpd[10127]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 24 19:48:00 thinkpadhome ntpd[10127]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 24 19:48:00 thinkpadhome ntpd[10127]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 849 days ago Apr 24 19:48:00 thinkpadhome ntpd[10127]: Listen and drop on 0 v6wildcard [::]:123 Apr 24 19:48:00 thinkpadhome ntpd[10127]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 24 19:48:00 thinkpadhome ntpd[10127]: Listen normally on 2 lo 127.0.0.1:123 Apr 24 19:48:00 thinkpadhome ntpd[10127]: Listen normally on 3 eth0 192.168.3.247:123 Apr 24 19:48:00 thinkpadhome ntpd[10127]: Listen normally on 4 wlan0 192.168.3.248:123 Apr 24 19:48:00 thinkpadhome ntpd[10127]: Listening on routing socket on fd #21 for interface updates Apr 24 19:48:00 thinkpadhome ntpd[10127]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 24 19:48:00 thinkpadhome ntpd[10127]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 24 19:48:01 thinkpadhome ntpd[10127]: Soliciting pool server 116.203.151.74 Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 24 19:48:01 thinkpadhome volumio[8885]: info: Preparing to save Alsa Options, stopping services first Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreCommandRouter::volumioGetState Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreCommandRouter::volumioPause Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreStateMachine::pause Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreStateMachine::stPlaybackTimer Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreStateMachine::servicePause Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CorePlayQueue::getTrack 0 Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreCommandRouter::servicePause Apr 24 19:48:01 thinkpadhome volumio[8885]: info: [1745495281791] ControllerUPNPBrowser::pause Apr 24 19:48:01 thinkpadhome volumio[8885]: verbose: ControllerMpd::sendMpdCommand pause Apr 24 19:48:01 thinkpadhome volumio[8885]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Out"}} Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 19:48:01 thinkpadhome volumio[8885]: info: Found match in Cards Database: setting mixer for card Analog Out Apr 24 19:48:01 thinkpadhome volumio[8885]: info: Setting mixer Master for card Analog Out Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 24 19:48:01 thinkpadhome volumio[8885]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 24 19:48:01 thinkpadhome volumio[8885]: info: Disabling external Volume Control Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 19:48:01 thinkpadhome volumio[8885]: info: Preparing to generate the ALSA configuration file Apr 24 19:48:01 thinkpadhome volumio[8885]: info: Apr 24 19:48:01 thinkpadhome volumio[8885]: ---------------------------- MPD announces state update: player Apr 24 19:48:01 thinkpadhome volumio[8885]: info: sendMpdCommand pause took 184 milliseconds Apr 24 19:48:01 thinkpadhome volumio[8885]: info: ControllerMpd::getState Apr 24 19:48:01 thinkpadhome volumio[8885]: verbose: ControllerMpd::sendMpdCommand status Apr 24 19:48:01 thinkpadhome volumio[8885]: info: sendMpdCommand status took 1 milliseconds Apr 24 19:48:01 thinkpadhome volumio[8885]: verbose: ControllerMpd::parseState Apr 24 19:48:01 thinkpadhome volumio[8885]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 19:48:01 thinkpadhome volumio[8885]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 24 19:48:01 thinkpadhome volumio[8885]: verbose: ControllerMpd::parseTrackInfo Apr 24 19:48:01 thinkpadhome volumio[8885]: info: ControllerMpd::pushState Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreCommandRouter::servicePushState Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CorePlayQueue::getTrack 0 Apr 24 19:48:01 thinkpadhome volumio[8885]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":56045,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"download.dsf","artist":null,"album":null,"uri":"http://192.168.3.220:9000/music/88723/download.dsf","trackType":"dsf"} Apr 24 19:48:01 thinkpadhome volumio[8885]: verbose: CURRENT POSITION 0 Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreStateMachine::syncState stateService pause Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreStateMachine::syncState currentStatus pause Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreStateMachine::pushState Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 19:48:01 thinkpadhome volumio[8885]: info: CoreCommandRouter::volumioPushState Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreStateMachine::stPlaybackTimer Apr 24 19:48:02 thinkpadhome volumio[8885]: info: ------------------------------ 32ms Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 24 19:48:02 thinkpadhome volumio[8885]: info: VolumeController:: Volume=40 Mute =false Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreStateMachine::pushState Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::volumioPushState Apr 24 19:48:02 thinkpadhome volumio[8885]: info: Asound.conf file written Apr 24 19:48:02 thinkpadhome sudo[10145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 24 19:48:02 thinkpadhome sudo[10145]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 19:48:02 thinkpadhome sudo[10145]: pam_unix(sudo:session): session closed for user root Apr 24 19:48:02 thinkpadhome volumio[8885]: info: Output device has changed, restarting MPD Apr 24 19:48:02 thinkpadhome sudo[10150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 24 19:48:02 thinkpadhome sudo[10150]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 19:48:02 thinkpadhome sudo[10150]: pam_unix(sudo:session): session closed for user root Apr 24 19:48:02 thinkpadhome volumio[8885]: info: Output device has changed, restarting Shairport Sync Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 19:48:02 thinkpadhome sudo[10153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 24 19:48:02 thinkpadhome sudo[10153]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 19:48:02 thinkpadhome systemd[1]: Stopping Music Player Daemon... Apr 24 19:48:02 thinkpadhome volumio[8885]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 24 19:48:02 thinkpadhome systemd[1]: mpd.service: Succeeded. Apr 24 19:48:02 thinkpadhome systemd[1]: Stopped Music Player Daemon. Apr 24 19:48:02 thinkpadhome volumio[8885]: info: Output device has changed, restarting MPD Apr 24 19:48:02 thinkpadhome systemd[1]: Starting Music Player Daemon... Apr 24 19:48:02 thinkpadhome sudo[10160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 24 19:48:02 thinkpadhome sudo[10160]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 19:48:02 thinkpadhome sudo[10160]: pam_unix(sudo:session): session closed for user root Apr 24 19:48:02 thinkpadhome volumio[8885]: info: Output device has changed, restarting Shairport Sync Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 19:48:02 thinkpadhome sudo[10163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 24 19:48:02 thinkpadhome sudo[10163]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 19:48:02 thinkpadhome systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 24 19:48:02 thinkpadhome systemd[1]: mpd.service: Succeeded. Apr 24 19:48:02 thinkpadhome systemd[1]: Stopped Music Player Daemon. Apr 24 19:48:02 thinkpadhome volumio[8885]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 24 19:48:02 thinkpadhome systemd[1]: Starting Music Player Daemon... Apr 24 19:48:02 thinkpadhome volumio[8885]: info: MPD Permissions set Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 19:48:02 thinkpadhome sudo[10168]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 24 19:48:02 thinkpadhome sudo[10168]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 19:48:02 thinkpadhome sudo[10168]: pam_unix(sudo:session): session closed for user root Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 19:48:02 thinkpadhome volumio[8885]: info: Starting Shairport Sync Apr 24 19:48:02 thinkpadhome volumio[8885]: info: Starting Shairport Sync Apr 24 19:48:02 thinkpadhome sudo[10184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 19:48:02 thinkpadhome sudo[10186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 19:48:02 thinkpadhome sudo[10186]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 19:48:02 thinkpadhome sudo[10184]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 19:48:02 thinkpadhome systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 19:48:02 thinkpadhome systemd[1]: shairport-sync.service: Succeeded. Apr 24 19:48:02 thinkpadhome systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 24 19:48:02 thinkpadhome systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 19:48:02 thinkpadhome sudo[10186]: pam_unix(sudo:session): session closed for user root Apr 24 19:48:02 thinkpadhome volumio[8885]: info: Shairport-Sync Started Apr 24 19:48:02 thinkpadhome systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 19:48:02 thinkpadhome systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 24 19:48:02 thinkpadhome systemd[1]: shairport-sync.service: Succeeded. Apr 24 19:48:02 thinkpadhome volumio[8885]: info: Discovery: adding e67af152-3b73-4568-ac2f-1bd458bdf15d Apr 24 19:48:02 thinkpadhome volumio[8885]: info: Discovery: Found device thinkpadhome Apr 24 19:48:02 thinkpadhome volumio[8885]: info: CoreCommandRouter::volumioGetState Apr 24 19:48:02 thinkpadhome systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 24 19:48:02 thinkpadhome systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 19:48:02 thinkpadhome sudo[10184]: pam_unix(sudo:session): session closed for user root Apr 24 19:48:02 thinkpadhome volumio[8885]: info: Shairport-Sync Started Apr 24 19:48:02 thinkpadhome ntpd[10127]: Soliciting pool server 78.46.102.180 Apr 24 19:48:02 thinkpadhome ntpd[10127]: Soliciting pool server 193.182.111.14 Apr 24 19:48:03 thinkpadhome volumio[8885]: info: Volumio Network Manager: Network status updated: 3 Apr 24 19:48:03 thinkpadhome mpd[10177]: Apr 24 19:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 24 19:48:03 thinkpadhome systemd[1]: Started Music Player Daemon. Apr 24 19:48:03 thinkpadhome sudo[10153]: pam_unix(sudo:session): session closed for user root Apr 24 19:48:03 thinkpadhome sudo[10163]: pam_unix(sudo:session): session closed for user root Apr 24 19:48:03 thinkpadhome volumio[8885]: error: MPD error: The expression evaluated to a falsy value: Apr 24 19:48:03 thinkpadhome volumio[8885]: assert.ok(self.idling) Apr 24 19:48:03 thinkpadhome volumio[8885]: error: The expression evaluated to a falsy value: Apr 24 19:48:03 thinkpadhome volumio[8885]: assert.ok(self.idling) Apr 24 19:48:03 thinkpadhome volumio[8885]: error: updateQueue error: null Apr 24 19:48:03 thinkpadhome ntpd[10127]: Soliciting pool server 162.159.200.1 Apr 24 19:48:03 thinkpadhome ntpd[10127]: Soliciting pool server 84.16.67.12 Apr 24 19:48:04 thinkpadhome ntpd[10127]: Soliciting pool server 193.182.111.12 Apr 24 19:48:04 thinkpadhome ntpd[10127]: Soliciting pool server 45.76.221.157 Apr 24 19:48:05 thinkpadhome ntpd[10127]: Soliciting pool server 193.182.111.143 Apr 24 19:48:05 thinkpadhome ntpd[10127]: Soliciting pool server 162.159.200.123 Apr 24 19:48:05 thinkpadhome volumio[8885]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 24 19:48:05 thinkpadhome volumio[8885]: info: Retrying Login Due to Network Error Apr 24 19:48:05 thinkpadhome volumio[8885]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 24 19:48:05 thinkpadhome volumio[8885]: info: CoreStateMachine::getcurrentVolume Apr 24 19:48:05 thinkpadhome volumio[8885]: info: CoreCommandRouter::volumioRetrievevolume Apr 24 19:48:06 thinkpadhome volumio[8885]: info: VolumeController:: Volume=40 Mute =false Apr 24 19:48:06 thinkpadhome volumio[8885]: info: CoreStateMachine::pushState Apr 24 19:48:06 thinkpadhome volumio[8885]: info: CoreCommandRouter::volumioPushState Apr 24 19:48:06 thinkpadhome volumio[8885]: info: CoreStateMachine::updateTrackBlock Apr 24 19:48:06 thinkpadhome volumio[8885]: info: CorePlayQueue::getTrackBlock Apr 24 19:48:06 thinkpadhome volumio[8885]: info: CoreCommandRouter::volumioRetrievevolume Apr 24 19:48:06 thinkpadhome volumio[8885]: info: VolumeController:: Volume=40 Mute =false Apr 24 19:48:06 thinkpadhome volumio[8885]: info: CoreStateMachine::pushState Apr 24 19:48:06 thinkpadhome volumio[8885]: info: CoreCommandRouter::volumioPushState Apr 24 19:48:06 thinkpadhome ntpd[10127]: Soliciting pool server 193.182.111.141 Apr 24 19:48:07 thinkpadhome ntpd[10127]: Soliciting pool server 202.118.1.81 Apr 24 19:48:15 thinkpadhome volumio[8885]: info: MyVolumio login type: Token Apr 24 19:48:20 thinkpadhome volumio[8885]: info: CoreCommandRouter::volumioPlay Apr 24 19:48:20 thinkpadhome volumio[8885]: info: CoreStateMachine::play index undefined Apr 24 19:48:20 thinkpadhome volumio[8885]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 19:48:20 thinkpadhome volumio[8885]: info: CorePlayQueue::getTrack 0 Apr 24 19:48:20 thinkpadhome volumio[8885]: info: CoreStateMachine::startPlaybackTimer Apr 24 19:48:20 thinkpadhome volumio[8885]: info: CorePlayQueue::getTrack 0 Apr 24 19:48:20 thinkpadhome volumio[8885]: info: [1745495300725] ControllerUPNPBrowser::resume Apr 24 19:48:20 thinkpadhome volumio[8885]: verbose: ControllerMpd::sendMpdCommand play Apr 24 19:48:20 thinkpadhome volumio[8885]: info: sendMpdCommand play took 0 milliseconds Apr 24 19:48:29 thinkpadhome volumio[8885]: info: CoreCommandRouter::volumioPlay Apr 24 19:48:29 thinkpadhome volumio[8885]: info: CoreStateMachine::play index undefined Apr 24 19:48:29 thinkpadhome volumio[8885]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 19:48:29 thinkpadhome volumio[8885]: info: CorePlayQueue::getTrack 0 Apr 24 19:48:29 thinkpadhome volumio[8885]: info: CoreStateMachine::startPlaybackTimer Apr 24 19:48:29 thinkpadhome volumio[8885]: info: CorePlayQueue::getTrack 0 Apr 24 19:48:29 thinkpadhome volumio[8885]: info: [1745495309762] ControllerUPNPBrowser::resume Apr 24 19:48:29 thinkpadhome volumio[8885]: verbose: ControllerMpd::sendMpdCommand play Apr 24 19:48:29 thinkpadhome volumio[8885]: info: sendMpdCommand play took 0 milliseconds Apr 24 19:48:37 thinkpadhome volumio[8885]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 24 19:48:37 thinkpadhome volumio[8885]: info: Preload queue cleared Apr 24 19:48:41 thinkpadhome volumio[8885]: info: CoreCommandRouter::volumioPlay Apr 24 19:48:41 thinkpadhome volumio[8885]: info: CoreStateMachine::play index undefined Apr 24 19:48:41 thinkpadhome volumio[8885]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 19:48:41 thinkpadhome volumio[8885]: info: CorePlayQueue::getTrack 0 Apr 24 19:48:41 thinkpadhome volumio[8885]: info: CoreStateMachine::startPlaybackTimer Apr 24 19:48:41 thinkpadhome volumio[8885]: info: CorePlayQueue::getTrack 0 Apr 24 19:48:41 thinkpadhome volumio[8885]: info: [1745495321335] ControllerUPNPBrowser::resume Apr 24 19:48:41 thinkpadhome volumio[8885]: verbose: ControllerMpd::sendMpdCommand play Apr 24 19:48:41 thinkpadhome volumio[8885]: info: sendMpdCommand play took 0 milliseconds Apr 24 19:48:41 thinkpadhome volumio[8885]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 19:48:41 thinkpadhome volumio[8885]: Error: connect ETIMEDOUT 103.240.182.55:80 Apr 24 19:48:41 thinkpadhome volumio[8885]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 24 19:48:41 thinkpadhome volumio[8885]: errno: -110, Apr 24 19:48:41 thinkpadhome volumio[8885]: code: 'ETIMEDOUT', Apr 24 19:48:41 thinkpadhome volumio[8885]: syscall: 'connect', Apr 24 19:48:41 thinkpadhome volumio[8885]: address: '103.240.182.55', Apr 24 19:48:41 thinkpadhome volumio[8885]: port: 80 Apr 24 19:48:41 thinkpadhome volumio[8885]: } Apr 24 19:48:41 thinkpadhome volumio[8885]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 19:48:41 thinkpadhome sudo[10318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-24 19:47 Apr 24 19:48:41 thinkpadhome sudo[10318]: 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="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"