-- Logs begin at Sun 2025-12-07 18:51:04 IST, end at Tue 2025-12-09 19:26:46 IST. -- Dec 09 19:25:01 yechezkel go-librespot[1410]: time="2025-12-09T19:25:01+02:00" level=trace msg="sent dealer ping" Dec 09 19:25:01 yechezkel go-librespot[1410]: time="2025-12-09T19:25:01+02:00" level=trace msg="received dealer pong" Dec 09 19:25:14 yechezkel go-librespot[1410]: time="2025-12-09T19:25:14+02:00" level=trace msg="received accesspoint ping" Dec 09 19:25:14 yechezkel go-librespot[1410]: time="2025-12-09T19:25:14+02:00" level=trace msg="received accesspoint pong ack" Dec 09 19:25:15 yechezkel volumio[1470]: 27 Dec 09 19:25:15 yechezkel volumio[1470]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 09 19:25:15 yechezkel volumio[1470]: Dload Upload Total Spent Left Speed Dec 09 19:25:15 yechezkel volumio[1062]: info: Dec 09 19:25:15 yechezkel volumio[1062]: ---------------------------- Client requests Volume 27 Dec 09 19:25:15 yechezkel volumio[1062]: info: VolumeController::SetAlsaVolume27 Dec 09 19:25:15 yechezkel volumio[1062]: info: CoreStateMachine::pushState Dec 09 19:25:15 yechezkel volumio[1062]: info: CoreCommandRouter::volumioPushState Dec 09 19:25:15 yechezkel volumio[1470]: [158B blob data] Dec 09 19:25:15 yechezkel volumio[1062]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Dec 09 19:25:15 yechezkel volumio[1470]: {"time":1765301115484,"response":"volume Success"}27 Dec 09 19:25:15 yechezkel volumio[1062]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Invalid command! Dec 09 19:25:31 yechezkel go-librespot[1410]: time="2025-12-09T19:25:31+02:00" level=trace msg="sent dealer ping" Dec 09 19:25:31 yechezkel go-librespot[1410]: time="2025-12-09T19:25:31+02:00" level=trace msg="received dealer pong" Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 19:25:32 yechezkel volumio[1062]: info: Retrieving Cloud Streaming UI Dec 09 19:25:32 yechezkel volumio[1062]: info: Getting Tidal Cloud Configuration Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 19:25:32 yechezkel volumio[1062]: info: Getting Qobuz Cloud Configuration Dec 09 19:25:32 yechezkel volumio[1062]: info: Asking plugin for UI Config Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 19:25:32 yechezkel volumio[1062]: info: Getting Spotify Cloud Configuration Dec 09 19:25:32 yechezkel volumio[1062]: info: Asking plugin for UI Config Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 19:25:32 yechezkel volumio[1062]: info: Saving Spotify Acccount Dec 09 19:25:32 yechezkel volumio[1062]: info: Got it Dec 09 19:25:32 yechezkel volumio[1062]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 09 19:25:32 yechezkel volumio[1062]: info: Got Tidal Cloud Configuration Dec 09 19:25:32 yechezkel volumio[1062]: info: Got it Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::volumioGetBrowseSources Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::volumioGetBrowseSources Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::volumioGetBrowseSources Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 19:25:32 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 09 19:25:36 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 19:25:39 yechezkel volumio[1062]: info: Enabling MyMusic plugin bluetooth Dec 09 19:25:39 yechezkel volumio[1062]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Dec 09 19:25:39 yechezkel volumio[1062]: info: Enabling plugin bluetooth Dec 09 19:25:39 yechezkel volumio[1062]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 09 19:25:39 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 09 19:25:39 yechezkel volumio[1062]: info: Done. Dec 09 19:25:39 yechezkel sudo[17085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name yechezkel Dec 09 19:25:39 yechezkel sudo[17085]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:25:39 yechezkel sudo[17085]: pam_unix(sudo:session): session closed for user root Dec 09 19:25:39 yechezkel volumio[1062]: info: Bluetooth name changed to yechezkel Dec 09 19:25:40 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 19:25:42 yechezkel sudo[17088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 09 19:25:42 yechezkel sudo[17088]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:25:42 yechezkel systemd[1]: Started Volumio Bluetooth Module. Dec 09 19:25:42 yechezkel sudo[17088]: pam_unix(sudo:session): session closed for user root Dec 09 19:25:42 yechezkel volumio[1062]: info: Volumio BT Module successfully started Dec 09 19:25:42 yechezkel sudo[17092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Dec 09 19:25:42 yechezkel sudo[17092]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:25:42 yechezkel volumiobt[17090]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Dec 09 19:25:42 yechezkel sudo[17092]: pam_unix(sudo:session): session closed for user root Dec 09 19:25:43 yechezkel sudo[17095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Dec 09 19:25:43 yechezkel sudo[17095]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:25:43 yechezkel volumiobt[17090]: pulseaudio: no process found Dec 09 19:25:43 yechezkel sudo[17095]: pam_unix(sudo:session): session closed for user root Dec 09 19:25:43 yechezkel dbus-daemon[854]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.39' (uid=1000 pid=17099 comm="/usr/bin/pulseaudio --high-priority=true ") Dec 09 19:25:43 yechezkel systemd[1]: Starting RealtimeKit Scheduling Policy Service... Dec 09 19:25:43 yechezkel volumio[1062]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 19:25:43 yechezkel dbus-daemon[854]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Dec 09 19:25:43 yechezkel systemd[1]: Started RealtimeKit Scheduling Policy Service. Dec 09 19:25:43 yechezkel rtkit-daemon[17100]: Successfully called chroot. Dec 09 19:25:43 yechezkel rtkit-daemon[17100]: Successfully dropped privileges. Dec 09 19:25:43 yechezkel rtkit-daemon[17100]: Successfully limited resources. Dec 09 19:25:43 yechezkel rtkit-daemon[17100]: Running. Dec 09 19:25:43 yechezkel rtkit-daemon[17100]: Canary thread running. Dec 09 19:25:43 yechezkel rtkit-daemon[17100]: Watchdog thread running. Dec 09 19:25:43 yechezkel dbus-daemon[854]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.40' (uid=0 pid=17100 comm="/usr/lib/rtkit/rtkit-daemon ") Dec 09 19:25:43 yechezkel systemd[1]: Starting Authorization Manager... Dec 09 19:25:43 yechezkel volumio[1062]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 19:25:43 yechezkel polkitd[17104]: started daemon version 0.105 using authority implementation `local' version `0.105' Dec 09 19:25:43 yechezkel dbus-daemon[854]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 09 19:25:43 yechezkel systemd[1]: Started Authorization Manager. Dec 09 19:25:43 yechezkel pulseaudio[17099]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 09 19:25:43 yechezkel pulseaudio[17099]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 09 19:25:43 yechezkel bluetoothd[903]: Endpoint registered: sender=:1.60 path=/MediaEndpoint/A2DPSource Dec 09 19:25:43 yechezkel bluetoothd[903]: Endpoint registered: sender=:1.60 path=/MediaEndpoint/A2DPSink Dec 09 19:25:43 yechezkel volumiobt[17090]: Applying permissions Dec 09 19:25:43 yechezkel volumiobt[17090]: Setting BT discoverable and pairable Dec 09 19:25:43 yechezkel pulseaudio[17099]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Dec 09 19:25:43 yechezkel volumiobt[17090]: [96B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [77B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [77B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [33B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [95B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [95B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [95B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [95B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [95B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [95B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [95B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [95B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [95B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [95B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [55B blob data] Dec 09 19:25:43 yechezkel volumiobt[17090]: [bluetoothctl]> -e power on Dec 09 19:25:43 yechezkel volumiobt[17090]: Invalid command in menu main: -e Dec 09 19:25:43 yechezkel volumiobt[17090]: Dec 09 19:25:43 yechezkel volumiobt[17090]: Use "help" for a list of available commands in a menu. Dec 09 19:25:43 yechezkel volumiobt[17090]: Use "menu " if you want to enter any submenu. Dec 09 19:25:43 yechezkel volumiobt[17090]: Use "back" if you want to return to menu main. Dec 09 19:25:43 yechezkel volumiobt[17090]: [bluetoothctl]> agent on Dec 09 19:25:43 yechezkel volumiobt[17090]: Agent is already registered Dec 09 19:25:43 yechezkel volumiobt[17090]: [bluetoothctl]> discoverable on Dec 09 19:25:43 yechezkel volumiobt[17090]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Dec 09 19:25:43 yechezkel volumiobt[17090]: [bluetoothctl]> pairable on Dec 09 19:25:43 yechezkel volumiobt[17090]: [bluetoothctl]> agent NoInputNoOutput Dec 09 19:25:43 yechezkel volumiobt[17090]: Agent is already registered Dec 09 19:25:43 yechezkel kernel: Bluetooth: RFCOMM TTY layer initialized Dec 09 19:25:43 yechezkel kernel: Bluetooth: RFCOMM socket layer initialized Dec 09 19:25:43 yechezkel kernel: Bluetooth: RFCOMM ver 1.11 Dec 09 19:25:43 yechezkel volumiobt[17090]: [bluetoothctl]> default-agent Dec 09 19:25:43 yechezkel volumiobt[17090]: [bluetoothctl]> quit Dec 09 19:25:43 yechezkel volumiobt[17090]: [65B blob data] Dec 09 19:25:43 yechezkel bluetoothd[903]: src/adapter.c:store_adapter_info() Unable set contents for /usr/var/lib/bluetooth/2C:CF:67:CF:C2:72/settings: (Failed to create file “/usr/var/lib/bluetooth/2C:CF:67:CF:C2:72/settings.0F35G3”: No such file or directory) Dec 09 19:25:44 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 19:25:48 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 19:25:52 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 19:25:56 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 19:26:00 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 19:26:01 yechezkel go-librespot[1410]: time="2025-12-09T19:26:01+02:00" level=trace msg="sent dealer ping" Dec 09 19:26:01 yechezkel go-librespot[1410]: time="2025-12-09T19:26:01+02:00" level=trace msg="received dealer pong" Dec 09 19:26:02 yechezkel sudo[17148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 19:26:02 yechezkel sudo[17148]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:02 yechezkel sudo[17148]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:02 yechezkel sudo[17151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 19:26:02 yechezkel sudo[17151]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:02 yechezkel sudo[17151]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:02 yechezkel volumio[1062]: verbose: New Socket.io Connection to 10.0.0.4 from 10.0.0.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 09 19:26:02 yechezkel volumio[1062]: info: CoreCommandRouter::volumioGetState Dec 09 19:26:02 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 19:26:02 yechezkel volumio[1062]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 19:26:02 yechezkel volumio[1062]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 19:26:02 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 19:26:02 yechezkel volumio[1062]: info: Listing playlists Dec 09 19:26:04 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 19:26:04 yechezkel volumio[1062]: info: Received Get System Info Dec 09 19:26:04 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 19:26:04 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 19:26:04 yechezkel volumio[1062]: info: Discovery: Getting this device information Dec 09 19:26:04 yechezkel volumio[1062]: info: CoreCommandRouter::volumioGetState Dec 09 19:26:04 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 19:26:18 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 19:26:18 yechezkel volumio[1062]: info: Received Get System Info Dec 09 19:26:18 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 19:26:18 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 19:26:18 yechezkel volumio[1062]: info: Discovery: Getting this device information Dec 09 19:26:18 yechezkel volumio[1062]: info: CoreCommandRouter::volumioGetState Dec 09 19:26:18 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 19:26:21 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 19:26:21 yechezkel volumio[1062]: info: Received Get System Info Dec 09 19:26:21 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 19:26:21 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 19:26:21 yechezkel volumio[1062]: info: Discovery: Getting this device information Dec 09 19:26:21 yechezkel volumio[1062]: info: CoreCommandRouter::volumioGetState Dec 09 19:26:21 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 19:26:22 yechezkel sudo[17224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 19:26:22 yechezkel sudo[17224]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:22 yechezkel sudo[17224]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:22 yechezkel sudo[17227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 19:26:22 yechezkel sudo[17227]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:22 yechezkel sudo[17227]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:22 yechezkel volumio[1062]: verbose: New Socket.io Connection to 10.0.0.4 from 10.0.0.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 09 19:26:22 yechezkel volumio[1062]: info: CoreCommandRouter::volumioGetState Dec 09 19:26:22 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 19:26:22 yechezkel volumio[1062]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 19:26:22 yechezkel volumio[1062]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 19:26:22 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 19:26:22 yechezkel volumio[1062]: info: Listing playlists Dec 09 19:26:26 yechezkel volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 19:26:26 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 09 19:26:26 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 09 19:26:26 yechezkel sudo[17246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 09 19:26:26 yechezkel sudo[17246]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:26 yechezkel sudo[17246]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:26 yechezkel sudo[17252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 09 19:26:26 yechezkel sudo[17252]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:26 yechezkel sudo[17252]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:26 yechezkel sudo[17261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 09 19:26:26 yechezkel sudo[17261]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:26 yechezkel sudo[17261]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:26 yechezkel sudo[17266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 09 19:26:26 yechezkel sudo[17266]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:26 yechezkel sudo[17266]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:26 yechezkel sudo[17272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 19:26:26 yechezkel sudo[17272]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:26 yechezkel sudo[17272]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:26 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 09 19:26:26 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 09 19:26:26 yechezkel sudo[17275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 19:26:26 yechezkel sudo[17275]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:26 yechezkel sudo[17275]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:26 yechezkel sudo[17278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 09 19:26:26 yechezkel sudo[17278]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:30 yechezkel sudo[17278]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:31 yechezkel go-librespot[1410]: time="2025-12-09T19:26:31+02:00" level=trace msg="sent dealer ping" Dec 09 19:26:31 yechezkel go-librespot[1410]: time="2025-12-09T19:26:31+02:00" level=trace msg="received dealer pong" Dec 09 19:26:37 yechezkel volumio[1062]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Dec 09 19:26:37 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Dec 09 19:26:37 yechezkel sudo[17298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Dec 09 19:26:37 yechezkel sudo[17298]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:37 yechezkel sudo[17298]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:37 yechezkel sudo[17300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Dec 09 19:26:37 yechezkel sudo[17300]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:37 yechezkel sudo[17300]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:37 yechezkel volumio[1062]: info: Permissions for /etc/network/interfaces set Dec 09 19:26:37 yechezkel volumio[1062]: info: Restarting Wireless Network Dec 09 19:26:37 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 09 19:26:37 yechezkel volumio[1062]: info: Discovery: Restarting Advertising due to device name change Dec 09 19:26:37 yechezkel sudo[17303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 09 19:26:37 yechezkel sudo[17303]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:37 yechezkel systemd[1]: Starting Wireless Services... Dec 09 19:26:37 yechezkel volumio[1062]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 19:26:37 yechezkel wireless.js[17305]: Cleaning previous... Dec 09 19:26:37 yechezkel systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Dec 09 19:26:37 yechezkel volumio[1062]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 19:26:38 yechezkel systemd[1]: hostapd.service: Succeeded. Dec 09 19:26:38 yechezkel systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Dec 09 19:26:38 yechezkel sudo[17323]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 09 19:26:38 yechezkel sudo[17323]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:38 yechezkel avahi-daemon[859]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 09 19:26:38 yechezkel avahi-daemon[859]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 09 19:26:38 yechezkel avahi-daemon[859]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 09 19:26:38 yechezkel sudo[17323]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:38 yechezkel volumio[1062]: info: Discovery: A device disappeared from network Dec 09 19:26:38 yechezkel volumio[1062]: info: Discovery: Device yechezkel disappeared from network Dec 09 19:26:38 yechezkel sudo[17325]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 09 19:26:38 yechezkel sudo[17325]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:38 yechezkel volumio[1062]: info: Discovery: A device disappeared from network Dec 09 19:26:38 yechezkel sudo[17325]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:38 yechezkel wireless.js[17305]: Stopped aP Dec 09 19:26:38 yechezkel sudo[17333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 09 19:26:38 yechezkel sudo[17333]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:38 yechezkel sudo[17333]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:38 yechezkel kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 09 19:26:38 yechezkel sudo[17335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 09 19:26:38 yechezkel sudo[17335]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:38 yechezkel sudo[17335]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:38 yechezkel sudo[17342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 09 19:26:38 yechezkel sudo[17342]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:38 yechezkel sudo[17342]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:38 yechezkel sudo[17344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 09 19:26:38 yechezkel sudo[17344]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:39 yechezkel ntpd[951]: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=179535 secs Dec 09 19:26:41 yechezkel sudo[17344]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:41 yechezkel wireless.js[17305]: SETTING APPROPRIATE REG DOMAIN: DE Dec 09 19:26:41 yechezkel sudo[17349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 09 19:26:41 yechezkel sudo[17349]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:41 yechezkel sudo[17349]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:41 yechezkel sudo[17351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Dec 09 19:26:41 yechezkel sudo[17351]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:41 yechezkel sudo[17351]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:41 yechezkel wireless.js[17305]: SUCCESSFULLY SET NEW REGDOMAIN: DE Dec 09 19:26:41 yechezkel sudo[17358]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 09 19:26:41 yechezkel sudo[17358]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:41 yechezkel sudo[17358]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:41 yechezkel sudo[17360]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 09 19:26:41 yechezkel sudo[17360]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 19:26:41 yechezkel volumio[1062]: info: Retrieving Cloud Streaming UI Dec 09 19:26:41 yechezkel volumio[1062]: info: Getting Tidal Cloud Configuration Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 19:26:41 yechezkel volumio[1062]: info: Getting Qobuz Cloud Configuration Dec 09 19:26:41 yechezkel volumio[1062]: info: Asking plugin for UI Config Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 19:26:41 yechezkel volumio[1062]: info: Getting Spotify Cloud Configuration Dec 09 19:26:41 yechezkel volumio[1062]: info: Asking plugin for UI Config Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 19:26:41 yechezkel volumio[1062]: info: Saving Spotify Acccount Dec 09 19:26:41 yechezkel volumio[1062]: info: Got it Dec 09 19:26:41 yechezkel volumio[1062]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 09 19:26:41 yechezkel volumio[1062]: info: Got Tidal Cloud Configuration Dec 09 19:26:41 yechezkel volumio[1062]: info: Got it Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::volumioGetBrowseSources Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::volumioGetBrowseSources Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 19:26:41 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 09 19:26:41 yechezkel sudo[17360]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:41 yechezkel avahi-daemon[859]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 09 19:26:41 yechezkel avahi-daemon[859]: New relevant interface wlan0.IPv4 for mDNS. Dec 09 19:26:41 yechezkel avahi-daemon[859]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 09 19:26:41 yechezkel kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 09 19:26:41 yechezkel systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Dec 09 19:26:41 yechezkel hostapd[17369]: Configuration file: /etc/hostapd/hostapd.conf Dec 09 19:26:41 yechezkel hostapd[17369]: wlan0: Could not connect to kernel driver Dec 09 19:26:41 yechezkel volumio[1062]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 19:26:41 yechezkel hostapd[17369]: Using interface wlan0 with hwaddr 2c:cf:67:cf:c2:71 and ssid "Volumio-F68D6" Dec 09 19:26:42 yechezkel hostapd[17369]: wlan0: interface state UNINITIALIZED->ENABLED Dec 09 19:26:42 yechezkel hostapd[17369]: wlan0: AP-ENABLED Dec 09 19:26:42 yechezkel systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Dec 09 19:26:42 yechezkel iwconfig[17372]: Error for wireless request "Set Power Management" (8B2C) : Dec 09 19:26:42 yechezkel iwconfig[17372]: SET failed on device wlan0 ; Invalid argument. Dec 09 19:26:42 yechezkel systemd[1]: wireless.service: Succeeded. Dec 09 19:26:42 yechezkel systemd[1]: Started Wireless Services. Dec 09 19:26:42 yechezkel sudo[17303]: pam_unix(sudo:session): session closed for user root Dec 09 19:26:42 yechezkel volumio[1062]: info: Discovery: Started advertising with name: yechezkel Dec 09 19:26:43 yechezkel volumio[1062]: info: Discovery: adding 6d3e59cf-98eb-4ea7-84a9-db6b5390cd50 Dec 09 19:26:43 yechezkel volumio[1062]: info: Discovery: Found device yechezkel Dec 09 19:26:43 yechezkel volumio[1062]: info: CoreCommandRouter::volumioGetState Dec 09 19:26:43 yechezkel volumio[1062]: info: MRS: Pushing multiroomSync output for this device Dec 09 19:26:43 yechezkel volumio[1062]: info: MRS: Pushing multiroomSync output Dec 09 19:26:43 yechezkel volumio[1062]: info: Adding audio output: Dec 09 19:26:43 yechezkel volumio[1062]: info: Adding audio output: Dec 09 19:26:43 yechezkel volumio[1062]: info: Discovery: this is already registered, 6d3e59cf-98eb-4ea7-84a9-db6b5390cd50 Dec 09 19:26:43 yechezkel volumio[1062]: info: Discovery: Found device yechezkel Dec 09 19:26:43 yechezkel volumio[1062]: info: CoreCommandRouter::volumioGetState Dec 09 19:26:43 yechezkel ntpd[951]: Listen normally on 5 wlan0 192.168.211.1:123 Dec 09 19:26:43 yechezkel ntpd[951]: new interface(s) found: waking up resolver Dec 09 19:26:45 yechezkel volumio[1062]: info: Disabling MyMusic plugin upnp Dec 09 19:26:45 yechezkel sudo[17378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 09 19:26:45 yechezkel sudo[17378]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 19:26:45 yechezkel systemd[1]: Stopping UPnP Renderer front-end to MPD... Dec 09 19:26:45 yechezkel volumio[1062]: error: Upnp client error: Error: This socket has been ended by the other party Dec 09 19:26:45 yechezkel volumio[1062]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 19:26:45 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 19:26:46 yechezkel volumio[1062]: info: Enabling MyMusic plugin upnp Dec 09 19:26:46 yechezkel volumio[1062]: info: Enabling plugin upnp Dec 09 19:26:46 yechezkel volumio[1062]: info: Loading plugin "upnp"... Dec 09 19:26:46 yechezkel volumio[1062]: info: [1765301206196] Starting Upmpd Daemon Dec 09 19:26:46 yechezkel volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 09 19:26:46 yechezkel volumio[1062]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 19:26:46 yechezkel volumio[1062]: Error: listen EADDRINUSE: address already in use :::6599 Dec 09 19:26:46 yechezkel volumio[1062]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Dec 09 19:26:46 yechezkel volumio[1062]: at listenInCluster (net.js:1366:12) Dec 09 19:26:46 yechezkel volumio[1062]: at Server.listen (net.js:1452:7) Dec 09 19:26:46 yechezkel volumio[1062]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 09 19:26:46 yechezkel volumio[1062]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 09 19:26:46 yechezkel volumio[1062]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 09 19:26:46 yechezkel volumio[1062]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 09 19:26:46 yechezkel volumio[1062]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Dec 09 19:26:46 yechezkel volumio[1062]: code: 'EADDRINUSE', Dec 09 19:26:46 yechezkel volumio[1062]: errno: -98, Dec 09 19:26:46 yechezkel volumio[1062]: syscall: 'listen', Dec 09 19:26:46 yechezkel volumio[1062]: address: '::', Dec 09 19:26:46 yechezkel volumio[1062]: port: 6599 Dec 09 19:26:46 yechezkel volumio[1062]: } Dec 09 19:26:46 yechezkel volumio[1062]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 19:26:46 yechezkel sudo[17403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-09 19:25 Dec 09 19:26:46 yechezkel sudo[17403]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"