-- Logs begin at Tue 2024-07-23 02:22:29 JST, end at Tue 2024-07-23 02:25:42 JST. -- Jul 23 02:24:07 volumio hostapd[1372]: wlan0: STA 0c:8b:fd:49:47:b0 IEEE 802.11: associated Jul 23 02:24:07 volumio hostapd[1372]: wlan0: STA 0c:8b:fd:49:47:b0 RADIUS: starting accounting session 9D20BF254763364D Jul 23 02:24:10 volumio dnsmasq-dhcp[879]: DHCPDISCOVER(wlan0) 0c:8b:fd:49:47:b0 Jul 23 02:24:10 volumio dnsmasq-dhcp[879]: DHCPOFFER(wlan0) 192.168.211.184 0c:8b:fd:49:47:b0 Jul 23 02:24:10 volumio dnsmasq-dhcp[879]: DHCPREQUEST(wlan0) 192.168.211.184 0c:8b:fd:49:47:b0 Jul 23 02:24:10 volumio dnsmasq-dhcp[879]: DHCPACK(wlan0) 192.168.211.184 0c:8b:fd:49:47:b0 DESKTOP-JANAK5Q Jul 23 02:24:13 volumio volumio[890]: verbose: New Socket.io Connection to 10.1.50.34 from 192.168.211.184 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Jul 23 02:24:13 volumio volumio[890]: verbose: New Socket.io Connection to 10.1.50.34 from 192.168.211.184 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::volumioGetState Jul 23 02:24:13 volumio volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::volumioGetQueue Jul 23 02:24:13 volumio volumio[890]: info: CoreStateMachine::getQueue Jul 23 02:24:13 volumio volumio[890]: info: CorePlayQueue::getQueue Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jul 23 02:24:13 volumio volumio[890]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Jul 23 02:24:13 volumio volumio[890]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Jul 23 02:24:13 volumio volumio[890]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Jul 23 02:24:13 volumio sudo[1527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 23 02:24:13 volumio sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:13 volumio sudo[1527]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:13 volumio sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 23 02:24:13 volumio sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:13 volumio sudo[1529]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:13 volumio volumio[890]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.184 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::volumioGetVisibleSources Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jul 23 02:24:13 volumio volumio[890]: info: Received Get System Info Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 23 02:24:13 volumio volumio[890]: info: Discovery: Getting this device information Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::volumioGetState Jul 23 02:24:13 volumio volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 23 02:24:13 volumio volumio-remote-updater[626]: No test mode Jul 23 02:24:13 volumio volumio-remote-updater[626]: No alpha test mode Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 23 02:24:13 volumio volumio-remote-updater[626]: curl_easy_perform() failed: Couldn't resolve host name Jul 23 02:24:13 volumio volumio-remote-updater[626]: terminate called after throwing an instance of 'std::invalid_argument' Jul 23 02:24:13 volumio volumio-remote-updater[626]: what(): parse error - unexpected end of input Jul 23 02:24:13 volumio systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Jul 23 02:24:13 volumio systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Jul 23 02:24:13 volumio volumio[890]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 02:24:14 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Jul 23 02:24:14 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Jul 23 02:24:14 volumio systemd[1]: Stopped volumio-remote-updater.service. Jul 23 02:24:14 volumio systemd[1]: Started volumio-remote-updater.service. Jul 23 02:24:14 volumio volumio-remote-updater[1533]: Error: No active session Jul 23 02:24:14 volumio volumio-remote-updater[1533]: [2024-07-22 17:24:14] [connect] Successful connection Jul 23 02:24:14 volumio volumio[890]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Jul 23 02:24:14 volumio volumio-remote-updater[1533]: [2024-07-22 17:24:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1721669054 101 Jul 23 02:24:15 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jul 23 02:24:15 volumio volumio[890]: info: Loading i18n strings for locale ja Jul 23 02:24:15 volumio volumio[890]: Updating browse sources language Jul 23 02:24:15 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 23 02:24:15 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jul 23 02:24:15 volumio volumio[890]: info: Setting timezone to Asia/Tokyo Jul 23 02:24:15 volumio sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jul 23 02:24:15 volumio sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:15 volumio sudo[1538]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:15 volumio sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Jul 23 02:24:15 volumio sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:15 volumio sudo[1541]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:15 volumio sudo[1544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jul 23 02:24:15 volumio sudo[1544]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:15 volumio sudo[1544]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:15 volumio sudo[1547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Jul 23 02:24:15 volumio sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:15 volumio dbus-daemon[653]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.17' (uid=0 pid=1548 comm="/usr/bin/timedatectl set-timezone Asia/Tokyo ") Jul 23 02:24:15 volumio systemd[1]: Starting Time & Date Service... Jul 23 02:24:15 volumio dbus-daemon[653]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 23 02:24:15 volumio systemd[1]: Started Time & Date Service. Jul 23 02:24:15 volumio sudo[1547]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:15 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 23 02:24:17 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jul 23 02:24:17 volumio volumio[890]: info: Loading i18n strings for locale ja Jul 23 02:24:17 volumio volumio[890]: Updating browse sources language Jul 23 02:24:17 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 23 02:24:17 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jul 23 02:24:17 volumio volumio[890]: info: Setting timezone to Asia/Tokyo Jul 23 02:24:17 volumio sudo[1551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jul 23 02:24:17 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:17 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:17 volumio sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Jul 23 02:24:17 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:17 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:17 volumio sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jul 23 02:24:17 volumio sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:17 volumio sudo[1557]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:17 volumio sudo[1560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Jul 23 02:24:17 volumio sudo[1560]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:17 volumio sudo[1560]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:17 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 23 02:24:17 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 23 02:24:17 volumio sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 23 02:24:17 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:18 volumio volumio[890]: verbose: New Socket.io Connection to 10.1.50.34 from 192.168.211.184 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Jul 23 02:24:18 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 23 02:24:18 volumio volumio[890]: info: CoreCommandRouter::volumioGetVisibleSources Jul 23 02:24:18 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 23 02:24:18 volumio volumio[890]: info: CoreCommandRouter::volumioGetState Jul 23 02:24:18 volumio volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:24:18 volumio volumio[890]: info: CoreCommandRouter::volumioGetState Jul 23 02:24:18 volumio volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:24:18 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 23 02:24:18 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 23 02:24:18 volumio volumio[890]: info: Received Get System Info Jul 23 02:24:18 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 23 02:24:18 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 23 02:24:18 volumio volumio[890]: info: Discovery: Getting this device information Jul 23 02:24:18 volumio volumio[890]: info: CoreCommandRouter::volumioGetState Jul 23 02:24:18 volumio volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:24:18 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 23 02:24:18 volumio volumio[890]: info: CoreCommandRouter::volumioGetState Jul 23 02:24:18 volumio volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:24:18 volumio volumio[890]: info: Listing playlists Jul 23 02:24:18 volumio volumio[890]: info: CoreCommandRouter::volumioGetQueue Jul 23 02:24:18 volumio volumio[890]: info: CoreStateMachine::getQueue Jul 23 02:24:18 volumio volumio[890]: info: CorePlayQueue::getQueue Jul 23 02:24:21 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:27 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Jul 23 02:24:27 volumio volumio[890]: info: System name has changed, restarting Shairport Sync Jul 23 02:24:27 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:27 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 02:24:27 volumio volumio[890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 23 02:24:27 volumio volumio[890]: info: Discovery: Restarting Advertising due to device name change Jul 23 02:24:27 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Jul 23 02:24:27 volumio sudo[1569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jul 23 02:24:27 volumio sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:27 volumio sudo[1569]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:27 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 23 02:24:27 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 02:24:27 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 23 02:24:27 volumio volumio[890]: info: Starting Shairport Sync Jul 23 02:24:27 volumio systemd[1]: upmpdcli.service: Succeeded. Jul 23 02:24:27 volumio sudo[1574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jul 23 02:24:27 volumio sudo[1574]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:27 volumio sudo[1577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 23 02:24:27 volumio sudo[1574]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:27 volumio sudo[1577]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:27 volumio volumio[890]: info: Permissions for /etc/hosts set Jul 23 02:24:27 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 23 02:24:27 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 23 02:24:27 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 23 02:24:27 volumio volumio[890]: info: Hostname now is volumio3 Jul 23 02:24:27 volumio sudo[1580]: volumio : unable to resolve host volumio Jul 23 02:24:27 volumio sudo[1580]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 23 02:24:27 volumio sudo[1580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio3 Jul 23 02:24:27 volumio sudo[1580]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 23 02:24:27 volumio sudo[1577]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:27 volumio sudo[1583]: volumio : unable to resolve host volumio Jul 23 02:24:27 volumio sudo[1583]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 23 02:24:27 volumio3 sudo[1580]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:27 volumio3 sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Jul 23 02:24:27 volumio3 sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:27 volumio3 volumio[890]: info: New hostname set Jul 23 02:24:27 volumio3 volumio[890]: info: Shairport-Sync Started Jul 23 02:24:27 volumio3 sudo[1583]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:27 volumio3 volumio[890]: info: Permissions for /etc/avahi/services/volumio.service Jul 23 02:24:27 volumio3 avahi-daemon[627]: Files changed, reloading. Jul 23 02:24:27 volumio3 avahi-daemon[627]: Loading service file /services/volumio.service. Jul 23 02:24:27 volumio3 volumio[890]: info: Avahi name changed to volumio3 Jul 23 02:24:28 volumio3 avahi-daemon[627]: Service "Volumio3" (/services/volumio.service) successfully established. Jul 23 02:24:28 volumio3 volumio[890]: info: Discovery: A device disappeared from network Jul 23 02:24:28 volumio3 volumio[890]: info: Discovery: Device volumio disappeared from network Jul 23 02:24:32 volumio3 volumio[890]: info: Discovery: Started advertising with name: Volumio3 Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 23 02:24:33 volumio3 volumio[890]: info: Preparing to save Alsa Options, stopping services first Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::volumioGetState Jul 23 02:24:33 volumio3 volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::volumioPause Jul 23 02:24:33 volumio3 volumio[890]: info: CoreStateMachine::pause Jul 23 02:24:33 volumio3 volumio[890]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"1","label":"Headphones"},"disallowPush":true} Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 23 02:24:33 volumio3 volumio[890]: info: Found match in Cards Database: setting mixer PCM for card Headphones Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 23 02:24:33 volumio3 volumio[890]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 23 02:24:33 volumio3 volumio[890]: info: Disabling external Volume Control Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 02:24:33 volumio3 volumio[890]: info: Preparing to generate the ALSA configuration file Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 23 02:24:33 volumio3 sudo[1597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 23 02:24:33 volumio3 sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:33 volumio3 sudo[1599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 23 02:24:33 volumio3 sudo[1599]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:33 volumio3 sudo[1599]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:33 volumio3 volumio[890]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jul 23 02:24:33 volumio3 volumio[890]: wlan0 Interface doesn't support scanning : Device or resource busy Jul 23 02:24:33 volumio3 volumio[890]: info: Cannot use regular scanning, forcing with ap-force Jul 23 02:24:33 volumio3 sudo[1604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jul 23 02:24:33 volumio3 sudo[1604]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:33 volumio3 sudo[1604]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:33 volumio3 volumio[890]: command failed: Device or resource busy (-16) Jul 23 02:24:33 volumio3 volumio[890]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jul 23 02:24:33 volumio3 volumio[890]: command failed: Device or resource busy (-16) Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 23 02:24:33 volumio3 volumio[890]: info: VolumeController:: Volume=86 Mute =false Jul 23 02:24:33 volumio3 volumio[890]: info: CoreStateMachine::pushState Jul 23 02:24:33 volumio3 volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::volumioPushState Jul 23 02:24:33 volumio3 volumio[890]: info: Asound.conf file written Jul 23 02:24:33 volumio3 sudo[1611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 23 02:24:33 volumio3 sudo[1611]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:33 volumio3 sudo[1611]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:33 volumio3 volumio[890]: No state is present for card b1 Jul 23 02:24:33 volumio3 volumio[890]: /usr/share/alsa/init/bcm2835:14: control element not found Jul 23 02:24:33 volumio3 volumio[890]: No state is present for card b1 Jul 23 02:24:33 volumio3 volumio[890]: No state is present for card Headphones Jul 23 02:24:33 volumio3 volumio[890]: /usr/share/alsa/init/bcm2835:20: control element not found Jul 23 02:24:33 volumio3 volumio[890]: No state is present for card Headphones Jul 23 02:24:33 volumio3 volumio[890]: info: Output device has changed, restarting MPD Jul 23 02:24:33 volumio3 sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 23 02:24:33 volumio3 sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:33 volumio3 volumio[890]: info: Output device has changed, restarting Shairport Sync Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 02:24:33 volumio3 sudo[1616]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:33 volumio3 sudo[1618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 23 02:24:33 volumio3 sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:33 volumio3 systemd[1]: Stopping Music Player Daemon... Jul 23 02:24:33 volumio3 volumio[890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 23 02:24:33 volumio3 volumio[890]: info: Output device has changed, restarting MPD Jul 23 02:24:33 volumio3 sudo[1625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 23 02:24:33 volumio3 sudo[1625]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:33 volumio3 sudo[1625]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:33 volumio3 volumio[890]: info: Output device has changed, restarting Shairport Sync Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 02:24:33 volumio3 sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 23 02:24:33 volumio3 sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:33 volumio3 volumio[890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 23 02:24:33 volumio3 volumio[890]: info: MPD Permissions set Jul 23 02:24:33 volumio3 volumio[890]: info: MPD Permissions set Jul 23 02:24:33 volumio3 systemd[1]: mpd.service: Succeeded. Jul 23 02:24:33 volumio3 systemd[1]: Stopped Music Player Daemon. Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 systemd[1]: Starting Music Player Daemon... Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:24:33 volumio3 volumio[890]: info: Discovery: adding 9a464858-8558-4b0f-b054-aeb86a0f5ce2 Jul 23 02:24:33 volumio3 volumio[890]: info: Discovery: Found device Volumio3 Jul 23 02:24:33 volumio3 volumio[890]: info: CoreCommandRouter::volumioGetState Jul 23 02:24:33 volumio3 volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:24:33 volumio3 volumio[890]: info: Starting Shairport Sync Jul 23 02:24:33 volumio3 volumio[890]: info: Starting Shairport Sync Jul 23 02:24:33 volumio3 sudo[1644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 23 02:24:33 volumio3 sudo[1644]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:33 volumio3 sudo[1646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 23 02:24:33 volumio3 sudo[1646]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:33 volumio3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 23 02:24:33 volumio3 systemd[1]: shairport-sync.service: Succeeded. Jul 23 02:24:33 volumio3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 23 02:24:33 volumio3 sudo[1634]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 23 02:24:33 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 23 02:24:33 volumio3 sudo[1634]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:33 volumio3 sudo[1644]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:33 volumio3 sudo[1634]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:33 volumio3 volumio[890]: info: Shairport-Sync Started Jul 23 02:24:33 volumio3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 23 02:24:33 volumio3 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 23 02:24:33 volumio3 systemd[1]: shairport-sync.service: Succeeded. Jul 23 02:24:33 volumio3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 23 02:24:33 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 23 02:24:33 volumio3 sudo[1646]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:33 volumio3 volumio[890]: info: Shairport-Sync Started Jul 23 02:24:34 volumio3 mpd[1651]: Jul 23 02:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 23 02:24:34 volumio3 systemd[1]: Started Music Player Daemon. Jul 23 02:24:34 volumio3 sudo[1628]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:34 volumio3 sudo[1618]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:34 volumio3 volumio[890]: error: MPD error: The expression evaluated to a falsy value: Jul 23 02:24:34 volumio3 volumio[890]: assert.ok(self.idling) Jul 23 02:24:34 volumio3 volumio[890]: error: The expression evaluated to a falsy value: Jul 23 02:24:34 volumio3 volumio[890]: assert.ok(self.idling) Jul 23 02:24:34 volumio3 volumio[890]: error: updateQueue error: null Jul 23 02:24:36 volumio3 sudo[1597]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:37 volumio3 volumio[890]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 23 02:24:37 volumio3 volumio[890]: info: CoreStateMachine::getcurrentVolume Jul 23 02:24:37 volumio3 volumio[890]: info: CoreCommandRouter::volumioRetrievevolume Jul 23 02:24:37 volumio3 volumio[890]: info: VolumeController:: Volume=86 Mute =false Jul 23 02:24:37 volumio3 volumio[890]: info: CoreStateMachine::pushState Jul 23 02:24:37 volumio3 volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:24:37 volumio3 volumio[890]: info: CoreCommandRouter::volumioPushState Jul 23 02:24:37 volumio3 volumio[890]: info: CoreStateMachine::updateTrackBlock Jul 23 02:24:37 volumio3 volumio[890]: info: CorePlayQueue::getTrackBlock Jul 23 02:24:37 volumio3 volumio[890]: info: CoreCommandRouter::volumioRetrievevolume Jul 23 02:24:37 volumio3 volumio[890]: info: VolumeController:: Volume=86 Mute =false Jul 23 02:24:37 volumio3 volumio[890]: info: CoreStateMachine::pushState Jul 23 02:24:37 volumio3 volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:24:37 volumio3 volumio[890]: info: CoreCommandRouter::volumioPushState Jul 23 02:24:37 volumio3 sudo[1703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Jul 23 02:24:37 volumio3 sudo[1703]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:37 volumio3 sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Jul 23 02:24:37 volumio3 sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:37 volumio3 sudo[1705]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:37 volumio3 volumio[890]: info: Internal perms successfully set Jul 23 02:24:37 volumio3 sudo[1703]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:37 volumio3 volumio[890]: info: smb.conf Permissions set Jul 23 02:24:37 volumio3 sudo[1709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Jul 23 02:24:37 volumio3 sudo[1709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:37 volumio3 sudo[1711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 23 02:24:37 volumio3 sudo[1711]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:37 volumio3 nmbd[746]: [2024/07/23 02:24:37.869118, 0] ../source3/nmbd/nmbd.c:58(terminate) Jul 23 02:24:37 volumio3 nmbd[746]: Got SIGTERM: going down... Jul 23 02:24:37 volumio3 systemd[1]: Stopping Samba NMB Daemon... Jul 23 02:24:37 volumio3 sudo[1711]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:37 volumio3 sudo[1714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 23 02:24:37 volumio3 sudo[1714]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:37 volumio3 sudo[1714]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:37 volumio3 systemd[1]: nmbd.service: Succeeded. Jul 23 02:24:37 volumio3 systemd[1]: Stopped Samba NMB Daemon. Jul 23 02:24:37 volumio3 sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 23 02:24:37 volumio3 sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:37 volumio3 systemd[1]: Starting Samba NMB Daemon... Jul 23 02:24:37 volumio3 systemd[1]: Started UPnP Renderer front-end to MPD. Jul 23 02:24:37 volumio3 sudo[1718]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:38 volumio3 volumio[890]: info: Upmpdcli Daemon Started Jul 23 02:24:38 volumio3 volumio[1723]: writing RSA key Jul 23 02:24:38 volumio3 nmbd[1722]: [2024/07/23 02:24:38.081548, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jul 23 02:24:38 volumio3 nmbd[1722]: started asyncdns process 1743 Jul 23 02:24:38 volumio3 nmbd[1722]: [2024/07/23 02:24:38.084773, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 23 02:24:38 volumio3 systemd[1]: Started Samba NMB Daemon. Jul 23 02:24:38 volumio3 nmbd[1722]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 23 02:24:38 volumio3 sudo[1709]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:38 volumio3 sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Jul 23 02:24:38 volumio3 sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:38 volumio3 systemd[1]: Stopping Samba SMB Daemon... Jul 23 02:24:38 volumio3 systemd[1]: smbd.service: Main process exited, code=killed, status=15/TERM Jul 23 02:24:38 volumio3 systemd[1]: smbd.service: Succeeded. Jul 23 02:24:38 volumio3 systemd[1]: Stopped Samba SMB Daemon. Jul 23 02:24:38 volumio3 systemd[1]: Starting Samba SMB Daemon... Jul 23 02:24:38 volumio3 winbindd[916]: [2024/07/23 02:24:38.385074, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name) Jul 23 02:24:38 volumio3 winbindd[916]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2542935764-3462329792-2532311201-501 Jul 23 02:24:38 volumio3 winbindd[916]: [2024/07/23 02:24:38.386672, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name) Jul 23 02:24:38 volumio3 winbindd[916]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2542935764-3462329792-2532311201-514 Jul 23 02:24:38 volumio3 smbd[1759]: [2024/07/23 02:24:38.394039, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 23 02:24:38 volumio3 smbd[1759]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 23 02:24:38 volumio3 systemd[1]: Started Samba SMB Daemon. Jul 23 02:24:38 volumio3 sudo[1744]: pam_unix(sudo:session): session closed for user root Jul 23 02:24:38 volumio3 volumio[890]: info: SAMBA Restarted Jul 23 02:24:45 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jul 23 02:24:45 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 23 02:24:46 volumio3 ntpd[794]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 23 02:24:47 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jul 23 02:24:47 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 23 02:24:47 volumio3 systemd[1]: systemd-timedated.service: Succeeded. Jul 23 02:24:48 volumio3 ntpd[794]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 23 02:24:49 volumio3 ntpd[794]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 23 02:24:49 volumio3 ntpd[794]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 23 02:24:58 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork Jul 23 02:24:58 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo Jul 23 02:24:58 volumio3 sudo[1774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 23 02:24:58 volumio3 sudo[1774]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:24:58 volumio3 sudo[1774]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:05 volumio3 volumio[890]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 02:25:08 volumio3 volumio[890]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 02:25:09 volumio3 volumio[890]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 02:25:12 volumio3 volumio[890]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 02:25:14 volumio3 volumio[890]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 02:25:14 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Jul 23 02:25:14 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Jul 23 02:25:14 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Jul 23 02:25:14 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Jul 23 02:25:14 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Jul 23 02:25:16 volumio3 volumio[890]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 02:25:17 volumio3 volumio[890]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 02:25:18 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jul 23 02:25:18 volumio3 volumio[890]: info: CoreCommandRouter::Reload Ui Jul 23 02:25:18 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Jul 23 02:25:18 volumio3 volumio[890]: info: Wizard terminated Successfully Jul 23 02:25:18 volumio3 volumio[890]: info: CoreCommandRouter::volumiosetStartupVolume Jul 23 02:25:18 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 02:25:18 volumio3 volumio[890]: info: Executing Deferred Wifi Connection Jul 23 02:25:18 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jul 23 02:25:18 volumio3 volumio[890]: info: Saving new wireless network Jul 23 02:25:18 volumio3 sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jul 23 02:25:18 volumio3 sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:18 volumio3 sudo[1777]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:18 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jul 23 02:25:18 volumio3 volumio[890]: info: Discovery: Restarting Advertising due to device name change Jul 23 02:25:18 volumio3 sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jul 23 02:25:18 volumio3 sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:18 volumio3 systemd[1]: Starting Wireless Services... Jul 23 02:25:18 volumio3 wireless.js[1782]: Cleaning previous... Jul 23 02:25:18 volumio3 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 23 02:25:19 volumio3 dnsmasq-dhcp[879]: DHCPREQUEST(wlan0) 192.168.211.184 0c:8b:fd:49:47:b0 Jul 23 02:25:19 volumio3 dnsmasq-dhcp[879]: DHCPACK(wlan0) 192.168.211.184 0c:8b:fd:49:47:b0 DESKTOP-JANAK5Q Jul 23 02:25:19 volumio3 sudo[1796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 23 02:25:19 volumio3 sudo[1796]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:19 volumio3 sudo[1796]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:19 volumio3 sudo[1798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 23 02:25:19 volumio3 sudo[1798]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:19 volumio3 sudo[1798]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:19 volumio3 sudo[1802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 23 02:25:19 volumio3 sudo[1802]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:19 volumio3 sudo[1802]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:19 volumio3 sudo[1804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 23 02:25:19 volumio3 sudo[1804]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:19 volumio3 sudo[1804]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:19 volumio3 volumio[890]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.184 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Jul 23 02:25:19 volumio3 systemd[1]: hostapd.service: Succeeded. Jul 23 02:25:19 volumio3 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 23 02:25:19 volumio3 volumio[890]: info: Discovery: A device disappeared from network Jul 23 02:25:19 volumio3 sudo[1808]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 23 02:25:19 volumio3 sudo[1808]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:19 volumio3 avahi-daemon[627]: Withdrawing address record for 192.168.211.1 on wlan0. Jul 23 02:25:19 volumio3 avahi-daemon[627]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jul 23 02:25:19 volumio3 avahi-daemon[627]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 23 02:25:19 volumio3 sudo[1808]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:19 volumio3 sudo[1810]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 23 02:25:19 volumio3 sudo[1810]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:20 volumio3 sudo[1810]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:20 volumio3 wireless.js[1782]: Stopped aP Jul 23 02:25:20 volumio3 sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 23 02:25:20 volumio3 sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:20 volumio3 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 23 02:25:20 volumio3 sudo[1817]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:20 volumio3 sudo[1819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jul 23 02:25:20 volumio3 sudo[1819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:20 volumio3 sudo[1819]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:20 volumio3 sudo[1826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 23 02:25:20 volumio3 sudo[1826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:20 volumio3 sudo[1826]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:20 volumio3 sudo[1828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jul 23 02:25:20 volumio3 sudo[1828]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:21 volumio3 ntpd[794]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=144 secs Jul 23 02:25:22 volumio3 sudo[1828]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:22 volumio3 wireless.js[1782]: SETTING APPROPRIATE REG DOMAIN: JP Jul 23 02:25:22 volumio3 sudo[1833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 23 02:25:22 volumio3 sudo[1833]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:22 volumio3 sudo[1833]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:22 volumio3 sudo[1835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP Jul 23 02:25:22 volumio3 sudo[1835]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:22 volumio3 sudo[1835]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:22 volumio3 wireless.js[1782]: SUCCESSFULLY SET NEW REGDOMAIN: JP Jul 23 02:25:22 volumio3 wireless.js[1782]: Start wireless flow Jul 23 02:25:22 volumio3 wireless.js[1782]: Stopped hotspot (if there).. Jul 23 02:25:22 volumio3 sudo[1840]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 23 02:25:22 volumio3 sudo[1840]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:22 volumio3 sudo[1840]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:22 volumio3 sudo[1842]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 23 02:25:22 volumio3 sudo[1842]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:23 volumio3 sudo[1842]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:23 volumio3 wireless.js[1782]: DHCP IP Jul 23 02:25:23 volumio3 wireless.js[1782]: Start ap Jul 23 02:25:23 volumio3 wpa_supplicant[1844]: Successfully initialized wpa_supplicant Jul 23 02:25:23 volumio3 sudo[1845]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jul 23 02:25:23 volumio3 sudo[1845]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:23 volumio3 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 23 02:25:23 volumio3 dhcpcd[1846]: dev: loaded udev Jul 23 02:25:23 volumio3 dhcpcd-run-hooks[1864]: wlan0: starting wpa_supplicant Jul 23 02:25:23 volumio3 volumio[890]: info: Discovery: Started advertising with name: Volumio3 Jul 23 02:25:23 volumio3 dhcpcd-run-hooks[1870]: wlan0: failed to start wpa_supplicant Jul 23 02:25:23 volumio3 dhcpcd-run-hooks[1871]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Jul 23 02:25:23 volumio3 dhcpcd[1846]: wlan0: connected to Access Point `' Jul 23 02:25:23 volumio3 dhcpcd[1846]: no interfaces have a carrier Jul 23 02:25:23 volumio3 dhcpcd[1846]: forked to background, child pid 1881 Jul 23 02:25:23 volumio3 dhcpcd[1881]: eth0: waiting for carrier Jul 23 02:25:23 volumio3 dhcpcd[1881]: wlan0: waiting for carrier Jul 23 02:25:23 volumio3 sudo[1845]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:24 volumio3 wireless.js[1782]: trying... Jul 23 02:25:24 volumio3 sudo[1883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 23 02:25:24 volumio3 sudo[1883]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:24 volumio3 sudo[1883]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:25 volumio3 wireless.js[1782]: trying... Jul 23 02:25:25 volumio3 sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 23 02:25:25 volumio3 sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:25 volumio3 sudo[1886]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:26 volumio3 wpa_supplicant[1868]: wlan0: Trying to associate with SSID 'Buffalo-G-34FC' Jul 23 02:25:26 volumio3 wireless.js[1782]: trying... Jul 23 02:25:26 volumio3 sudo[1889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 23 02:25:26 volumio3 sudo[1889]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:26 volumio3 sudo[1889]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:27 volumio3 wireless.js[1782]: trying... Jul 23 02:25:27 volumio3 sudo[1892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 23 02:25:27 volumio3 sudo[1892]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:27 volumio3 sudo[1892]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:28 volumio3 wireless.js[1782]: trying... Jul 23 02:25:28 volumio3 sudo[1895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 23 02:25:28 volumio3 sudo[1895]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:28 volumio3 sudo[1895]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:29 volumio3 dhcpcd[1881]: wlan0: carrier acquired Jul 23 02:25:29 volumio3 dhcpcd[1881]: wlan0: connected to Access Point `Buffalo-G-34FC' Jul 23 02:25:29 volumio3 wpa_supplicant[1868]: wlan0: Associated with cc:e1:d5:58:34:fc Jul 23 02:25:29 volumio3 wpa_supplicant[1868]: wlan0: CTRL-EVENT-CONNECTED - Connection to cc:e1:d5:58:34:fc completed [id=0 id_str=] Jul 23 02:25:29 volumio3 wpa_supplicant[1868]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 23 02:25:29 volumio3 wpa_supplicant[1868]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Jul 23 02:25:29 volumio3 dhcpcd[1881]: DUID 00:01:00:01:2e:31:52:89:dc:a6:32:69:a3:c3 Jul 23 02:25:29 volumio3 dhcpcd[1881]: wlan0: IAID 32:69:a3:c3 Jul 23 02:25:29 volumio3 dhcpcd[1881]: wlan0: adding address fe80::3ff0:ae1b:34d6:c6e7 Jul 23 02:25:29 volumio3 dhcpcd[1881]: ipv6_addaddr1: Permission denied Jul 23 02:25:29 volumio3 dhcpcd[1881]: wlan0: carrier lost Jul 23 02:25:29 volumio3 dhcpcd[1881]: wlan0: carrier acquired Jul 23 02:25:29 volumio3 dhcpcd[1881]: wlan0: IAID 32:69:a3:c3 Jul 23 02:25:29 volumio3 dhcpcd[1881]: wlan0: soliciting an IPv6 router Jul 23 02:25:29 volumio3 wireless.js[1782]: trying... Jul 23 02:25:29 volumio3 sudo[1910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 23 02:25:29 volumio3 sudo[1910]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:29 volumio3 sudo[1910]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:29 volumio3 dhcpcd[1881]: wlan0: soliciting a DHCP lease Jul 23 02:25:29 volumio3 dhcpcd[1881]: wlan0: offered 10.1.50.34 from 10.1.50.254 Jul 23 02:25:29 volumio3 dhcpcd[1881]: wlan0: probing address 10.1.50.34/24 Jul 23 02:25:30 volumio3 wireless.js[1782]: trying... Jul 23 02:25:30 volumio3 sudo[1913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 23 02:25:30 volumio3 sudo[1913]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:30 volumio3 sudo[1913]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:31 volumio3 wireless.js[1782]: trying... Jul 23 02:25:31 volumio3 sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 23 02:25:31 volumio3 sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:31 volumio3 sudo[1916]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:32 volumio3 wireless.js[1782]: trying... Jul 23 02:25:32 volumio3 sudo[1919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 23 02:25:32 volumio3 sudo[1919]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:32 volumio3 sudo[1919]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:33 volumio3 wireless.js[1782]: trying... Jul 23 02:25:33 volumio3 sudo[1922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 23 02:25:33 volumio3 sudo[1922]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:33 volumio3 sudo[1922]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:34 volumio3 wireless.js[1782]: trying... Jul 23 02:25:34 volumio3 sudo[1965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 23 02:25:34 volumio3 sudo[1965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:34 volumio3 sudo[1965]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:34 volumio3 dhcpcd[1881]: wlan0: leased 10.1.50.34 for 259200 seconds Jul 23 02:25:34 volumio3 avahi-daemon[627]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.50.34. Jul 23 02:25:34 volumio3 avahi-daemon[627]: New relevant interface wlan0.IPv4 for mDNS. Jul 23 02:25:34 volumio3 dhcpcd[1881]: wlan0: adding route to 10.1.50.0/24 Jul 23 02:25:34 volumio3 avahi-daemon[627]: Registering new address record for 10.1.50.34 on wlan0.IPv4. Jul 23 02:25:34 volumio3 dhcpcd[1881]: wlan0: adding default route via 10.1.50.254 Jul 23 02:25:34 volumio3 dnsmasq[879]: reading /etc/resolv.conf Jul 23 02:25:34 volumio3 dnsmasq[879]: using nameserver 10.1.50.254#53 Jul 23 02:25:34 volumio3 dnsmasq[879]: using nameserver 1.1.1.1#53 Jul 23 02:25:34 volumio3 dnsmasq[879]: using nameserver 208.67.222.222#53 Jul 23 02:25:34 volumio3 dnsmasq[879]: using nameserver 208.67.220.220#53 Jul 23 02:25:35 volumio3 volumio[890]: info: Discovery: adding 56c5b785-f418-4586-9997-5b641c50752b Jul 23 02:25:35 volumio3 volumio[890]: info: Discovery: Found device Volumio4 Jul 23 02:25:35 volumio3 volumio[890]: info: Discovery: Connecting to remote: 10.1.50.51 Jul 23 02:25:35 volumio3 volumio[890]: info: Discovery: Connected to remote: 10.1.50.51 Jul 23 02:25:35 volumio3 wireless.js[1782]: trying... Jul 23 02:25:35 volumio3 sudo[1991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 23 02:25:35 volumio3 sudo[1991]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:35 volumio3 sudo[1991]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:35 volumio3 wireless.js[1782]: Connected to: ----Buffalo-G-34FC Jul 23 02:25:35 volumio3 wireless.js[1782]: ---- Jul 23 02:25:35 volumio3 sudo[1994]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 23 02:25:35 volumio3 sudo[1994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:35 volumio3 sudo[1994]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:35 volumio3 wireless.js[1782]: ... joined AP, wlan0 IPv4 is 10.1.50.34, ipV6 is undefined Jul 23 02:25:35 volumio3 wireless.js[1782]: It's done! AP Jul 23 02:25:35 volumio3 systemd[1]: Started Wireless Services. Jul 23 02:25:35 volumio3 sudo[1780]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:35 volumio3 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 23 02:25:35 volumio3 volumio[890]: info: Discovery: adding 9a464858-8558-4b0f-b054-aeb86a0f5ce2 Jul 23 02:25:35 volumio3 volumio[890]: info: Discovery: Found device Volumio3 Jul 23 02:25:35 volumio3 volumio[890]: info: CoreCommandRouter::volumioGetState Jul 23 02:25:35 volumio3 volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:25:35 volumio3 volumio[890]: verbose: New Socket.io Connection to 10.1.50.34:3000 from 10.1.50.51 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 23 02:25:35 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jul 23 02:25:35 volumio3 volumio[890]: info: CoreCommandRouter::volumioGetState Jul 23 02:25:35 volumio3 volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:25:35 volumio3 dnsmasq-dhcp[879]: no address range available for DHCP request via wlan0 Jul 23 02:25:36 volumio3 ntpd[794]: Listen normally on 5 wlan0 10.1.50.34:123 Jul 23 02:25:36 volumio3 ntpd[794]: new interface(s) found: waking up resolver Jul 23 02:25:38 volumio3 nmbd[1722]: [2024/07/23 02:25:38.111508, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 23 02:25:38 volumio3 nmbd[1722]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Jul 23 02:25:40 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jul 23 02:25:40 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jul 23 02:25:40 volumio3 sudo[2000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 23 02:25:40 volumio3 sudo[2000]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:40 volumio3 sudo[2000]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:40 volumio3 sudo[2003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 23 02:25:40 volumio3 sudo[2003]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:40 volumio3 sudo[2003]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:40 volumio3 volumio[890]: verbose: New Socket.io Connection to 10.1.50.34 from 10.1.50.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Jul 23 02:25:40 volumio3 volumio[890]: info: CoreCommandRouter::volumioGetState Jul 23 02:25:40 volumio3 volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:25:40 volumio3 volumio[890]: info: CoreCommandRouter::volumioGetQueue Jul 23 02:25:40 volumio3 volumio[890]: info: CoreStateMachine::getQueue Jul 23 02:25:40 volumio3 volumio[890]: info: CorePlayQueue::getQueue Jul 23 02:25:40 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jul 23 02:25:40 volumio3 volumio[890]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Jul 23 02:25:40 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Jul 23 02:25:40 volumio3 volumio[890]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Jul 23 02:25:40 volumio3 volumio[890]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Jul 23 02:25:40 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Jul 23 02:25:41 volumio3 sudo[2008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 23 02:25:41 volumio3 sudo[2008]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:41 volumio3 sudo[2008]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:41 volumio3 sudo[2010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 23 02:25:41 volumio3 sudo[2010]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 02:25:41 volumio3 sudo[2010]: pam_unix(sudo:session): session closed for user root Jul 23 02:25:41 volumio3 volumio[890]: verbose: New Socket.io Connection to 10.1.50.34 from 10.1.50.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Jul 23 02:25:41 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 23 02:25:41 volumio3 volumio[890]: info: CoreCommandRouter::volumioGetVisibleSources Jul 23 02:25:41 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 23 02:25:41 volumio3 volumio[890]: info: CoreCommandRouter::volumioGetState Jul 23 02:25:41 volumio3 volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:25:41 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 23 02:25:41 volumio3 volumio[890]: info: CoreCommandRouter::volumioGetQueue Jul 23 02:25:41 volumio3 volumio[890]: info: CoreStateMachine::getQueue Jul 23 02:25:41 volumio3 volumio[890]: info: CorePlayQueue::getQueue Jul 23 02:25:41 volumio3 volumio[890]: info: Listing playlists Jul 23 02:25:41 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 23 02:25:41 volumio3 volumio[890]: info: Received Get System Info Jul 23 02:25:41 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 23 02:25:41 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 23 02:25:41 volumio3 volumio[890]: info: Discovery: Getting this device information Jul 23 02:25:41 volumio3 volumio[890]: info: CoreCommandRouter::volumioGetState Jul 23 02:25:41 volumio3 volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:25:41 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 23 02:25:41 volumio3 volumio[890]: info: CoreCommandRouter::volumioGetState Jul 23 02:25:41 volumio3 volumio[890]: info: CorePlayQueue::getTrack 0 Jul 23 02:25:41 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 23 02:25:41 volumio3 volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 23 02:25:41 volumio3 volumio[890]: 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' Jul 23 02:25:41 volumio3 volumio[890]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 02:25:41 volumio3 volumio[890]: Error: certificate is not yet valid Jul 23 02:25:41 volumio3 volumio[890]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jul 23 02:25:41 volumio3 volumio[890]: at TLSSocket.emit (events.js:315:20) Jul 23 02:25:41 volumio3 volumio[890]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jul 23 02:25:41 volumio3 volumio[890]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jul 23 02:25:41 volumio3 volumio[890]: code: 'CERT_NOT_YET_VALID' Jul 23 02:25:41 volumio3 volumio[890]: } Jul 23 02:25:41 volumio3 volumio[890]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 02:25:42 volumio3 sudo[2022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-23 02:24 Jul 23 02:25:42 volumio3 sudo[2022]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"