-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Sat 2025-12-06 11:08:07 EET. -- Dec 06 11:07:06 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:07:06+02:00" level=trace msg="sent dealer ping" Dec 06 11:07:06 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:07:06+02:00" level=trace msg="received dealer pong" Dec 06 11:07:35 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:07:35+02:00" level=trace msg="received accesspoint ping" Dec 06 11:07:35 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:07:35+02:00" level=trace msg="received accesspoint pong ack" Dec 06 11:07:36 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:07:36+02:00" level=trace msg="sent dealer ping" Dec 06 11:07:36 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:07:36+02:00" level=trace msg="received dealer pong" Dec 06 11:07:39 volumio-iqaudio-pi-dac-pro volumiologrotate[478]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Dec 06 11:07:39 volumio-iqaudio-pi-dac-pro volumiologrotate[478]: ls: cannot access '(IQAUDIO': No such file or directory Dec 06 11:07:39 volumio-iqaudio-pi-dac-pro volumiologrotate[478]: ls: cannot access 'PI-DAC': No such file or directory Dec 06 11:07:39 volumio-iqaudio-pi-dac-pro volumiologrotate[478]: ls: cannot access 'PRO)': No such file or directory Dec 06 11:07:40 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::Close All Modals sent Dec 06 11:07:42 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 11:07:42 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro shairport-sync[1678]: {"time":1765011865890,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro shairport-sync[1678]: Dload Upload Total Spent Left Speed Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: ---------------------------- Client requests Stop Airplay Playback Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: Airplay playback stop Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: Airplay Playback Stopped, clearing state Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::resetVolumioState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::getcurrentVolume Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioRetrievevolume Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro shairport-sync[1678]: [158B blob data] Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: VolumeController:: Volume=32 Mute =false Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::updateTrackBlock Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CorePlayQueue::getTrackBlock Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioRetrievevolume Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: VolumeController:: Volume=32 Mute =false Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:48 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:54 volumio-iqaudio-pi-dac-pro kernel: hwmon hwmon1: Voltage normalised Dec 06 11:07:56 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:07:56+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 06 11:07:56 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:07:56+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 343" Dec 06 11:07:56 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:07:56+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 06 11:07:56 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:07:56+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1979" Dec 06 11:07:56 volumio-iqaudio-pi-dac-pro kernel: hwmon hwmon1: Undervoltage detected! Dec 06 11:07:56 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:07:56+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 06 11:07:56 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:07:56+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1418" Dec 06 11:07:57 volumio-iqaudio-pi-dac-pro volumio[994]: info: Airplay started streaming, receiving metadatas Dec 06 11:07:57 volumio-iqaudio-pi-dac-pro volumio[994]: info: Airplay started streaming, receiving metadatas Dec 06 11:07:57 volumio-iqaudio-pi-dac-pro shairport-sync[1678]: {"time":1765012068599,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Dec 06 11:07:57 volumio-iqaudio-pi-dac-pro shairport-sync[1678]: Dload Upload Total Spent Left Speed Dec 06 11:07:57 volumio-iqaudio-pi-dac-pro volumio[994]: info: Dec 06 11:07:57 volumio-iqaudio-pi-dac-pro volumio[994]: ---------------------------- Client requests Start Airplay PlaybackRoutine Dec 06 11:07:57 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Dec 06 11:07:57 volumio-iqaudio-pi-dac-pro volumio[994]: info: Airplay playback start Dec 06 11:07:57 volumio-iqaudio-pi-dac-pro shairport-sync[1678]: [158B blob data] Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:58 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:07:59 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:07:59+02:00" level=debug msg="skipping dealer message" uri=playback-settings/content-settings-update Dec 06 11:07:59 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:07:59+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 148" Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:04+02:00" level=debug msg="handling transfer player command from 2718ce97e0f32e07947c49e4b8bddd06535e5391" Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:04+02:00" level=debug msg="resolved context of track" uri="spotify:user:ywaasx5wmptq8vg2w285auupa:collection" Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:04+02:00" level=trace msg="fetched new page 0 with 992 items (list: 992)" uri="spotify:user:ywaasx5wmptq8vg2w285auupa:collection" Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:04+02:00" level=debug msg="loading track (paused: false, position: 7683ms)" uri="spotify:track:3MTfE3Mo3lfwAymYLU2luX" Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:04+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:04+02:00" level=trace msg="emitting websocket event: will_play" Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:ywaasx5wmptq8vg2w285auupa:collection","uri":"spotify:track:3MTfE3Mo3lfwAymYLU2luX","play_origin":"com.spotify.service.smartshuffle.core"}} Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:ywaasx5wmptq8vg2w285auupa:collection","uri":"spotify:track:3MTfE3Mo3lfwAymYLU2luX","play_origin":"com.spotify.service.smartshuffle.core"}} Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:ywaasx5wmptq8vg2w285auupa:collection","uri":"spotify:track:3MTfE3Mo3lfwAymYLU2luX","play_origin":"com.spotify.service.smartshuffle.core"}} Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:ywaasx5wmptq8vg2w285auupa:collection","uri":"spotify:track:3MTfE3Mo3lfwAymYLU2luX","play_origin":"com.spotify.service.smartshuffle.core"}} Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:04+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 391" Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:04+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1470" Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:04+02:00" level=debug msg="selected format OGG_VORBIS_320 (752d4acac7dbe3e02b7579855b2e089352ae3c6e)" uri="spotify:track:3MTfE3Mo3lfwAymYLU2luX" Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:04+02:00" level=debug msg="requested aes key for file 752d4acac7dbe3e02b7579855b2e089352ae3c6e, gid: 3MTfE3Mo3lfwAymYLU2luX" Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:04 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:05+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:3MTfE3Mo3lfwAymYLU2luX" Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: play PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::servicePushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreStateMachine::pushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: CoreCommandRouter::volumioPushState Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: pause PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: pause PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: pause PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: pause PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: pause PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: pause PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] CurState: pause PrevState: play Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:05+02:00" level=debug msg="fetched first chunk of 19, total size is 9713136 bytes" uri="spotify:track:3MTfE3Mo3lfwAymYLU2luX" Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:05+02:00" level=trace msg="seek to 7683ms (diff: 88ms, samples: 338820, bytes: 321241)" uri="spotify:track:3MTfE3Mo3lfwAymYLU2luX" Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:05+02:00" level=debug msg="created new output device" Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:05+02:00" level=warning msg="failed handling dealer request" error="failed loading current track (transfer): failed setting stream for spotify:track:3MTfE3Mo3lfwAymYLU2luX: ALSA error at snd_pcm_open: Device or resource busy" Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:05+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 06 11:08:05 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:05+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:06+02:00" level=trace msg="sent dealer ping" Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:06+02:00" level=trace msg="received dealer pong" Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:06+02:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:3MTfE3Mo3lfwAymYLU2luX" Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:06+02:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:3MTfE3Mo3lfwAymYLU2luX" Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro go-librespot[4816]: time="2025-12-06T11:08:06+02:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:3MTfE3Mo3lfwAymYLU2luX" Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro volumio[994]: info: [ASDebug] Togle GPIO: OFF Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro volumio[994]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro volumio[994]: Error: EBADF: bad file descriptor, write Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro volumio[994]: at Object.writeSync (fs.js:711:3) Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro volumio[994]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro volumio[994]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro volumio[994]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro volumio[994]: at listOnTimeout (internal/timers.js:554:17) Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro volumio[994]: at processTimers (internal/timers.js:497:7) { Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro volumio[994]: errno: -9, Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro volumio[994]: syscall: 'write', Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro volumio[994]: code: 'EBADF' Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro volumio[994]: } Dec 06 11:08:06 volumio-iqaudio-pi-dac-pro volumio[994]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 11:08:07 volumio-iqaudio-pi-dac-pro sudo[5752]: volumio : unable to resolve host volumio-iqaudio-pi-dac-pro Dec 06 11:08:07 volumio-iqaudio-pi-dac-pro sudo[5752]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 06 11:08:07 volumio-iqaudio-pi-dac-pro sudo[5752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-06 11:07 Dec 06 11:08:07 volumio-iqaudio-pi-dac-pro sudo[5752]: 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"