-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2024-09-01 22:11:39 CEST. -- Sep 01 22:10:01 shd-studio volumio[654]: info: Sep 01 22:10:01 shd-studio volumio[654]: ---------------------------- MPD announces state update: player Sep 01 22:10:01 shd-studio volumio[654]: info: ControllerMpd::getState Sep 01 22:10:01 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 01 22:10:01 shd-studio volumio[654]: info: sendMpdCommand status took 2 milliseconds Sep 01 22:10:01 shd-studio volumio[654]: verbose: ControllerMpd::parseState Sep 01 22:10:01 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 22:10:01 shd-studio volumio[654]: info: Sep 01 22:10:01 shd-studio volumio[654]: ---------------------------- MPD announces state update: player Sep 01 22:10:01 shd-studio volumio[654]: info: ControllerMpd::getState Sep 01 22:10:01 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 01 22:10:01 shd-studio volumio[654]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 01 22:10:01 shd-studio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 01 22:10:01 shd-studio volumio[654]: info: ControllerMpd::pushState Sep 01 22:10:01 shd-studio volumio[654]: info: CoreCommandRouter::servicePushState Sep 01 22:10:01 shd-studio volumio[654]: verbose: In UPNP mode Sep 01 22:10:01 shd-studio volumio[654]: verbose: STATE SERVICE {"status":"play","position":3,"seek":0,"duration":380,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Roof Garden","artist":"Al Jarreau","album":"Breakin' Away","uri":"http://192.168.178.52:57645/proxy/tidal/30FDB4610A42188E2296CEF43834E383.flac","trackType":"tidal"} Sep 01 22:10:01 shd-studio volumio[654]: verbose: CURRENT POSITION 1 Sep 01 22:10:01 shd-studio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 01 22:10:01 shd-studio volumio[654]: info: CoreStateMachine::syncState currentStatus stop Sep 01 22:10:01 shd-studio volumio[654]: info: ------------------------------ 14ms Sep 01 22:10:01 shd-studio volumio[654]: info: sendMpdCommand status took 7 milliseconds Sep 01 22:10:01 shd-studio volumio[654]: verbose: ControllerMpd::parseState Sep 01 22:10:01 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 22:10:01 shd-studio volumio[654]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 22:10:01 shd-studio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 01 22:10:01 shd-studio volumio[654]: info: ControllerMpd::pushState Sep 01 22:10:01 shd-studio volumio[654]: info: CoreCommandRouter::servicePushState Sep 01 22:10:01 shd-studio volumio[654]: verbose: In UPNP mode Sep 01 22:10:01 shd-studio volumio[654]: verbose: STATE SERVICE {"status":"play","position":3,"seek":0,"duration":380,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Roof Garden","artist":"Al Jarreau","album":"Breakin' Away","uri":"http://192.168.178.52:57645/proxy/tidal/30FDB4610A42188E2296CEF43834E383.flac","trackType":"tidal"} Sep 01 22:10:01 shd-studio volumio[654]: verbose: CURRENT POSITION 1 Sep 01 22:10:01 shd-studio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 01 22:10:01 shd-studio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 01 22:10:01 shd-studio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 01 22:10:01 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 22:10:01 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:01 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 22:10:01 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:01 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:01 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:01 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:01 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:01 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:01 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:01 shd-studio volumio[654]: info: ------------------------------ 55ms Sep 01 22:10:01 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetQueue Sep 01 22:10:01 shd-studio volumio[654]: info: CoreStateMachine::getQueue Sep 01 22:10:01 shd-studio volumio[654]: info: CorePlayQueue::getQueue Sep 01 22:10:01 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:01 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetQueue Sep 01 22:10:01 shd-studio volumio[654]: info: CoreStateMachine::getQueue Sep 01 22:10:01 shd-studio volumio[654]: info: CorePlayQueue::getQueue Sep 01 22:10:01 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:08 shd-studio volumio[654]: info: Sep 01 22:10:08 shd-studio volumio[654]: ---------------------------- MPD announces state update: player Sep 01 22:10:08 shd-studio volumio[654]: info: ControllerMpd::getState Sep 01 22:10:08 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 01 22:10:08 shd-studio volumio[654]: info: Sep 01 22:10:08 shd-studio volumio[654]: ---------------------------- MPD announces state update: player Sep 01 22:10:08 shd-studio volumio[654]: info: ControllerMpd::getState Sep 01 22:10:08 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 01 22:10:08 shd-studio volumio[654]: info: sendMpdCommand status took 3 milliseconds Sep 01 22:10:08 shd-studio volumio[654]: verbose: ControllerMpd::parseState Sep 01 22:10:08 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 22:10:08 shd-studio volumio[654]: info: sendMpdCommand status took 7 milliseconds Sep 01 22:10:08 shd-studio volumio[654]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 01 22:10:08 shd-studio volumio[654]: verbose: ControllerMpd::parseState Sep 01 22:10:08 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 22:10:08 shd-studio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 01 22:10:08 shd-studio volumio[654]: info: ControllerMpd::pushState Sep 01 22:10:08 shd-studio volumio[654]: info: CoreCommandRouter::servicePushState Sep 01 22:10:08 shd-studio volumio[654]: verbose: In UPNP mode Sep 01 22:10:08 shd-studio volumio[654]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Easy On Me","artist":"Adele","album":"Easy On Me","uri":"http://192.168.178.52:57645/proxy/tidal/AB9FC6A5B815537A4BF8B5081E8551C7.flac","trackType":"tidal"} Sep 01 22:10:08 shd-studio volumio[654]: verbose: CURRENT POSITION 1 Sep 01 22:10:08 shd-studio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 01 22:10:08 shd-studio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 01 22:10:08 shd-studio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 01 22:10:08 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 22:10:08 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:08 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 22:10:08 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:08 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:08 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:08 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:08 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:08 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:08 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:08 shd-studio volumio[654]: info: ------------------------------ 64ms Sep 01 22:10:08 shd-studio volumio[654]: info: sendMpdCommand playlistinfo took 52 milliseconds Sep 01 22:10:08 shd-studio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 01 22:10:08 shd-studio volumio[654]: info: ControllerMpd::pushState Sep 01 22:10:08 shd-studio volumio[654]: info: CoreCommandRouter::servicePushState Sep 01 22:10:08 shd-studio volumio[654]: verbose: In UPNP mode Sep 01 22:10:08 shd-studio volumio[654]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Easy On Me","artist":"Adele","album":"Easy On Me","uri":"http://192.168.178.52:57645/proxy/tidal/AB9FC6A5B815537A4BF8B5081E8551C7.flac","trackType":"tidal"} Sep 01 22:10:08 shd-studio volumio[654]: verbose: CURRENT POSITION 1 Sep 01 22:10:08 shd-studio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 01 22:10:08 shd-studio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 01 22:10:08 shd-studio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 01 22:10:08 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 22:10:08 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:08 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:08 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:08 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:08 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:08 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:08 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:08 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:08 shd-studio volumio[654]: info: ------------------------------ 87ms Sep 01 22:10:08 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetQueue Sep 01 22:10:08 shd-studio volumio[654]: info: CoreStateMachine::getQueue Sep 01 22:10:08 shd-studio volumio[654]: info: CorePlayQueue::getQueue Sep 01 22:10:08 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:08 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetQueue Sep 01 22:10:08 shd-studio volumio[654]: info: CoreStateMachine::getQueue Sep 01 22:10:08 shd-studio volumio[654]: info: CorePlayQueue::getQueue Sep 01 22:10:08 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetQueue Sep 01 22:10:08 shd-studio volumio[654]: info: CoreStateMachine::getQueue Sep 01 22:10:08 shd-studio volumio[654]: info: CorePlayQueue::getQueue Sep 01 22:10:08 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetQueue Sep 01 22:10:08 shd-studio volumio[654]: info: CoreStateMachine::getQueue Sep 01 22:10:08 shd-studio volumio[654]: info: CorePlayQueue::getQueue Sep 01 22:10:08 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:08 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:08 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:15 shd-studio go-librespot[2102]: time="2024-09-01T22:10:15+02:00" level=error msg="did not receive last pong from dealer, 30s passed" Sep 01 22:10:15 shd-studio go-librespot[2102]: time="2024-09-01T22:10:15+02:00" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusServiceRestart and reason = \"\"" Sep 01 22:10:16 shd-studio go-librespot[2102]: time="2024-09-01T22:10:16+02:00" level=debug msg="re-established dealer connection" Sep 01 22:10:16 shd-studio go-librespot[2102]: time="2024-09-01T22:10:16+02:00" level=debug msg="received connection id: Nzc5YjM5MTgtMGM5ZS00NTdkLWE2YTItZGEwMDY5ZjE4NDgwK2RlYWxlcit0Y3A6Ly8wYWNiZjkxMi5pcC5ndWUxLnNwb3RpZnkubmV0OjU3MDArNzYyMUZBQ0MzNkE5OTFBMDk4MzFDQzMyRDQ4QUVBNzc1MjJENEFGMzA2QUZFQjU1MUM0MEIzRDU3NTdEMUU3OQ==" Sep 01 22:10:17 shd-studio go-librespot[2102]: time="2024-09-01T22:10:17+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 01 22:10:19 shd-studio sudo[2380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 22:10:19 shd-studio sudo[2380]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 22:10:19 shd-studio sudo[2380]: pam_unix(sudo:session): session closed for user root Sep 01 22:10:19 shd-studio sudo[2383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 22:10:19 shd-studio sudo[2383]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 22:10:19 shd-studio sudo[2383]: pam_unix(sudo:session): session closed for user root Sep 01 22:10:19 shd-studio volumio[654]: verbose: New Socket.io Connection to 192.168.178.55 from 192.168.178.52 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Mobile Safari/537.36 Total Clients: 6 Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetState Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Sep 01 22:10:19 shd-studio volumio[654]: info: Received Get System Info Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 22:10:19 shd-studio volumio[654]: info: Discovery: Getting this device information Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetState Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetState Sep 01 22:10:19 shd-studio volumio[654]: info: Listing playlists Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 01 22:10:19 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 22:10:20 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 01 22:10:21 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 22:10:21 shd-studio volumio[654]: info: Received Get System Info Sep 01 22:10:21 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 22:10:21 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 22:10:21 shd-studio volumio[654]: info: Discovery: Getting this device information Sep 01 22:10:21 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetState Sep 01 22:10:21 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 22:10:21 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 01 22:10:23 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 22:10:23 shd-studio volumio[654]: info: Received Get System Info Sep 01 22:10:23 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 22:10:23 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 22:10:23 shd-studio volumio[654]: info: Discovery: Getting this device information Sep 01 22:10:23 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetState Sep 01 22:10:23 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 22:10:25 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 22:10:25 shd-studio volumio[654]: info: Received Get System Info Sep 01 22:10:25 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 22:10:25 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 22:10:25 shd-studio volumio[654]: info: Discovery: Getting this device information Sep 01 22:10:25 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetState Sep 01 22:10:25 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 22:10:26 shd-studio volumio[654]: info: CoreCommandRouter::volumioPause Sep 01 22:10:26 shd-studio volumio[654]: info: CoreStateMachine::pause Sep 01 22:10:26 shd-studio volumio[654]: info: CoreStateMachine::stPlaybackTimer Sep 01 22:10:26 shd-studio volumio[654]: info: CoreStateMachine::servicePause Sep 01 22:10:26 shd-studio volumio[654]: info: CorePlayQueue::getTrack 1 Sep 01 22:10:26 shd-studio volumio[654]: info: CoreCommandRouter::servicePause Sep 01 22:10:26 shd-studio volumio[654]: info: [1725221426007] ControllerTidal::pause Sep 01 22:10:26 shd-studio volumio[654]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 22:10:26 shd-studio volumio[654]: info: ControllerMpd::pause Sep 01 22:10:26 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand pause Sep 01 22:10:26 shd-studio volumio[654]: info: Sep 01 22:10:26 shd-studio volumio[654]: ---------------------------- MPD announces state update: player Sep 01 22:10:26 shd-studio volumio[654]: info: sendMpdCommand pause took 3 milliseconds Sep 01 22:10:26 shd-studio volumio[654]: info: ControllerMpd::getState Sep 01 22:10:26 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 01 22:10:26 shd-studio volumio[654]: info: sendMpdCommand status took 1 milliseconds Sep 01 22:10:26 shd-studio volumio[654]: verbose: ControllerMpd::parseState Sep 01 22:10:26 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 22:10:26 shd-studio volumio[654]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 22:10:26 shd-studio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 01 22:10:26 shd-studio volumio[654]: info: ControllerMpd::pushState Sep 01 22:10:26 shd-studio volumio[654]: info: CoreCommandRouter::servicePushState Sep 01 22:10:26 shd-studio volumio[654]: info: CorePlayQueue::getTrack 1 Sep 01 22:10:26 shd-studio volumio[654]: verbose: STATE SERVICE {"status":"pause","position":2,"seek":18811,"duration":225,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1349 Kbps","isStreaming":false,"title":"Easy On Me","artist":"Adele","album":"Easy On Me","uri":"http://192.168.178.52:57645/proxy/tidal/AB9FC6A5B815537A4BF8B5081E8551C7.flac","trackType":"tidal"} Sep 01 22:10:26 shd-studio volumio[654]: verbose: CURRENT POSITION 1 Sep 01 22:10:26 shd-studio volumio[654]: info: CoreStateMachine::syncState stateService pause Sep 01 22:10:26 shd-studio volumio[654]: info: CoreStateMachine::syncState currentStatus pause Sep 01 22:10:26 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:26 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 22:10:26 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:26 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:26 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:26 shd-studio volumio[654]: info: CoreStateMachine::stPlaybackTimer Sep 01 22:10:26 shd-studio volumio[654]: info: ------------------------------ 36ms Sep 01 22:10:26 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetQueue Sep 01 22:10:26 shd-studio volumio[654]: info: CoreStateMachine::getQueue Sep 01 22:10:26 shd-studio volumio[654]: info: CorePlayQueue::getQueue Sep 01 22:10:26 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:26 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 22:10:26 shd-studio volumio[654]: info: Received Get System Info Sep 01 22:10:26 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 22:10:26 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 22:10:26 shd-studio volumio[654]: info: Discovery: Getting this device information Sep 01 22:10:26 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetState Sep 01 22:10:26 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 22:10:27 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetQueue Sep 01 22:10:27 shd-studio volumio[654]: info: CoreStateMachine::getQueue Sep 01 22:10:27 shd-studio volumio[654]: info: CorePlayQueue::getQueue Sep 01 22:10:28 shd-studio volumio[654]: info: CoreCommandRouter::volumioPlay Sep 01 22:10:28 shd-studio volumio[654]: info: CoreStateMachine::play index 2 Sep 01 22:10:28 shd-studio volumio[654]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 22:10:28 shd-studio volumio[654]: info: CoreStateMachine::stop Sep 01 22:10:28 shd-studio volumio[654]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 22:10:28 shd-studio volumio[654]: info: CoreStateMachine::updateTrackBlock Sep 01 22:10:28 shd-studio volumio[654]: info: CorePlayQueue::getTrackBlock Sep 01 22:10:28 shd-studio volumio[654]: info: CoreStateMachine::stPlaybackTimer Sep 01 22:10:28 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:28 shd-studio volumio[654]: info: CorePlayQueue::getTrack 1 Sep 01 22:10:28 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 22:10:28 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:28 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:28 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:28 shd-studio volumio[654]: info: CoreStateMachine::serviceStop Sep 01 22:10:28 shd-studio volumio[654]: info: CorePlayQueue::getTrack 1 Sep 01 22:10:28 shd-studio volumio[654]: info: CoreCommandRouter::serviceStop Sep 01 22:10:28 shd-studio volumio[654]: info: [1725221428784] ControllerTidal::stop Sep 01 22:10:28 shd-studio volumio[654]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 22:10:28 shd-studio volumio[654]: info: ControllerMpd::stop Sep 01 22:10:28 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 22:10:28 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:28 shd-studio volumio[654]: info: Sep 01 22:10:28 shd-studio volumio[654]: ---------------------------- MPD announces state update: player Sep 01 22:10:28 shd-studio volumio[654]: info: sendMpdCommand stop took 22 milliseconds Sep 01 22:10:28 shd-studio volumio[654]: info: ControllerMpd::getState Sep 01 22:10:28 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 01 22:10:28 shd-studio volumio[654]: info: CoreStateMachine::play index undefined Sep 01 22:10:28 shd-studio volumio[654]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 22:10:28 shd-studio volumio[654]: info: CorePlayQueue::getTrack 2 Sep 01 22:10:28 shd-studio volumio[654]: info: CoreStateMachine::startPlaybackTimer Sep 01 22:10:28 shd-studio volumio[654]: info: CorePlayQueue::getTrack 2 Sep 01 22:10:28 shd-studio volumio[654]: info: [1725221428809] ControllerTidal::clearAddPlayTrack Sep 01 22:10:28 shd-studio volumio[654]: info: Getting stream with soundQuality HI_RES Sep 01 22:10:28 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 22:10:28 shd-studio volumio[654]: info: sendMpdCommand status took 11 milliseconds Sep 01 22:10:28 shd-studio volumio[654]: info: sendMpdCommand stop took 9 milliseconds Sep 01 22:10:28 shd-studio volumio[654]: verbose: ControllerMpd::parseState Sep 01 22:10:28 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 22:10:28 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 22:10:28 shd-studio volumio[654]: info: Sep 01 22:10:28 shd-studio volumio[654]: ---------------------------- MPD announces system playlist update Sep 01 22:10:28 shd-studio volumio[654]: info: Ignoring MPD Status Update Sep 01 22:10:28 shd-studio volumio[654]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 01 22:10:28 shd-studio volumio[654]: info: sendMpdCommand clear took 3 milliseconds Sep 01 22:10:28 shd-studio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 01 22:10:28 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/66288723&soundQuality=HI_RES" Sep 01 22:10:28 shd-studio volumio[654]: info: ControllerMpd::pushState Sep 01 22:10:28 shd-studio volumio[654]: info: CoreCommandRouter::servicePushState Sep 01 22:10:28 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:28 shd-studio volumio[654]: info: CorePlayQueue::getTrack 2 Sep 01 22:10:28 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 22:10:28 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:28 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:28 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:28 shd-studio volumio[654]: info: CorePlayQueue::getTrack 2 Sep 01 22:10:28 shd-studio volumio[654]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Sep 01 22:10:28 shd-studio volumio[654]: info: ------------------------------ 47ms Sep 01 22:10:28 shd-studio volumio[654]: error: updateQueue error: null Sep 01 22:10:28 shd-studio volumio[654]: info: Sep 01 22:10:28 shd-studio volumio[654]: ---------------------------- MPD announces system playlist update Sep 01 22:10:28 shd-studio volumio[654]: info: Ignoring MPD Status Update Sep 01 22:10:28 shd-studio volumio[654]: info: ------------------------------ 42ms Sep 01 22:10:28 shd-studio volumio[654]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/66288723&soundQuality=HI_RES" took 40 milliseconds Sep 01 22:10:28 shd-studio volumio[654]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 22:10:28 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand play Sep 01 22:10:28 shd-studio volumio[654]: info: ------------------------------ 5ms Sep 01 22:10:28 shd-studio volumio[654]: STREAMING PROXY: Handling url /?data=tidal://song/66288723&soundQuality=HI_RES Sep 01 22:10:28 shd-studio volumio[654]: info: sendMpdCommand play took 4 milliseconds Sep 01 22:10:28 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:28 shd-studio volumio[654]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 01 22:10:28 shd-studio volumio[654]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl Sep 01 22:10:37 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 22:10:37 shd-studio volumio[654]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 01 22:10:37 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 01 22:10:37 shd-studio volumio[654]: info: Received Get System Version Sep 01 22:10:37 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 22:10:37 shd-studio volumio[654]: info: Received Get System Info Sep 01 22:10:37 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 22:10:37 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 22:10:37 shd-studio volumio[654]: info: Discovery: Getting this device information Sep 01 22:10:37 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetState Sep 01 22:10:37 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 22:10:38 shd-studio volumio[654]: info: CoreCommandRouter::volumioPlay Sep 01 22:10:38 shd-studio volumio[654]: info: CoreStateMachine::play index 6 Sep 01 22:10:38 shd-studio volumio[654]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 22:10:38 shd-studio volumio[654]: info: CoreStateMachine::stop Sep 01 22:10:38 shd-studio volumio[654]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 22:10:38 shd-studio volumio[654]: info: CoreStateMachine::play index undefined Sep 01 22:10:38 shd-studio volumio[654]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 22:10:38 shd-studio volumio[654]: info: CorePlayQueue::getTrack 6 Sep 01 22:10:38 shd-studio volumio[654]: info: CoreStateMachine::startPlaybackTimer Sep 01 22:10:38 shd-studio volumio[654]: info: CorePlayQueue::getTrack 6 Sep 01 22:10:38 shd-studio volumio[654]: info: [1725221438521] ControllerSpotify::clearAddPlayTrack Sep 01 22:10:38 shd-studio volumio[654]: info: Sending Spotify command with payload to local API: /player/play Sep 01 22:10:38 shd-studio go-librespot[2102]: time="2024-09-01T22:10:38+02:00" level=debug msg="loading track spotify:track:1r8ratK2ne9XUlHo47zeY0 (paused: false, position: 1ms)" Sep 01 22:10:38 shd-studio go-librespot[2102]: time="2024-09-01T22:10:38+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 01 22:10:38 shd-studio go-librespot[2102]: time="2024-09-01T22:10:38+02:00" level=trace msg="emitting websocket event: will_play" Sep 01 22:10:38 shd-studio volumio[654]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","play_origin":"go-librespot"}} Sep 01 22:10:38 shd-studio volumio[654]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","play_origin":"go-librespot"}} Sep 01 22:10:38 shd-studio go-librespot[2102]: time="2024-09-01T22:10:38+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:1r8ratK2ne9XUlHo47zeY0" Sep 01 22:10:38 shd-studio go-librespot[2102]: time="2024-09-01T22:10:38+02:00" level=debug msg="requested aes key for file e2f3ed3e4314165823ad25e6a60465db17a0cd0c, gid: 1r8ratK2ne9XUlHo47zeY0" Sep 01 22:10:39 shd-studio go-librespot[2102]: time="2024-09-01T22:10:39+02:00" level=debug msg="fetched first chunk of 24, total size is 12200552 bytes" Sep 01 22:10:39 shd-studio go-librespot[2102]: time="2024-09-01T22:10:39+02:00" level=trace msg="seek to 1ms (diff: 1ms, samples: 44, bytes: 0)" Sep 01 22:10:39 shd-studio go-librespot[2102]: time="2024-09-01T22:10:39+02:00" level=info msg="loaded track \"Audrey's Dance - Instrumental\" (uri: spotify:track:1r8ratK2ne9XUlHo47zeY0, paused: false, position: 1ms, duration: 317533ms)" Sep 01 22:10:39 shd-studio go-librespot[2102]: time="2024-09-01T22:10:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 01 22:10:39 shd-studio go-librespot[2102]: time="2024-09-01T22:10:39+02:00" level=trace msg="emitting websocket event: metadata" Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","name":"Audrey's Dance - Instrumental","artist_names":["Angelo Badalamenti"],"album_name":"Soundtrack From Twin Peaks","album_cover_url":"https://i.scdn.co/image/ab67616d00001e020fe62e9afb4ae8f52a241d33","position":1,"duration":317533}} Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","name":"Audrey's Dance - Instrumental","artist_names":["Angelo Badalamenti"],"album_name":"Soundtrack From Twin Peaks","album_cover_url":"https://i.scdn.co/image/ab67616d00001e020fe62e9afb4ae8f52a241d33","position":1,"duration":317533}} Sep 01 22:10:39 shd-studio go-librespot[2102]: time="2024-09-01T22:10:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 01 22:10:39 shd-studio go-librespot[2102]: time="2024-09-01T22:10:39+02:00" level=trace msg="emitting websocket event: playing" Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","play_origin":"go-librespot"}} Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: PUSH STATE SPOTIFY Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: {"status":"play","service":"spop","title":"Audrey's Dance - Instrumental","artist":"Angelo Badalamenti","album":"Soundtrack From Twin Peaks","albumart":"https://i.scdn.co/image/ab67616d00001e020fe62e9afb4ae8f52a241d33","uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","trackType":"spotify","seek":1,"duration":317,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 01 22:10:39 shd-studio volumio[654]: info: CoreCommandRouter::servicePushState Sep 01 22:10:39 shd-studio volumio[654]: info: CorePlayQueue::getTrack 6 Sep 01 22:10:39 shd-studio volumio[654]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Audrey's Dance - Instrumental","artist":"Angelo Badalamenti","album":"Soundtrack From Twin Peaks","albumart":"https://i.scdn.co/image/ab67616d00001e020fe62e9afb4ae8f52a241d33","uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","trackType":"spotify","seek":1,"duration":317,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 01 22:10:39 shd-studio volumio[654]: verbose: CURRENT POSITION 6 Sep 01 22:10:39 shd-studio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 01 22:10:39 shd-studio volumio[654]: info: CoreStateMachine::syncState currentStatus stop Sep 01 22:10:39 shd-studio go-librespot[2102]: time="2024-09-01T22:10:39+02:00" level=debug msg="fetched chunk 1/23, size: 524288" Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","play_origin":"go-librespot"}} Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: PUSH STATE SPOTIFY Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: {"status":"play","service":"spop","title":"Audrey's Dance - Instrumental","artist":"Angelo Badalamenti","album":"Soundtrack From Twin Peaks","albumart":"https://i.scdn.co/image/ab67616d00001e020fe62e9afb4ae8f52a241d33","uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","trackType":"spotify","seek":1,"duration":317,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 01 22:10:39 shd-studio volumio[654]: info: CoreCommandRouter::servicePushState Sep 01 22:10:39 shd-studio volumio[654]: info: CorePlayQueue::getTrack 6 Sep 01 22:10:39 shd-studio volumio[654]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Audrey's Dance - Instrumental","artist":"Angelo Badalamenti","album":"Soundtrack From Twin Peaks","albumart":"https://i.scdn.co/image/ab67616d00001e020fe62e9afb4ae8f52a241d33","uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","trackType":"spotify","seek":1,"duration":317,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 01 22:10:39 shd-studio volumio[654]: verbose: CURRENT POSITION 6 Sep 01 22:10:39 shd-studio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 01 22:10:39 shd-studio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 01 22:10:39 shd-studio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 01 22:10:39 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:39 shd-studio volumio[654]: info: CorePlayQueue::getTrack 6 Sep 01 22:10:39 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 22:10:39 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:39 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:39 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:39 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:39 shd-studio volumio[654]: info: CorePlayQueue::getTrack 6 Sep 01 22:10:39 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:39 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:39 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:39 shd-studio go-librespot[2102]: time="2024-09-01T22:10:39+02:00" level=debug msg="fetched chunk 2/23, size: 524288" Sep 01 22:10:39 shd-studio go-librespot[2102]: time="2024-09-01T22:10:39+02:00" level=debug msg="fetched chunk 3/23, size: 524288" Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: PUSH STATE SPOTIFY Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: {"status":"play","service":"spop","title":"Audrey's Dance - Instrumental","artist":"Angelo Badalamenti","album":"Soundtrack From Twin Peaks","albumart":"https://i.scdn.co/image/ab67616d00001e020fe62e9afb4ae8f52a241d33","uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","trackType":"spotify","seek":1,"duration":317,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 01 22:10:39 shd-studio volumio[654]: info: CoreCommandRouter::servicePushState Sep 01 22:10:39 shd-studio volumio[654]: info: CorePlayQueue::getTrack 6 Sep 01 22:10:39 shd-studio volumio[654]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Audrey's Dance - Instrumental","artist":"Angelo Badalamenti","album":"Soundtrack From Twin Peaks","albumart":"https://i.scdn.co/image/ab67616d00001e020fe62e9afb4ae8f52a241d33","uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","trackType":"spotify","seek":1,"duration":317,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 01 22:10:39 shd-studio volumio[654]: verbose: CURRENT POSITION 6 Sep 01 22:10:39 shd-studio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 01 22:10:39 shd-studio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 01 22:10:39 shd-studio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 01 22:10:39 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:39 shd-studio volumio[654]: info: CorePlayQueue::getTrack 6 Sep 01 22:10:39 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:39 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:39 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:39 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:39 shd-studio volumio[654]: info: CorePlayQueue::getTrack 6 Sep 01 22:10:39 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:39 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:39 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: PUSH STATE SPOTIFY Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: {"status":"play","service":"spop","title":"Audrey's Dance - Instrumental","artist":"Angelo Badalamenti","album":"Soundtrack From Twin Peaks","albumart":"https://i.scdn.co/image/ab67616d00001e020fe62e9afb4ae8f52a241d33","uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","trackType":"spotify","seek":1,"duration":317,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 01 22:10:39 shd-studio volumio[654]: info: CoreCommandRouter::servicePushState Sep 01 22:10:39 shd-studio volumio[654]: info: CorePlayQueue::getTrack 6 Sep 01 22:10:39 shd-studio volumio[654]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Audrey's Dance - Instrumental","artist":"Angelo Badalamenti","album":"Soundtrack From Twin Peaks","albumart":"https://i.scdn.co/image/ab67616d00001e020fe62e9afb4ae8f52a241d33","uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","trackType":"spotify","seek":1,"duration":317,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 01 22:10:39 shd-studio volumio[654]: verbose: CURRENT POSITION 6 Sep 01 22:10:39 shd-studio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 01 22:10:39 shd-studio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 01 22:10:39 shd-studio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 01 22:10:39 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:39 shd-studio volumio[654]: info: CorePlayQueue::getTrack 6 Sep 01 22:10:39 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:39 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:39 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:39 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:39 shd-studio volumio[654]: info: CorePlayQueue::getTrack 6 Sep 01 22:10:39 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:39 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:39 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:39 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:41 shd-studio volumio[654]: info: CoreCommandRouter::volumioPlay Sep 01 22:10:41 shd-studio volumio[654]: info: CoreStateMachine::play index 7 Sep 01 22:10:41 shd-studio volumio[654]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 22:10:41 shd-studio volumio[654]: info: CoreStateMachine::stop Sep 01 22:10:41 shd-studio volumio[654]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 22:10:41 shd-studio volumio[654]: info: CoreStateMachine::stPlaybackTimer Sep 01 22:10:41 shd-studio volumio[654]: info: CoreStateMachine::updateTrackBlock Sep 01 22:10:41 shd-studio volumio[654]: info: CorePlayQueue::getTrackBlock Sep 01 22:10:41 shd-studio volumio[654]: info: CoreStateMachine::pushState Sep 01 22:10:41 shd-studio volumio[654]: info: CorePlayQueue::getTrack 6 Sep 01 22:10:41 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 22:10:41 shd-studio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 01 22:10:41 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output update for this device Sep 01 22:10:41 shd-studio volumio[654]: info: MRS: Pushing multiroomSync output Sep 01 22:10:41 shd-studio volumio[654]: info: CoreStateMachine::serviceStop Sep 01 22:10:41 shd-studio volumio[654]: info: CorePlayQueue::getTrack 6 Sep 01 22:10:41 shd-studio volumio[654]: info: CoreCommandRouter::serviceStop Sep 01 22:10:41 shd-studio volumio[654]: info: Spotify Stop Sep 01 22:10:41 shd-studio volumio[654]: SPOTIFY: SPOTIFY STOP Sep 01 22:10:41 shd-studio volumio[654]: SPOTIFY: {"status":"play","position":6,"title":"Audrey's Dance - Instrumental","artist":"Angelo Badalamenti","album":"Soundtrack From Twin Peaks","albumart":"https://i.scdn.co/image/ab67616d0000b2730fe62e9afb4ae8f52a241d33","uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","trackType":"spotify","codec":"ogg","seek":1,"duration":317,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":0,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Sep 01 22:10:41 shd-studio volumio[654]: info: Sending Spotify command to local API: /player/pause Sep 01 22:10:41 shd-studio volumio[654]: info: CoreStateMachine::play index undefined Sep 01 22:10:41 shd-studio volumio[654]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 22:10:41 shd-studio volumio[654]: info: CorePlayQueue::getTrack 7 Sep 01 22:10:41 shd-studio volumio[654]: info: CoreStateMachine::startPlaybackTimer Sep 01 22:10:41 shd-studio volumio[654]: info: CorePlayQueue::getTrack 7 Sep 01 22:10:41 shd-studio volumio[654]: info: [1725221441080] ControllerTidal::clearAddPlayTrack Sep 01 22:10:41 shd-studio volumio[654]: info: Getting stream with soundQuality HI_RES Sep 01 22:10:41 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 22:10:41 shd-studio volumio[654]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 01 22:10:41 shd-studio volumio[654]: info: Sep 01 22:10:41 shd-studio volumio[654]: ---------------------------- MPD announces state update: player Sep 01 22:10:41 shd-studio volumio[654]: info: sendMpdCommand stop took 14 milliseconds Sep 01 22:10:41 shd-studio volumio[654]: info: ControllerMpd::getState Sep 01 22:10:41 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 01 22:10:41 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 22:10:41 shd-studio volumio[654]: info: Sep 01 22:10:41 shd-studio volumio[654]: ---------------------------- MPD announces system playlist update Sep 01 22:10:41 shd-studio volumio[654]: info: Ignoring MPD Status Update Sep 01 22:10:41 shd-studio volumio[654]: info: sendMpdCommand status took 10 milliseconds Sep 01 22:10:41 shd-studio volumio[654]: info: sendMpdCommand clear took 10 milliseconds Sep 01 22:10:41 shd-studio volumio[654]: verbose: ControllerMpd::parseState Sep 01 22:10:41 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 22:10:41 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/636411&soundQuality=HI_RES" Sep 01 22:10:41 shd-studio volumio[654]: error: updateQueue error: null Sep 01 22:10:41 shd-studio volumio[654]: info: Sep 01 22:10:41 shd-studio volumio[654]: ---------------------------- MPD announces system playlist update Sep 01 22:10:41 shd-studio volumio[654]: info: Ignoring MPD Status Update Sep 01 22:10:41 shd-studio volumio[654]: info: ------------------------------ 14ms Sep 01 22:10:41 shd-studio volumio[654]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 01 22:10:41 shd-studio volumio[654]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/636411&soundQuality=HI_RES" took 11 milliseconds Sep 01 22:10:41 shd-studio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 01 22:10:41 shd-studio volumio[654]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 22:10:41 shd-studio volumio[654]: verbose: ControllerMpd::sendMpdCommand play Sep 01 22:10:41 shd-studio volumio[654]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 01 22:10:41 shd-studio volumio[654]: info: ------------------------------ 29ms Sep 01 22:10:41 shd-studio volumio[654]: STREAMING PROXY: Handling url /?data=tidal://song/636411&soundQuality=HI_RES Sep 01 22:10:41 shd-studio volumio[654]: info: ------------------------------ 14ms Sep 01 22:10:41 shd-studio volumio[654]: info: sendMpdCommand play took 11 milliseconds Sep 01 22:10:41 shd-studio volumio[654]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 01 22:10:41 shd-studio volumio[654]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl Sep 01 22:10:41 shd-studio go-librespot[2102]: time="2024-09-01T22:10:41+02:00" level=debug msg="pause track at 2460ms" Sep 01 22:10:41 shd-studio go-librespot[2102]: time="2024-09-01T22:10:41+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 01 22:10:41 shd-studio go-librespot[2102]: time="2024-09-01T22:10:41+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 01 22:10:41 shd-studio go-librespot[2102]: time="2024-09-01T22:10:41+02:00" level=trace msg="emitting websocket event: paused" Sep 01 22:10:41 shd-studio volumio[654]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","play_origin":"go-librespot"}} Sep 01 22:10:41 shd-studio volumio[654]: SPOTIFY: PUSH STATE SPOTIFY Sep 01 22:10:41 shd-studio volumio[654]: SPOTIFY: {"status":"pause","service":"spop","title":"Audrey's Dance - Instrumental","artist":"Angelo Badalamenti","album":"Soundtrack From Twin Peaks","albumart":"https://i.scdn.co/image/ab67616d00001e020fe62e9afb4ae8f52a241d33","uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","trackType":"spotify","seek":2001,"duration":317,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 01 22:10:41 shd-studio volumio[654]: info: CoreCommandRouter::servicePushState Sep 01 22:10:41 shd-studio volumio[654]: info: CorePlayQueue::getTrack 7 Sep 01 22:10:41 shd-studio volumio[654]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received spop Sep 01 22:10:41 shd-studio volumio[654]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","play_origin":"go-librespot"}} Sep 01 22:10:41 shd-studio volumio[654]: SPOTIFY: PUSH STATE SPOTIFY Sep 01 22:10:41 shd-studio volumio[654]: SPOTIFY: {"status":"pause","service":"spop","title":"Audrey's Dance - Instrumental","artist":"Angelo Badalamenti","album":"Soundtrack From Twin Peaks","albumart":"https://i.scdn.co/image/ab67616d00001e020fe62e9afb4ae8f52a241d33","uri":"spotify:track:1r8ratK2ne9XUlHo47zeY0","trackType":"spotify","seek":2001,"duration":317,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 01 22:10:41 shd-studio volumio[654]: info: CoreCommandRouter::servicePushState Sep 01 22:10:41 shd-studio volumio[654]: info: CorePlayQueue::getTrack 7 Sep 01 22:10:41 shd-studio volumio[654]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received spop Sep 01 22:10:46 shd-studio volumiologrotate[455]: ls: cannot access '/var/log/samba/log.wb-SHD': No such file or directory Sep 01 22:10:46 shd-studio volumiologrotate[455]: ls: cannot access 'STUDIO': No such file or directory Sep 01 22:10:49 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:10:49 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:10:49 shd-studio volumio[654]: info: Retrieving Cloud Streaming UI Sep 01 22:10:49 shd-studio volumio[654]: info: Getting Tidal Cloud Configuration Sep 01 22:10:49 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:10:49 shd-studio volumio[654]: info: Getting Qobuz Cloud Configuration Sep 01 22:10:49 shd-studio volumio[654]: info: Asking plugin for UI Config Sep 01 22:10:49 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:10:50 shd-studio volumio[654]: info: Getting Spotify Cloud Configuration Sep 01 22:10:50 shd-studio volumio[654]: info: Asking plugin for UI Config Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:10:50 shd-studio volumio[654]: info: Saving Spotify Acccount Sep 01 22:10:50 shd-studio volumio[654]: info: Got it Sep 01 22:10:50 shd-studio volumio[654]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 01 22:10:50 shd-studio volumio[654]: info: Got Tidal Cloud Configuration Sep 01 22:10:50 shd-studio volumio[654]: info: Got it Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 22:10:50 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 01 22:10:54 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 22:10:58 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 22:11:00 shd-studio volumio[654]: info: Disabling MyMusic plugin upnp Sep 01 22:11:00 shd-studio sudo[2433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 01 22:11:00 shd-studio sudo[2433]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 22:11:00 shd-studio systemd[1]: Stopping UPnP Renderer front-end to MPD... Sep 01 22:11:00 shd-studio volumio[654]: error: Upnp client error: Error: This socket has been ended by the other party Sep 01 22:11:02 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 22:11:06 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 22:11:10 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:11:14 shd-studio volumio[654]: info: Retrieving Cloud Streaming UI Sep 01 22:11:14 shd-studio volumio[654]: info: Getting Tidal Cloud Configuration Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:11:14 shd-studio volumio[654]: info: Getting Qobuz Cloud Configuration Sep 01 22:11:14 shd-studio volumio[654]: info: Asking plugin for UI Config Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:11:14 shd-studio volumio[654]: info: Getting Spotify Cloud Configuration Sep 01 22:11:14 shd-studio volumio[654]: info: Asking plugin for UI Config Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:11:14 shd-studio volumio[654]: info: Saving Spotify Acccount Sep 01 22:11:14 shd-studio volumio[654]: info: Got it Sep 01 22:11:14 shd-studio volumio[654]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 01 22:11:14 shd-studio volumio[654]: info: Got Tidal Cloud Configuration Sep 01 22:11:14 shd-studio volumio[654]: info: Got it Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 22:11:14 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 01 22:11:18 shd-studio volumio[654]: info: Disabling MyMusic plugin upnp Sep 01 22:11:18 shd-studio sudo[2439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 01 22:11:18 shd-studio sudo[2439]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 22:11:18 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 22:11:22 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:11:29 shd-studio volumio[654]: info: Retrieving Cloud Streaming UI Sep 01 22:11:29 shd-studio volumio[654]: info: Getting Tidal Cloud Configuration Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:11:29 shd-studio volumio[654]: info: Getting Qobuz Cloud Configuration Sep 01 22:11:29 shd-studio volumio[654]: info: Asking plugin for UI Config Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:11:29 shd-studio volumio[654]: info: Getting Spotify Cloud Configuration Sep 01 22:11:29 shd-studio volumio[654]: info: Asking plugin for UI Config Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:11:29 shd-studio volumio[654]: info: Saving Spotify Acccount Sep 01 22:11:29 shd-studio volumio[654]: info: Got it Sep 01 22:11:29 shd-studio volumio[654]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 01 22:11:29 shd-studio volumio[654]: info: Got Tidal Cloud Configuration Sep 01 22:11:29 shd-studio volumio[654]: info: Got it Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 22:11:29 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 01 22:11:33 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 22:11:36 shd-studio volumio[654]: info: Disabling MyMusic plugin upnp Sep 01 22:11:36 shd-studio sudo[2443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 01 22:11:36 shd-studio sudo[2443]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 22:11:37 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 22:11:38 shd-studio volumio[654]: info: Enabling MyMusic plugin upnp Sep 01 22:11:38 shd-studio volumio[654]: info: Enabling plugin upnp Sep 01 22:11:38 shd-studio volumio[654]: info: Loading plugin "upnp"... Sep 01 22:11:38 shd-studio volumio[654]: info: [1725221498540] Starting Upmpd Daemon Sep 01 22:11:38 shd-studio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 22:11:38 shd-studio volumio[654]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 22:11:38 shd-studio volumio[654]: Error: listen EADDRINUSE: address already in use :::6599 Sep 01 22:11:38 shd-studio volumio[654]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Sep 01 22:11:38 shd-studio volumio[654]: at listenInCluster (net.js:1379:12) Sep 01 22:11:38 shd-studio volumio[654]: at Server.listen (net.js:1465:7) Sep 01 22:11:38 shd-studio volumio[654]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 01 22:11:38 shd-studio volumio[654]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 01 22:11:38 shd-studio volumio[654]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 01 22:11:38 shd-studio volumio[654]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 01 22:11:38 shd-studio volumio[654]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Sep 01 22:11:38 shd-studio volumio[654]: code: 'EADDRINUSE', Sep 01 22:11:38 shd-studio volumio[654]: errno: -98, Sep 01 22:11:38 shd-studio volumio[654]: syscall: 'listen', Sep 01 22:11:38 shd-studio volumio[654]: address: '::', Sep 01 22:11:38 shd-studio volumio[654]: port: 6599 Sep 01 22:11:38 shd-studio volumio[654]: } Sep 01 22:11:38 shd-studio volumio[654]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 22:11:39 shd-studio sudo[2454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 22:10 Sep 01 22:11:39 shd-studio sudo[2454]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:00:28 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="29ccfe8a9cff19911db4b736879ac117"