-- Logs begin at Sat 2025-03-01 10:06:00 +08, end at Sat 2025-03-01 10:12:12 +08. -- Mar 01 10:11:01 volumio-rp5 volumiologrotate[725]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Mar 01 10:11:01 volumio-rp5 volumiologrotate[725]: ls: cannot access 'RP5': No such file or directory Mar 01 10:11:29 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 10:11:29 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 10:11:29 volumio-rp5 volumio[1278]: info: Discovery: Getting this device information Mar 01 10:11:29 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioGetState Mar 01 10:11:29 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:29 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 10:11:32 volumio-rp5 volumio[1278]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Mar 01 10:11:32 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Mar 01 10:11:32 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioGetState Mar 01 10:11:32 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:32 volumio-rp5 volumio[1278]: info: Restoring Previous Volume level: false true Mar 01 10:11:32 volumio-rp5 volumio[1278]: info: VolumeController::SetAlsaVolume100 Mar 01 10:11:32 volumio-rp5 volumio[1278]: info: Volume configurations have been set Mar 01 10:11:32 volumio-rp5 vtcs[2815]: [2025-03-01 10:11:32.955] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Mar 01 10:11:32 volumio-rp5 vtcs[2815]: [2025-03-01 10:11:32.955] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Mar 01 10:11:32 volumio-rp5 sudo[3056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 01 10:11:32 volumio-rp5 sudo[3056]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:11:32 volumio-rp5 systemd[1]: Stopping Volumio Tidal Connect Service... Mar 01 10:11:32 volumio-rp5 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 01 10:11:32 volumio-rp5 systemd[1]: vtcs.service: Succeeded. Mar 01 10:11:32 volumio-rp5 systemd[1]: Stopped Volumio Tidal Connect Service. Mar 01 10:11:32 volumio-rp5 sudo[3056]: pam_unix(sudo:session): session closed for user root Mar 01 10:11:32 volumio-rp5 volumio[1278]: xcb_connection_has_error() returned true Mar 01 10:11:32 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 01 10:11:32 volumio-rp5 volumio[1278]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Mar 01 10:11:32 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 01 10:11:32 volumio-rp5 volumio[1278]: info: Disabling external Volume Control Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreStateMachine::pushState Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioPushState Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output update for this device Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output Mar 01 10:11:33 volumio-rp5 volumio[1278]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 10:11:33 volumio-rp5 sudo[3069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 01 10:11:33 volumio-rp5 sudo[3069]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreStateMachine::pushState Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioPushState Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output update for this device Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output Mar 01 10:11:33 volumio-rp5 sudo[3069]: pam_unix(sudo:session): session closed for user root Mar 01 10:11:33 volumio-rp5 volumio[1278]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Mar 01 10:11:33 volumio-rp5 volumio[1278]: amixer: Unable to find simple control 'SoftMaster',0 Mar 01 10:11:33 volumio-rp5 volumio[1278]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Mar 01 10:11:33 volumio-rp5 volumio[1278]: amixer: Unable to find simple control 'SoftMaster',0 Mar 01 10:11:33 volumio-rp5 volumio[1278]: xcb_connection_has_error() returned true Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 10:11:33 volumio-rp5 volumio[1278]: xcb_connection_has_error() returned true Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 01 10:11:33 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 01 10:11:35 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioGetState Mar 01 10:11:35 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:35 volumio-rp5 volumio[1278]: info: TidalConnect service stoped! Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: TidalConnect service stoped! Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 01 10:11:36 volumio-rp5 sudo[3104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 01 10:11:36 volumio-rp5 sudo[3104]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:11:36 volumio-rp5 systemd[1]: Started Volumio Tidal Connect Service. Mar 01 10:11:36 volumio-rp5 sudo[3104]: pam_unix(sudo:session): session closed for user root Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: Executing endpoint tc_getconfig Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 01 10:11:36 volumio-rp5 vtcs[3106]: STARTING TidalConnect services, version: 1.4.0.34 Mar 01 10:11:36 volumio-rp5 vtcs[3106]: STARTED TidalConnect services. Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: Executing endpoint tc_connect Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: Connecting to TidalConnect Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::servicePushState Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreStateMachine::pushState Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioPushState Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output update for this device Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:36 volumio-rp5 volumio[1278]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Mar 01 10:11:36 volumio-rp5 volumio[1278]: verbose: CURRENT POSITION 0 Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreStateMachine::syncState stateService stop Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreStateMachine::syncState currentStatus stop Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreStateMachine::pushState Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioPushState Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output update for this device Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: No code Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreStateMachine::pushState Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioPushState Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output update for this device Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::servicePushState Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreStateMachine::pushState Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioPushState Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output update for this device Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:36 volumio-rp5 volumio[1278]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Mar 01 10:11:36 volumio-rp5 volumio[1278]: verbose: CURRENT POSITION 0 Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreStateMachine::syncState stateService stop Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreStateMachine::syncState currentStatus stop Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreStateMachine::pushState Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioPushState Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output update for this device Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: No code Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreStateMachine::pushState Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioPushState Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output update for this device Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:11:36 volumio-rp5 volumio[1278]: xcb_connection_has_error() returned true Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 10:11:36 volumio-rp5 volumio[1278]: xcb_connection_has_error() returned true Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 01 10:11:36 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 01 10:11:37 volumio-rp5 volumio[1278]: info: VolumeController::SetAlsaVolumeNaN Mar 01 10:11:37 volumio-rp5 volumio[1278]: info: CoreStateMachine::pushState Mar 01 10:11:37 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:37 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 10:11:37 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioPushState Mar 01 10:11:37 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output update for this device Mar 01 10:11:37 volumio-rp5 volumio[1278]: info: MRS: Pushing multiroomSync output Mar 01 10:11:37 volumio-rp5 volumio[1278]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Mar 01 10:11:37 volumio-rp5 volumio[1278]: amixer: Unable to find simple control 'SoftMaster',0 Mar 01 10:11:39 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioGetState Mar 01 10:11:39 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:39 volumio-rp5 volumio[1278]: info: TidalConnect service started! Mar 01 10:11:40 volumio-rp5 volumio[1278]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:11:40 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 01 10:11:40 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Mar 01 10:11:40 volumio-rp5 sudo[3133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Mar 01 10:11:40 volumio-rp5 sudo[3133]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:11:40 volumio-rp5 sudo[3139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 01 10:11:40 volumio-rp5 sudo[3139]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:11:40 volumio-rp5 sudo[3139]: pam_unix(sudo:session): session closed for user root Mar 01 10:11:40 volumio-rp5 sudo[3133]: pam_unix(sudo:session): session closed for user root Mar 01 10:11:40 volumio-rp5 sudo[3146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 01 10:11:40 volumio-rp5 sudo[3146]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:11:40 volumio-rp5 sudo[3146]: pam_unix(sudo:session): session closed for user root Mar 01 10:11:40 volumio-rp5 sudo[3153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 01 10:11:40 volumio-rp5 sudo[3153]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:11:40 volumio-rp5 sudo[3153]: pam_unix(sudo:session): session closed for user root Mar 01 10:11:40 volumio-rp5 sudo[3159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 01 10:11:40 volumio-rp5 sudo[3159]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:11:40 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 01 10:11:40 volumio-rp5 sudo[3159]: pam_unix(sudo:session): session closed for user root Mar 01 10:11:40 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 01 10:11:40 volumio-rp5 sudo[3162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 01 10:11:40 volumio-rp5 sudo[3162]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:11:40 volumio-rp5 sudo[3162]: pam_unix(sudo:session): session closed for user root Mar 01 10:11:40 volumio-rp5 sudo[3165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 01 10:11:40 volumio-rp5 sudo[3165]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:11:44 volumio-rp5 sudo[3165]: pam_unix(sudo:session): session closed for user root Mar 01 10:11:59 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 10:11:59 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 10:11:59 volumio-rp5 volumio[1278]: info: Discovery: Getting this device information Mar 01 10:11:59 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioGetState Mar 01 10:11:59 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:11:59 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 10:12:02 volumio-rp5 volumiologrotate[725]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Mar 01 10:12:02 volumio-rp5 volumiologrotate[725]: ls: cannot access 'RP5': No such file or directory Mar 01 10:12:05 volumio-rp5 volumio[1278]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Mar 01 10:12:05 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Mar 01 10:12:06 volumio-rp5 sudo[3261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Mar 01 10:12:06 volumio-rp5 sudo[3261]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:12:06 volumio-rp5 sudo[3261]: pam_unix(sudo:session): session closed for user root Mar 01 10:12:06 volumio-rp5 volumio[1278]: info: Permissions for /etc/hostapd/hostapd.conf Mar 01 10:12:06 volumio-rp5 volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Mar 01 10:12:06 volumio-rp5 volumio[1278]: info: Discovery: Restarting Advertising due to device name change Mar 01 10:12:06 volumio-rp5 sudo[3264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Mar 01 10:12:06 volumio-rp5 sudo[3264]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:12:06 volumio-rp5 systemd[1]: wireless.service: Killing process 1055 (wpa_supplicant) with signal SIGKILL. Mar 01 10:12:06 volumio-rp5 systemd[1]: wireless.service: Killing process 1124 (dhcpcd) with signal SIGKILL. Mar 01 10:12:06 volumio-rp5 systemd[1]: Stopping Wireless Services... Mar 01 10:12:06 volumio-rp5 volumio[1278]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 10:12:06 volumio-rp5 systemd[1]: wireless.service: Succeeded. Mar 01 10:12:06 volumio-rp5 systemd[1]: Stopped Wireless Services. Mar 01 10:12:06 volumio-rp5 volumio[1278]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 10:12:06 volumio-rp5 systemd[1]: Starting Wireless Services... Mar 01 10:12:06 volumio-rp5 volumio[1278]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 10:12:06 volumio-rp5 wireless.js[3268]: Cleaning previous... Mar 01 10:12:06 volumio-rp5 sudo[3282]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 01 10:12:06 volumio-rp5 sudo[3282]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:12:06 volumio-rp5 avahi-daemon[739]: Withdrawing address record for 192.168.68.68 on wlan0. Mar 01 10:12:06 volumio-rp5 avahi-daemon[739]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.68. Mar 01 10:12:06 volumio-rp5 avahi-daemon[739]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 01 10:12:06 volumio-rp5 sudo[3282]: pam_unix(sudo:session): session closed for user root Mar 01 10:12:06 volumio-rp5 volumio[1278]: info: Discovery: A device disappeared from network Mar 01 10:12:06 volumio-rp5 sudo[3284]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 01 10:12:06 volumio-rp5 sudo[3284]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:12:06 volumio-rp5 volumio[1278]: info: Discovery: A device disappeared from network Mar 01 10:12:07 volumio-rp5 sudo[3284]: pam_unix(sudo:session): session closed for user root Mar 01 10:12:07 volumio-rp5 wireless.js[3268]: Stopped aP Mar 01 10:12:07 volumio-rp5 sudo[3291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 01 10:12:07 volumio-rp5 sudo[3291]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:12:07 volumio-rp5 sudo[3291]: pam_unix(sudo:session): session closed for user root Mar 01 10:12:07 volumio-rp5 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 01 10:12:07 volumio-rp5 sudo[3293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 01 10:12:07 volumio-rp5 sudo[3293]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:12:07 volumio-rp5 sudo[3293]: pam_unix(sudo:session): session closed for user root Mar 01 10:12:07 volumio-rp5 sudo[3300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 01 10:12:07 volumio-rp5 sudo[3300]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:12:07 volumio-rp5 sudo[3300]: pam_unix(sudo:session): session closed for user root Mar 01 10:12:07 volumio-rp5 sudo[3302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 01 10:12:07 volumio-rp5 sudo[3302]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:12:07 volumio-rp5 volumio[1278]: info: Discovery: A device disappeared from network Mar 01 10:12:08 volumio-rp5 ntpd[975]: Deleting interface #4 wlan0, 192.168.68.68#123, interface stats: received=0, sent=0, dropped=0, active_time=344 secs Mar 01 10:12:10 volumio-rp5 sudo[3302]: pam_unix(sudo:session): session closed for user root Mar 01 10:12:10 volumio-rp5 wireless.js[3268]: SETTING APPROPRIATE REG DOMAIN: US Mar 01 10:12:10 volumio-rp5 sudo[3307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 01 10:12:10 volumio-rp5 sudo[3307]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:12:10 volumio-rp5 sudo[3307]: pam_unix(sudo:session): session closed for user root Mar 01 10:12:10 volumio-rp5 sudo[3309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Mar 01 10:12:10 volumio-rp5 sudo[3309]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:12:10 volumio-rp5 sudo[3309]: pam_unix(sudo:session): session closed for user root Mar 01 10:12:10 volumio-rp5 wireless.js[3268]: SUCCESSFULLY SET NEW REGDOMAIN: US Mar 01 10:12:10 volumio-rp5 wireless.js[3268]: Start wireless flow Mar 01 10:12:10 volumio-rp5 wireless.js[3268]: Stopped hotspot (if there).. Mar 01 10:12:10 volumio-rp5 sudo[3314]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 01 10:12:10 volumio-rp5 sudo[3314]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:12:10 volumio-rp5 sudo[3314]: pam_unix(sudo:session): session closed for user root Mar 01 10:12:10 volumio-rp5 sudo[3316]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 01 10:12:10 volumio-rp5 sudo[3316]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:12:10 volumio-rp5 sudo[3316]: pam_unix(sudo:session): session closed for user root Mar 01 10:12:10 volumio-rp5 wireless.js[3268]: DHCP IP Mar 01 10:12:10 volumio-rp5 wireless.js[3268]: Start ap Mar 01 10:12:10 volumio-rp5 wpa_supplicant[3318]: Successfully initialized wpa_supplicant Mar 01 10:12:10 volumio-rp5 sudo[3319]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 01 10:12:10 volumio-rp5 sudo[3319]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:12:10 volumio-rp5 dhcpcd[3320]: main: control_open: Connection refused Mar 01 10:12:10 volumio-rp5 dhcpcd[3320]: dev: loaded udev Mar 01 10:12:10 volumio-rp5 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 01 10:12:10 volumio-rp5 dhcpcd-run-hooks[3329]: wlan0: starting wpa_supplicant Mar 01 10:12:10 volumio-rp5 dhcpcd-run-hooks[3334]: wlan0: failed to start wpa_supplicant Mar 01 10:12:10 volumio-rp5 dhcpcd-run-hooks[3335]: 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 Mar 01 10:12:10 volumio-rp5 dhcpcd[3320]: wlan0: connected to Access Point `' Mar 01 10:12:10 volumio-rp5 dhcpcd[3320]: DUID 00:01:00:01:2e:f2:a2:d6:d8:3a:dd:a3:32:f4 Mar 01 10:12:10 volumio-rp5 dhcpcd[3320]: eth0: IAID dd:a3:32:f4 Mar 01 10:12:10 volumio-rp5 dhcpcd[3320]: eth0: adding address fe80::d057:aef:a237:f70c Mar 01 10:12:10 volumio-rp5 dhcpcd[3320]: ipv6_addaddr1: Permission denied Mar 01 10:12:10 volumio-rp5 dhcpcd[3320]: wlan0: waiting for carrier Mar 01 10:12:10 volumio-rp5 dhcpcd[3320]: eth0: rebinding lease of 192.168.68.66 Mar 01 10:12:10 volumio-rp5 dhcpcd[3320]: eth0: soliciting an IPv6 router Mar 01 10:12:10 volumio-rp5 dhcpcd[3320]: eth0: leased 192.168.68.66 for 7200 seconds Mar 01 10:12:10 volumio-rp5 dhcpcd[3320]: eth0: adding route to 192.168.68.0/22 Mar 01 10:12:10 volumio-rp5 dhcpcd[3320]: eth0: adding default route via 192.168.68.1 Mar 01 10:12:11 volumio-rp5 dhcpcd[3320]: forked to background, child pid 3373 Mar 01 10:12:11 volumio-rp5 sudo[3319]: pam_unix(sudo:session): session closed for user root Mar 01 10:12:11 volumio-rp5 volumio[1278]: info: Discovery: Started advertising with name: Volumio RP5 Mar 01 10:12:11 volumio-rp5 wireless.js[3268]: trying... Mar 01 10:12:11 volumio-rp5 sudo[3376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 01 10:12:11 volumio-rp5 sudo[3376]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:12:11 volumio-rp5 sudo[3376]: pam_unix(sudo:session): session closed for user root Mar 01 10:12:12 volumio-rp5 volumio[1278]: info: Discovery: adding caf5318c-f1f0-47f1-a99a-beca2db1e962 Mar 01 10:12:12 volumio-rp5 volumio[1278]: info: Discovery: Found device Volumio RP5 Mar 01 10:12:12 volumio-rp5 volumio[1278]: info: CoreCommandRouter::volumioGetState Mar 01 10:12:12 volumio-rp5 volumio[1278]: info: CorePlayQueue::getTrack 0 Mar 01 10:12:12 volumio-rp5 volumio[1278]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 10:12:12 volumio-rp5 volumio[1278]: Error: The value NaN is not a number Mar 01 10:12:12 volumio-rp5 volumio[1278]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Mar 01 10:12:12 volumio-rp5 volumio[1278]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Mar 01 10:12:12 volumio-rp5 volumio[1278]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Mar 01 10:12:12 volumio-rp5 volumio[1278]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Mar 01 10:12:12 volumio-rp5 volumio[1278]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Mar 01 10:12:12 volumio-rp5 volumio[1278]: at Browser.emit (events.js:315:20) Mar 01 10:12:12 volumio-rp5 volumio[1278]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Mar 01 10:12:12 volumio-rp5 volumio[1278]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Mar 01 10:12:12 volumio-rp5 volumio[1278]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Mar 01 10:12:12 volumio-rp5 volumio[1278]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Mar 01 10:12:12 volumio-rp5 volumio[1278]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Mar 01 10:12:12 volumio-rp5 volumio[1278]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Mar 01 10:12:12 volumio-rp5 volumio[1278]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Mar 01 10:12:12 volumio-rp5 volumio[1278]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 10:12:12 volumio-rp5 sudo[3388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-01 10:11 Mar 01 10:12:12 volumio-rp5 sudo[3388]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"