-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Fri 2025-09-05 23:52:47 EEST. -- Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=debug msg="obtained new client token: AACnhGIOMU6rsiPp6mGO5y//VH+m2DrRDVgRKTpaUD+ibuUbg7tPI6KMDuaT4VXSM9ZZ1ON5/TnLEQ8f22RzFEMQ4yEqrAcIZ+OUxl62zHfD21CNeOe6I0czCtyc5/xPPk1NkjjBdhyWeGvxsSZdBYSL3aXooPu2Wc3ZehYd8zlsCDGrm73MjXM5Uabp3W5Yfgyv8dihwHHdJvI1zS07yLWaMyRqNjUn9MOPFTkjn36e7EHVwMuHoWAJ" Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=info msg="connected to ap-gew1.spotify.com:4070" Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=debug msg="completed keyexchange" Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=debug msg="completed challenge" Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=debug msg="authenticated as x2gz62t5lq3u94h8zlz032gkt" Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=debug msg="authenticated as x2gz62t5lq3u94h8zlz032gkt" Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=debug msg="dealer connection opened" Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=info msg="accepted zeroconf user x2gz62t5lq3u94h8zlz032gkt from OnePlus Pad 2" Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=debug msg="autoplay enabled: false" Sep 05 23:51:02 volumio2 go-librespot[1281]: time="2025-09-05T23:51:02+03:00" level=debug msg="received connection id: ZGQ4Y2UyZGItZWJmZi00ZWZiLWIyYjktMzBlY2I4Yjg1NzUxK2RlYWxlcit0Y3A6Ly8wYWNhNDI0YS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArRUU2MzIzNDY0QkQwN0Y1MzQzODE4NDI0MDIyN0Q3NUVCRTBCOUNDMzI0N0U3NDBBNTk3MEY2REZENzlBNzkzNA==" Sep 05 23:51:03 volumio2 go-librespot[1281]: time="2025-09-05T23:51:03+03:00" level=debug msg="put connect state because NEW_DEVICE" Sep 05 23:51:03 volumio2 go-librespot[1281]: time="2025-09-05T23:51:03+03:00" level=debug msg="handling transfer player command from 839bddf9bbb5bad80db6e154378299e970a4572f" Sep 05 23:51:03 volumio2 go-librespot[1281]: time="2025-09-05T23:51:03+03:00" level=debug msg="resolved context of track" uri="spotify:album:2ulLCUuxrYYuHjOqnhBkx9" Sep 05 23:51:03 volumio2 go-librespot[1281]: time="2025-09-05T23:51:03+03:00" level=trace msg="fetched new page 0 with 16 items (list: 16)" uri="spotify:album:2ulLCUuxrYYuHjOqnhBkx9" Sep 05 23:51:03 volumio2 go-librespot[1281]: time="2025-09-05T23:51:03+03:00" level=debug msg="loading track (paused: false, position: 37245ms)" uri="spotify:track:1gXm1qiMPJO4djxDgS5Sz0" Sep 05 23:51:03 volumio2 go-librespot[1281]: time="2025-09-05T23:51:03+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 05 23:51:03 volumio2 go-librespot[1281]: time="2025-09-05T23:51:03+03:00" level=trace msg="emitting websocket event: will_play" Sep 05 23:51:03 volumio2 volumio[995]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1gXm1qiMPJO4djxDgS5Sz0","play_origin":""}} Sep 05 23:51:03 volumio2 go-librespot[1281]: time="2025-09-05T23:51:03+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Sep 05 23:51:03 volumio2 go-librespot[1281]: time="2025-09-05T23:51:03+03:00" level=warning msg="failed handling dealer request" error="failed loading current track (transfer): failed creating stream for spotify:track:1gXm1qiMPJO4djxDgS5Sz0: no supported formats" Sep 05 23:51:03 volumio2 go-librespot[1281]: time="2025-09-05T23:51:03+03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Sep 05 23:51:03 volumio2 go-librespot[1281]: time="2025-09-05T23:51:03+03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Sep 05 23:51:03 volumio2 go-librespot[1281]: time="2025-09-05T23:51:03+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1373" Sep 05 23:51:03 volumio2 go-librespot[1281]: time="2025-09-05T23:51:03+03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Sep 05 23:51:03 volumio2 go-librespot[1281]: time="2025-09-05T23:51:03+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1131" Sep 05 23:52:11 volumio2 volumio[995]: info: Clearing queue after UPNP request Sep 05 23:52:11 volumio2 volumio[995]: info: CoreStateMachine::ClearQueue Sep 05 23:52:11 volumio2 volumio[995]: info: CoreStateMachine::stop Sep 05 23:52:11 volumio2 volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 23:52:11 volumio2 volumio[995]: info: CorePlayQueue::clearPlayQueue Sep 05 23:52:11 volumio2 volumio[995]: info: CorePlayQueue::saveQueue Sep 05 23:52:11 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushState Sep 05 23:52:11 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushQueue Sep 05 23:52:11 volumio2 volumio[995]: error: Upnp client error: Error: This socket has been ended by the other party Sep 05 23:52:13 volumio2 volumio[995]: info: Starting UPNP Playback Sep 05 23:52:13 volumio2 volumio[995]: info: Preparing playback through UPNP Sep 05 23:52:13 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Sep 05 23:52:13 volumio2 volumio[995]: info: CorePlayQueue::getTrack 0 Sep 05 23:52:13 volumio2 volumio[995]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 05 23:52:13 volumio2 volumio[995]: info: Sep 05 23:52:13 volumio2 volumio[995]: ---------------------------- MPD announces system playlist update Sep 05 23:52:13 volumio2 volumio[995]: info: Ignoring MPD Status Update Sep 05 23:52:13 volumio2 volumio[995]: info: Sep 05 23:52:13 volumio2 volumio[995]: ---------------------------- MPD announces system playlist update Sep 05 23:52:13 volumio2 volumio[995]: info: Ignoring MPD Status Update Sep 05 23:52:13 volumio2 volumio[995]: info: ------------------------------ 3ms Sep 05 23:52:13 volumio2 volumio[995]: info: ------------------------------ 2ms Sep 05 23:52:13 volumio2 volumio[995]: info: Sep 05 23:52:13 volumio2 volumio[995]: ---------------------------- MPD announces system playlist update Sep 05 23:52:13 volumio2 volumio[995]: info: Ignoring MPD Status Update Sep 05 23:52:13 volumio2 volumio[995]: info: Sep 05 23:52:13 volumio2 volumio[995]: ---------------------------- MPD announces system playlist update Sep 05 23:52:13 volumio2 volumio[995]: info: Ignoring MPD Status Update Sep 05 23:52:13 volumio2 volumio[995]: info: ------------------------------ 2ms Sep 05 23:52:13 volumio2 volumio[995]: info: ------------------------------ 2ms Sep 05 23:52:13 volumio2 volumio[995]: info: Sep 05 23:52:13 volumio2 volumio[995]: ---------------------------- MPD announces system playlist update Sep 05 23:52:13 volumio2 volumio[995]: info: Ignoring MPD Status Update Sep 05 23:52:13 volumio2 volumio[995]: info: ------------------------------ 1ms Sep 05 23:52:13 volumio2 volumio[995]: info: Sep 05 23:52:13 volumio2 volumio[995]: ---------------------------- MPD announces system playlist update Sep 05 23:52:13 volumio2 volumio[995]: info: Ignoring MPD Status Update Sep 05 23:52:13 volumio2 volumio[995]: info: ------------------------------ 1ms Sep 05 23:52:15 volumio2 volumio[995]: info: Sep 05 23:52:15 volumio2 volumio[995]: ---------------------------- MPD announces state update: player Sep 05 23:52:15 volumio2 volumio[995]: info: ControllerMpd::getState Sep 05 23:52:15 volumio2 volumio[995]: verbose: ControllerMpd::sendMpdCommand status Sep 05 23:52:15 volumio2 volumio[995]: info: Sep 05 23:52:15 volumio2 volumio[995]: ---------------------------- MPD announces state update: player Sep 05 23:52:15 volumio2 volumio[995]: info: sendMpdCommand status took 6 milliseconds Sep 05 23:52:15 volumio2 volumio[995]: info: ControllerMpd::getState Sep 05 23:52:15 volumio2 volumio[995]: verbose: ControllerMpd::sendMpdCommand status Sep 05 23:52:15 volumio2 volumio[995]: verbose: ControllerMpd::parseState Sep 05 23:52:15 volumio2 volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 23:52:15 volumio2 volumio[995]: info: sendMpdCommand status took 3 milliseconds Sep 05 23:52:15 volumio2 volumio[995]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 23:52:15 volumio2 volumio[995]: verbose: ControllerMpd::parseState Sep 05 23:52:15 volumio2 volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 23:52:15 volumio2 volumio[995]: verbose: ControllerMpd::parseTrackInfo Sep 05 23:52:15 volumio2 volumio[995]: info: ControllerMpd::pushState Sep 05 23:52:15 volumio2 volumio[995]: info: CoreCommandRouter::servicePushState Sep 05 23:52:15 volumio2 volumio[995]: verbose: In UPNP mode Sep 05 23:52:15 volumio2 volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":363,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Glass: Études: No. 2","artist":"Víkingur Ólafsson","album":"Philip Glass: Piano Works","uri":"http://192.168.0.103:11629/tidal/77615084.flac","trackType":"tidal"} Sep 05 23:52:15 volumio2 volumio[995]: verbose: CURRENT POSITION 0 Sep 05 23:52:15 volumio2 volumio[995]: info: CoreStateMachine::syncState stateService play Sep 05 23:52:15 volumio2 volumio[995]: info: CoreStateMachine::syncState currentStatus stop Sep 05 23:52:15 volumio2 volumio[995]: info: ------------------------------ 15ms Sep 05 23:52:15 volumio2 volumio[995]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 05 23:52:15 volumio2 volumio[995]: verbose: ControllerMpd::parseTrackInfo Sep 05 23:52:15 volumio2 volumio[995]: info: ControllerMpd::pushState Sep 05 23:52:15 volumio2 volumio[995]: info: CoreCommandRouter::servicePushState Sep 05 23:52:15 volumio2 volumio[995]: verbose: In UPNP mode Sep 05 23:52:15 volumio2 volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":363,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Glass: Études: No. 2","artist":"Víkingur Ólafsson","album":"Philip Glass: Piano Works","uri":"http://192.168.0.103:11629/tidal/77615084.flac","trackType":"tidal"} Sep 05 23:52:15 volumio2 volumio[995]: verbose: CURRENT POSITION 0 Sep 05 23:52:15 volumio2 volumio[995]: info: CoreStateMachine::syncState stateService play Sep 05 23:52:15 volumio2 volumio[995]: info: CoreStateMachine::syncState currentStatus play Sep 05 23:52:15 volumio2 volumio[995]: info: Received an update from plugin. extracting info from payload Sep 05 23:52:15 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 23:52:15 volumio2 volumio[995]: info: CoreStateMachine::pushState Sep 05 23:52:15 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 23:52:15 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushState Sep 05 23:52:15 volumio2 volumio[995]: info: CoreStateMachine::pushState Sep 05 23:52:15 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushState Sep 05 23:52:15 volumio2 volumio[995]: info: ------------------------------ 21ms Sep 05 23:52:15 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetQueue Sep 05 23:52:15 volumio2 volumio[995]: info: CoreStateMachine::getQueue Sep 05 23:52:15 volumio2 volumio[995]: info: CorePlayQueue::getQueue Sep 05 23:52:15 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetQueue Sep 05 23:52:15 volumio2 volumio[995]: info: CoreStateMachine::getQueue Sep 05 23:52:15 volumio2 volumio[995]: info: CorePlayQueue::getQueue Sep 05 23:52:27 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 23:52:27 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 23:52:27 volumio2 volumio[995]: info: Discovery: Getting this device information Sep 05 23:52:27 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Sep 05 23:52:27 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 23:52:27 volumio2 volumio[995]: verbose: New Socket.io Connection to 192.168.0.118:3000 from 192.168.0.103 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Sep 05 23:52:27 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Sep 05 23:52:27 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 05 23:52:27 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 05 23:52:30 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Sep 05 23:52:30 volumio2 volumio[995]: verbose: New Socket.io Connection to 192.168.0.118:3000 from 192.168.0.103 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Sep 05 23:52:30 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Sep 05 23:52:30 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 05 23:52:30 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 05 23:52:30 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Sep 05 23:52:31 volumio2 sudo[13052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 23:52:31 volumio2 sudo[13052]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 23:52:31 volumio2 sudo[13052]: pam_unix(sudo:session): session closed for user root Sep 05 23:52:31 volumio2 sudo[13055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 23:52:31 volumio2 sudo[13055]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 23:52:31 volumio2 sudo[13055]: pam_unix(sudo:session): session closed for user root Sep 05 23:52:31 volumio2 volumio[995]: verbose: New Socket.io Connection to 192.168.0.118 from 192.168.0.103 UA: Mozilla/5.0 (Linux; Android 15; OPD2403 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.158 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetVisibleSources Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 05 23:52:31 volumio2 sudo[13058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 23:52:31 volumio2 sudo[13058]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 23:52:31 volumio2 sudo[13058]: pam_unix(sudo:session): session closed for user root Sep 05 23:52:31 volumio2 sudo[13061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 23:52:31 volumio2 sudo[13061]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 23:52:31 volumio2 sudo[13061]: pam_unix(sudo:session): session closed for user root Sep 05 23:52:31 volumio2 volumio[995]: verbose: New Socket.io Connection to 192.168.0.118 from 192.168.0.103 UA: Mozilla/5.0 (Linux; Android 15; OPD2403 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.158 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetVisibleSources Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 23:52:31 volumio2 volumio[995]: info: Listing playlists Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 05 23:52:31 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 05 23:52:32 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 23:52:32 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 23:52:32 volumio2 volumio[995]: info: Discovery: Getting this device information Sep 05 23:52:32 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Sep 05 23:52:32 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 23:52:33 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 05 23:52:33 volumio2 volumio[995]: info: Received Get System Info Sep 05 23:52:33 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 23:52:33 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 23:52:33 volumio2 volumio[995]: info: Discovery: Getting this device information Sep 05 23:52:33 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Sep 05 23:52:33 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 23:52:35 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 05 23:52:35 volumio2 volumio[995]: info: Received Get System Info Sep 05 23:52:35 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 23:52:35 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 23:52:35 volumio2 volumio[995]: info: Discovery: Getting this device information Sep 05 23:52:35 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Sep 05 23:52:35 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 23:52:37 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetQueue Sep 05 23:52:37 volumio2 volumio[995]: info: CoreStateMachine::getQueue Sep 05 23:52:37 volumio2 volumio[995]: info: CorePlayQueue::getQueue Sep 05 23:52:40 volumio2 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 23:52:40 volumio2 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 23:52:40 volumio2 volumio[995]: info: Retrieving Cloud Streaming UI Sep 05 23:52:40 volumio2 volumio[995]: info: Getting Tidal Cloud Configuration Sep 05 23:52:40 volumio2 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 23:52:40 volumio2 volumio[995]: info: Getting Qobuz Cloud Configuration Sep 05 23:52:40 volumio2 volumio[995]: info: Asking plugin for UI Config Sep 05 23:52:40 volumio2 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 23:52:40 volumio2 volumio[995]: info: Getting Spotify Cloud Configuration Sep 05 23:52:40 volumio2 volumio[995]: info: Asking plugin for UI Config Sep 05 23:52:40 volumio2 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 23:52:40 volumio2 volumio[995]: info: Saving Spotify Acccount Sep 05 23:52:40 volumio2 volumio[995]: info: Got Tidal Cloud Configuration Sep 05 23:52:40 volumio2 volumio[995]: info: Got it Sep 05 23:52:40 volumio2 volumio[995]: info: Got it Sep 05 23:52:40 volumio2 volumio[995]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 05 23:52:41 volumio2 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 23:52:41 volumio2 volumio[995]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 05 23:52:41 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 05 23:52:41 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 05 23:52:41 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 05 23:52:41 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 05 23:52:41 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 23:52:41 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 23:52:41 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 23:52:41 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 23:52:41 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetBrowseSources Sep 05 23:52:41 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetBrowseSources Sep 05 23:52:41 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetBrowseSources Sep 05 23:52:41 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 23:52:41 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 05 23:52:43 volumio2 volumio[995]: info: Disabling MyMusic plugin upnp Sep 05 23:52:43 volumio2 sudo[13140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 05 23:52:43 volumio2 sudo[13140]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 23:52:43 volumio2 volumio[995]: error: Upnp client error: Error: This socket has been ended by the other party Sep 05 23:52:43 volumio2 systemd[1]: Stopping UPnP Renderer front-end to MPD... Sep 05 23:52:45 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 23:52:47 volumio2 volumio[995]: info: Enabling MyMusic plugin upnp Sep 05 23:52:47 volumio2 volumio[995]: info: Enabling plugin upnp Sep 05 23:52:47 volumio2 volumio[995]: info: Loading plugin "upnp"... Sep 05 23:52:47 volumio2 volumio[995]: info: [1757105567615] Starting Upmpd Daemon Sep 05 23:52:47 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 23:52:47 volumio2 volumio[995]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 23:52:47 volumio2 volumio[995]: Error: listen EADDRINUSE: address already in use :::6599 Sep 05 23:52:47 volumio2 volumio[995]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Sep 05 23:52:47 volumio2 volumio[995]: at listenInCluster (net.js:1366:12) Sep 05 23:52:47 volumio2 volumio[995]: at Server.listen (net.js:1452:7) Sep 05 23:52:47 volumio2 volumio[995]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 05 23:52:47 volumio2 volumio[995]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 05 23:52:47 volumio2 volumio[995]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 05 23:52:47 volumio2 volumio[995]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 05 23:52:47 volumio2 volumio[995]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Sep 05 23:52:47 volumio2 volumio[995]: code: 'EADDRINUSE', Sep 05 23:52:47 volumio2 volumio[995]: errno: -98, Sep 05 23:52:47 volumio2 volumio[995]: syscall: 'listen', Sep 05 23:52:47 volumio2 volumio[995]: address: '::', Sep 05 23:52:47 volumio2 volumio[995]: port: 6599 Sep 05 23:52:47 volumio2 volumio[995]: } Sep 05 23:52:47 volumio2 volumio[995]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 23:52:47 volumio2 sudo[13175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 23:51 Sep 05 23:52:47 volumio2 sudo[13175]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"