-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Tue 2025-03-11 22:32:35 EET. -- Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=trace msg="emitting websocket event: not_playing" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:1geS7W7WU9eXevxLyFPp5f" Mar 11 22:31:24 volumio2 volumio[995]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:1yUkdS0KELJgJsQwVjGZDK","play_origin":"prerelease feature"}} Mar 11 22:31:24 volumio2 volumio[995]: error: Failed to decode event: not_playing Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=trace msg="emitting websocket event: will_play" Mar 11 22:31:24 volumio2 volumio[995]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1geS7W7WU9eXevxLyFPp5f","play_origin":"prerelease feature"}} Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=debug msg="selected format OGG_VORBIS_320 (a65b76c1684bd10e0e7a466a7226b0577328f23f)" uri="spotify:track:1geS7W7WU9eXevxLyFPp5f" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=debug msg="requested aes key for file a65b76c1684bd10e0e7a466a7226b0577328f23f, gid: 1geS7W7WU9eXevxLyFPp5f" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=debug msg="fetched first chunk of 25, total size is 12697815 bytes" uri="spotify:track:1geS7W7WU9eXevxLyFPp5f" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=warning msg="reducing track normalisation factor to prevent clipping, please add negative pregain to avoid" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:1geS7W7WU9eXevxLyFPp5f" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=debug msg="fetched chunk 2/24, size: 524288" uri="spotify:track:1geS7W7WU9eXevxLyFPp5f" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=trace msg="cleared closed output device" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5011 us, period size = 221 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 99 frames" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=debug msg="created new output device" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=info msg="loaded track \"After the Last Sky\" (paused: true, position: 0ms, duration: 342026ms, prefetched: false)" uri="spotify:track:1geS7W7WU9eXevxLyFPp5f" Mar 11 22:31:24 volumio2 kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 5 Mar 11 22:31:24 volumio2 kernel: xhci_hcd 0000:01:00.0: @0000000429518690 00000000 00000000 0e000000 02068000 Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=debug msg="fetched chunk 3/24, size: 524288" uri="spotify:track:1geS7W7WU9eXevxLyFPp5f" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=debug msg="fetched chunk 1/24, size: 524288" uri="spotify:track:1geS7W7WU9eXevxLyFPp5f" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=trace msg="emitting websocket event: metadata" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=trace msg="emitting websocket event: stopped" Mar 11 22:31:24 volumio2 go-librespot[5713]: time="2025-03-11T22:31:24+02:00" level=trace msg="emitting websocket event: stopped" Mar 11 22:31:24 volumio2 volumio[995]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1geS7W7WU9eXevxLyFPp5f","name":"After the Last Sky","artist_names":["Anouar Brahem","Anja Lechner","Django Bates","Dave Holland"],"album_name":"Melancholic Oud","album_cover_url":"https://i.scdn.co/image/ab67616d00001e028073910e547d524b0c17193b","position":0,"duration":342026,"release_date":"year:2025 month:2 day:4","track_number":1,"disc_number":1}} Mar 11 22:31:24 volumio2 volumio[995]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"prerelease feature"}} Mar 11 22:31:24 volumio2 volumio[995]: SPOTIFY: PUSH STATE SPOTIFY Mar 11 22:31:24 volumio2 volumio[995]: SPOTIFY: {"status":"stop","service":"spop","title":"After the Last Sky","artist":"Anouar Brahem, Anja Lechner, Django Bates, Dave Holland","album":"Melancholic Oud","albumart":"https://i.scdn.co/image/ab67616d00001e028073910e547d524b0c17193b","uri":"spotify:track:1geS7W7WU9eXevxLyFPp5f","trackType":"spotify","seek":0,"duration":342,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Mar 11 22:31:24 volumio2 volumio[995]: info: CoreCommandRouter::servicePushState Mar 11 22:31:24 volumio2 volumio[995]: info: CoreStateMachine::pushState Mar 11 22:31:24 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:31:24 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushState Mar 11 22:31:24 volumio2 volumio[995]: info: CorePlayQueue::getTrack 0 Mar 11 22:31:24 volumio2 volumio[995]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"After the Last Sky","artist":"Anouar Brahem, Anja Lechner, Django Bates, Dave Holland","album":"Melancholic Oud","albumart":"https://i.scdn.co/image/ab67616d00001e028073910e547d524b0c17193b","uri":"spotify:track:1geS7W7WU9eXevxLyFPp5f","trackType":"spotify","seek":0,"duration":342,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Mar 11 22:31:24 volumio2 volumio[995]: verbose: CURRENT POSITION 0 Mar 11 22:31:24 volumio2 volumio[995]: info: CoreStateMachine::syncState stateService stop Mar 11 22:31:24 volumio2 volumio[995]: info: CoreStateMachine::syncState currentStatus play Mar 11 22:31:24 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushState Mar 11 22:31:24 volumio2 volumio[995]: info: CoreStateMachine::stPlaybackTimer Mar 11 22:31:24 volumio2 volumio[995]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"prerelease feature"}} Mar 11 22:31:24 volumio2 volumio[995]: SPOTIFY: PUSH STATE SPOTIFY Mar 11 22:31:24 volumio2 volumio[995]: SPOTIFY: {"status":"stop","service":"spop","title":"After the Last Sky","artist":"Anouar Brahem, Anja Lechner, Django Bates, Dave Holland","album":"Melancholic Oud","albumart":"https://i.scdn.co/image/ab67616d00001e028073910e547d524b0c17193b","uri":"spotify:track:1geS7W7WU9eXevxLyFPp5f","trackType":"spotify","seek":0,"duration":342,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Mar 11 22:31:24 volumio2 volumio[995]: info: CoreCommandRouter::servicePushState Mar 11 22:31:24 volumio2 volumio[995]: info: CoreStateMachine::pushState Mar 11 22:31:24 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:31:24 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushState Mar 11 22:31:25 volumio2 volumio[995]: info: CorePlayQueue::getTrack 0 Mar 11 22:31:25 volumio2 volumio[995]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"After the Last Sky","artist":"Anouar Brahem, Anja Lechner, Django Bates, Dave Holland","album":"Melancholic Oud","albumart":"https://i.scdn.co/image/ab67616d00001e028073910e547d524b0c17193b","uri":"spotify:track:1geS7W7WU9eXevxLyFPp5f","trackType":"spotify","seek":0,"duration":342,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Mar 11 22:31:25 volumio2 volumio[995]: verbose: CURRENT POSITION 0 Mar 11 22:31:25 volumio2 volumio[995]: info: CoreStateMachine::syncState stateService stop Mar 11 22:31:25 volumio2 volumio[995]: info: CoreStateMachine::syncState currentStatus stop Mar 11 22:31:25 volumio2 volumio[995]: info: CoreStateMachine::pushState Mar 11 22:31:25 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushState Mar 11 22:31:25 volumio2 volumio[995]: info: No code Mar 11 22:31:25 volumio2 volumio[995]: info: CoreStateMachine::pushState Mar 11 22:31:25 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushState Mar 11 22:31:25 volumio2 go-librespot[5713]: time="2025-03-11T22:31:25+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 11 22:31:25 volumio2 go-librespot[5713]: time="2025-03-11T22:31:25+02:00" level=trace msg="emitting websocket event: paused" Mar 11 22:31:25 volumio2 volumio[995]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1geS7W7WU9eXevxLyFPp5f","play_origin":"prerelease feature"}} Mar 11 22:31:25 volumio2 volumio[995]: SPOTIFY: PUSH STATE SPOTIFY Mar 11 22:31:25 volumio2 volumio[995]: SPOTIFY: {"status":"pause","service":"spop","title":"After the Last Sky","artist":"Anouar Brahem, Anja Lechner, Django Bates, Dave Holland","album":"Melancholic Oud","albumart":"https://i.scdn.co/image/ab67616d00001e028073910e547d524b0c17193b","uri":"spotify:track:1geS7W7WU9eXevxLyFPp5f","trackType":"spotify","seek":0,"duration":342,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Mar 11 22:31:25 volumio2 volumio[995]: info: CoreCommandRouter::servicePushState Mar 11 22:31:25 volumio2 volumio[995]: info: CoreStateMachine::pushState Mar 11 22:31:25 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:31:25 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushState Mar 11 22:31:54 volumio2 volumio[995]: info: Clearing queue after UPNP request Mar 11 22:31:54 volumio2 volumio[995]: info: CoreStateMachine::ClearQueue Mar 11 22:31:54 volumio2 volumio[995]: info: CoreStateMachine::stop Mar 11 22:31:54 volumio2 volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 22:31:54 volumio2 volumio[995]: info: CoreStateMachine::updateTrackBlock Mar 11 22:31:54 volumio2 volumio[995]: info: CorePlayQueue::getTrackBlock Mar 11 22:31:54 volumio2 volumio[995]: info: CoreStateMachine::stPlaybackTimer Mar 11 22:31:54 volumio2 volumio[995]: info: CoreStateMachine::pushState Mar 11 22:31:54 volumio2 volumio[995]: info: CorePlayQueue::getTrack 0 Mar 11 22:31:54 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:31:54 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushState Mar 11 22:31:54 volumio2 volumio[995]: info: CoreStateMachine::serviceStop Mar 11 22:31:54 volumio2 volumio[995]: info: CorePlayQueue::getTrack 0 Mar 11 22:31:54 volumio2 volumio[995]: info: ControllerMpd::stop Mar 11 22:31:54 volumio2 volumio[995]: verbose: ControllerMpd::sendMpdCommand stop Mar 11 22:31:54 volumio2 volumio[995]: info: CorePlayQueue::clearPlayQueue Mar 11 22:31:54 volumio2 volumio[995]: info: CorePlayQueue::saveQueue Mar 11 22:31:54 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushState Mar 11 22:31:54 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushQueue Mar 11 22:31:54 volumio2 volumio[995]: error: Upnp client error: Error: This socket has been ended by the other party Mar 11 22:31:54 volumio2 volumio[995]: info: sendMpdCommand stop took 13 milliseconds Mar 11 22:31:56 volumio2 volumio[995]: info: Starting UPNP Playback Mar 11 22:31:56 volumio2 volumio[995]: info: Preparing playback through UPNP Mar 11 22:31:56 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Mar 11 22:31:56 volumio2 volumio[995]: info: CorePlayQueue::getTrack 0 Mar 11 22:31:56 volumio2 volumio[995]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 11 22:31:57 volumio2 volumio[995]: info: Mar 11 22:31:57 volumio2 volumio[995]: ---------------------------- MPD announces system playlist update Mar 11 22:31:57 volumio2 volumio[995]: info: Ignoring MPD Status Update Mar 11 22:31:57 volumio2 volumio[995]: info: Mar 11 22:31:57 volumio2 volumio[995]: ---------------------------- MPD announces system playlist update Mar 11 22:31:57 volumio2 volumio[995]: info: Ignoring MPD Status Update Mar 11 22:31:57 volumio2 volumio[995]: info: ------------------------------ 3ms Mar 11 22:31:57 volumio2 volumio[995]: info: ------------------------------ 2ms Mar 11 22:31:57 volumio2 volumio[995]: info: Mar 11 22:31:57 volumio2 volumio[995]: ---------------------------- MPD announces system playlist update Mar 11 22:31:57 volumio2 volumio[995]: info: Ignoring MPD Status Update Mar 11 22:31:57 volumio2 volumio[995]: info: Mar 11 22:31:57 volumio2 volumio[995]: ---------------------------- MPD announces system playlist update Mar 11 22:31:57 volumio2 volumio[995]: info: Ignoring MPD Status Update Mar 11 22:31:57 volumio2 volumio[995]: info: ------------------------------ 2ms Mar 11 22:31:57 volumio2 volumio[995]: info: ------------------------------ 2ms Mar 11 22:31:57 volumio2 volumio[995]: info: Mar 11 22:31:57 volumio2 volumio[995]: ---------------------------- MPD announces system playlist update Mar 11 22:31:57 volumio2 volumio[995]: info: Ignoring MPD Status Update Mar 11 22:31:57 volumio2 volumio[995]: info: ------------------------------ 1ms Mar 11 22:31:57 volumio2 volumio[995]: info: Mar 11 22:31:57 volumio2 volumio[995]: ---------------------------- MPD announces system playlist update Mar 11 22:31:57 volumio2 volumio[995]: info: Ignoring MPD Status Update Mar 11 22:31:57 volumio2 volumio[995]: info: ------------------------------ 1ms Mar 11 22:31:58 volumio2 volumio[995]: info: Mar 11 22:31:58 volumio2 volumio[995]: ---------------------------- MPD announces state update: player Mar 11 22:31:58 volumio2 volumio[995]: info: ControllerMpd::getState Mar 11 22:31:58 volumio2 volumio[995]: verbose: ControllerMpd::sendMpdCommand status Mar 11 22:31:58 volumio2 volumio[995]: info: Mar 11 22:31:58 volumio2 volumio[995]: ---------------------------- MPD announces state update: player Mar 11 22:31:58 volumio2 volumio[995]: info: sendMpdCommand status took 4 milliseconds Mar 11 22:31:58 volumio2 volumio[995]: info: ControllerMpd::getState Mar 11 22:31:58 volumio2 volumio[995]: verbose: ControllerMpd::sendMpdCommand status Mar 11 22:31:58 volumio2 volumio[995]: verbose: ControllerMpd::parseState Mar 11 22:31:58 volumio2 volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 22:31:58 volumio2 volumio[995]: info: sendMpdCommand status took 5 milliseconds Mar 11 22:31:58 volumio2 volumio[995]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 11 22:31:58 volumio2 volumio[995]: verbose: ControllerMpd::parseState Mar 11 22:31:58 volumio2 volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 22:31:58 volumio2 volumio[995]: verbose: ControllerMpd::parseTrackInfo Mar 11 22:31:58 volumio2 volumio[995]: info: ControllerMpd::pushState Mar 11 22:31:58 volumio2 volumio[995]: info: CoreCommandRouter::servicePushState Mar 11 22:31:58 volumio2 volumio[995]: verbose: In UPNP mode Mar 11 22:31:58 volumio2 volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":342,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"After the Last Sky","artist":"Anouar Brahem","album":"Melancholic Oud","uri":"http://192.168.0.103:18364/tidal/415508925.flac","trackType":"tidal"} Mar 11 22:31:58 volumio2 volumio[995]: verbose: CURRENT POSITION 0 Mar 11 22:31:58 volumio2 volumio[995]: info: CoreStateMachine::syncState stateService play Mar 11 22:31:58 volumio2 volumio[995]: info: CoreStateMachine::syncState currentStatus stop Mar 11 22:31:58 volumio2 volumio[995]: info: ------------------------------ 16ms Mar 11 22:31:58 volumio2 volumio[995]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 11 22:31:58 volumio2 volumio[995]: verbose: ControllerMpd::parseTrackInfo Mar 11 22:31:58 volumio2 volumio[995]: info: ControllerMpd::pushState Mar 11 22:31:58 volumio2 volumio[995]: info: CoreCommandRouter::servicePushState Mar 11 22:31:58 volumio2 volumio[995]: verbose: In UPNP mode Mar 11 22:31:58 volumio2 volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":342,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"After the Last Sky","artist":"Anouar Brahem","album":"Melancholic Oud","uri":"http://192.168.0.103:18364/tidal/415508925.flac","trackType":"tidal"} Mar 11 22:31:58 volumio2 volumio[995]: verbose: CURRENT POSITION 0 Mar 11 22:31:58 volumio2 volumio[995]: info: CoreStateMachine::syncState stateService play Mar 11 22:31:58 volumio2 volumio[995]: info: CoreStateMachine::syncState currentStatus play Mar 11 22:31:58 volumio2 volumio[995]: info: Received an update from plugin. extracting info from payload Mar 11 22:31:58 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 11 22:31:58 volumio2 volumio[995]: info: CoreStateMachine::pushState Mar 11 22:31:58 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:31:58 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushState Mar 11 22:31:58 volumio2 volumio[995]: info: CoreStateMachine::pushState Mar 11 22:31:58 volumio2 volumio[995]: info: CoreCommandRouter::volumioPushState Mar 11 22:31:58 volumio2 volumio[995]: info: ------------------------------ 26ms Mar 11 22:31:58 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetQueue Mar 11 22:31:58 volumio2 volumio[995]: info: CoreStateMachine::getQueue Mar 11 22:31:58 volumio2 volumio[995]: info: CorePlayQueue::getQueue Mar 11 22:31:58 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetQueue Mar 11 22:31:58 volumio2 volumio[995]: info: CoreStateMachine::getQueue Mar 11 22:31:58 volumio2 volumio[995]: info: CorePlayQueue::getQueue Mar 11 22:32:14 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 22:32:14 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 22:32:14 volumio2 volumio[995]: info: Discovery: Getting this device information Mar 11 22:32:14 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Mar 11 22:32:14 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 22:32:14 volumio2 volumio[995]: verbose: New Socket.io Connection to 192.168.0.119:3000 from 192.168.0.103 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Mar 11 22:32:14 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Mar 11 22:32:14 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 11 22:32:14 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 11 22:32:19 volumio2 sudo[7236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 11 22:32:19 volumio2 sudo[7236]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:32:19 volumio2 sudo[7236]: pam_unix(sudo:session): session closed for user root Mar 11 22:32:19 volumio2 sudo[7239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:32:19 volumio2 sudo[7239]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:32:19 volumio2 sudo[7239]: pam_unix(sudo:session): session closed for user root Mar 11 22:32:19 volumio2 volumio[995]: verbose: New Socket.io Connection to 192.168.0.119 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/133.0.6943.137 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 11 22:32:19 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetVisibleSources Mar 11 22:32:19 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 22:32:19 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 11 22:32:19 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Mar 11 22:32:19 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 11 22:32:19 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 11 22:32:19 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 11 22:32:19 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 11 22:32:19 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 22:32:19 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 22:32:19 volumio2 volumio[995]: info: Discovery: Getting this device information Mar 11 22:32:19 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Mar 11 22:32:19 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 22:32:19 volumio2 sudo[7245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 11 22:32:19 volumio2 sudo[7245]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:32:19 volumio2 sudo[7245]: pam_unix(sudo:session): session closed for user root Mar 11 22:32:19 volumio2 sudo[7248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:32:19 volumio2 sudo[7248]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:32:19 volumio2 sudo[7248]: pam_unix(sudo:session): session closed for user root Mar 11 22:32:19 volumio2 volumio[995]: verbose: New Socket.io Connection to 192.168.0.119 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/133.0.6943.137 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 11 22:32:20 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Mar 11 22:32:20 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 11 22:32:20 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetVisibleSources Mar 11 22:32:20 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 22:32:20 volumio2 volumio[995]: info: Listing playlists Mar 11 22:32:20 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 11 22:32:20 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 11 22:32:20 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 11 22:32:20 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 11 22:32:22 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 11 22:32:22 volumio2 volumio[995]: info: Received Get System Info Mar 11 22:32:22 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 22:32:22 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 22:32:22 volumio2 volumio[995]: info: Discovery: Getting this device information Mar 11 22:32:22 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Mar 11 22:32:22 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 22:32:23 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 11 22:32:23 volumio2 volumio[995]: info: Received Get System Info Mar 11 22:32:23 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 22:32:23 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 22:32:23 volumio2 volumio[995]: info: Discovery: Getting this device information Mar 11 22:32:23 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetState Mar 11 22:32:23 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 22:32:26 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetQueue Mar 11 22:32:26 volumio2 volumio[995]: info: CoreStateMachine::getQueue Mar 11 22:32:26 volumio2 volumio[995]: info: CorePlayQueue::getQueue Mar 11 22:32:29 volumio2 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 22:32:29 volumio2 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 22:32:29 volumio2 volumio[995]: info: Retrieving Cloud Streaming UI Mar 11 22:32:29 volumio2 volumio[995]: info: Getting Tidal Cloud Configuration Mar 11 22:32:29 volumio2 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 22:32:29 volumio2 volumio[995]: info: Getting Qobuz Cloud Configuration Mar 11 22:32:29 volumio2 volumio[995]: info: Asking plugin for UI Config Mar 11 22:32:29 volumio2 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 22:32:29 volumio2 volumio[995]: info: Getting Spotify Cloud Configuration Mar 11 22:32:29 volumio2 volumio[995]: info: Asking plugin for UI Config Mar 11 22:32:29 volumio2 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 22:32:29 volumio2 volumio[995]: info: Saving Spotify Acccount Mar 11 22:32:29 volumio2 volumio[995]: info: Got Tidal Cloud Configuration Mar 11 22:32:29 volumio2 volumio[995]: info: Got it Mar 11 22:32:29 volumio2 volumio[995]: info: Got it Mar 11 22:32:29 volumio2 volumio[995]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 11 22:32:29 volumio2 volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 22:32:29 volumio2 volumio[995]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 11 22:32:30 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 11 22:32:30 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 11 22:32:30 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 11 22:32:30 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 11 22:32:30 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:32:30 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:32:30 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:32:30 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:32:30 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetBrowseSources Mar 11 22:32:30 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetBrowseSources Mar 11 22:32:30 volumio2 volumio[995]: info: CoreCommandRouter::volumioGetBrowseSources Mar 11 22:32:30 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 22:32:30 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 11 22:32:31 volumio2 volumio[995]: info: Disabling MyMusic plugin upnp Mar 11 22:32:31 volumio2 sudo[7292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Mar 11 22:32:31 volumio2 sudo[7292]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:32:31 volumio2 volumio[995]: error: Upnp client error: Error: This socket has been ended by the other party Mar 11 22:32:31 volumio2 systemd[1]: Stopping UPnP Renderer front-end to MPD... Mar 11 22:32:34 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 22:32:35 volumio2 volumio[995]: info: Enabling MyMusic plugin upnp Mar 11 22:32:35 volumio2 volumio[995]: info: Enabling plugin upnp Mar 11 22:32:35 volumio2 volumio[995]: info: Loading plugin "upnp"... Mar 11 22:32:35 volumio2 volumio[995]: info: [1741725155101] Starting Upmpd Daemon Mar 11 22:32:35 volumio2 volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 11 22:32:35 volumio2 volumio[995]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 22:32:35 volumio2 volumio[995]: Error: listen EADDRINUSE: address already in use :::6599 Mar 11 22:32:35 volumio2 volumio[995]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Mar 11 22:32:35 volumio2 volumio[995]: at listenInCluster (net.js:1366:12) Mar 11 22:32:35 volumio2 volumio[995]: at Server.listen (net.js:1452:7) Mar 11 22:32:35 volumio2 volumio[995]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Mar 11 22:32:35 volumio2 volumio[995]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Mar 11 22:32:35 volumio2 volumio[995]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 11 22:32:35 volumio2 volumio[995]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 11 22:32:35 volumio2 volumio[995]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Mar 11 22:32:35 volumio2 volumio[995]: code: 'EADDRINUSE', Mar 11 22:32:35 volumio2 volumio[995]: errno: -98, Mar 11 22:32:35 volumio2 volumio[995]: syscall: 'listen', Mar 11 22:32:35 volumio2 volumio[995]: address: '::', Mar 11 22:32:35 volumio2 volumio[995]: port: 6599 Mar 11 22:32:35 volumio2 volumio[995]: } Mar 11 22:32:35 volumio2 volumio[995]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 22:32:35 volumio2 sudo[7324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-11 22:31 Mar 11 22:32:35 volumio2 sudo[7324]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"