May 28 19:00:11 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 1 May 28 19:00:11 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:11 harshahifi volumio[1360]: info: Prefetching next song May 28 19:00:11 harshahifi volumio[1360]: info: DOING PREFETCH IN MPD May 28 19:00:11 harshahifi volumio[1360]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Music/Phil Collins - Hits (1998)/03. Easy Lover.flac" May 28 19:00:11 harshahifi volumio[1360]: info: May 28 19:00:11 harshahifi volumio[1360]: ---------------------------- MPD announces system playlist update May 28 19:00:11 harshahifi volumio[1360]: info: Ignoring MPD Status Update May 28 19:00:11 harshahifi volumio[1360]: info: sendMpdCommand add "INTERNAL/Music/Phil Collins - Hits (1998)/03. Easy Lover.flac" took 0 milliseconds May 28 19:00:11 harshahifi volumio[1360]: verbose: ControllerMpd::sendMpdCommand consume 1 May 28 19:00:11 harshahifi volumio[1360]: info: May 28 19:00:11 harshahifi volumio[1360]: ---------------------------- MPD announces system playlist update May 28 19:00:11 harshahifi volumio[1360]: info: Ignoring MPD Status Update May 28 19:00:11 harshahifi volumio[1360]: info: May 28 19:00:11 harshahifi volumio[1360]: ---------------------------- MPD announces system playlist update May 28 19:00:11 harshahifi volumio[1360]: info: Ignoring MPD Status Update May 28 19:00:11 harshahifi volumio[1360]: info: ------------------------------ 1ms May 28 19:00:11 harshahifi volumio[1360]: info: sendMpdCommand consume 1 took 1 milliseconds May 28 19:00:11 harshahifi volumio[1360]: info: ------------------------------ 0ms May 28 19:00:11 harshahifi volumio[1360]: info: ------------------------------ 0ms May 28 19:00:16 harshahifi volumio[1360]: info: CoreStateMachine::startPlaybackTimer May 28 19:00:16 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:16 harshahifi volumio[1360]: info: CoreStateMachine::pushState May 28 19:00:16 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:16 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 19:00:16 harshahifi volumio[1360]: info: CoreCommandRouter::volumioPushState May 28 19:00:16 harshahifi volumio5-onboarding[1542]: time=2026-05-28T19:00:16.575+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.26:45810,00:00:00:00:00:00%02 @ 0x2000ae0" state=STATUS_PLAYING positionMs=250 volume=100 May 28 19:00:16 harshahifi volumio5-onboarding[1542]: time=2026-05-28T19:00:16.575+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.26:45810,00:00:00:00:00:00%02 @ 0x2000ae0" id="music-library/INTERNAL/Music/Phil Collins - Hits (1998)/03. Easy Lover.flac" title="Easy Lover" May 28 19:00:17 harshahifi volumio[1360]: info: May 28 19:00:17 harshahifi volumio[1360]: ---------------------------- MPD announces system playlist update May 28 19:00:17 harshahifi volumio[1360]: info: Ignoring MPD Status Update May 28 19:00:17 harshahifi volumio[1360]: info: May 28 19:00:17 harshahifi volumio[1360]: ---------------------------- MPD announces state update: player May 28 19:00:17 harshahifi volumio[1360]: info: ControllerMpd::getState May 28 19:00:17 harshahifi volumio[1360]: verbose: ControllerMpd::sendMpdCommand status May 28 19:00:17 harshahifi volumio[1360]: info: May 28 19:00:17 harshahifi volumio[1360]: ---------------------------- MPD announces system playlist update May 28 19:00:17 harshahifi volumio[1360]: info: Ignoring MPD Status Update May 28 19:00:17 harshahifi volumio[1360]: info: May 28 19:00:17 harshahifi volumio[1360]: ---------------------------- MPD announces state update: player May 28 19:00:17 harshahifi volumio[1360]: info: ControllerMpd::getState May 28 19:00:17 harshahifi volumio[1360]: verbose: ControllerMpd::sendMpdCommand status May 28 19:00:17 harshahifi volumio[1360]: info: May 28 19:00:17 harshahifi volumio[1360]: ---------------------------- MPD announces system playlist update May 28 19:00:17 harshahifi volumio[1360]: info: Ignoring MPD Status Update May 28 19:00:17 harshahifi volumio[1360]: info: May 28 19:00:17 harshahifi volumio[1360]: ---------------------------- MPD announces state update: player May 28 19:00:17 harshahifi volumio[1360]: info: ControllerMpd::getState May 28 19:00:17 harshahifi volumio[1360]: verbose: ControllerMpd::sendMpdCommand status May 28 19:00:17 harshahifi volumio[1360]: info: ------------------------------ 2ms May 28 19:00:17 harshahifi volumio[1360]: info: sendMpdCommand status took 1 milliseconds May 28 19:00:17 harshahifi volumio[1360]: info: ------------------------------ 1ms May 28 19:00:17 harshahifi volumio[1360]: info: sendMpdCommand status took 1 milliseconds May 28 19:00:17 harshahifi volumio[1360]: verbose: ControllerMpd::parseState May 28 19:00:17 harshahifi volumio[1360]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 19:00:17 harshahifi volumio[1360]: verbose: ControllerMpd::parseState May 28 19:00:17 harshahifi volumio[1360]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 19:00:17 harshahifi volumio[1360]: info: ------------------------------ 2ms May 28 19:00:17 harshahifi volumio[1360]: info: sendMpdCommand status took 1 milliseconds May 28 19:00:17 harshahifi volumio[1360]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 19:00:17 harshahifi volumio[1360]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 19:00:17 harshahifi volumio[1360]: verbose: ControllerMpd::parseState May 28 19:00:17 harshahifi volumio[1360]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 19:00:17 harshahifi volumio[1360]: verbose: ControllerMpd::parseTrackInfo May 28 19:00:17 harshahifi volumio[1360]: verbose: ControllerMpd::parseTrackInfo May 28 19:00:17 harshahifi volumio[1360]: info: ControllerMpd::pushState May 28 19:00:17 harshahifi volumio[1360]: info: CoreCommandRouter::servicePushState May 28 19:00:17 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:17 harshahifi volumio[1360]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":303,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"939 Kbps","isStreaming":false,"title":"Easy Lover","artist":"Phil Collins","album":"...Hits","uri":"INTERNAL/Music/Phil Collins - Hits (1998)/03. Easy Lover.flac","trackType":"flac"} May 28 19:00:17 harshahifi volumio[1360]: verbose: CURRENT POSITION 2 May 28 19:00:17 harshahifi volumio[1360]: info: CoreStateMachine::syncState stateService play May 28 19:00:17 harshahifi volumio[1360]: info: CoreStateMachine::syncState currentStatus play May 28 19:00:17 harshahifi volumio[1360]: info: Received an update from plugin. extracting info from payload May 28 19:00:17 harshahifi volumio[1360]: info: CoreStateMachine::pushState May 28 19:00:17 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:17 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 19:00:17 harshahifi volumio[1360]: info: CoreCommandRouter::volumioPushState May 28 19:00:17 harshahifi volumio[1360]: info: CoreStateMachine::pushState May 28 19:00:17 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:17 harshahifi volumio[1360]: info: CoreCommandRouter::volumioPushState May 28 19:00:17 harshahifi volumio[1360]: info: ControllerMpd::pushState May 28 19:00:17 harshahifi volumio[1360]: info: CoreCommandRouter::servicePushState May 28 19:00:17 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:17 harshahifi volumio[1360]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":303,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"939 Kbps","isStreaming":false,"title":"Easy Lover","artist":"Phil Collins","album":"...Hits","uri":"INTERNAL/Music/Phil Collins - Hits (1998)/03. Easy Lover.flac","trackType":"flac"} May 28 19:00:17 harshahifi volumio[1360]: verbose: CURRENT POSITION 2 May 28 19:00:17 harshahifi volumio[1360]: info: CoreStateMachine::syncState stateService play May 28 19:00:17 harshahifi volumio[1360]: info: CoreStateMachine::syncState currentStatus play May 28 19:00:17 harshahifi volumio[1360]: info: Received an update from plugin. extracting info from payload May 28 19:00:17 harshahifi volumio[1360]: info: CoreStateMachine::pushState May 28 19:00:17 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:17 harshahifi volumio[1360]: info: CoreCommandRouter::volumioPushState May 28 19:00:17 harshahifi volumio[1360]: info: CoreStateMachine::pushState May 28 19:00:17 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:17 harshahifi volumio[1360]: info: CoreCommandRouter::volumioPushState May 28 19:00:17 harshahifi volumio5-onboarding[1542]: time=2026-05-28T19:00:17.095+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.26:45810,00:00:00:00:00:00%02 @ 0x2000ae0" state=STATUS_PLAYING positionMs=68 volume=100 May 28 19:00:17 harshahifi volumio5-onboarding[1542]: time=2026-05-28T19:00:17.095+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.26:45810,00:00:00:00:00:00%02 @ 0x2000ae0" state=STATUS_PLAYING positionMs=68 volume=100 May 28 19:00:17 harshahifi volumio5-onboarding[1542]: time=2026-05-28T19:00:17.095+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.26:45810,00:00:00:00:00:00%02 @ 0x2000ae0" id="music-library/INTERNAL/Music/Phil Collins - Hits (1998)/03. Easy Lover.flac" title="Easy Lover" May 28 19:00:17 harshahifi volumio5-onboarding[1542]: time=2026-05-28T19:00:17.095+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.26:45810,00:00:00:00:00:00%02 @ 0x2000ae0" id="music-library/INTERNAL/Music/Phil Collins - Hits (1998)/03. Easy Lover.flac" title="Easy Lover" May 28 19:00:17 harshahifi volumio5-onboarding[1542]: time=2026-05-28T19:00:17.096+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.26:45810,00:00:00:00:00:00%02 @ 0x2000ae0" state=STATUS_PLAYING positionMs=68 volume=100 May 28 19:00:17 harshahifi volumio5-onboarding[1542]: time=2026-05-28T19:00:17.096+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.26:45810,00:00:00:00:00:00%02 @ 0x2000ae0" id="music-library/INTERNAL/Music/Phil Collins - Hits (1998)/03. Easy Lover.flac" title="Easy Lover" May 28 19:00:17 harshahifi volumio5-onboarding[1542]: time=2026-05-28T19:00:17.096+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.26:45810,00:00:00:00:00:00%02 @ 0x2000ae0" state=STATUS_PLAYING positionMs=68 volume=100 May 28 19:00:17 harshahifi volumio5-onboarding[1542]: time=2026-05-28T19:00:17.096+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.26:45810,00:00:00:00:00:00%02 @ 0x2000ae0" id="music-library/INTERNAL/Music/Phil Collins - Hits (1998)/03. Easy Lover.flac" title="Easy Lover" May 28 19:00:17 harshahifi volumio[1360]: info: ------------------------------ 8ms May 28 19:00:17 harshahifi volumio[1360]: info: ------------------------------ 8ms May 28 19:00:17 harshahifi volumio[1360]: info: sendMpdCommand playlistinfo took 6 milliseconds May 28 19:00:17 harshahifi volumio[1360]: verbose: ControllerMpd::parseTrackInfo May 28 19:00:17 harshahifi volumio[1360]: info: ControllerMpd::pushState May 28 19:00:17 harshahifi volumio[1360]: info: CoreCommandRouter::servicePushState May 28 19:00:17 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:17 harshahifi volumio[1360]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":303,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"939 Kbps","isStreaming":false,"title":"Easy Lover","artist":"Phil Collins","album":"...Hits","uri":"INTERNAL/Music/Phil Collins - Hits (1998)/03. Easy Lover.flac","trackType":"flac"} May 28 19:00:17 harshahifi volumio[1360]: verbose: CURRENT POSITION 2 May 28 19:00:17 harshahifi volumio[1360]: info: CoreStateMachine::syncState stateService play May 28 19:00:17 harshahifi volumio[1360]: info: CoreStateMachine::syncState currentStatus play May 28 19:00:17 harshahifi volumio[1360]: info: Received an update from plugin. extracting info from payload May 28 19:00:17 harshahifi volumio[1360]: info: CoreStateMachine::pushState May 28 19:00:17 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:17 harshahifi volumio[1360]: info: CoreCommandRouter::volumioPushState May 28 19:00:17 harshahifi volumio[1360]: info: CoreStateMachine::pushState May 28 19:00:17 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:17 harshahifi volumio[1360]: info: CoreCommandRouter::volumioPushState May 28 19:00:17 harshahifi volumio5-onboarding[1542]: time=2026-05-28T19:00:17.100+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.26:45810,00:00:00:00:00:00%02 @ 0x2000ae0" state=STATUS_PLAYING positionMs=68 volume=100 May 28 19:00:17 harshahifi volumio5-onboarding[1542]: time=2026-05-28T19:00:17.100+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.26:45810,00:00:00:00:00:00%02 @ 0x2000ae0" state=STATUS_PLAYING positionMs=68 volume=100 May 28 19:00:17 harshahifi volumio5-onboarding[1542]: time=2026-05-28T19:00:17.100+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.26:45810,00:00:00:00:00:00%02 @ 0x2000ae0" id="music-library/INTERNAL/Music/Phil Collins - Hits (1998)/03. Easy Lover.flac" title="Easy Lover" May 28 19:00:17 harshahifi volumio5-onboarding[1542]: time=2026-05-28T19:00:17.100+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.26:45810,00:00:00:00:00:00%02 @ 0x2000ae0" id="music-library/INTERNAL/Music/Phil Collins - Hits (1998)/03. Easy Lover.flac" title="Easy Lover" May 28 19:00:17 harshahifi volumio[1360]: info: ------------------------------ 11ms May 28 19:00:57 harshahifi volumio[1360]: verbose: New Socket.io Connection to 192.168.31.225 from 192.168.31.26 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7 Build/CP1A.260405.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.184 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 28 19:00:57 harshahifi volumio[1360]: info: Received Get System Info May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 19:00:57 harshahifi volumio[1360]: info: Discovery: Getting this device information May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetVisibleSources May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 28 19:00:57 harshahifi volumio[1360]: info: Received Get System Info May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 19:00:57 harshahifi volumio[1360]: info: Discovery: Getting this device information May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: Listing playlists May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetQueue May 28 19:00:57 harshahifi volumio[1360]: info: CoreStateMachine::getQueue May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getQueue May 28 19:00:57 harshahifi volumio[1360]: info: Received Get System Info May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 19:00:57 harshahifi volumio[1360]: info: Discovery: Getting this device information May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetVisibleSources May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 28 19:00:57 harshahifi volumio[1360]: info: Received Get System Info May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 19:00:57 harshahifi volumio[1360]: info: Discovery: Getting this device information May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: Listing playlists May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetQueue May 28 19:00:57 harshahifi volumio[1360]: info: CoreStateMachine::getQueue May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getQueue May 28 19:00:57 harshahifi volumio[1360]: info: Received Get System Info May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 19:00:57 harshahifi volumio[1360]: info: Discovery: Getting this device information May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetVisibleSources May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 28 19:00:57 harshahifi volumio[1360]: info: Received Get System Info May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 19:00:57 harshahifi volumio[1360]: info: Discovery: Getting this device information May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: Listing playlists May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetQueue May 28 19:00:57 harshahifi volumio[1360]: info: CoreStateMachine::getQueue May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getQueue May 28 19:00:57 harshahifi volumio[1360]: info: Received Get System Info May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 19:00:57 harshahifi volumio[1360]: info: Discovery: Getting this device information May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:00:57 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 28 19:00:57 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 28 19:00:59 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 19:00:59 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 28 19:01:01 harshahifi volumio[1360]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 19:01:09 harshahifi volumio[1360]: info: Received OAUTH Data May 28 19:01:09 harshahifi volumio[1360]: info: Executing Spotify Oauth Login May 28 19:01:09 harshahifi volumio[1360]: info: Saving Spotify Refresh Token May 28 19:01:10 harshahifi sudo[19131]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 19:01:10 harshahifi sudo[19133]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 19:01:10 harshahifi sudo[19131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:01:10 harshahifi sudo[19133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:01:10 harshahifi sudo[19131]: pam_unix(sudo:session): session closed for user root May 28 19:01:10 harshahifi sudo[19133]: pam_unix(sudo:session): session closed for user root May 28 19:01:10 harshahifi volumio[1360]: verbose: New Socket.io Connection to 192.168.31.225 from 192.168.31.26 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7 Build/CP1A.260405.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.184 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 28 19:01:10 harshahifi volumio[1360]: info: New Spotify access tokenBQAU-zFS5v... May 28 19:01:10 harshahifi volumio[1360]: info: Spotify credentials grant success - running version from March 24, 2019 May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetVisibleSources May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:01:10 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 28 19:01:10 harshahifi volumio[1360]: info: Received Get System Info May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 19:01:10 harshahifi volumio[1360]: info: Discovery: Getting this device information May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:01:10 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::volumioGetState May 28 19:01:10 harshahifi volumio[1360]: info: CorePlayQueue::getTrack 2 May 28 19:01:10 harshahifi volumio[1360]: info: Listing playlists May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 28 19:01:10 harshahifi volumio[1360]: SPOTIFY: User informations: {"account_id":"JFyaScyQHu","country":"IN","display_name":"Harshavardhan Raju","email":"dhvraju@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/12101063779"},"followers":{"href":null,"total":6},"href":"https://api.spotify.com/v1/users/12101063779","id":"12101063779","images":[{"height":300,"url":"https://scontent-atl3-2.xx.fbcdn.net/v/t1.6435-1/62070563_10216183754880271_6350310306224275456_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=100&ccb=1-7&_nc_sid=08baa4&_nc_ohc=1C_raHWZXFEQ7kNvwHwEwf3&_nc_oc=AdpXhhWrTsdVsTqg32g9Ue4MAtaGtPh-JKa5pWQl_FMDNXcQmdGvmuLQcapNkomVbbUfEEIvNBqfPLNSwQ_OD4D3&_nc_zt=24&_nc_ht=scontent-atl3-2.xx&edm=AP4hL3IEAAAA&_nc_gid=W_vi9Saedt0YhUsvycFOtQ&_nc_tpa=Q5bMBQHdJNlcwr7TYcYjF4_gAHxQdwjx1P2yFF7d7_1aF3kBOoWMSkV4j98gr0BdafPNbQrQEuW9&oh=00_Af5UgNPz9dO0dGng5gEsRpfDhpgckTGxub1_nJHfhffNkQ&oe=6A3F17A6","width":300},{"height":64,"url":"https://scontent-atl3-2.xx.fbcdn.net/v/t1.6435-1/62070563_10216183754880271_6350310306224275456_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=100&ccb=1-7&_nc_sid=28885b&_nc_ohc=1C_raHWZXFEQ7kNvwHwEwf3&_nc_oc=AdpXhhWrTsdVsTqg32g9Ue4MAtaGtPh-JKa5pWQl_FMDNXcQmdGvmuLQcapNkomVbbUfEEIvNBqfPLNSwQ_OD4D3&_nc_zt=24&_nc_ht=scontent-atl3-2.xx&edm=AP4hL3IEAAAA&_nc_gid=W_vi9Saedt0YhUsvycFOtQ&_nc_tpa=Q5bMBQHaN4oEH0t_DgVPZ62etR3iIctgFN91PkCJhCTIp9ArFsyuAdq_fRjYsHYPeFt3do0Z5Ucs&oh=00_Af5yb7c3ZT_l8zLC2tqAJs0-KFMhcQguwyzO7gpxfTztgQ&oe=6A3F17A6","width":64}],"product":"premium","type":"user","uri":"spotify:user:12101063779"} May 28 19:01:10 harshahifi volumio[1360]: info: Creating Spotify config file May 28 19:01:10 harshahifi volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:01:10 harshahifi volumio[1360]: info: Spotify config file written May 28 19:01:10 harshahifi sudo[19139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 28 19:01:10 harshahifi sudo[19139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:01:10 harshahifi systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... May 28 19:01:10 harshahifi systemd[1]: go-librespot-daemon.service: Deactivated successfully. May 28 19:01:10 harshahifi volumio[1360]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 19:01:10 harshahifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 28 19:01:10 harshahifi volumio[1360]: Error: socket hang up May 28 19:01:10 harshahifi volumio[1360]: at connResetException (node:internal/errors:720:14) May 28 19:01:10 harshahifi volumio[1360]: at Socket.socketOnEnd (node:_http_client:519:23) May 28 19:01:10 harshahifi volumio[1360]: at Socket.emit (node:events:526:35) May 28 19:01:10 harshahifi volumio[1360]: at endReadableNT (node:internal/streams/readable:1376:12) May 28 19:01:10 harshahifi volumio[1360]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { May 28 19:01:10 harshahifi volumio[1360]: code: 'ECONNRESET', May 28 19:01:10 harshahifi volumio[1360]: response: undefined May 28 19:01:10 harshahifi volumio[1360]: } May 28 19:01:10 harshahifi volumio[1360]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 19:01:10 harshahifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 28 19:01:10 harshahifi go-librespot[19141]: go-librespot daemon starting... May 28 19:01:10 harshahifi sudo[19139]: pam_unix(sudo:session): session closed for user root May 28 19:01:10 harshahifi go-librespot[19153]: time="2026-05-28T19:01:10+05:30" level=info msg="running go-librespot 0.7.1" May 28 19:01:10 harshahifi go-librespot[19153]: time="2026-05-28T19:01:10+05:30" level=debug msg="app state loaded" May 28 19:01:10 harshahifi go-librespot[19153]: time="2026-05-28T19:01:10+05:30" level=info msg="api server listening on 127.0.0.1:9879" May 28 19:01:10 harshahifi sudo[19162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-28 19:00' May 28 19:01:10 harshahifi sudo[19162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"