May 19 21:13:01 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 21:13:05 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 21:13:07 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 19 21:13:07 volumio dbus-daemon[962]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.132' (uid=0 pid=8583 comm="timedatectl show --property=NTPSynchronized --valu") May 19 21:13:07 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 19 21:13:07 volumio dbus-daemon[962]: [system] Successfully activated service 'org.freedesktop.timedate1' May 19 21:13:07 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 19 21:13:07 volumio setdatetime-helper.sh[8582]: Time is already synchronized. May 19 21:13:07 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 19 21:13:07 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 19 21:13:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 21:13:13 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 21:13:17 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 21:13:21 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 21:13:22 volumio volumio[1450]: info: VolumeController::SetAlsaVolume42 May 19 21:13:22 volumio volumio[1450]: info: CoreStateMachine::pushState May 19 21:13:22 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 21:13:22 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 19 21:13:22 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 19 21:13:22 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:13:23 volumio volumio[1450]: info: VolumeController::SetAlsaVolume86 May 19 21:13:23 volumio volumio[1450]: info: CoreStateMachine::pushState May 19 21:13:23 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 21:13:23 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 19 21:13:23 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 19 21:13:23 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:13:23 volumio volumio[1450]: info: VolumeController::SetAlsaVolume89 May 19 21:13:23 volumio volumio[1450]: info: CoreStateMachine::pushState May 19 21:13:23 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 21:13:23 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 19 21:13:23 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 19 21:13:23 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:13:23 volumio volumio[1450]: info: VolumeController::SetAlsaVolume90 May 19 21:13:23 volumio volumio[1450]: info: CoreStateMachine::pushState May 19 21:13:23 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 21:13:23 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 19 21:13:23 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 19 21:13:23 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:13:25 volumio volumio[1450]: info: VolumeController::SetAlsaVolume60 May 19 21:13:25 volumio volumio[1450]: info: CoreStateMachine::pushState May 19 21:13:25 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 21:13:25 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 19 21:13:25 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 19 21:13:25 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:13:25 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 19 21:13:26 volumio volumio[1450]: info: VolumeController::SetAlsaVolume65 May 19 21:13:26 volumio volumio[1450]: info: CoreStateMachine::pushState May 19 21:13:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 21:13:26 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 19 21:13:26 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 19 21:13:26 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:13:30 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 19 21:13:31 volumio volumio[1450]: info: CoreCommandRouter::volumioVolatilePlay May 19 21:13:31 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [FUNC] play May 19 21:13:31 volumio volumio[1450]: ------------------------------------ BT MESSAGE: sendPlay -> calling D-Bus Play() May 19 21:13:32 volumio volumio[1450]: info: Executing endpoint metavolumio May 19 21:13:32 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 19 21:13:32 volumio volumio[1450]: info: Executing endpoint metavolumio May 19 21:13:32 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 19 21:13:32 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: true from D4:68:AA:2A:75:B5 May 19 21:13:32 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Playback started, enabling output May 19 21:13:32 volumio volumio[1450]: info: CoreCommandRouter::volumioStop May 19 21:13:32 volumio volumio[1450]: info: CoreStateMachine::stop May 19 21:13:32 volumio volumio[1450]: info: CoreStateMachine::serviceStop May 19 21:13:32 volumio volumio[1450]: info: Received STOP, but no service to execute it May 19 21:13:32 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput May 19 21:13:32 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [AAMP] Modular pipeline enabled - using default ALSA route: volumio May 19 21:13:32 volumio bluetoothd[961]: Adv Monitor app :1.134 disconnected from D-Bus May 19 21:13:32 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Detected connected BT MAC: D4:68:AA:2A:75:B5 May 19 21:13:32 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Spawning bluealsa-aplay with args: --profile-a2dp --pcm=volumio --pcm-buffer-time=500000 --pcm-period-time=100000 D4:68:AA:2A:75:B5 May 19 21:13:32 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [metaCache] Loaded metadata for D4:68:AA:2A:75:B5 from memory May 19 21:13:32 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Loaded metadata from cache for D4:68:AA:2A:75:B5 May 19 21:13:32 volumio volumio[1450]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [8639] D: aplay.c:904: Creating IO worker D4:68:AA:2A:75:B5 May 19 21:13:32 volumio volumio[1450]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [8639] D: aplay.c:1320: Starting main loop May 19 21:13:32 volumio volumio[1450]: bluealsa-aplay: [8640] D: aplay.c:577: Opening BlueALSA source PCM: /org/bluealsa/hci0/dev_D4_68_AA_2A_75_B5/a2dpsnk/source May 19 21:13:32 volumio bluealsa[1019]: ../src/dbus.c:47: Called: org.bluealsa.PCM1.Open() on /org/bluealsa/hci0/dev_D4_68_AA_2A_75_B5/a2dpsnk/source May 19 21:13:32 volumio volumio[1450]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [8640] D: aplay.c:603: Starting IO loop May 19 21:13:32 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Seek received post-resume, pushing metadata May 19 21:13:32 volumio volumio[1450]: info: CoreCommandRouter::servicePushState May 19 21:13:32 volumio volumio[1450]: info: CoreStateMachine::pushState May 19 21:13:32 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 21:13:32 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 19 21:13:32 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 19 21:13:32 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:13:33 volumio bluealsa[1019]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State May 19 21:13:33 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: false from D4:68:AA:2A:75:B5 May 19 21:13:33 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Received pause signal, scheduling idle check May 19 21:13:33 volumio volumio[1450]: info: CoreCommandRouter::servicePushState May 19 21:13:33 volumio volumio[1450]: info: CoreStateMachine::pushState May 19 21:13:33 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 21:13:33 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 19 21:13:33 volumio bluealsa[1019]: ../src/ba-transport.c:319: New A2DP transport: 15 May 19 21:13:33 volumio bluealsa[1019]: ../src/ba-transport.c:320: A2DP socket MTU: 15: R:672 W:1004 May 19 21:13:33 volumio bluealsa[1019]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State May 19 21:13:33 volumio bluealsa[1019]: ../src/ba-transport.c:1075: Starting transport: A2DP Sink (SBC) May 19 21:13:33 volumio bluealsa[1019]: ../src/ba-transport-pcm.c:294: Created BT socket duplicate: [15]: 18 May 19 21:13:33 volumio bluealsa[1019]: ../src/a2dp-sbc.c:331: PCM IO loop: START: a2dp_sbc_dec_thread: A2DP Sink (SBC) May 19 21:13:33 volumio bluealsa[1019]: ../src/ba-transport-pcm.c:373: Created new IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) May 19 21:13:33 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 19 21:13:33 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:13:33 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: true from D4:68:AA:2A:75:B5 May 19 21:13:33 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Playback started, enabling output May 19 21:13:33 volumio volumio[1450]: info: CoreCommandRouter::volumioStop May 19 21:13:33 volumio volumio[1450]: info: CoreStateMachine::stop May 19 21:13:33 volumio volumio[1450]: info: CoreStateMachine::serviceStop May 19 21:13:33 volumio volumio[1450]: info: CoreCommandRouter::serviceStop May 19 21:13:33 volumio volumio[1450]: error: WARNING: No stop method for service [object Object] May 19 21:13:33 volumio dbus-daemon[962]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.40" (uid=1000 pid=1450 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=961 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") May 19 21:13:33 volumio bluealsa[1019]: ../src/codec-sbc.c:278: SBC setup: 44100 Hz JointStereo allocation=Loudness blocks=16 sub-bands=8 bit-pool=53 => 327993 bps May 19 21:13:33 volumio volumio[1450]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [8640] D: aplay.c:732: Opening ALSA playback PCM: name=volumio channels=2 rate=44100 May 19 21:13:33 volumio volumio[1450]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [8640] D: aplay.c:339: Opening ALSA mixer: name=default elem=Master index=0 May 19 21:13:34 volumio kernel: wlan0: AP c2:a5:11:38:b5:d0 changed bandwidth, new used config is 2462.000 MHz, width 2 (2452.000/0 MHz) May 19 21:13:35 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Fallback: pushing metadata after resume May 19 21:13:35 volumio volumio[1450]: info: CoreCommandRouter::servicePushState May 19 21:13:35 volumio volumio[1450]: info: CoreStateMachine::pushState May 19 21:13:35 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 21:13:35 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 19 21:13:35 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 19 21:13:35 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:13:36 volumio kernel: wlan0: AP c2:a5:11:38:b5:d0 changed bandwidth, new used config is 2462.000 MHz, width 1 (2462.000/0 MHz) May 19 21:13:37 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 19 21:13:38 volumio volumio[1450]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found May 19 21:13:38 volumio volumio[1450]: at createHttpError (/volumio/node_modules/send/index.js:979:12) May 19 21:13:38 volumio volumio[1450]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) May 19 21:13:38 volumio volumio[1450]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14) May 19 21:13:38 volumio volumio[1450]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8) May 19 21:13:38 volumio volumio[1450]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3) May 19 21:13:38 volumio volumio[1450]: at Promise._failFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:449:13) May 19 21:13:38 volumio volumio[1450]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 19 21:13:38 volumio volumio[1450]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) May 19 21:14:03 volumio systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... May 19 21:14:03 volumio bluealsa[1019]: ../src/io.c:47: BT socket disconnected: Connection reset by peer May 19 21:14:03 volumio bluealsa[1019]: ../src/ba-transport-pcm.c:307: Closing BT socket duplicate [15]: 18 May 19 21:14:03 volumio bluealsa[1019]: ../src/a2dp-sbc.c:392: PCM IO loop: EXIT: a2dp_sbc_dec_thread: A2DP Sink (SBC) May 19 21:14:03 volumio bluealsa[1019]: ../src/ba-transport.c:346: Releasing A2DP transport: 15 May 19 21:14:03 volumio bluealsa[1019]: ../src/dbus.c:47: Called: org.bluez.MediaEndpoint1.ClearConfiguration() on /org/bluez/hci0/A2DP/SBC/sink/1 May 19 21:14:03 volumio bluealsa[1019]: bluez.c:615: Disconnecting media endpoint: /org/bluez/hci0/A2DP/SBC/sink/1 May 19 21:14:03 volumio volumio[1450]: [97B blob data] May 19 21:14:03 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth May 19 21:14:03 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput May 19 21:14:03 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output May 19 21:14:03 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Killing bluealsa-aplay process May 19 21:14:03 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. May 19 21:14:03 volumio volumio[1450]: verbose: UNSET VOLATILE: Service: bluetooth May 19 21:14:03 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth May 19 21:14:03 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput May 19 21:14:03 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output May 19 21:14:03 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. May 19 21:14:03 volumio volumio[1450]: info: CoreStateMachine::resetVolumioState May 19 21:14:03 volumio volumio[1450]: info: CoreStateMachine::getcurrentVolume May 19 21:14:03 volumio volumio[1450]: info: CoreCommandRouter::volumioRetrievevolume May 19 21:14:03 volumio volumio[1450]: info: CoreStateMachine::resetVolumioState May 19 21:14:03 volumio volumio[1450]: info: CoreStateMachine::getcurrentVolume May 19 21:14:03 volumio volumio[1450]: info: CoreCommandRouter::volumioRetrievevolume May 19 21:14:03 volumio systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. May 19 21:14:03 volumio volumio[1450]: info: CoreCommandRouter::volumioStop May 19 21:14:03 volumio volumio[1450]: info: CoreStateMachine::stop May 19 21:14:03 volumio volumio[1450]: info: CoreStateMachine::setConsumeUpdateService undefined May 19 21:14:03 volumio volumio[1450]: info: CoreCommandRouter::volumioStop May 19 21:14:03 volumio volumio[1450]: info: CoreStateMachine::stop May 19 21:14:03 volumio volumio[1450]: info: CoreStateMachine::setConsumeUpdateService undefined May 19 21:14:03 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Detached Bluetooth after transport removal May 19 21:14:03 volumio volumio[1450]: ------------------------------------ BT MESSAGE: bluealsa-aplay exited with code null, signal SIGKILL May 19 21:14:03 volumio acpid[958]: input device has been disconnected, fd 18 May 19 21:14:03 volumio volumio[1450]: info: VolumeController:: Volume=66 Mute =false May 19 21:14:03 volumio volumio[1450]: info: CoreStateMachine::pushState May 19 21:14:03 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 19 21:14:03 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 21:14:03 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 19 21:14:03 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 19 21:14:03 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:14:03 volumio volumio[1450]: info: CoreStateMachine::updateTrackBlock May 19 21:14:03 volumio volumio[1450]: info: CorePlayQueue::getTrackBlock May 19 21:14:03 volumio volumio[1450]: info: CoreCommandRouter::volumioRetrievevolume May 19 21:14:03 volumio volumio[1450]: info: VolumeController:: Volume=66 Mute =false May 19 21:14:03 volumio volumio[1450]: info: CoreStateMachine::pushState May 19 21:14:03 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 19 21:14:03 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 19 21:14:03 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 19 21:14:03 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:14:03 volumio volumio[1450]: info: CoreStateMachine::updateTrackBlock May 19 21:14:03 volumio volumio[1450]: info: CorePlayQueue::getTrackBlock May 19 21:14:03 volumio volumio[1450]: info: CoreCommandRouter::volumioRetrievevolume May 19 21:14:03 volumio volumio[1450]: info: VolumeController:: Volume=66 Mute =false May 19 21:14:03 volumio volumio[1450]: info: CoreStateMachine::pushState May 19 21:14:03 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 19 21:14:03 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 19 21:14:03 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 19 21:14:03 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:14:03 volumio volumio[1450]: info: VolumeController:: Volume=66 Mute =false May 19 21:14:03 volumio volumio[1450]: info: CoreStateMachine::pushState May 19 21:14:03 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 19 21:14:03 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 19 21:14:03 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 19 21:14:03 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:14:03 volumio bluealsa[1019]: ../src/ba-transport.c:381: Closing A2DP transport: 15 May 19 21:14:03 volumio bluealsa[1019]: ../src/ba-transport-pcm.c:257: Exiting IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) May 19 21:14:03 volumio bluealsa[1019]: ../src/ba-transport-pcm.c:453: Closing PCM: 16 May 19 21:14:03 volumio bluealsa[1019]: ../src/ba-transport.c:826: Freeing transport: A2DP Sink (SBC) May 19 21:14:03 volumio bluealsa[1019]: ../src/storage.c:160: Saving storage: /var/lib/bluealsa/D4:68:AA:2A:75:B5 May 19 21:14:03 volumio bluealsa[1019]: ../src/ba-device.c:143: Freeing device: D4:68:AA:2A:75:B5 May 19 21:14:03 volumio bluealsa[1019]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_D4_68_AA_2A_75_B5/fd0, ...) May 19 21:14:03 volumio bluealsa[1019]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_D4_68_AA_2A_75_B5/player0, ...) May 19 21:14:03 volumio bluealsa[1019]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_D4_68_AA_2A_75_B5/sep1, ...) May 19 21:14:03 volumio bluealsa[1019]: bluez.c:1428: Removing Stream End-Point: D4:68:AA:2A:75:B5: SRC: SBC May 19 21:14:03 volumio bluealsa[1019]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_D4_68_AA_2A_75_B5/sep2, ...) May 19 21:14:03 volumio bluealsa[1019]: bluez.c:1428: Removing Stream End-Point: D4:68:AA:2A:75:B5: SRC: SBC May 19 21:14:03 volumio bluealsa[1019]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_D4_68_AA_2A_75_B5/sep3, ...) May 19 21:14:03 volumio bluealsa[1019]: bluez.c:1428: Removing Stream End-Point: D4:68:AA:2A:75:B5: SRC: AAC May 19 21:14:03 volumio bluealsa[1019]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_D4_68_AA_2A_75_B5/sep4, ...) May 19 21:14:03 volumio bluealsa[1019]: bluez.c:1428: Removing Stream End-Point: D4:68:AA:2A:75:B5: SRC: AAC May 19 21:14:03 volumio bluealsa[1019]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_D4_68_AA_2A_75_B5/sep5, ...) May 19 21:14:03 volumio bluealsa[1019]: bluez.c:1428: Removing Stream End-Point: D4:68:AA:2A:75:B5: SRC: (null) May 19 21:14:03 volumio bluealsa[1019]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_D4_68_AA_2A_75_B5/sep6, ...) May 19 21:14:03 volumio bluealsa[1019]: bluez.c:1428: Removing Stream End-Point: D4:68:AA:2A:75:B5: SRC: (null) May 19 21:14:03 volumio kernel: wlan0: deauthenticating from c2:a5:11:38:b5:d0 by local choice (Reason: 3=DEAUTH_LEAVING) May 19 21:14:03 volumio wpa_supplicant[1238]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c2:a5:11:38:b5:d0 reason=3 locally_generated=1 May 19 21:14:03 volumio avahi-daemon[1445]: Interface wlan0.IPv4 no longer relevant for mDNS. May 19 21:14:03 volumio avahi-daemon[1445]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.132. May 19 21:14:03 volumio volumio[1450]: info: Discovery: A device disappeared from network May 19 21:14:03 volumio volumio[1450]: info: Discovery: Device motivo disappeared from network May 19 21:14:03 volumio dhcpcd[987]: wlan0: carrier lost May 19 21:14:03 volumio avahi-daemon[1445]: Withdrawing address record for 192.168.0.132 on wlan0. May 19 21:14:03 volumio volumio[1450]: info: Discovery: Disconnected from remote: 192.168.0.131 May 19 21:14:03 volumio wpa_supplicant[1238]: rfkill: WLAN soft blocked May 19 21:14:03 volumio wpa_supplicant[1238]: rfkill: WLAN soft blocked May 19 21:14:03 volumio volumio[1450]: info: Discovery: A device disappeared from network May 19 21:14:03 volumio volumio[1450]: info: Discovery: Device bathroom disappeared from network May 19 21:14:03 volumio volumio[1450]: info: Discovery: Disconnected from remote: 192.168.0.140 May 19 21:14:03 volumio volumio[1450]: info: Discovery: A device disappeared from network May 19 21:14:03 volumio volumio[1450]: info: Discovery: Device volumio disappeared from network May 19 21:14:03 volumio volumio[1450]: info: Discovery: A device disappeared from network May 19 21:14:03 volumio volumio[1450]: info: Discovery: Device minidsp-shd disappeared from network May 19 21:14:03 volumio volumio[1450]: info: Discovery: Disconnected from remote: 192.168.0.156 May 19 21:14:04 volumio dhcpcd[987]: wlan0: deleting route to 192.168.0.0/24 May 19 21:14:04 volumio dhcpcd[987]: wlan0: deleting default route via 192.168.0.1 May 19 21:14:04 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 19 21:14:04 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 19 21:14:04 volumio systemd[1]: welcome.service: Deactivated successfully. May 19 21:14:04 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 19 21:14:04 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 19 21:14:04 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 19 21:14:04 volumio welcome[8732]: Resolved ip:[1] 192.168.0.142 May 19 21:14:04 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 19 21:14:04 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 19 21:14:05 volumio ntpd[1095]: IO: Deleting interface #6 wlan0, 192.168.0.132#123, interface stats: received=0, sent=0, dropped=0, active_time=161 secs May 19 21:14:08 volumio systemd[1]: systemd-rfkill.service: Deactivated successfully. May 19 21:14:09 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 19 21:14:09 volumio dbus-daemon[962]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.136' (uid=0 pid=8742 comm="timedatectl show --property=NTPSynchronized --valu") May 19 21:14:09 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 19 21:14:09 volumio dbus-daemon[962]: [system] Successfully activated service 'org.freedesktop.timedate1' May 19 21:14:09 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 19 21:14:09 volumio setdatetime-helper.sh[8741]: Time is already synchronized. May 19 21:14:09 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 19 21:14:09 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 19 21:14:12 volumio volumio[1450]: info: Volumio Network Manager: Network status updated: 1 May 19 21:14:15 volumio volumio[1450]: info: CoreCommandRouter::getUIConfigOnPlugin May 19 21:14:15 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 19 21:14:15 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 19 21:14:15 volumio sudo[8764]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 19 21:14:15 volumio sudo[8764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:15 volumio sudo[8771]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 19 21:14:15 volumio sudo[8771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:15 volumio sudo[8773]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 19 21:14:15 volumio sudo[8773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:15 volumio sudo[8771]: pam_unix(sudo:session): session closed for user root May 19 21:14:15 volumio sudo[8764]: pam_unix(sudo:session): session closed for user root May 19 21:14:15 volumio sudo[8773]: pam_unix(sudo:session): session closed for user root May 19 21:14:15 volumio sudo[8782]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 19 21:14:15 volumio sudo[8782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:15 volumio sudo[8782]: pam_unix(sudo:session): session closed for user root May 19 21:14:15 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 19 21:14:15 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 19 21:14:15 volumio sudo[8789]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 19 21:14:15 volumio sudo[8789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:15 volumio sudo[8789]: pam_unix(sudo:session): session closed for user root May 19 21:14:15 volumio sudo[8792]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 21:14:15 volumio sudo[8792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:15 volumio sudo[8792]: pam_unix(sudo:session): session closed for user root May 19 21:14:15 volumio sudo[8795]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 19 21:14:15 volumio sudo[8795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:15 volumio sudo[8795]: pam_unix(sudo:session): session closed for user root May 19 21:14:15 volumio volumio[1450]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 19 21:14:15 volumio volumio[1450]: wlan0 Interface doesn't support scanning : Network is down May 19 21:14:15 volumio volumio[1450]: info: Cannot use regular scanning, forcing with ap-force May 19 21:14:15 volumio sudo[8800]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 19 21:14:15 volumio sudo[8800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:15 volumio sudo[8800]: pam_unix(sudo:session): session closed for user root May 19 21:14:15 volumio volumio[1450]: command failed: Network is down (-100) May 19 21:14:15 volumio volumio[1450]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 19 21:14:15 volumio volumio[1450]: command failed: Network is down (-100) May 19 21:14:21 volumio systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... May 19 21:14:21 volumio systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. May 19 21:14:21 volumio wpa_supplicant[1238]: rfkill: WLAN unblocked May 19 21:14:21 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM May 19 21:14:21 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM May 19 21:14:21 volumio kernel: iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring May 19 21:14:21 volumio wpa_supplicant[1238]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=WORLD May 19 21:14:21 volumio wpa_supplicant[1238]: rfkill: WLAN unblocked May 19 21:14:24 volumio wpa_supplicant[1238]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=DE May 19 21:14:24 volumio wpa_supplicant[1238]: wlan0: SME: Trying to authenticate with c2:a5:11:38:b5:d0 (SSID='rvl-10022' freq=2462 MHz) May 19 21:14:24 volumio kernel: wlan0: authenticate with c2:a5:11:38:b5:d0 (local address=ac:ed:5c:1a:b2:f1) May 19 21:14:24 volumio kernel: wlan0: send auth to c2:a5:11:38:b5:d0 (try 1/3) May 19 21:14:24 volumio wpa_supplicant[1238]: wlan0: Trying to associate with c2:a5:11:38:b5:d0 (SSID='rvl-10022' freq=2462 MHz) May 19 21:14:24 volumio kernel: wlan0: authenticated May 19 21:14:24 volumio kernel: wlan0: associate with c2:a5:11:38:b5:d0 (try 1/3) May 19 21:14:25 volumio kernel: wlan0: associate with c2:a5:11:38:b5:d0 (try 2/3) May 19 21:14:25 volumio kernel: wlan0: RX AssocResp from c2:a5:11:38:b5:d0 (capab=0x1431 status=0 aid=3) May 19 21:14:25 volumio wpa_supplicant[1238]: wlan0: Associated with c2:a5:11:38:b5:d0 May 19 21:14:25 volumio wpa_supplicant[1238]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 19 21:14:25 volumio kernel: wlan0: associated May 19 21:14:26 volumio systemd[1]: systemd-rfkill.service: Deactivated successfully. May 19 21:14:28 volumio wpa_supplicant[1238]: wlan0: WPA: Key negotiation completed with c2:a5:11:38:b5:d0 [PTK=CCMP GTK=CCMP] May 19 21:14:28 volumio wpa_supplicant[1238]: wlan0: CTRL-EVENT-CONNECTED - Connection to c2:a5:11:38:b5:d0 completed [id=0 id_str=] May 19 21:14:28 volumio dhcpcd[987]: wlan0: carrier acquired May 19 21:14:28 volumio dhcpcd[987]: wlan0: IAID 5c:1a:b2:f1 May 19 21:14:28 volumio dhcpcd[987]: wlan0: soliciting an IPv6 router May 19 21:14:29 volumio dhcpcd[987]: wlan0: rebinding lease of 192.168.0.132 May 19 21:14:29 volumio dhcpcd[987]: wlan0: probing address 192.168.0.132/24 May 19 21:14:33 volumio dhcpcd[987]: wlan0: leased 192.168.0.132 for 86400 seconds May 19 21:14:33 volumio avahi-daemon[1445]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.132. May 19 21:14:33 volumio avahi-daemon[1445]: New relevant interface wlan0.IPv4 for mDNS. May 19 21:14:33 volumio dhcpcd[987]: wlan0: adding route to 192.168.0.0/24 May 19 21:14:33 volumio avahi-daemon[1445]: Registering new address record for 192.168.0.132 on wlan0.IPv4. May 19 21:14:33 volumio dhcpcd[987]: wlan0: adding default route via 192.168.0.1 May 19 21:14:33 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 19 21:14:33 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 19 21:14:33 volumio systemd[1]: welcome.service: Deactivated successfully. May 19 21:14:33 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 19 21:14:33 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 19 21:14:33 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 19 21:14:33 volumio welcome[8850]: Resolved ip:[2] 192.168.0.142 192.168.0.132 May 19 21:14:33 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 19 21:14:33 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 19 21:14:33 volumio volumio[1450]: info: Discovery: adding e5c2f1d9-7c19-4037-a309-70cded3bf77d May 19 21:14:33 volumio volumio[1450]: info: Discovery: Found device miniDSP-SHD May 19 21:14:33 volumio volumio[1450]: info: Discovery: Connecting to remote: 192.168.0.156 May 19 21:14:33 volumio volumio[1450]: info: MRS: Pushing audio output on request May 19 21:14:33 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:14:33 volumio volumio[1450]: info: Discovery: Connected to remote: 192.168.0.156 May 19 21:14:33 volumio volumio[1450]: info: Discovery: adding 5c53dcfe-cabb-4cdc-8b88-635a017cf77d May 19 21:14:33 volumio volumio[1450]: info: Discovery: Found device bathroom May 19 21:14:33 volumio volumio[1450]: info: Discovery: Connecting to remote: 192.168.0.140 May 19 21:14:33 volumio volumio[1450]: info: MRS: Pushing audio output on request May 19 21:14:33 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:14:33 volumio volumio[1450]: info: Discovery: Connected to remote: 192.168.0.140 May 19 21:14:33 volumio volumio[1450]: info: Discovery: adding c78c46fc-60fd-446a-8cc4-b1ebc238ff40 May 19 21:14:33 volumio volumio[1450]: info: Discovery: Found device Motivo May 19 21:14:33 volumio volumio[1450]: info: Discovery: Connecting to remote: 192.168.0.131 May 19 21:14:34 volumio volumio[1450]: info: MRS: Pushing audio output on request May 19 21:14:34 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:14:34 volumio volumio[1450]: info: Discovery: Connected to remote: 192.168.0.131 May 19 21:14:34 volumio volumio[1450]: info: Discovery: adding 5c39d368-b095-48ed-83c9-7d354182167b May 19 21:14:34 volumio volumio[1450]: info: Discovery: Found device Volumio May 19 21:14:34 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 19 21:14:34 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 19 21:14:35 volumio ntpd[1095]: IO: Listen normally on 7 wlan0 192.168.0.132:123 May 19 21:14:35 volumio ntpd[1095]: IO: new interface(s) found: waking up resolver May 19 21:14:39 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 19 21:14:42 volumio volumio[1450]: info: Volumio Network Manager: Network status updated: 3 May 19 21:14:45 volumio volumio[1450]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] May 19 21:14:45 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet May 19 21:14:45 volumio sudo[8892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces May 19 21:14:45 volumio sudo[8892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:45 volumio sudo[8892]: pam_unix(sudo:session): session closed for user root May 19 21:14:45 volumio sudo[8894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf May 19 21:14:45 volumio sudo[8894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:45 volumio sudo[8894]: pam_unix(sudo:session): session closed for user root May 19 21:14:45 volumio volumio[1450]: info: Permissions for /etc/network/interfaces set May 19 21:14:45 volumio volumio[1450]: info: Restarting Wireless Network May 19 21:14:45 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 19 21:14:45 volumio volumio[1450]: info: Discovery: Restarting Advertising due to device name change May 19 21:14:45 volumio sudo[8897]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 19 21:14:45 volumio sudo[8897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:45 volumio wpa_supplicant[1238]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all May 19 21:14:45 volumio wpa_supplicant[1238]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all May 19 21:14:45 volumio systemd[1]: Stopping wireless.service - Wireless Services... May 19 21:14:45 volumio wpa_supplicant[1238]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 May 19 21:14:45 volumio wpa_supplicant[1238]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING May 19 21:14:45 volumio dhcpcd[987]: wlan0: carrier lost May 19 21:14:45 volumio kernel: wlan0: deauthenticating from c2:a5:11:38:b5:d0 by local choice (Reason: 3=DEAUTH_LEAVING) May 19 21:14:45 volumio avahi-daemon[1445]: Withdrawing address record for 192.168.0.132 on wlan0. May 19 21:14:45 volumio avahi-daemon[1445]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.132. May 19 21:14:45 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 19 21:14:45 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 19 21:14:45 volumio systemd[1]: welcome.service: Deactivated successfully. May 19 21:14:45 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 19 21:14:45 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 19 21:14:45 volumio wpa_supplicant[1238]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c2:a5:11:38:b5:d0 reason=3 locally_generated=1 May 19 21:14:45 volumio avahi-daemon[1445]: Interface wlan0.IPv4 no longer relevant for mDNS. May 19 21:14:45 volumio dhcpcd[987]: wlan0: deleting route to 192.168.0.0/24 May 19 21:14:45 volumio dhcpcd[987]: wlan0: deleting default route via 192.168.0.1 May 19 21:14:45 volumio wpa_supplicant[1238]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all May 19 21:14:45 volumio volumio[1450]: info: Discovery: A device disappeared from network May 19 21:14:45 volumio volumio[1450]: info: Discovery: Device motivo disappeared from network May 19 21:14:45 volumio volumio[1450]: info: Discovery: Disconnected from remote: 192.168.0.131 May 19 21:14:45 volumio volumio[1450]: info: Discovery: A device disappeared from network May 19 21:14:45 volumio volumio[1450]: info: Discovery: Device bathroom disappeared from network May 19 21:14:45 volumio volumio[1450]: info: Discovery: Disconnected from remote: 192.168.0.140 May 19 21:14:45 volumio volumio[1450]: info: Discovery: A device disappeared from network May 19 21:14:45 volumio volumio[1450]: info: Discovery: Device minidsp-shd disappeared from network May 19 21:14:45 volumio volumio[1450]: info: Discovery: Disconnected from remote: 192.168.0.156 May 19 21:14:45 volumio volumio[1450]: info: Discovery: A device disappeared from network May 19 21:14:45 volumio volumio[1450]: info: Discovery: Device volumio disappeared from network May 19 21:14:45 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 19 21:14:45 volumio welcome[8915]: Resolved ip:[1] 192.168.0.142 May 19 21:14:45 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 19 21:14:45 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 19 21:14:45 volumio wpa_supplicant[1238]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all May 19 21:14:45 volumio wpa_supplicant[1238]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 May 19 21:14:45 volumio wpa_supplicant[1238]: wlan0: CTRL-EVENT-TERMINATING May 19 21:14:45 volumio systemd[1]: wireless.service: Deactivated successfully. May 19 21:14:45 volumio systemd[1]: Stopped wireless.service - Wireless Services. May 19 21:14:45 volumio systemd[1]: wireless.service: Consumed 1.516s CPU time. May 19 21:14:45 volumio systemd[1]: Starting wireless.service - Wireless Services... May 19 21:14:45 volumio sudo[8958]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 May 19 21:14:45 volumio sudo[8958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 19 21:14:45 volumio sudo[8958]: pam_unix(sudo:session): session closed for user root May 19 21:14:45 volumio sudo[8960]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down May 19 21:14:45 volumio sudo[8960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 19 21:14:45 volumio sudo[8960]: pam_unix(sudo:session): session closed for user root May 19 21:14:45 volumio sudo[8968]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 19 21:14:45 volumio sudo[8968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:45 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM May 19 21:14:45 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM May 19 21:14:45 volumio kernel: iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring May 19 21:14:45 volumio sudo[8968]: pam_unix(sudo:session): session closed for user root May 19 21:14:45 volumio sudo[8970]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 19 21:14:45 volumio sudo[8970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:45 volumio sudo[8970]: pam_unix(sudo:session): session closed for user root May 19 21:14:45 volumio sudo[8978]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 19 21:14:45 volumio sudo[8978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:45 volumio sudo[8978]: pam_unix(sudo:session): session closed for user root May 19 21:14:45 volumio sudo[8980]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 19 21:14:45 volumio sudo[8980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:46 volumio volumio[1450]: info: Discovery: A device disappeared from network May 19 21:14:46 volumio volumio[1450]: info: Discovery: A device disappeared from network May 19 21:14:47 volumio ntpd[1095]: IO: Deleting interface #7 wlan0, 192.168.0.132#123, interface stats: received=0, sent=0, dropped=0, active_time=12 secs May 19 21:14:49 volumio sudo[8980]: pam_unix(sudo:session): session closed for user root May 19 21:14:49 volumio sudo[8986]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 19 21:14:49 volumio sudo[8986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:49 volumio sudo[8986]: pam_unix(sudo:session): session closed for user root May 19 21:14:49 volumio sudo[8988]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE May 19 21:14:49 volumio sudo[8988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:49 volumio sudo[8988]: pam_unix(sudo:session): session closed for user root May 19 21:14:49 volumio sudo[8996]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 May 19 21:14:49 volumio sudo[8996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 19 21:14:49 volumio sudo[8996]: pam_unix(sudo:session): session closed for user root May 19 21:14:49 volumio sudo[8998]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down May 19 21:14:49 volumio sudo[8998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 19 21:14:49 volumio sudo[8998]: pam_unix(sudo:session): session closed for user root May 19 21:14:49 volumio wpa_supplicant[9003]: Successfully initialized wpa_supplicant May 19 21:14:49 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM May 19 21:14:49 volumio kernel: iwlwifi 0000:02:00.0: Applying debug destination EXTERNAL_DRAM May 19 21:14:49 volumio kernel: iwlwifi 0000:02:00.0: FW already configured (0) - re-configuring May 19 21:14:49 volumio sudo[9004]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 19 21:14:49 volumio sudo[9004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 19 21:14:49 volumio dhcpcd[987]: ps_ctl_dispatch: cannot handle another client May 19 21:14:49 volumio dhcpcd[987]: control_free: No such file or directory May 19 21:14:49 volumio sudo[9004]: pam_unix(sudo:session): session closed for user root May 19 21:14:49 volumio wpa_supplicant[9008]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=WORLD May 19 21:14:50 volumio volumio[1450]: info: Discovery: Started advertising with name: Volumio May 19 21:14:50 volumio sudo[9012]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 21:14:50 volumio sudo[9012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:50 volumio sudo[9012]: pam_unix(sudo:session): session closed for user root May 19 21:14:51 volumio volumio[1450]: info: Discovery: adding 5c39d368-b095-48ed-83c9-7d354182167b May 19 21:14:51 volumio volumio[1450]: info: Discovery: Found device Volumio May 19 21:14:51 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 19 21:14:51 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 19 21:14:51 volumio volumio[1450]: info: Discovery: this is already registered, 5c39d368-b095-48ed-83c9-7d354182167b May 19 21:14:51 volumio volumio[1450]: info: Discovery: Found device Volumio May 19 21:14:51 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 19 21:14:51 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 19 21:14:51 volumio volumio[1450]: verbose: New Socket.io Connection to 192.168.0.142:3000 from 192.168.0.156 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 19 21:14:51 volumio volumio[1450]: verbose: New Socket.io Connection to 192.168.0.142:3000 from 192.168.0.140 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 19 21:14:51 volumio volumio[1450]: verbose: New Socket.io Connection to 192.168.0.142:3000 from 192.168.0.131 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 19 21:14:51 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 19 21:14:51 volumio sudo[9018]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 21:14:51 volumio sudo[9018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:51 volumio sudo[9018]: pam_unix(sudo:session): session closed for user root May 19 21:14:51 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 19 21:14:51 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 19 21:14:51 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 19 21:14:51 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 19 21:14:51 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 19 21:14:51 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 19 21:14:51 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 19 21:14:51 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 19 21:14:52 volumio volumio[1450]: info: Volumio Network Manager: Network status updated: 1 May 19 21:14:52 volumio sudo[9036]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 21:14:52 volumio sudo[9036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:52 volumio sudo[9036]: pam_unix(sudo:session): session closed for user root May 19 21:14:53 volumio wpa_supplicant[9008]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=DE May 19 21:14:53 volumio wpa_supplicant[9008]: wlan0: SME: Trying to authenticate with c2:a5:11:38:b5:d0 (SSID='rvl-10022' freq=2462 MHz) May 19 21:14:53 volumio kernel: wlan0: authenticate with c2:a5:11:38:b5:d0 (local address=ac:ed:5c:1a:b2:f1) May 19 21:14:53 volumio kernel: wlan0: send auth to c2:a5:11:38:b5:d0 (try 1/3) May 19 21:14:53 volumio wpa_supplicant[9008]: wlan0: Trying to associate with c2:a5:11:38:b5:d0 (SSID='rvl-10022' freq=2462 MHz) May 19 21:14:53 volumio kernel: wlan0: authenticated May 19 21:14:53 volumio kernel: wlan0: associate with c2:a5:11:38:b5:d0 (try 1/3) May 19 21:14:53 volumio kernel: wlan0: RX AssocResp from c2:a5:11:38:b5:d0 (capab=0x1431 status=0 aid=3) May 19 21:14:53 volumio wpa_supplicant[9008]: wlan0: Associated with c2:a5:11:38:b5:d0 May 19 21:14:53 volumio wpa_supplicant[9008]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 19 21:14:53 volumio kernel: wlan0: associated May 19 21:14:53 volumio wpa_supplicant[9008]: wlan0: WPA: Key negotiation completed with c2:a5:11:38:b5:d0 [PTK=CCMP GTK=CCMP] May 19 21:14:53 volumio wpa_supplicant[9008]: wlan0: CTRL-EVENT-CONNECTED - Connection to c2:a5:11:38:b5:d0 completed [id=0 id_str=] May 19 21:14:53 volumio dhcpcd[987]: wlan0: carrier acquired May 19 21:14:53 volumio dhcpcd[987]: wlan0: config file changed, expiring leases May 19 21:14:53 volumio dhcpcd[987]: wlan0: IAID 5c:1a:b2:f1 May 19 21:14:53 volumio sudo[9041]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 21:14:53 volumio sudo[9041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:53 volumio sudo[9041]: pam_unix(sudo:session): session closed for user root May 19 21:14:53 volumio dhcpcd[987]: wlan0: rebinding lease of 192.168.0.132 May 19 21:14:53 volumio dhcpcd[987]: wlan0: probing address 192.168.0.132/24 May 19 21:14:54 volumio dhcpcd[987]: wlan0: soliciting an IPv6 router May 19 21:14:54 volumio sudo[9047]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 21:14:54 volumio sudo[9047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:54 volumio sudo[9047]: pam_unix(sudo:session): session closed for user root May 19 21:14:55 volumio sudo[9051]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 21:14:55 volumio sudo[9051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:55 volumio sudo[9051]: pam_unix(sudo:session): session closed for user root May 19 21:14:56 volumio sudo[9055]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 21:14:56 volumio sudo[9055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:56 volumio sudo[9055]: pam_unix(sudo:session): session closed for user root May 19 21:14:57 volumio sudo[9059]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 21:14:57 volumio sudo[9059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:57 volumio sudo[9059]: pam_unix(sudo:session): session closed for user root May 19 21:14:58 volumio sudo[9063]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 21:14:58 volumio sudo[9063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:58 volumio sudo[9063]: pam_unix(sudo:session): session closed for user root May 19 21:14:59 volumio dhcpcd[987]: wlan0: leased 192.168.0.132 for 86400 seconds May 19 21:14:59 volumio avahi-daemon[1445]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.132. May 19 21:14:59 volumio dhcpcd[987]: wlan0: adding route to 192.168.0.0/24 May 19 21:14:59 volumio avahi-daemon[1445]: New relevant interface wlan0.IPv4 for mDNS. May 19 21:14:59 volumio avahi-daemon[1445]: Registering new address record for 192.168.0.132 on wlan0.IPv4. May 19 21:14:59 volumio dhcpcd[987]: wlan0: adding default route via 192.168.0.1 May 19 21:14:59 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 19 21:14:59 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 19 21:14:59 volumio systemd[1]: welcome.service: Deactivated successfully. May 19 21:14:59 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 19 21:14:59 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 19 21:14:59 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 19 21:14:59 volumio welcome[9074]: Resolved ip:[2] 192.168.0.142 192.168.0.132 May 19 21:14:59 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 19 21:14:59 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 19 21:14:59 volumio volumio[1450]: info: Discovery: adding 5c53dcfe-cabb-4cdc-8b88-635a017cf77d May 19 21:14:59 volumio volumio[1450]: info: Discovery: Found device bathroom May 19 21:14:59 volumio volumio[1450]: info: Discovery: Connecting to remote: 192.168.0.140 May 19 21:14:59 volumio volumio[1450]: info: MRS: Pushing audio output on request May 19 21:14:59 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:14:59 volumio volumio[1450]: info: Discovery: Connected to remote: 192.168.0.140 May 19 21:14:59 volumio volumio[1450]: info: MRS: Adding multiroomSync output May 19 21:14:59 volumio volumio[1450]: info: Adding audio output: May 19 21:14:59 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:14:59 volumio volumio[1450]: info: Discovery: adding e5c2f1d9-7c19-4037-a309-70cded3bf77d May 19 21:14:59 volumio volumio[1450]: info: Discovery: Found device miniDSP-SHD May 19 21:14:59 volumio volumio[1450]: info: Discovery: Connecting to remote: 192.168.0.156 May 19 21:14:59 volumio volumio[1450]: info: MRS: Pushing audio output on request May 19 21:14:59 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:14:59 volumio volumio[1450]: info: Discovery: Connected to remote: 192.168.0.156 May 19 21:14:59 volumio volumio[1450]: info: Discovery: adding c78c46fc-60fd-446a-8cc4-b1ebc238ff40 May 19 21:14:59 volumio volumio[1450]: info: Discovery: Found device Motivo May 19 21:14:59 volumio volumio[1450]: info: Discovery: Connecting to remote: 192.168.0.131 May 19 21:14:59 volumio volumio[1450]: info: MRS: Pushing audio output on request May 19 21:14:59 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 19 21:14:59 volumio volumio[1450]: info: Discovery: Connected to remote: 192.168.0.131 May 19 21:14:59 volumio sudo[9101]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 19 21:14:59 volumio sudo[9101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 19 21:14:59 volumio sudo[9101]: pam_unix(sudo:session): session closed for user root May 19 21:14:59 volumio sudo[9104]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 19 21:14:59 volumio sudo[9104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 19 21:14:59 volumio sudo[9104]: pam_unix(sudo:session): session closed for user root May 19 21:14:59 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 19 21:14:59 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 19 21:14:59 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 19 21:14:59 volumio systemd[1]: shairport-sync.service: Consumed 2.156s CPU time. May 19 21:14:59 volumio avahi-daemon[1445]: Got SIGTERM, quitting. May 19 21:14:59 volumio avahi-daemon[1445]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.132. May 19 21:14:59 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 19 21:14:59 volumio volumio[1450]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 21:14:59 volumio avahi-daemon[1445]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.142. May 19 21:14:59 volumio avahi-daemon[1445]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 19 21:14:59 volumio dbus-daemon[962]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.158' (uid=0 pid=1312 comm="/usr/sbin/smbd --foreground --no-process-group") May 19 21:14:59 volumio vtcs[8442]: [2025-05-19 21:14:59.598] [tisoc] [error] [avahiImpl.cpp:113] avahiClientCallback() AVAHI_CLIENT_S_COLLISION/AVAHI_CLIENT_FAILURE May 19 21:14:59 volumio volumio[1450]: Error: dns service error: unknown May 19 21:14:59 volumio volumio[1450]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) May 19 21:14:59 volumio volumio[1450]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { May 19 21:14:59 volumio volumio[1450]: errorCode: -65537 May 19 21:14:59 volumio volumio[1450]: } May 19 21:14:59 volumio volumio[1450]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 21:14:59 volumio avahi-daemon[1445]: avahi-daemon 0.8 exiting. May 19 21:14:59 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. May 19 21:14:59 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 19 21:14:59 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 19 21:14:59 volumio avahi-daemon[9120]: Process 1445 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) May 19 21:14:59 volumio avahi-daemon[9120]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). May 19 21:14:59 volumio avahi-daemon[9120]: Successfully dropped root privileges. May 19 21:14:59 volumio avahi-daemon[9120]: avahi-daemon 0.8 starting up. May 19 21:14:59 volumio dbus-daemon[962]: [system] Successfully activated service 'org.freedesktop.Avahi' May 19 21:14:59 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 19 21:14:59 volumio avahi-daemon[9120]: Successfully called chroot(). May 19 21:14:59 volumio avahi-daemon[9120]: Successfully dropped remaining capabilities. May 19 21:14:59 volumio avahi-daemon[9120]: No service file found in /etc/avahi/services. May 19 21:14:59 volumio avahi-daemon[9120]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.132. May 19 21:14:59 volumio avahi-daemon[9120]: New relevant interface wlan0.IPv4 for mDNS. May 19 21:14:59 volumio avahi-daemon[9120]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.142. May 19 21:14:59 volumio avahi-daemon[9120]: New relevant interface eth0.IPv4 for mDNS. May 19 21:14:59 volumio avahi-daemon[9120]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 19 21:14:59 volumio avahi-daemon[9120]: New relevant interface lo.IPv4 for mDNS. May 19 21:14:59 volumio avahi-daemon[9120]: Network interface enumeration completed. May 19 21:14:59 volumio avahi-daemon[9120]: Registering new address record for 192.168.0.132 on wlan0.IPv4. May 19 21:14:59 volumio avahi-daemon[9120]: Registering new address record for 192.168.0.142 on eth0.IPv4. May 19 21:14:59 volumio avahi-daemon[9120]: Registering new address record for 127.0.0.1 on lo.IPv4. May 19 21:14:59 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 19 21:14:59 volumio systemd[1]: Started wireless.service - Wireless Services. May 19 21:14:59 volumio sudo[8897]: pam_unix(sudo:session): session closed for user root May 19 21:14:59 volumio sudo[9143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-19 21:13' May 19 21:14:59 volumio sudo[9143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="f224c96f24bffeaafa716653a140d091b9b76522" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon May 19 11:05:09 UTC 2025" VOLUMIO_VERSION="0.067" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="37e5aed7881b3d72147ad6ac2a0a4d19"