-- Logs begin at Thu 2019-02-14 15:41:59 IST, end at Sun 2025-12-14 17:21:29 IST. -- Dec 14 17:20:03 moojik wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Dec 14 17:20:03 moojik go-librespot[1247]: time="2025-12-14T17:20:03+05:30" level=debug msg="fetched chunk 13/15, size: 524288" uri="spotify:track:1VRlztOHme0ldUZBkDM5Fk" Dec 14 17:20:07 moojik go-librespot[1247]: time="2025-12-14T17:20:07+05:30" level=trace msg="sent dealer ping" Dec 14 17:20:07 moojik go-librespot[1247]: time="2025-12-14T17:20:07+05:30" level=trace msg="received dealer pong" Dec 14 17:20:09 moojik wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Dec 14 17:20:15 moojik wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Dec 14 17:20:16 moojik go-librespot[1247]: time="2025-12-14T17:20:16+05:30" level=debug msg="handling pause player command from 7a879f70d494be3832bb047eda4a0f048122ef5e" Dec 14 17:20:16 moojik go-librespot[1247]: time="2025-12-14T17:20:16+05:30" level=debug msg="pause track at 142477ms" Dec 14 17:20:16 moojik go-librespot[1247]: time="2025-12-14T17:20:16+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 14 17:20:16 moojik go-librespot[1247]: time="2025-12-14T17:20:16+05:30" level=debug msg="sending successful reply for dealer request" Dec 14 17:20:16 moojik go-librespot[1247]: time="2025-12-14T17:20:16+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 14 17:20:16 moojik go-librespot[1247]: time="2025-12-14T17:20:16+05:30" level=trace msg="emitting websocket event: paused" Dec 14 17:20:16 moojik volumio[942]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:33M5hHqza4eCv32Zs0H2qn","uri":"spotify:track:1VRlztOHme0ldUZBkDM5Fk","play_origin":"playlist"}} Dec 14 17:20:16 moojik volumio[942]: SPOTIFY: PUSH STATE SPOTIFY Dec 14 17:20:16 moojik volumio[942]: SPOTIFY: {"status":"pause","service":"spop","title":"Violin Concerto No.1 In A Minor, BWV 1041: 1. Allegro moderato","artist":"Johann Sebastian Bach, Hilary Hahn, Los Angeles Chamber Orchestra, Jeffrey Kahane","album":"J.S.Bach: Violin Concertos","albumart":"https://i.scdn.co/image/ab67616d00001e02e6759599c12262d5420de7e9","uri":"spotify:track:1VRlztOHme0ldUZBkDM5Fk","trackType":"spotify","seek":143029,"duration":203,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 14 17:20:16 moojik volumio[942]: info: CoreCommandRouter::servicePushState Dec 14 17:20:16 moojik volumio[942]: info: CoreStateMachine::pushState Dec 14 17:20:16 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 17:20:16 moojik volumio[942]: info: CoreCommandRouter::volumioPushState Dec 14 17:20:16 moojik volumio[942]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 17:20:21 moojik wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Dec 14 17:20:23 moojik go-librespot[1247]: time="2025-12-14T17:20:23+05:30" level=info msg="playback was transferred to " Dec 14 17:20:23 moojik go-librespot[1247]: time="2025-12-14T17:20:23+05:30" level=trace msg="closed output device because of stop command" Dec 14 17:20:23 moojik go-librespot[1247]: time="2025-12-14T17:20:23+05:30" level=debug msg="put connect state inactive" Dec 14 17:20:23 moojik go-librespot[1247]: time="2025-12-14T17:20:23+05:30" level=trace msg="emitting websocket event: inactive" Dec 14 17:20:23 moojik go-librespot[1247]: time="2025-12-14T17:20:23+05:30" level=trace msg="emitting websocket event: stopped" Dec 14 17:20:23 moojik volumio[942]: SPOTIFY: received: {"type":"inactive","data":null} Dec 14 17:20:23 moojik volumio[942]: error: Failed to decode event: inactive Dec 14 17:20:23 moojik volumio[942]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":""}} Dec 14 17:20:23 moojik volumio[942]: SPOTIFY: PUSH STATE SPOTIFY Dec 14 17:20:23 moojik volumio[942]: SPOTIFY: {"status":"stop","service":"spop","title":"Violin Concerto No.1 In A Minor, BWV 1041: 1. Allegro moderato","artist":"Johann Sebastian Bach, Hilary Hahn, Los Angeles Chamber Orchestra, Jeffrey Kahane","album":"J.S.Bach: Violin Concertos","albumart":"https://i.scdn.co/image/ab67616d00001e02e6759599c12262d5420de7e9","uri":"spotify:track:1VRlztOHme0ldUZBkDM5Fk","trackType":"spotify","seek":143029,"duration":203,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 14 17:20:23 moojik volumio[942]: info: CoreCommandRouter::servicePushState Dec 14 17:20:23 moojik volumio[942]: info: CoreStateMachine::pushState Dec 14 17:20:23 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 17:20:23 moojik volumio[942]: info: CoreCommandRouter::volumioPushState Dec 14 17:20:23 moojik volumio[942]: info: CorePlayQueue::getTrack 0 Dec 14 17:20:23 moojik volumio[942]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Violin Concerto No.1 In A Minor, BWV 1041: 1. Allegro moderato","artist":"Johann Sebastian Bach, Hilary Hahn, Los Angeles Chamber Orchestra, Jeffrey Kahane","album":"J.S.Bach: Violin Concertos","albumart":"https://i.scdn.co/image/ab67616d00001e02e6759599c12262d5420de7e9","uri":"spotify:track:1VRlztOHme0ldUZBkDM5Fk","trackType":"spotify","seek":143029,"duration":203,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 14 17:20:23 moojik volumio[942]: verbose: CURRENT POSITION 0 Dec 14 17:20:23 moojik volumio[942]: info: CoreStateMachine::syncState stateService stop Dec 14 17:20:23 moojik volumio[942]: info: CoreStateMachine::syncState currentStatus pause Dec 14 17:20:23 moojik volumio[942]: info: CoreStateMachine::pushState Dec 14 17:20:23 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 17:20:23 moojik volumio[942]: info: CoreCommandRouter::volumioPushState Dec 14 17:20:23 moojik volumio[942]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 17:20:23 moojik volumio[942]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 17:20:23 moojik go-librespot[1247]: time="2025-12-14T17:20:23+05:30" level=debug msg="dealer connection closed" Dec 14 17:20:23 moojik go-librespot[1247]: time="2025-12-14T17:20:23+05:30" level=debug msg="dealer recv loop stopped" Dec 14 17:20:23 moojik go-librespot[1247]: time="2025-12-14T17:20:23+05:30" level=debug msg="obtained new client token: AAAksrsXB/s5nKNJKefotfPksttyvF4hzC8duF0wej7BFJxzl3S23m1IJudEhcAwMLJl67uXEdtbw0r7sO0d+djMgfItv/QBuMJIr2XQgycq8odGk6+jE8BRDCKe256x3LrFRMDNPKopfiZeHRjCKm9wh7DWBXJJKfjVhGdDgu1xrMLrsvdVfS1E+mhL9dbJ8xparVMODR4xnuw2TYJnTGpLOqtr7APJm4gY4B91O/6s+UBdHyxsWW24hA==" Dec 14 17:20:23 moojik go-librespot[1247]: time="2025-12-14T17:20:23+05:30" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 14 17:20:23 moojik go-librespot[1247]: time="2025-12-14T17:20:23+05:30" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=debug msg="connected to ap-gae2.spotify.com:443" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=debug msg="completed keyexchange" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=debug msg="completed challenge" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=info msg="authenticated AP" username="au******ia" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=info msg="authenticated Login5" username="au******ia" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=debug msg="restored session after logout" username="au******ia" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=debug msg="dealer connection opened" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=trace msg="starting accesspoint recv loop" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=trace msg="starting dealer recv loop" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=trace msg="received accesspoint ping" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 14 17:20:24 moojik go-librespot[1247]: time="2025-12-14T17:20:24+05:30" level=debug msg="received connection id: MWQ5MzFkMWMtYmQ1...QTQ2NDJDRUJBQw==" Dec 14 17:20:25 moojik go-librespot[1247]: time="2025-12-14T17:20:25+05:30" level=trace msg="received accesspoint pong ack" Dec 14 17:20:25 moojik go-librespot[1247]: time="2025-12-14T17:20:25+05:30" level=debug msg="put connect state because NEW_DEVICE" Dec 14 17:20:27 moojik wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Dec 14 17:20:35 moojik wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Dec 14 17:20:37 moojik volumio[942]: info: Clearing queue after UPNP request Dec 14 17:20:37 moojik volumio[942]: info: CoreStateMachine::ClearQueue Dec 14 17:20:37 moojik volumio[942]: info: CoreStateMachine::stop Dec 14 17:20:37 moojik volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 17:20:37 moojik volumio[942]: info: CoreStateMachine::updateTrackBlock Dec 14 17:20:37 moojik volumio[942]: info: CorePlayQueue::getTrackBlock Dec 14 17:20:37 moojik volumio[942]: info: CoreStateMachine::stPlaybackTimer Dec 14 17:20:37 moojik volumio[942]: info: CoreStateMachine::pushState Dec 14 17:20:37 moojik volumio[942]: info: CorePlayQueue::getTrack 0 Dec 14 17:20:37 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 17:20:37 moojik volumio[942]: info: CoreCommandRouter::volumioPushState Dec 14 17:20:37 moojik volumio[942]: info: CoreStateMachine::serviceStop Dec 14 17:20:37 moojik volumio[942]: info: CorePlayQueue::getTrack 0 Dec 14 17:20:37 moojik volumio[942]: info: ControllerMpd::stop Dec 14 17:20:37 moojik volumio[942]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 17:20:37 moojik volumio[942]: info: CorePlayQueue::clearPlayQueue Dec 14 17:20:37 moojik volumio[942]: info: CorePlayQueue::saveQueue Dec 14 17:20:37 moojik volumio[942]: info: CoreCommandRouter::volumioPushState Dec 14 17:20:37 moojik volumio[942]: info: CoreCommandRouter::volumioPushQueue Dec 14 17:20:37 moojik volumio[942]: error: Upnp client error: Error: This socket has been ended by the other party Dec 14 17:20:37 moojik volumio[942]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 17:20:37 moojik volumio[942]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 17:20:37 moojik volumio[942]: info: sendMpdCommand stop took 16 milliseconds Dec 14 17:20:39 moojik volumio[942]: info: Starting UPNP Playback Dec 14 17:20:39 moojik volumio[942]: info: Preparing playback through UPNP Dec 14 17:20:39 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:39 moojik volumio[942]: info: CorePlayQueue::getTrack 0 Dec 14 17:20:39 moojik volumio[942]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 14 17:20:39 moojik volumio[942]: info: Dec 14 17:20:39 moojik volumio[942]: ---------------------------- MPD announces system playlist update Dec 14 17:20:39 moojik volumio[942]: info: Ignoring MPD Status Update Dec 14 17:20:39 moojik volumio[942]: info: ------------------------------ 4ms Dec 14 17:20:39 moojik volumio[942]: info: Dec 14 17:20:39 moojik volumio[942]: ---------------------------- MPD announces system playlist update Dec 14 17:20:39 moojik volumio[942]: info: Ignoring MPD Status Update Dec 14 17:20:39 moojik volumio[942]: info: ------------------------------ 1ms Dec 14 17:20:39 moojik volumio[942]: info: Dec 14 17:20:39 moojik volumio[942]: ---------------------------- MPD announces system playlist update Dec 14 17:20:39 moojik volumio[942]: info: Ignoring MPD Status Update Dec 14 17:20:39 moojik volumio[942]: info: ------------------------------ 1ms Dec 14 17:20:39 moojik volumio[942]: info: Dec 14 17:20:39 moojik volumio[942]: ---------------------------- MPD announces system playlist update Dec 14 17:20:39 moojik volumio[942]: info: Ignoring MPD Status Update Dec 14 17:20:39 moojik volumio[942]: info: ------------------------------ 2ms Dec 14 17:20:39 moojik volumio[942]: info: Dec 14 17:20:39 moojik volumio[942]: ---------------------------- MPD announces system playlist update Dec 14 17:20:39 moojik volumio[942]: info: Ignoring MPD Status Update Dec 14 17:20:39 moojik volumio[942]: info: ------------------------------ 1ms Dec 14 17:20:39 moojik volumio[942]: info: Dec 14 17:20:39 moojik volumio[942]: ---------------------------- MPD announces system playlist update Dec 14 17:20:39 moojik volumio[942]: info: Ignoring MPD Status Update Dec 14 17:20:39 moojik volumio[942]: info: ------------------------------ 2ms Dec 14 17:20:40 moojik volumio[942]: info: Dec 14 17:20:40 moojik volumio[942]: ---------------------------- MPD announces state update: player Dec 14 17:20:40 moojik volumio[942]: info: ControllerMpd::getState Dec 14 17:20:40 moojik volumio[942]: verbose: ControllerMpd::sendMpdCommand status Dec 14 17:20:40 moojik volumio[942]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Dec 14 17:20:40 moojik volumio[942]: info: FusionDsp - ---- read samplerate from file: 96000 Dec 14 17:20:40 moojik volumio[942]: info: camilladsp stopping service pid 9172... Dec 14 17:20:40 moojik volumio[942]: info: camilladsp service terminated, instance 1 Dec 14 17:20:40 moojik volumio[942]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 14 17:20:40 moojik volumio[942]: info: FusionDsp - else 1 -2.5 Dec 14 17:20:40 moojik volumio[942]: info: camilladsp service started and running in background, instance 1 Dec 14 17:20:40 moojik volumio[942]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Dec 14 17:20:40 moojik volumio[942]: info: FusionDsp - ---- read samplerate from file: 96000 Dec 14 17:20:40 moojik volumio[942]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 14 17:20:40 moojik volumio[942]: info: FusionDsp - else 1 -2.5 Dec 14 17:20:40 moojik volumio[942]: info: Dec 14 17:20:40 moojik volumio[942]: ---------------------------- MPD announces state update: player Dec 14 17:20:40 moojik volumio[942]: info: sendMpdCommand status took 62 milliseconds Dec 14 17:20:40 moojik volumio[942]: info: ControllerMpd::getState Dec 14 17:20:40 moojik volumio[942]: verbose: ControllerMpd::sendMpdCommand status Dec 14 17:20:40 moojik volumio[942]: verbose: ControllerMpd::parseState Dec 14 17:20:40 moojik volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 17:20:40 moojik volumio[942]: info: sendMpdCommand status took 5 milliseconds Dec 14 17:20:40 moojik volumio[942]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 14 17:20:40 moojik volumio[942]: verbose: ControllerMpd::parseState Dec 14 17:20:40 moojik volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 17:20:40 moojik volumio[942]: verbose: ControllerMpd::parseTrackInfo Dec 14 17:20:40 moojik volumio[942]: info: ControllerMpd::pushState Dec 14 17:20:40 moojik volumio[942]: info: CoreCommandRouter::servicePushState Dec 14 17:20:40 moojik volumio[942]: verbose: In UPNP mode Dec 14 17:20:40 moojik volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eye In The Sky","artist":"The Alan Parsons Project","album":"Eye In The Sky","uri":"http://192.168.68.109:12441/tidal/70379770.flac","trackType":"tidal"} Dec 14 17:20:40 moojik volumio[942]: verbose: CURRENT POSITION 0 Dec 14 17:20:40 moojik volumio[942]: info: CoreStateMachine::syncState stateService play Dec 14 17:20:40 moojik volumio[942]: info: CoreStateMachine::syncState currentStatus stop Dec 14 17:20:40 moojik volumio[942]: info: ------------------------------ 78ms Dec 14 17:20:40 moojik volumio[942]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 14 17:20:40 moojik volumio[942]: verbose: ControllerMpd::parseTrackInfo Dec 14 17:20:40 moojik volumio[942]: info: ControllerMpd::pushState Dec 14 17:20:40 moojik volumio[942]: info: CoreCommandRouter::servicePushState Dec 14 17:20:40 moojik volumio[942]: verbose: In UPNP mode Dec 14 17:20:40 moojik volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eye In The Sky","artist":"The Alan Parsons Project","album":"Eye In The Sky","uri":"http://192.168.68.109:12441/tidal/70379770.flac","trackType":"tidal"} Dec 14 17:20:40 moojik volumio[942]: verbose: CURRENT POSITION 0 Dec 14 17:20:40 moojik volumio[942]: info: CoreStateMachine::syncState stateService play Dec 14 17:20:40 moojik volumio[942]: info: CoreStateMachine::syncState currentStatus play Dec 14 17:20:40 moojik volumio[942]: info: Received an update from plugin. extracting info from payload Dec 14 17:20:40 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 14 17:20:40 moojik volumio[942]: info: CoreStateMachine::pushState Dec 14 17:20:40 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 17:20:40 moojik volumio[942]: info: CoreCommandRouter::volumioPushState Dec 14 17:20:40 moojik volumio[942]: info: CoreStateMachine::pushState Dec 14 17:20:40 moojik volumio[942]: info: CoreCommandRouter::volumioPushState Dec 14 17:20:40 moojik volumio[942]: info: ------------------------------ 38ms Dec 14 17:20:40 moojik volumio[942]: info: CoreCommandRouter::volumioGetQueue Dec 14 17:20:40 moojik volumio[942]: info: CoreStateMachine::getQueue Dec 14 17:20:40 moojik volumio[942]: info: CorePlayQueue::getQueue Dec 14 17:20:40 moojik volumio[942]: info: CoreCommandRouter::volumioGetQueue Dec 14 17:20:40 moojik volumio[942]: info: CoreStateMachine::getQueue Dec 14 17:20:40 moojik volumio[942]: info: CorePlayQueue::getQueue Dec 14 17:20:40 moojik volumio[942]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 17:20:40 moojik volumio[942]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 17:20:40 moojik volumio[942]: error: FusionDsp - WebSocket error: [object Object] Dec 14 17:20:41 moojik wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Dec 14 17:20:44 moojik volumio[942]: info: Dec 14 17:20:44 moojik volumio[942]: ---------------------------- MPD announces state update: player Dec 14 17:20:44 moojik volumio[942]: info: ControllerMpd::getState Dec 14 17:20:44 moojik volumio[942]: verbose: ControllerMpd::sendMpdCommand status Dec 14 17:20:44 moojik volumio[942]: info: sendMpdCommand status took 2 milliseconds Dec 14 17:20:44 moojik volumio[942]: verbose: ControllerMpd::parseState Dec 14 17:20:44 moojik volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 17:20:44 moojik volumio[942]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 14 17:20:44 moojik volumio[942]: verbose: ControllerMpd::parseTrackInfo Dec 14 17:20:44 moojik volumio[942]: info: ControllerMpd::pushState Dec 14 17:20:44 moojik volumio[942]: info: CoreCommandRouter::servicePushState Dec 14 17:20:44 moojik volumio[942]: verbose: In UPNP mode Dec 14 17:20:44 moojik volumio[942]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5239,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2954 Kbps","isStreaming":false,"title":"Eye In The Sky","artist":"The Alan Parsons Project","album":"Eye In The Sky","uri":"http://192.168.68.109:12441/tidal/70379770.flac","trackType":"tidal"} Dec 14 17:20:44 moojik volumio[942]: verbose: CURRENT POSITION 0 Dec 14 17:20:44 moojik volumio[942]: info: CoreStateMachine::syncState stateService pause Dec 14 17:20:44 moojik volumio[942]: info: CoreStateMachine::syncState currentStatus play Dec 14 17:20:44 moojik volumio[942]: info: CoreStateMachine::pushState Dec 14 17:20:44 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 17:20:44 moojik volumio[942]: info: CoreCommandRouter::volumioPushState Dec 14 17:20:44 moojik volumio[942]: info: CoreStateMachine::stPlaybackTimer Dec 14 17:20:44 moojik volumio[942]: info: ------------------------------ 14ms Dec 14 17:20:44 moojik volumio[942]: info: CoreCommandRouter::volumioGetQueue Dec 14 17:20:44 moojik volumio[942]: info: CoreStateMachine::getQueue Dec 14 17:20:44 moojik volumio[942]: info: CorePlayQueue::getQueue Dec 14 17:20:44 moojik volumio[942]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 17:20:47 moojik wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Dec 14 17:20:51 moojik volumio[942]: verbose: New Socket.io Connection to 192.168.68.105:3000 from 192.168.68.109 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 14 17:20:52 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:20:52 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:20:52 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:20:52 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:52 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:20:52 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:20:52 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:20:52 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:20:52 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:52 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:20:53 moojik wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Dec 14 17:20:54 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:20:54 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:20:54 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:20:54 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:54 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:20:54 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:20:54 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:20:54 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:20:54 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:54 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:20:54 moojik volumio[942]: verbose: New Socket.io Connection to 192.168.68.105:3000 from 192.168.68.109 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 14 17:20:54 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 14 17:20:54 moojik go-librespot[1247]: time="2025-12-14T17:20:54+05:30" level=trace msg="sent dealer ping" Dec 14 17:20:55 moojik go-librespot[1247]: time="2025-12-14T17:20:55+05:30" level=trace msg="received dealer pong" Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 14 17:20:55 moojik volumio[942]: verbose: New Socket.io Connection to 192.168.68.105:3000 from 192.168.68.109 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 14 17:20:55 moojik sudo[9327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 14 17:20:55 moojik sudo[9327]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:55 moojik sudo[9334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 17:20:55 moojik sudo[9334]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:55 moojik sudo[9327]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:55 moojik sudo[9334]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:55 moojik sudo[9341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 17:20:55 moojik sudo[9341]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:55 moojik sudo[9341]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:55 moojik sudo[9348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 17:20:55 moojik sudo[9348]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:55 moojik sudo[9348]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:55 moojik sudo[9354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 17:20:55 moojik sudo[9354]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:55 moojik sudo[9354]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:55 moojik sudo[9357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 17:20:55 moojik sudo[9357]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:55 moojik sudo[9357]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:20:55 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:20:55 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:55 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:20:56 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:20:56 moojik volumio[942]: verbose: New Socket.io Connection to 192.168.68.105:3000 from 192.168.68.109 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:20:56 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 14 17:20:56 moojik sudo[9366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 14 17:20:56 moojik sudo[9366]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:56 moojik sudo[9366]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:56 moojik sudo[9371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 17:20:56 moojik sudo[9371]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:56 moojik sudo[9371]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:56 moojik sudo[9380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 17:20:56 moojik sudo[9380]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:56 moojik sudo[9384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 17:20:56 moojik sudo[9384]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:56 moojik sudo[9380]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:56 moojik sudo[9384]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:56 moojik sudo[9392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 17:20:56 moojik sudo[9392]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:56 moojik sudo[9392]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:56 moojik sudo[9394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 17:20:56 moojik sudo[9394]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:56 moojik sudo[9394]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:56 moojik volumio[942]: verbose: New Socket.io Connection to 192.168.68.105:3000 from 192.168.68.109 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:20:56 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 14 17:20:56 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 14 17:20:57 moojik sudo[9401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 17:20:57 moojik sudo[9401]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:57 moojik sudo[9401]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:57 moojik sudo[9404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 17:20:57 moojik sudo[9404]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:57 moojik sudo[9404]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:57 moojik volumio[942]: verbose: New Socket.io Connection to 192.168.68.105 from 192.168.68.109 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 17:20:58 moojik sudo[9407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 17:20:58 moojik sudo[9407]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:58 moojik sudo[9407]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:58 moojik sudo[9410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 17:20:58 moojik sudo[9410]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 14 17:20:58 moojik sudo[9410]: pam_unix(sudo:session): session closed for user root Dec 14 17:20:58 moojik volumio[942]: verbose: New Socket.io Connection to 192.168.68.105 from 192.168.68.109 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 14 17:20:58 moojik volumio[942]: info: Received Get System Info Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:20:58 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:20:58 moojik volumio[942]: info: Listing playlists Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 14 17:20:58 moojik volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 17:20:59 moojik volumio[942]: info: FusionDsp - no preset used Dec 14 17:20:59 moojik volumio[942]: info: FusionDsp - Dec 14 17:20:59 moojik wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Dec 14 17:21:00 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 17:21:00 moojik volumio[942]: info: Received Get System Info Dec 14 17:21:00 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:21:00 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:21:00 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:21:00 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:21:00 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:21:00 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 14 17:21:00 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 14 17:21:03 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 17:21:03 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 14 17:21:03 moojik volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 17:21:03 moojik volumio[942]: info: FusionDsp - no preset used Dec 14 17:21:04 moojik volumio[942]: info: FusionDsp - Dec 14 17:21:05 moojik wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Dec 14 17:21:05 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 17:21:06 moojik volumio[942]: info: Received Get System Info Dec 14 17:21:06 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:21:06 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:21:06 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:21:06 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:21:06 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:21:06 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 17:21:06 moojik volumio[942]: info: Received Get System Info Dec 14 17:21:06 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:21:06 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:21:06 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:21:06 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:21:06 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:21:06 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 17:21:06 moojik volumio[942]: info: Received Get System Info Dec 14 17:21:06 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:21:06 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:21:06 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:21:06 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:21:06 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:21:08 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 14 17:21:11 moojik wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 17:21:14 moojik volumio[942]: info: Retrieving Cloud Streaming UI Dec 14 17:21:14 moojik volumio[942]: info: Getting Tidal Cloud Configuration Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 17:21:14 moojik volumio[942]: info: Getting Qobuz Cloud Configuration Dec 14 17:21:14 moojik volumio[942]: info: Asking plugin for UI Config Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 17:21:14 moojik volumio[942]: info: Getting Spotify Cloud Configuration Dec 14 17:21:14 moojik volumio[942]: info: Asking plugin for UI Config Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 17:21:14 moojik volumio[942]: info: Saving Spotify Acccount Dec 14 17:21:14 moojik volumio[942]: info: Got Tidal Cloud Configuration Dec 14 17:21:14 moojik volumio[942]: info: Got it Dec 14 17:21:14 moojik volumio[942]: info: Got it Dec 14 17:21:14 moojik volumio[942]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 17:21:14 moojik volumio[942]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 17:21:14 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 14 17:21:17 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 17:21:17 moojik volumio[942]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 14 17:21:17 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 14 17:21:17 moojik volumio[942]: info: Received Get System Version Dec 14 17:21:17 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 17:21:17 moojik volumio[942]: info: Received Get System Info Dec 14 17:21:17 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 17:21:17 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 17:21:17 moojik volumio[942]: info: Discovery: Getting this device information Dec 14 17:21:17 moojik volumio[942]: info: CoreCommandRouter::volumioGetState Dec 14 17:21:17 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 17:21:17 moojik wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Dec 14 17:21:18 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 17:21:20 moojik volumio[942]: info: Disabling MyMusic plugin upnp Dec 14 17:21:20 moojik sudo[9477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 14 17:21:20 moojik sudo[9477]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 17:21:20 moojik systemd[1]: Stopping UPnP Renderer front-end to MPD... Dec 14 17:21:20 moojik volumio[942]: error: Upnp client error: Error: This socket has been ended by the other party Dec 14 17:21:22 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 17:21:23 moojik wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Dec 14 17:21:24 moojik go-librespot[1247]: time="2025-12-14T17:21:24+05:30" level=trace msg="sent dealer ping" Dec 14 17:21:25 moojik go-librespot[1247]: time="2025-12-14T17:21:25+05:30" level=trace msg="received dealer pong" Dec 14 17:21:26 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 17:21:29 moojik volumio[942]: info: Enabling MyMusic plugin upnp Dec 14 17:21:29 moojik volumio[942]: info: Enabling plugin upnp Dec 14 17:21:29 moojik volumio[942]: info: Loading plugin "upnp"... Dec 14 17:21:29 moojik volumio[942]: info: [1765713089062] Starting Upmpd Daemon Dec 14 17:21:29 moojik volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 14 17:21:29 moojik volumio[942]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 17:21:29 moojik volumio[942]: Error: listen EADDRINUSE: address already in use :::6599 Dec 14 17:21:29 moojik volumio[942]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Dec 14 17:21:29 moojik volumio[942]: at listenInCluster (net.js:1366:12) Dec 14 17:21:29 moojik volumio[942]: at Server.listen (net.js:1452:7) Dec 14 17:21:29 moojik volumio[942]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 14 17:21:29 moojik volumio[942]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 14 17:21:29 moojik volumio[942]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 14 17:21:29 moojik volumio[942]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 14 17:21:29 moojik volumio[942]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Dec 14 17:21:29 moojik volumio[942]: code: 'EADDRINUSE', Dec 14 17:21:29 moojik volumio[942]: errno: -98, Dec 14 17:21:29 moojik volumio[942]: syscall: 'listen', Dec 14 17:21:29 moojik volumio[942]: address: '::', Dec 14 17:21:29 moojik volumio[942]: port: 6599 Dec 14 17:21:29 moojik volumio[942]: } Dec 14 17:21:29 moojik volumio[942]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 17:21:29 moojik sudo[9520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-14 17:20 Dec 14 17:21:29 moojik sudo[9520]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"