Jul 11 09:51:01 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 09:51:01 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 09:51:01 volumio volumio[1326]: info: Discovery: Getting this device information Jul 11 09:51:01 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Jul 11 09:51:01 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 09:51:01 volumio volumio[1326]: verbose: New Socket.io Connection to 192.168.211.1:3000 from 192.168.211.183 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jul 11 09:51:01 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Jul 11 09:51:01 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 11 09:51:01 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 11 09:51:19 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 11 09:51:54 volumio volumio[1326]: info: CoreCommandRouter::volumioPlay Jul 11 09:51:54 volumio volumio[1326]: info: CoreStateMachine::play index undefined Jul 11 09:51:54 volumio volumio[1326]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 09:51:54 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Jul 11 09:51:54 volumio volumio[1326]: info: CoreStateMachine::startPlaybackTimer Jul 11 09:51:54 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Jul 11 09:51:54 volumio volumio[1326]: info: [1752202314436] ControllerWebradio::clearAddPlayTrack Jul 11 09:51:54 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand stop Jul 11 09:51:54 volumio volumio[1326]: info: sendMpdCommand stop took 2 milliseconds Jul 11 09:51:54 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand clear Jul 11 09:51:54 volumio volumio[1326]: info: Jul 11 09:51:54 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Jul 11 09:51:54 volumio volumio[1326]: info: Ignoring MPD Status Update Jul 11 09:51:54 volumio volumio[1326]: info: sendMpdCommand clear took 1 milliseconds Jul 11 09:51:54 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand load "https://radio.vpsthai.net/proxy/dwhyntou/stream" Jul 11 09:51:54 volumio volumio[1326]: error: updateQueue error: null Jul 11 09:51:54 volumio volumio[1326]: info: ------------------------------ 3ms Jul 11 09:51:54 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 11 09:51:54 volumio dbus-daemon[589]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.50' (uid=0 pid=3610 comm="timedatectl show --property=NTPSynchronized --valu") Jul 11 09:51:54 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 11 09:51:54 volumio dbus-daemon[589]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 11 09:51:54 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 11 09:51:54 volumio setdatetime-helper.sh[3608]: Time is already synchronized. Jul 11 09:51:54 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 11 09:51:54 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 11 09:51:59 volumio kernel: hwmon hwmon1: Undervoltage detected! Jul 11 09:52:02 volumio volumio[1326]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 09:52:02 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 11 09:52:03 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand add "https://radio.vpsthai.net/proxy/dwhyntou/stream" Jul 11 09:52:03 volumio volumio[1326]: info: Jul 11 09:52:03 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Jul 11 09:52:03 volumio volumio[1326]: info: Ignoring MPD Status Update Jul 11 09:52:03 volumio volumio[1326]: info: sendMpdCommand add "https://radio.vpsthai.net/proxy/dwhyntou/stream" took 4 milliseconds Jul 11 09:52:03 volumio volumio[1326]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 11 09:52:03 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand play Jul 11 09:52:03 volumio volumio[1326]: info: ------------------------------ 6ms Jul 11 09:52:03 volumio volumio[1326]: info: sendMpdCommand play took 4 milliseconds Jul 11 09:52:03 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jul 11 09:52:03 volumio sudo[3638]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jul 11 09:52:03 volumio sudo[3638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:03 volumio sudo[3638]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:03 volumio sudo[3651]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 11 09:52:03 volumio sudo[3644]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 11 09:52:03 volumio sudo[3644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:03 volumio sudo[3651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:03 volumio sudo[3644]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:03 volumio sudo[3651]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:03 volumio sudo[3662]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 11 09:52:03 volumio sudo[3660]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 11 09:52:03 volumio sudo[3662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:03 volumio sudo[3660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:03 volumio sudo[3655]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 11 09:52:03 volumio sudo[3655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:03 volumio sudo[3662]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:03 volumio sudo[3660]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:03 volumio sudo[3655]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:03 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 11 09:52:03 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 11 09:52:03 volumio sudo[3671]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 11 09:52:03 volumio sudo[3671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:03 volumio sudo[3671]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:03 volumio kernel: ieee80211 phy0: brcmf_vif_set_mgmt_ie: vndr ie set error : -52 Jul 11 09:52:03 volumio kernel: ieee80211 phy0: brcmf_cfg80211_scan: scan error (-52) Jul 11 09:52:03 volumio volumio[1326]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jul 11 09:52:03 volumio volumio[1326]: wlan0 Interface doesn't support scanning : Invalid exchange Jul 11 09:52:03 volumio volumio[1326]: info: Cannot use regular scanning, forcing with ap-force Jul 11 09:52:03 volumio sudo[3675]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jul 11 09:52:03 volumio sudo[3675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:07 volumio sudo[3675]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:07 volumio volumio[1326]: Upnp client error: Error: This socket has been ended by the other party Jul 11 09:52:13 volumio volumio[1326]: info: Jul 11 09:52:13 volumio volumio[1326]: ---------------------------- MPD announces state update: player Jul 11 09:52:13 volumio volumio[1326]: info: ControllerMpd::getState Jul 11 09:52:13 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Jul 11 09:52:13 volumio volumio[1326]: info: Jul 11 09:52:13 volumio volumio[1326]: ---------------------------- MPD announces state update: player Jul 11 09:52:13 volumio volumio[1326]: info: ControllerMpd::getState Jul 11 09:52:13 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Jul 11 09:52:13 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 11 09:52:13 volumio volumio[1326]: info: sendMpdCommand status took 6 milliseconds Jul 11 09:52:13 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 11 09:52:13 volumio volumio[1326]: info: sendMpdCommand status took 5 milliseconds Jul 11 09:52:13 volumio volumio[1326]: verbose: ControllerMpd::parseState Jul 11 09:52:13 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 09:52:13 volumio volumio[1326]: verbose: ControllerMpd::parseState Jul 11 09:52:13 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 09:52:13 volumio volumio[1326]: info: sendMpdCommand clearerror took 11 milliseconds Jul 11 09:52:13 volumio volumio[1326]: info: sendMpdCommand clearerror took 10 milliseconds Jul 11 09:52:13 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 11 09:52:13 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 11 09:52:13 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Jul 11 09:52:13 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Jul 11 09:52:13 volumio volumio[1326]: info: ControllerMpd::pushState Jul 11 09:52:13 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Jul 11 09:52:13 volumio volumio[1326]: info: CoreStateMachine::pushState Jul 11 09:52:13 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 09:52:13 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Jul 11 09:52:13 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Jul 11 09:52:13 volumio volumio[1326]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://radio.vpsthai.net/proxy/dwhyntou/stream","trackType":""} Jul 11 09:52:13 volumio volumio[1326]: verbose: CURRENT POSITION 0 Jul 11 09:52:13 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Jul 11 09:52:13 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Jul 11 09:52:13 volumio volumio[1326]: info: CoreStateMachine::pushState Jul 11 09:52:13 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Jul 11 09:52:13 volumio volumio[1326]: info: No code Jul 11 09:52:13 volumio volumio[1326]: info: CoreStateMachine::pushState Jul 11 09:52:13 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Jul 11 09:52:13 volumio volumio[1326]: info: ControllerMpd::pushState Jul 11 09:52:13 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Jul 11 09:52:13 volumio volumio[1326]: info: CoreStateMachine::pushState Jul 11 09:52:13 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Jul 11 09:52:13 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Jul 11 09:52:13 volumio volumio[1326]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://radio.vpsthai.net/proxy/dwhyntou/stream","trackType":""} Jul 11 09:52:13 volumio volumio[1326]: verbose: CURRENT POSITION 0 Jul 11 09:52:13 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Jul 11 09:52:13 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Jul 11 09:52:13 volumio volumio[1326]: info: CoreStateMachine::pushState Jul 11 09:52:13 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Jul 11 09:52:13 volumio volumio[1326]: info: No code Jul 11 09:52:13 volumio volumio[1326]: info: CoreStateMachine::pushState Jul 11 09:52:13 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Jul 11 09:52:13 volumio volumio[1326]: info: ------------------------------ 73ms Jul 11 09:52:13 volumio volumio[1326]: info: ------------------------------ 73ms Jul 11 09:52:13 volumio volumio[1326]: info: PeppyMeterBasic ---peppymeterbasic status Jul 11 09:52:13 volumio volumio[1326]: info: PeppyMeterBasic ---peppymeterbasic status undefined Jul 11 09:52:13 volumio volumio[1326]: info: PeppyMeterBasic ---peppymeterbasic status undefined Jul 11 09:52:13 volumio volumio[1326]: info: PeppyMeterBasic ---peppymeterbasic status undefined Jul 11 09:52:13 volumio volumio[1326]: info: PeppyMeterBasic ---peppymeterbasic status undefined Jul 11 09:52:13 volumio volumio[1326]: info: PeppyMeterBasic ---peppymeterbasic status undefined Jul 11 09:52:20 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jul 11 09:52:20 volumio volumio[1326]: info: Saving new wireless network Jul 11 09:52:20 volumio sudo[3722]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jul 11 09:52:20 volumio sudo[3722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:20 volumio sudo[3722]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:20 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jul 11 09:52:20 volumio volumio[1326]: info: Discovery: Restarting Advertising due to device name change Jul 11 09:52:20 volumio sudo[3725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jul 11 09:52:20 volumio sudo[3725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:20 volumio systemd[1]: Starting wireless.service - Wireless Services... Jul 11 09:52:21 volumio volumio[1326]: info: Discovery: A device disappeared from network Jul 11 09:52:21 volumio volumio[1326]: info: Discovery: Device volumio disappeared from network Jul 11 09:52:21 volumio volumio[1326]: info: Discovery: A device disappeared from network Jul 11 09:52:21 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Jul 11 09:52:21 volumio dnsmasq[3323]: exiting on receipt of SIGTERM Jul 11 09:52:21 volumio systemd[1]: dnsmasq.service: Deactivated successfully. Jul 11 09:52:21 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Jul 11 09:52:22 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Jul 11 09:52:22 volumio dhcpcd[760]: wlan0: carrier lost Jul 11 09:52:22 volumio systemd[1]: hostapd.service: Deactivated successfully. Jul 11 09:52:22 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Jul 11 09:52:22 volumio avahi-daemon[587]: Withdrawing address record for 169.254.88.245 on wlan0. Jul 11 09:52:22 volumio dhcpcd[760]: wlan0: deleting route to 169.254.0.0/16 Jul 11 09:52:22 volumio dhcpcd[760]: wlan0: deleting default route Jul 11 09:52:22 volumio sudo[3757]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jul 11 09:52:22 volumio sudo[3757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 11 09:52:22 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jul 11 09:52:22 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jul 11 09:52:22 volumio systemd[1]: welcome.service: Deactivated successfully. Jul 11 09:52:22 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jul 11 09:52:22 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jul 11 09:52:22 volumio avahi-daemon[587]: Withdrawing address record for 192.168.211.1 on wlan0. Jul 11 09:52:22 volumio avahi-daemon[587]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jul 11 09:52:22 volumio avahi-daemon[587]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 11 09:52:22 volumio sudo[3757]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:22 volumio sudo[3771]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jul 11 09:52:22 volumio sudo[3771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 11 09:52:22 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jul 11 09:52:23 volumio welcome[3770]: Resolved ip:[0] Jul 11 09:52:23 volumio sudo[3771]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:23 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jul 11 09:52:23 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jul 11 09:52:23 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jul 11 09:52:23 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jul 11 09:52:23 volumio systemd[1]: welcome.service: Deactivated successfully. Jul 11 09:52:23 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jul 11 09:52:23 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jul 11 09:52:23 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jul 11 09:52:23 volumio welcome[3780]: Resolved ip:[0] Jul 11 09:52:23 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jul 11 09:52:23 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jul 11 09:52:23 volumio sudo[3789]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 11 09:52:23 volumio sudo[3789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:23 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 11 09:52:23 volumio sudo[3789]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:23 volumio sudo[3792]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jul 11 09:52:23 volumio sudo[3792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:23 volumio sudo[3792]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:23 volumio sudo[3800]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 11 09:52:23 volumio sudo[3800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:23 volumio sudo[3800]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:23 volumio sudo[3802]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jul 11 09:52:23 volumio sudo[3802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:24 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 11 09:52:25 volumio ntpd[829]: IO: Deleting interface #6 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=168 secs Jul 11 09:52:25 volumio ntpd[829]: IO: Deleting interface #7 wlan0, 169.254.88.245#123, interface stats: received=0, sent=10, dropped=0, active_time=157 secs Jul 11 09:52:25 volumio ntpd[829]: PROTO: 122.155.180.231 unlink local addr 169.254.88.245 -> Jul 11 09:52:25 volumio ntpd[829]: PROTO: 202.28.116.236 unlink local addr 169.254.88.245 -> Jul 11 09:52:25 volumio ntpd[829]: PROTO: 202.12.97.45 unlink local addr 169.254.88.245 -> Jul 11 09:52:25 volumio ntpd[829]: PROTO: 202.80.227.130 unlink local addr 169.254.88.245 -> Jul 11 09:52:25 volumio ntpd[829]: PROTO: 171.103.24.86 unlink local addr 169.254.88.245 -> Jul 11 09:52:25 volumio ntpd[829]: PROTO: 202.28.117.7 unlink local addr 169.254.88.245 -> Jul 11 09:52:25 volumio volumio[1326]: info: Discovery: Started advertising with name: Volumio Jul 11 09:52:26 volumio sudo[3802]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:26 volumio sudo[3811]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 11 09:52:26 volumio sudo[3811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:26 volumio sudo[3811]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:26 volumio sudo[3813]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set TH Jul 11 09:52:26 volumio sudo[3813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:26 volumio sudo[3813]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:26 volumio sudo[3821]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jul 11 09:52:26 volumio sudo[3821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 11 09:52:26 volumio sudo[3821]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:26 volumio sudo[3823]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jul 11 09:52:26 volumio sudo[3823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 11 09:52:26 volumio volumio[1326]: info: Discovery: adding 301fff0b-4564-4a72-8b4c-8ec3ed1d7062 Jul 11 09:52:26 volumio volumio[1326]: info: Discovery: Found device Volumio Jul 11 09:52:26 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Jul 11 09:52:27 volumio sudo[3823]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:27 volumio wpa_supplicant[3829]: Successfully initialized wpa_supplicant Jul 11 09:52:27 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 11 09:52:27 volumio sudo[3830]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jul 11 09:52:27 volumio sudo[3830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 11 09:52:27 volumio dhcpcd[760]: ps_ctl_dispatch: cannot handle another client Jul 11 09:52:27 volumio dhcpcd[760]: control_free: No such file or directory Jul 11 09:52:27 volumio sudo[3830]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:27 volumio wpa_supplicant[3829]: nl80211: kernel reports: Registration to specific type not supported Jul 11 09:52:28 volumio sudo[3851]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:52:28 volumio sudo[3851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:28 volumio sudo[3851]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:29 volumio sudo[3855]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:52:29 volumio sudo[3855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:29 volumio sudo[3855]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:29 volumio wpa_supplicant[3834]: wlan0: Trying to associate with f8:2e:3f:14:80:c8 (SSID='pikoonplaninsod2.4' freq=2442 MHz) Jul 11 09:52:30 volumio sudo[3859]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:52:30 volumio sudo[3859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:30 volumio sudo[3859]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:30 volumio wpa_supplicant[3834]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=f8:2e:3f:14:80:c8 status_code=16 Jul 11 09:52:31 volumio sudo[3863]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:52:31 volumio sudo[3863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:31 volumio sudo[3863]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:32 volumio sudo[3867]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:52:32 volumio sudo[3867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:32 volumio sudo[3867]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:33 volumio volumio[1326]: info: CoreCommandRouter::volumioPlay Jul 11 09:52:33 volumio volumio[1326]: info: CoreStateMachine::play index undefined Jul 11 09:52:33 volumio volumio[1326]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 09:52:33 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Jul 11 09:52:33 volumio volumio[1326]: info: CoreStateMachine::startPlaybackTimer Jul 11 09:52:33 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Jul 11 09:52:33 volumio volumio[1326]: info: [1752202353036] ControllerWebradio::clearAddPlayTrack Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand stop Jul 11 09:52:33 volumio volumio[1326]: info: sendMpdCommand stop took 2 milliseconds Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand clear Jul 11 09:52:33 volumio volumio[1326]: info: Jul 11 09:52:33 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Jul 11 09:52:33 volumio volumio[1326]: info: Ignoring MPD Status Update Jul 11 09:52:33 volumio volumio[1326]: info: sendMpdCommand clear took 4 milliseconds Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand load "https://radio.vpsthai.net/proxy/dwhyntou/stream" Jul 11 09:52:33 volumio volumio[1326]: error: updateQueue error: null Jul 11 09:52:33 volumio volumio[1326]: info: ------------------------------ 8ms Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand add "https://radio.vpsthai.net/proxy/dwhyntou/stream" Jul 11 09:52:33 volumio volumio[1326]: info: Jul 11 09:52:33 volumio volumio[1326]: ---------------------------- MPD announces system playlist update Jul 11 09:52:33 volumio volumio[1326]: info: Ignoring MPD Status Update Jul 11 09:52:33 volumio volumio[1326]: info: sendMpdCommand add "https://radio.vpsthai.net/proxy/dwhyntou/stream" took 3 milliseconds Jul 11 09:52:33 volumio volumio[1326]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand play Jul 11 09:52:33 volumio volumio[1326]: info: ------------------------------ 5ms Jul 11 09:52:33 volumio volumio[1326]: info: sendMpdCommand play took 3 milliseconds Jul 11 09:52:33 volumio volumio[1326]: info: Jul 11 09:52:33 volumio volumio[1326]: ---------------------------- MPD announces state update: player Jul 11 09:52:33 volumio volumio[1326]: info: ControllerMpd::getState Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Jul 11 09:52:33 volumio volumio[1326]: info: Jul 11 09:52:33 volumio volumio[1326]: ---------------------------- MPD announces state update: player Jul 11 09:52:33 volumio volumio[1326]: info: ControllerMpd::getState Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 11 09:52:33 volumio volumio[1326]: info: sendMpdCommand status took 14 milliseconds Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::parseState Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 11 09:52:33 volumio volumio[1326]: info: sendMpdCommand status took 24 milliseconds Jul 11 09:52:33 volumio volumio[1326]: info: sendMpdCommand clearerror took 14 milliseconds Jul 11 09:52:33 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::parseState Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Jul 11 09:52:33 volumio volumio[1326]: info: ControllerMpd::pushState Jul 11 09:52:33 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Jul 11 09:52:33 volumio volumio[1326]: info: CoreStateMachine::pushState Jul 11 09:52:33 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 09:52:33 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Jul 11 09:52:33 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Jul 11 09:52:33 volumio volumio[1326]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://radio.vpsthai.net/proxy/dwhyntou/stream","trackType":""} Jul 11 09:52:33 volumio volumio[1326]: verbose: CURRENT POSITION 0 Jul 11 09:52:33 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Jul 11 09:52:33 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Jul 11 09:52:33 volumio volumio[1326]: info: CoreStateMachine::pushState Jul 11 09:52:33 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Jul 11 09:52:33 volumio volumio[1326]: info: No code Jul 11 09:52:33 volumio volumio[1326]: info: CoreStateMachine::pushState Jul 11 09:52:33 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Jul 11 09:52:33 volumio volumio[1326]: info: ------------------------------ 98ms Jul 11 09:52:33 volumio volumio[1326]: info: PeppyMeterBasic ---peppymeterbasic status Jul 11 09:52:33 volumio volumio[1326]: info: PeppyMeterBasic ---peppymeterbasic status undefined Jul 11 09:52:33 volumio volumio[1326]: info: PeppyMeterBasic ---peppymeterbasic status undefined Jul 11 09:52:33 volumio volumio[1326]: info: sendMpdCommand clearerror took 103 milliseconds Jul 11 09:52:33 volumio volumio[1326]: info: sendMpdCommand playlistinfo took 101 milliseconds Jul 11 09:52:33 volumio volumio[1326]: verbose: ControllerMpd::parseTrackInfo Jul 11 09:52:33 volumio volumio[1326]: info: ControllerMpd::pushState Jul 11 09:52:33 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Jul 11 09:52:33 volumio volumio[1326]: info: CoreStateMachine::pushState Jul 11 09:52:33 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 09:52:33 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Jul 11 09:52:33 volumio sudo[3875]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:52:33 volumio sudo[3875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:33 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Jul 11 09:52:33 volumio volumio[1326]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://radio.vpsthai.net/proxy/dwhyntou/stream","trackType":""} Jul 11 09:52:33 volumio volumio[1326]: verbose: CURRENT POSITION 0 Jul 11 09:52:33 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Jul 11 09:52:33 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Jul 11 09:52:33 volumio volumio[1326]: info: CoreStateMachine::pushState Jul 11 09:52:33 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Jul 11 09:52:33 volumio volumio[1326]: info: No code Jul 11 09:52:33 volumio volumio[1326]: info: CoreStateMachine::pushState Jul 11 09:52:33 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Jul 11 09:52:33 volumio sudo[3875]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:33 volumio volumio[1326]: info: ------------------------------ 188ms Jul 11 09:52:33 volumio volumio[1326]: info: PeppyMeterBasic ---peppymeterbasic status Jul 11 09:52:33 volumio volumio[1326]: info: PeppyMeterBasic ---peppymeterbasic status undefined Jul 11 09:52:33 volumio volumio[1326]: info: PeppyMeterBasic ---peppymeterbasic status undefined Jul 11 09:52:33 volumio wpa_supplicant[3834]: wlan0: Trying to associate with f8:2e:3f:14:80:c8 (SSID='pikoonplaninsod2.4' freq=2442 MHz) Jul 11 09:52:33 volumio wpa_supplicant[3834]: wlan0: Associated with f8:2e:3f:14:80:c8 Jul 11 09:52:33 volumio wpa_supplicant[3834]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 11 09:52:33 volumio wpa_supplicant[3834]: wlan0: WPA: Key negotiation completed with f8:2e:3f:14:80:c8 [PTK=CCMP GTK=TKIP] Jul 11 09:52:33 volumio wpa_supplicant[3834]: wlan0: CTRL-EVENT-CONNECTED - Connection to f8:2e:3f:14:80:c8 completed [id=0 id_str=] Jul 11 09:52:33 volumio dhcpcd[760]: wlan0: carrier acquired Jul 11 09:52:33 volumio dhcpcd[760]: wlan0: IAID 9e:11:09:36 Jul 11 09:52:33 volumio dhcpcd[760]: wlan0: soliciting an IPv6 router Jul 11 09:52:34 volumio dhcpcd[760]: wlan0: soliciting a DHCP lease Jul 11 09:52:34 volumio sudo[3881]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:52:34 volumio sudo[3881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:34 volumio sudo[3881]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:35 volumio sudo[3887]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:52:35 volumio sudo[3887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:35 volumio sudo[3887]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:36 volumio sudo[3891]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:52:36 volumio sudo[3891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:36 volumio sudo[3891]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:36 volumio dhcpcd[760]: wlan0: offered 192.168.1.229 from 192.168.1.1 Jul 11 09:52:36 volumio dhcpcd[760]: wlan0: probing address 192.168.1.229/24 Jul 11 09:52:36 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 11 09:52:36 volumio volumio[1326]: info: Preload queue cleared Jul 11 09:52:37 volumio sudo[3896]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:52:37 volumio sudo[3896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:37 volumio sudo[3896]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:38 volumio sudo[3914]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:52:38 volumio sudo[3914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:38 volumio sudo[3914]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:39 volumio sudo[3918]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:52:39 volumio sudo[3918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:39 volumio sudo[3918]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:40 volumio sudo[3922]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:52:40 volumio sudo[3922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:40 volumio sudo[3922]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:41 volumio dhcpcd[760]: wlan0: leased 192.168.1.229 for 86400 seconds Jul 11 09:52:41 volumio avahi-daemon[587]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.229. Jul 11 09:52:41 volumio avahi-daemon[587]: New relevant interface wlan0.IPv4 for mDNS. Jul 11 09:52:41 volumio avahi-daemon[587]: Registering new address record for 192.168.1.229 on wlan0.IPv4. Jul 11 09:52:41 volumio dhcpcd[760]: wlan0: adding route to 192.168.1.0/24 Jul 11 09:52:41 volumio dhcpcd[760]: wlan0: adding default route via 192.168.1.1 Jul 11 09:52:41 volumio sudo[3928]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:52:41 volumio sudo[3928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:41 volumio sudo[3928]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:41 volumio volumio[1326]: compat.c: read() failed: Resource temporarily unavailable Jul 11 09:52:41 volumio volumio[1326]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 09:52:41 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jul 11 09:52:41 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jul 11 09:52:41 volumio systemd[1]: welcome.service: Deactivated successfully. Jul 11 09:52:41 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jul 11 09:52:41 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jul 11 09:52:41 volumio volumio[1326]: Error: dns service error: unknown Jul 11 09:52:41 volumio volumio[1326]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jul 11 09:52:41 volumio volumio[1326]: errorCode: -65537 Jul 11 09:52:41 volumio volumio[1326]: } Jul 11 09:52:41 volumio volumio[1326]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 09:52:41 volumio sudo[3945]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 11 09:52:41 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jul 11 09:52:41 volumio sudo[3945]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 11 09:52:41 volumio welcome[3947]: Resolved ip:[1] 192.168.1.229 Jul 11 09:52:41 volumio sudo[3945]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:41 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jul 11 09:52:41 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jul 11 09:52:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jul 11 09:52:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jul 11 09:52:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 11 09:52:41 volumio systemd[1]: shairport-sync.service: Consumed 4.693s CPU time. Jul 11 09:52:41 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jul 11 09:52:41 volumio avahi-daemon[587]: Got SIGTERM, quitting. Jul 11 09:52:41 volumio avahi-daemon[587]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.229. Jul 11 09:52:41 volumio avahi-daemon[587]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jul 11 09:52:41 volumio avahi-daemon[587]: avahi-daemon 0.8 exiting. Jul 11 09:52:41 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. Jul 11 09:52:41 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jul 11 09:52:41 volumio dbus-daemon[589]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.55' (uid=0 pid=1231 comm="/usr/sbin/smbd --foreground --no-process-group") Jul 11 09:52:41 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jul 11 09:52:41 volumio avahi-daemon[3971]: Process 587 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Jul 11 09:52:41 volumio avahi-daemon[3971]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jul 11 09:52:41 volumio avahi-daemon[3971]: Successfully dropped root privileges. Jul 11 09:52:41 volumio avahi-daemon[3971]: avahi-daemon 0.8 starting up. Jul 11 09:52:41 volumio dbus-daemon[589]: [system] Successfully activated service 'org.freedesktop.Avahi' Jul 11 09:52:41 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jul 11 09:52:41 volumio avahi-daemon[3971]: Successfully called chroot(). Jul 11 09:52:41 volumio avahi-daemon[3971]: Successfully dropped remaining capabilities. Jul 11 09:52:41 volumio avahi-daemon[3971]: No service file found in /etc/avahi/services. Jul 11 09:52:41 volumio avahi-daemon[3971]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.229. Jul 11 09:52:41 volumio avahi-daemon[3971]: New relevant interface wlan0.IPv4 for mDNS. Jul 11 09:52:41 volumio avahi-daemon[3971]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jul 11 09:52:41 volumio avahi-daemon[3971]: New relevant interface lo.IPv4 for mDNS. Jul 11 09:52:41 volumio avahi-daemon[3971]: Network interface enumeration completed. Jul 11 09:52:41 volumio avahi-daemon[3971]: Registering new address record for 192.168.1.229 on wlan0.IPv4. Jul 11 09:52:41 volumio avahi-daemon[3971]: Registering new address record for 127.0.0.1 on lo.IPv4. Jul 11 09:52:41 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 11 09:52:41 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 11 09:52:41 volumio systemd[1]: Started wireless.service - Wireless Services. Jul 11 09:52:41 volumio sudo[3725]: pam_unix(sudo:session): session closed for user root Jul 11 09:52:42 volumio sudo[3995]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-11 09:51' Jul 11 09:52:42 volumio sudo[3995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 09:52:42 volumio ntpd[829]: IO: Listen normally on 8 wlan0 192.168.1.229:123 Jul 11 09:52:42 volumio ntpd[829]: IO: new interface(s) found: waking up resolver Jul 11 09:52:42 volumio ntpd[829]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jul 11 09:52:42 volumio ntpd[829]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jul 11 09:52:42 volumio ntpd[829]: DNS: Pool taking: 202.28.92.208 Jul 11 09:52:42 volumio ntpd[829]: DNS: Pool skipping: 171.103.24.86 Jul 11 09:52:42 volumio ntpd[829]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jul 11 09:52:42 volumio avahi-daemon[3971]: Server startup complete. Host name is volumio.local. Local service cookie is 1507814705. PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"