-- Logs begin at Sun 2024-11-24 20:07:16 CET, end at Sun 2024-11-24 21:21:05 CET. -- Nov 24 21:20:16 rivo sudo[8913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 21:20:16 rivo sudo[8913]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:16 rivo sudo[8913]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:17 rivo sudo[8916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 21:20:17 rivo sudo[8916]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:17 rivo sudo[8916]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:17 rivo volumio[3480]: verbose: New Socket.io Connection to 192.168.1.23 from 192.168.1.14 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.39 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 24 21:20:17 rivo sudo[8924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 21:20:17 rivo sudo[8924]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:17 rivo sudo[8924]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:17 rivo sudo[8927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 21:20:17 rivo sudo[8927]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:17 rivo sudo[8927]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:17 rivo volumio[3480]: verbose: New Socket.io Connection to 192.168.1.23 from 192.168.1.14 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.39 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::volumioGetVisibleSources Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::volumioGetQueue Nov 24 21:20:17 rivo volumio[3480]: info: CoreStateMachine::getQueue Nov 24 21:20:17 rivo volumio[3480]: info: CorePlayQueue::getQueue Nov 24 21:20:17 rivo volumio[3480]: info: Listing playlists Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 24 21:20:17 rivo volumio[3480]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 24 21:20:17 rivo volumio[3480]: info: Received Get System Info Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 21:20:17 rivo volumio[3480]: info: Discovery: Getting this device information Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 24 21:20:17 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 24 21:20:18 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 21:20:18 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 21:20:18 rivo volumio[3480]: info: Discovery: Getting this device information Nov 24 21:20:18 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:18 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 21:20:19 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 24 21:20:19 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 21:20:19 rivo volumio[3480]: info: Received Get System Info Nov 24 21:20:19 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 21:20:19 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 21:20:19 rivo volumio[3480]: info: Discovery: Getting this device information Nov 24 21:20:19 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:19 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 21:20:20 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 21:20:20 rivo volumio[3480]: info: Received Get System Info Nov 24 21:20:20 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 21:20:20 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 21:20:20 rivo volumio[3480]: info: Discovery: Getting this device information Nov 24 21:20:20 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:20 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 21:20:21 rivo volumio[3480]: info: CoreCommandRouter::volumioNext Nov 24 21:20:21 rivo volumio[3480]: info: CoreStateMachine::next Nov 24 21:20:21 rivo volumio[3480]: info: CoreStateMachine::stop Nov 24 21:20:21 rivo volumio[3480]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 21:20:21 rivo volumio[3480]: info: CoreStateMachine::stPlaybackTimer Nov 24 21:20:21 rivo volumio[3480]: info: CoreStateMachine::updateTrackBlock Nov 24 21:20:21 rivo volumio[3480]: info: CorePlayQueue::getTrackBlock Nov 24 21:20:21 rivo volumio[3480]: info: CoreStateMachine::pushState Nov 24 21:20:21 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:21 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 21:20:21 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:21 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Nov 24 21:20:21 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:21 rivo volumio[3480]: info: CoreStateMachine::serviceStop Nov 24 21:20:21 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:21 rivo volumio[3480]: info: CoreCommandRouter::serviceStop Nov 24 21:20:21 rivo volumio[3480]: info: [1732479621340] ControllerWebradio::stop Nov 24 21:20:21 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand stop Nov 24 21:20:21 rivo volumio[3480]: info: Updating RAAT Signal Path Nov 24 21:20:21 rivo volumio[3480]: info: Nov 24 21:20:21 rivo volumio[3480]: ---------------------------- MPD announces state update: player Nov 24 21:20:21 rivo volumio[3480]: info: sendMpdCommand stop took 61 milliseconds Nov 24 21:20:21 rivo volumio[3480]: info: ControllerMpd::getState Nov 24 21:20:21 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Nov 24 21:20:21 rivo volumio[3480]: info: CoreStateMachine::play index undefined Nov 24 21:20:21 rivo volumio[3480]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 21:20:21 rivo volumio[3480]: info: CorePlayQueue::getTrack 1 Nov 24 21:20:21 rivo volumio[3480]: info: Nov 24 21:20:21 rivo volumio[3480]: ---------------------------- MPD announces state update: player Nov 24 21:20:21 rivo volumio[3480]: info: ControllerMpd::getState Nov 24 21:20:21 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Nov 24 21:20:21 rivo volumio[3480]: info: Nov 24 21:20:21 rivo volumio[3480]: ---------------------------- MPD announces state update: player Nov 24 21:20:21 rivo volumio[3480]: info: ControllerMpd::getState Nov 24 21:20:21 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Nov 24 21:20:21 rivo volumio[3480]: info: sendMpdCommand status took 17 milliseconds Nov 24 21:20:21 rivo volumio[3480]: info: sendMpdCommand status took 8 milliseconds Nov 24 21:20:21 rivo volumio[3480]: verbose: ControllerMpd::parseState Nov 24 21:20:21 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 21:20:21 rivo volumio[3480]: verbose: ControllerMpd::parseState Nov 24 21:20:21 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 21:20:21 rivo volumio[3480]: info: sendMpdCommand status took 12 milliseconds Nov 24 21:20:21 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 10 milliseconds Nov 24 21:20:21 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 24 21:20:21 rivo volumio[3480]: verbose: ControllerMpd::parseState Nov 24 21:20:21 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 21:20:21 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Nov 24 21:20:21 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Nov 24 21:20:21 rivo volumio[3480]: info: ControllerMpd::pushState Nov 24 21:20:21 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Nov 24 21:20:21 rivo volumio[3480]: info: CoreStateMachine::pushState Nov 24 21:20:21 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:21 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:21 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Nov 24 21:20:21 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:21 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:21 rivo volumio[3480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 24 21:20:21 rivo volumio[3480]: info: ControllerMpd::pushState Nov 24 21:20:21 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Nov 24 21:20:21 rivo volumio[3480]: info: CoreStateMachine::pushState Nov 24 21:20:21 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:21 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:21 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Nov 24 21:20:21 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:21 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:21 rivo volumio[3480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 24 21:20:21 rivo volumio[3480]: info: ------------------------------ 62ms Nov 24 21:20:21 rivo volumio[3480]: info: ------------------------------ 55ms Nov 24 21:20:21 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 35 milliseconds Nov 24 21:20:21 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Nov 24 21:20:21 rivo volumio[3480]: info: ControllerMpd::pushState Nov 24 21:20:21 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Nov 24 21:20:21 rivo volumio[3480]: info: CoreStateMachine::pushState Nov 24 21:20:21 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:21 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:21 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Nov 24 21:20:21 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:21 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:21 rivo volumio[3480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 24 21:20:21 rivo volumio[3480]: info: ------------------------------ 70ms Nov 24 21:20:21 rivo volumio[3480]: info: Updating RAAT Signal Path Nov 24 21:20:21 rivo volumio[3480]: info: Updating RAAT Signal Path Nov 24 21:20:21 rivo volumio[3480]: info: Updating RAAT Signal Path Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::volumioPlay Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::play index undefined Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 21:20:25 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::startPlaybackTimer Nov 24 21:20:25 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:25 rivo volumio[3480]: info: [1732479625033] ControllerWebradio::clearAddPlayTrack Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand stop Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand stop took 1 milliseconds Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand clear Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Nov 24 21:20:25 rivo volumio[3480]: info: Ignoring MPD Status Update Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand clear took 2 milliseconds Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand load "http://s3.slotex.pl:7770/;" Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Nov 24 21:20:25 rivo volumio[3480]: info: Ignoring MPD Status Update Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Nov 24 21:20:25 rivo volumio[3480]: info: Ignoring MPD Status Update Nov 24 21:20:25 rivo volumio[3480]: error: updateQueue error: null Nov 24 21:20:25 rivo volumio[3480]: info: ------------------------------ 7ms Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand add "http://s3.slotex.pl:7770/;" Nov 24 21:20:25 rivo volumio[3480]: error: updateQueue error: null Nov 24 21:20:25 rivo volumio[3480]: error: updateQueue error: null Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Nov 24 21:20:25 rivo volumio[3480]: info: Ignoring MPD Status Update Nov 24 21:20:25 rivo volumio[3480]: info: ------------------------------ 259ms Nov 24 21:20:25 rivo volumio[3480]: info: ------------------------------ 259ms Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand add "http://s3.slotex.pl:7770/;" took 3 milliseconds Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand play Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Nov 24 21:20:25 rivo volumio[3480]: info: Ignoring MPD Status Update Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Nov 24 21:20:25 rivo volumio[3480]: info: Ignoring MPD Status Update Nov 24 21:20:25 rivo volumio[3480]: info: ------------------------------ 10ms Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand play took 9 milliseconds Nov 24 21:20:25 rivo volumio[3480]: info: ------------------------------ 5ms Nov 24 21:20:25 rivo volumio[3480]: info: ------------------------------ 3ms Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces state update: player Nov 24 21:20:25 rivo volumio[3480]: info: ControllerMpd::getState Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces state update: player Nov 24 21:20:25 rivo volumio[3480]: info: ControllerMpd::getState Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces state update: player Nov 24 21:20:25 rivo volumio[3480]: info: ControllerMpd::getState Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Nov 24 21:20:25 rivo volumio[3480]: info: Ignoring MPD Status Update Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces state update: player Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand status took 155 milliseconds Nov 24 21:20:25 rivo volumio[3480]: info: ControllerMpd::getState Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand status took 155 milliseconds Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand status took 154 milliseconds Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::parseState Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::parseState Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::parseState Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 21:20:25 rivo volumio[3480]: info: ------------------------------ 7ms Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand status took 6 milliseconds Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::parseState Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Nov 24 21:20:25 rivo volumio[3480]: info: ControllerMpd::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Nov 24 21:20:25 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:25 rivo volumio[3480]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":";","artist":"RADIO BERCIK - SILESIA","album":null,"uri":"http://s3.slotex.pl:7770/;","trackType":"pl:7770/;"} Nov 24 21:20:25 rivo volumio[3480]: verbose: CURRENT POSITION 0 Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::syncState stateService play Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus stop Nov 24 21:20:25 rivo volumio[3480]: info: ControllerMpd::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Nov 24 21:20:25 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:25 rivo volumio[3480]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":";","artist":"RADIO BERCIK - SILESIA","album":null,"uri":"http://s3.slotex.pl:7770/;","trackType":"pl:7770/;"} Nov 24 21:20:25 rivo volumio[3480]: verbose: CURRENT POSITION 0 Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::syncState stateService play Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus play Nov 24 21:20:25 rivo volumio[3480]: info: Received an update from plugin. extracting info from payload Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:25 rivo volumio[3480]: info: ControllerMpd::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Nov 24 21:20:25 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:25 rivo volumio[3480]: verbose: STATE SERVICE {"status":"play","position":0,"seek":182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":";","artist":"RADIO BERCIK - SILESIA","album":null,"uri":"http://s3.slotex.pl:7770/;","trackType":"pl:7770/;"} Nov 24 21:20:25 rivo volumio[3480]: verbose: CURRENT POSITION 0 Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::syncState stateService play Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus play Nov 24 21:20:25 rivo volumio[3480]: info: Received an update from plugin. extracting info from payload Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:25 rivo volumio[3480]: info: ------------------------------ 191ms Nov 24 21:20:25 rivo volumio[3480]: info: ------------------------------ 214ms Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Nov 24 21:20:25 rivo volumio[3480]: info: Ignoring MPD Status Update Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces state update: player Nov 24 21:20:25 rivo volumio[3480]: info: ControllerMpd::getState Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Nov 24 21:20:25 rivo volumio[3480]: info: Ignoring MPD Status Update Nov 24 21:20:25 rivo volumio[3480]: info: Nov 24 21:20:25 rivo volumio[3480]: ---------------------------- MPD announces state update: player Nov 24 21:20:25 rivo volumio[3480]: info: ControllerMpd::getState Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 62 milliseconds Nov 24 21:20:25 rivo volumio[3480]: info: ------------------------------ 8ms Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand status took 6 milliseconds Nov 24 21:20:25 rivo volumio[3480]: info: ------------------------------ 5ms Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand status took 4 milliseconds Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::parseState Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::parseState Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 21:20:25 rivo volumio[3480]: info: ControllerMpd::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Nov 24 21:20:25 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:25 rivo volumio[3480]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":";","artist":"RADIO BERCIK - SILESIA","album":null,"uri":"http://s3.slotex.pl:7770/;","trackType":"pl:7770/;"} Nov 24 21:20:25 rivo volumio[3480]: verbose: CURRENT POSITION 0 Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::syncState stateService play Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus play Nov 24 21:20:25 rivo volumio[3480]: info: Received an update from plugin. extracting info from payload Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:25 rivo volumio[3480]: info: ------------------------------ 102ms Nov 24 21:20:25 rivo volumio[3480]: info: Updating RAAT Signal Path Nov 24 21:20:25 rivo volumio[3480]: info: Updating RAAT Signal Path Nov 24 21:20:25 rivo volumio[3480]: info: Updating RAAT Signal Path Nov 24 21:20:25 rivo volumio[3480]: info: Updating RAAT Signal Path Nov 24 21:20:25 rivo volumio[3480]: info: Updating RAAT Signal Path Nov 24 21:20:25 rivo volumio[3480]: info: Updating RAAT Signal Path Nov 24 21:20:25 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 55 milliseconds Nov 24 21:20:25 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Nov 24 21:20:25 rivo volumio[3480]: info: ControllerMpd::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Nov 24 21:20:25 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:25 rivo volumio[3480]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1360,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":";","artist":"RADIO BERCIK - SILESIA","album":null,"uri":"http://s3.slotex.pl:7770/;","trackType":"pl:7770/;"} Nov 24 21:20:25 rivo volumio[3480]: verbose: CURRENT POSITION 0 Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::syncState stateService play Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus play Nov 24 21:20:25 rivo volumio[3480]: info: Received an update from plugin. extracting info from payload Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:25 rivo volumio[3480]: info: ControllerMpd::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Nov 24 21:20:25 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:25 rivo volumio[3480]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1360,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":";","artist":"RADIO BERCIK - SILESIA","album":null,"uri":"http://s3.slotex.pl:7770/;","trackType":"pl:7770/;"} Nov 24 21:20:25 rivo volumio[3480]: verbose: CURRENT POSITION 0 Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::syncState stateService play Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus play Nov 24 21:20:25 rivo volumio[3480]: info: Received an update from plugin. extracting info from payload Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:25 rivo volumio[3480]: info: CoreStateMachine::pushState Nov 24 21:20:25 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Nov 24 21:20:25 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:25 rivo volumio[3480]: info: ------------------------------ 148ms Nov 24 21:20:25 rivo volumio[3480]: info: ------------------------------ 146ms Nov 24 21:20:25 rivo volumio[3480]: info: Updating RAAT Signal Path Nov 24 21:20:25 rivo volumio[3480]: info: Updating RAAT Signal Path Nov 24 21:20:25 rivo volumio[3480]: info: Updating RAAT Signal Path Nov 24 21:20:25 rivo volumio[3480]: info: Updating RAAT Signal Path Nov 24 21:20:27 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 21:20:31 rivo volumio[3480]: info: Retrieving Cloud Streaming UI Nov 24 21:20:31 rivo volumio[3480]: info: Getting Tidal Cloud Configuration Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 21:20:31 rivo volumio[3480]: info: Getting Qobuz Cloud Configuration Nov 24 21:20:31 rivo volumio[3480]: info: Asking plugin for UI Config Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 21:20:31 rivo volumio[3480]: info: Getting Spotify Cloud Configuration Nov 24 21:20:31 rivo volumio[3480]: info: Asking plugin for UI Config Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 21:20:31 rivo volumio[3480]: info: Saving Spotify Acccount Nov 24 21:20:31 rivo volumio[3480]: info: Got it Nov 24 21:20:31 rivo volumio[3480]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 24 21:20:31 rivo volumio[3480]: info: Got Tidal Cloud Configuration Nov 24 21:20:31 rivo volumio[3480]: info: Got it Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::volumioGetBrowseSources Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::volumioGetBrowseSources Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::volumioGetBrowseSources Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 21:20:31 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 24 21:20:35 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 21:20:36 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 24 21:20:36 rivo volumio[3480]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 24 21:20:36 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 24 21:20:36 rivo volumio[3480]: info: Received Get System Version Nov 24 21:20:36 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 24 21:20:36 rivo volumio[3480]: info: Received Get System Info Nov 24 21:20:36 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 21:20:36 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 21:20:36 rivo volumio[3480]: info: Discovery: Getting this device information Nov 24 21:20:36 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:36 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 21:20:39 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 21:20:43 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 21:20:43 rivo volumio[3480]: info: Disabling MyMusic plugin multiroom Nov 24 21:20:43 rivo volumio[3480]: info: Disabling plugin multiroom Nov 24 21:20:43 rivo volumio[3480]: info: Preparing to generate the ALSA configuration file Nov 24 21:20:43 rivo volumio[3480]: info: Asound.conf file written Nov 24 21:20:43 rivo sudo[9027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 24 21:20:43 rivo sudo[9027]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:43 rivo sudo[9027]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:43 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Nov 24 21:20:43 rivo kernel: Err: can not set rate to 4278967296! Nov 24 21:20:43 rivo kernel: Range[3920000 - 500000000] Nov 24 21:20:43 rivo kernel: out of value, fixed it Nov 24 21:20:43 rivo kernel: id=0 set inskew=0 Nov 24 21:20:43 rivo volumio[3480]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Nov 24 21:20:43 rivo volumio[3480]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Nov 24 21:20:43 rivo volumio[3480]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Nov 24 21:20:43 rivo volumio[3480]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Nov 24 21:20:43 rivo volumio[3480]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Quloos QU02 Validity:0' : Operation not permitted Nov 24 21:20:43 rivo volumio[3480]: info: Output device has changed, restarting MPD Nov 24 21:20:43 rivo sudo[9032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 21:20:43 rivo sudo[9032]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:43 rivo volumio[3480]: info: Output device has changed, restarting Shairport Sync Nov 24 21:20:43 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:43 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:20:43 rivo sudo[9032]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:43 rivo sudo[9035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 21:20:43 rivo sudo[9035]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:43 rivo systemd[1]: Stopping Music Player Daemon... Nov 24 21:20:44 rivo volumio[3480]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 21:20:44 rivo volumio[3480]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:44 rivo volumio[3480]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:44 rivo vtcs[4071]: [2024-11-24 21:20:44.053] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Nov 24 21:20:44 rivo vtcs[4071]: [2024-11-24 21:20:44.056] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Nov 24 21:20:44 rivo vtcs[4071]: [2024-11-24 21:20:44.056] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 24 21:20:44 rivo volumio[3480]: info: Done. Nov 24 21:20:44 rivo sudo[9043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 24 21:20:44 rivo sudo[9043]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:44 rivo volumio[3480]: info: MPD Permissions set Nov 24 21:20:44 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:44 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 24 21:20:44 rivo sudo[9046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 24 21:20:44 rivo sudo[9046]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:44 rivo systemd[1]: vtcs.service: Succeeded. Nov 24 21:20:44 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Nov 24 21:20:44 rivo sudo[9043]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 24 21:20:44 rivo sudo[9046]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:44 rivo volumio[3480]: info: Starting Shairport Sync Nov 24 21:20:44 rivo systemd[1]: mpd.service: Succeeded. Nov 24 21:20:44 rivo systemd[1]: Stopped Music Player Daemon. Nov 24 21:20:44 rivo sudo[9059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 21:20:44 rivo sudo[9059]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:44 rivo systemd[1]: Starting Music Player Daemon... Nov 24 21:20:44 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 24 21:20:44 rivo systemd[1]: shairport-sync.service: Succeeded. Nov 24 21:20:44 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 24 21:20:44 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 24 21:20:44 rivo sudo[9059]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:44 rivo volumio[3480]: info: Shairport-Sync Started Nov 24 21:20:44 rivo sudo[9061]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 24 21:20:44 rivo sudo[9061]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:44 rivo sudo[9061]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:20:44 rivo volumio[3480]: info: Not Reporting Auto name since its the default one Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 24 21:20:44 rivo sudo[9082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 24 21:20:44 rivo sudo[9082]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:44 rivo sudo[9082]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:44 rivo sudo[9084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 24 21:20:44 rivo sudo[9084]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:44 rivo systemd[1]: Stopping RAAT DAEMON... Nov 24 21:20:44 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Nov 24 21:20:44 rivo systemd[1]: raat-daemon.service: Succeeded. Nov 24 21:20:44 rivo systemd[1]: Stopped RAAT DAEMON. Nov 24 21:20:44 rivo systemd[1]: Started RAAT DAEMON. Nov 24 21:20:44 rivo sudo[9084]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:44 rivo volumio[3480]: info: Raat Daemon started successfully Nov 24 21:20:44 rivo volumio[3480]: info: Executing endpoint restartRAATSocket Nov 24 21:20:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Nov 24 21:20:45 rivo volumio[3480]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Nov 24 21:20:46 rivo mpd[9074]: Nov 24 21:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 24 21:20:46 rivo systemd[1]: Started Music Player Daemon. Nov 24 21:20:46 rivo sudo[9035]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:46 rivo volumio[3480]: error: updateQueue error: null Nov 24 21:20:47 rivo volumio[3480]: info: TidalConnect service stoped! Nov 24 21:20:47 rivo volumio[3480]: info: TidalConnect service stoped! Nov 24 21:20:47 rivo volumio[3480]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 24 21:20:47 rivo volumio[3480]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 24 21:20:47 rivo sudo[9111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 24 21:20:47 rivo sudo[9111]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:47 rivo systemd[1]: Started Volumio Tidal Connect Service. Nov 24 21:20:47 rivo sudo[9111]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:47 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 21:20:47 rivo volumio[3480]: info: Executing endpoint tc_getconfig Nov 24 21:20:47 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 24 21:20:47 rivo vtcs[9113]: STARTING TidalConnect services, version: 1.4.0.34 Nov 24 21:20:47 rivo vtcs[9113]: STARTED TidalConnect services. Nov 24 21:20:47 rivo volumio[3480]: info: Executing endpoint tc_connect Nov 24 21:20:47 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 24 21:20:47 rivo volumio[3480]: info: Connecting to TidalConnect Nov 24 21:20:47 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Nov 24 21:20:47 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:47 rivo volumio[3480]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 24 21:20:47 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Nov 24 21:20:47 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:47 rivo volumio[3480]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 24 21:20:48 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 21:20:48 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 21:20:48 rivo volumio[3480]: info: Discovery: Getting this device information Nov 24 21:20:48 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Nov 24 21:20:48 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 21:20:50 rivo volumio[3480]: info: TidalConnect service started! Nov 24 21:20:51 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 21:20:51 rivo volumio[3480]: info: Enabling MyMusic plugin multiroom Nov 24 21:20:51 rivo volumio[3480]: info: Enabling plugin multiroom Nov 24 21:20:51 rivo volumio[3480]: info: Loading plugin "multiroom"... Nov 24 21:20:51 rivo volumio[3480]: info: Applying required configuration parameters for plugin multiroom Nov 24 21:20:51 rivo sudo[9141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 24 21:20:51 rivo sudo[9141]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:51 rivo sudo[9141]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:51 rivo volumio[3480]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Nov 24 21:20:51 rivo volumio[3480]: info: MRS: MultiRoom plugin initialized Nov 24 21:20:51 rivo volumio[3480]: info: MRS: STOPPING SNAPCLIENT Nov 24 21:20:51 rivo volumio[3480]: info: MRS: Snap server stop Nov 24 21:20:51 rivo sudo[9157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 24 21:20:51 rivo sudo[9157]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:51 rivo volumio[3480]: info: MRS: STOPPING volumioStreaming Nov 24 21:20:51 rivo sudo[9160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Nov 24 21:20:51 rivo sudo[9160]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:51 rivo sudo[9157]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:51 rivo sudo[9163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 24 21:20:51 rivo sudo[9163]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:51 rivo sudo[9160]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:51 rivo volumio[3480]: info: Preparing to generate the ALSA configuration file Nov 24 21:20:51 rivo sudo[9166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 24 21:20:51 rivo sudo[9166]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:51 rivo volumio[3480]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 24 21:20:51 rivo sudo[9163]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:51 rivo volumio[3480]: info: Reading ALSA contributions from plugins. Nov 24 21:20:51 rivo volumio[3480]: info: MRS: SNAPSERVER STOPPED Nov 24 21:20:51 rivo sudo[9166]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:51 rivo volumio[3480]: info: MRS: SNAPCLIENT STOPPED Nov 24 21:20:51 rivo volumio[3480]: info: MRS: volumioStreaming STOPPED Nov 24 21:20:51 rivo volumio[3480]: info: MRS: Removed streaming files Nov 24 21:20:51 rivo volumio[3480]: info: Asound.conf file written Nov 24 21:20:51 rivo sudo[9169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 24 21:20:51 rivo sudo[9169]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:51 rivo sudo[9169]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:52 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Nov 24 21:20:52 rivo kernel: Err: can not set rate to 4274967296! Nov 24 21:20:52 rivo kernel: Range[3920000 - 500000000] Nov 24 21:20:52 rivo kernel: out of value, fixed it Nov 24 21:20:52 rivo kernel: id=0 set inskew=0 Nov 24 21:20:52 rivo volumio[3480]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Nov 24 21:20:52 rivo volumio[3480]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Nov 24 21:20:52 rivo volumio[3480]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Nov 24 21:20:52 rivo volumio[3480]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Nov 24 21:20:52 rivo volumio[3480]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Quloos QU02 Validity:0' : Operation not permitted Nov 24 21:20:52 rivo volumio[3480]: info: Output device has changed, restarting MPD Nov 24 21:20:52 rivo volumio[3480]: info: Output device has changed, restarting Shairport Sync Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:20:52 rivo sudo[9175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 21:20:52 rivo sudo[9175]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:52 rivo sudo[9175]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:52 rivo sudo[9176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 21:20:52 rivo sudo[9176]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:52 rivo systemd[1]: Stopping Music Player Daemon... Nov 24 21:20:52 rivo volumio[3480]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 21:20:52 rivo volumio[3480]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo systemd[1]: mpd.service: Succeeded. Nov 24 21:20:52 rivo systemd[1]: Stopped Music Player Daemon. Nov 24 21:20:52 rivo volumio[3480]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo systemd[1]: Starting Music Player Daemon... Nov 24 21:20:52 rivo vtcs[9113]: [2024-11-24 21:20:52.221] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Nov 24 21:20:52 rivo vtcs[9113]: [2024-11-24 21:20:52.222] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Nov 24 21:20:52 rivo vtcs[9113]: [2024-11-24 21:20:52.222] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 24 21:20:52 rivo volumio[3480]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo sudo[9194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 24 21:20:52 rivo sudo[9194]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:52 rivo volumio[3480]: info: PLUGIN START: multiroom Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Nov 24 21:20:52 rivo volumio[3480]: info: MRS: Pushing multiroomSync output for this device Nov 24 21:20:52 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Nov 24 21:20:52 rivo volumio[3480]: info: Adding audio output: Nov 24 21:20:52 rivo volumio[3480]: error: Can't add: Nov 24 21:20:52 rivo volumio[3480]: info: Adding audio output: Nov 24 21:20:52 rivo volumio[3480]: error: Can't add: Nov 24 21:20:52 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Nov 24 21:20:52 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 24 21:20:52 rivo systemd[1]: vtcs.service: Succeeded. Nov 24 21:20:52 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Nov 24 21:20:52 rivo sudo[9194]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:52 rivo volumio[3480]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo sudo[9183]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 24 21:20:52 rivo sudo[9183]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:52 rivo sudo[9183]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:52 rivo volumio[3480]: info: MPD Permissions set Nov 24 21:20:52 rivo volumio[3480]: error: Upnp client error: Error: This socket has been ended by the other party Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo sudo[9206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 24 21:20:52 rivo sudo[9206]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:52 rivo sudo[9206]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: Done. Nov 24 21:20:52 rivo volumio[3480]: info: Starting Shairport Sync Nov 24 21:20:52 rivo sudo[9217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 21:20:52 rivo sudo[9217]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:52 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 24 21:20:52 rivo systemd[1]: shairport-sync.service: Succeeded. Nov 24 21:20:52 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 24 21:20:52 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 24 21:20:52 rivo sudo[9217]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:52 rivo volumio[3480]: info: Shairport-Sync Started Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 21:20:52 rivo volumio[3480]: info: Not Reporting Auto name since its the default one Nov 24 21:20:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 24 21:20:52 rivo sudo[9230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 24 21:20:52 rivo sudo[9230]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:52 rivo sudo[9230]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:52 rivo sudo[9232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 24 21:20:52 rivo sudo[9232]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:52 rivo systemd[1]: Stopping RAAT DAEMON... Nov 24 21:20:52 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Nov 24 21:20:52 rivo systemd[1]: raat-daemon.service: Succeeded. Nov 24 21:20:52 rivo systemd[1]: Stopped RAAT DAEMON. Nov 24 21:20:52 rivo systemd[1]: Started RAAT DAEMON. Nov 24 21:20:52 rivo sudo[9232]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:52 rivo volumio[3480]: info: Raat Daemon started successfully Nov 24 21:20:53 rivo volumio[3480]: info: Executing endpoint restartRAATSocket Nov 24 21:20:53 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Nov 24 21:20:53 rivo volumio[3480]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 24 21:20:54 rivo mpd[9207]: Nov 24 21:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 24 21:20:54 rivo systemd[1]: Started Music Player Daemon. Nov 24 21:20:54 rivo sudo[9176]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:54 rivo volumio[3480]: error: updateQueue error: null Nov 24 21:20:55 rivo volumio[3480]: info: TidalConnect service stoped! Nov 24 21:20:55 rivo volumio[3480]: info: TidalConnect service stoped! Nov 24 21:20:55 rivo volumio[3480]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 24 21:20:55 rivo volumio[3480]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 24 21:20:55 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 21:20:55 rivo sudo[9260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 24 21:20:55 rivo sudo[9260]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:55 rivo systemd[1]: Started Volumio Tidal Connect Service. Nov 24 21:20:55 rivo sudo[9260]: pam_unix(sudo:session): session closed for user root Nov 24 21:20:55 rivo volumio[3480]: info: Executing endpoint tc_getconfig Nov 24 21:20:55 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 24 21:20:55 rivo vtcs[9262]: STARTING TidalConnect services, version: 1.4.0.34 Nov 24 21:20:56 rivo vtcs[9262]: STARTED TidalConnect services. Nov 24 21:20:56 rivo volumio[3480]: info: Executing endpoint tc_connect Nov 24 21:20:56 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 24 21:20:56 rivo volumio[3480]: info: Connecting to TidalConnect Nov 24 21:20:56 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Nov 24 21:20:56 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:56 rivo volumio[3480]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 24 21:20:56 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Nov 24 21:20:56 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Nov 24 21:20:56 rivo volumio[3480]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 24 21:20:56 rivo volumio[3480]: info: Disabling MyMusic plugin upnp Nov 24 21:20:56 rivo sudo[9284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Nov 24 21:20:56 rivo sudo[9284]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 21:20:56 rivo systemd[1]: Stopping UPnP Renderer front-end to MPD... Nov 24 21:20:56 rivo volumio[3480]: error: Upnp client error: Error: This socket has been ended by the other party Nov 24 21:20:57 rivo volumio[3480]: info: MRS: Getting audio outputs on start Nov 24 21:20:57 rivo volumio[3480]: info: MRS: Requesting all other devices output Nov 24 21:20:58 rivo volumio[3480]: info: TidalConnect service started! Nov 24 21:20:59 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 21:21:03 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 21:21:04 rivo volumio[3480]: info: Enabling MyMusic plugin upnp Nov 24 21:21:04 rivo volumio[3480]: info: Enabling plugin upnp Nov 24 21:21:04 rivo volumio[3480]: info: Loading plugin "upnp"... Nov 24 21:21:04 rivo volumio[3480]: info: [1732479664729] Starting Upmpd Daemon Nov 24 21:21:04 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 24 21:21:04 rivo volumio[3480]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 21:21:04 rivo volumio[3480]: Error: listen EADDRINUSE: address already in use :::6599 Nov 24 21:21:04 rivo volumio[3480]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Nov 24 21:21:04 rivo volumio[3480]: at listenInCluster (net.js:1379:12) Nov 24 21:21:04 rivo volumio[3480]: at Server.listen (net.js:1465:7) Nov 24 21:21:04 rivo volumio[3480]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Nov 24 21:21:04 rivo volumio[3480]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Nov 24 21:21:04 rivo volumio[3480]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Nov 24 21:21:04 rivo volumio[3480]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 24 21:21:04 rivo volumio[3480]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Nov 24 21:21:04 rivo volumio[3480]: code: 'EADDRINUSE', Nov 24 21:21:04 rivo volumio[3480]: errno: -98, Nov 24 21:21:04 rivo volumio[3480]: syscall: 'listen', Nov 24 21:21:04 rivo volumio[3480]: address: '::', Nov 24 21:21:04 rivo volumio[3480]: port: 6599 Nov 24 21:21:04 rivo volumio[3480]: } Nov 24 21:21:04 rivo volumio[3480]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 21:21:05 rivo sudo[9312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 21:20 Nov 24 21:21:05 rivo sudo[9312]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:47:06 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e08c3e210a64867536255c2503fb09"