Oct 16 15:15:06 rivo volumio[3214]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"} Oct 16 15:15:06 rivo volumio[3214]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"} Oct 16 15:15:06 rivo volumio[3214]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"} Oct 16 15:15:06 rivo volumio[3214]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"} Oct 16 15:15:06 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:15:06 rivo volumio[3214]: info: CorePlayQueue::getTrack 34 Oct 16 15:15:07 rivo volumio[3214]: info: [squeezelite_mc] Notification listener started Oct 16 15:15:07 rivo volumio[3214]: info: [squeezelite_mc] Getting players connected to pCP-Oct-26 (192.168.1.4) Oct 16 15:15:07 rivo volumio[3214]: info: [squeezelite_mc] Notification listener started Oct 16 15:15:07 rivo volumio[3214]: info: [squeezelite_mc] Getting players connected to Music (192.168.1.4) Oct 16 15:15:07 rivo volumio[3214]: info: [squeezelite_mc] Players connected to pCP-Oct-26 (192.168.1.4): [{"id":"b8:27:eb:ed:a8:de","uuid":null,"ip":"192.168.1.13","name":"Pi3-player","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}},{"id":"02:00:00:13:2d:01","uuid":null,"ip":"192.168.1.5","name":"rivo","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}}] Oct 16 15:15:07 rivo volumio[3214]: info: [squeezelite_mc] Player found: {"id":"02:00:00:13:2d:01","uuid":null,"ip":"192.168.1.5","name":"rivo","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}} Oct 16 15:15:07 rivo volumio[3214]: info: [squeezelite_mc] Players connected to Music (192.168.1.4): [{"id":"b8:27:eb:ed:a8:de","uuid":null,"ip":"192.168.1.13","name":"Pi3-player","server":{"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"}},{"id":"02:00:00:13:2d:01","uuid":null,"ip":"192.168.1.5","name":"rivo","server":{"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"}}] Oct 16 15:15:08 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:15:08 rivo volumio[3214]: info: CorePlayQueue::getTrack 34 Oct 16 15:15:09 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 28794. Oct 16 15:15:09 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 15:15:09 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 15:15:09 rivo upmpdcli[3953]: Could not open config: /tmp/upmpdcli.conf Oct 16 15:15:10 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 15:15:10 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 15:15:12 rivo volumio[3214]: info: [squeezelite_mc] 'client' notification received from pCP-Oct-26 (192.168.1.4); type is 'disconnect' Oct 16 15:15:12 rivo volumio[3214]: info: [squeezelite_mc] 'client' notification received from pCP-Oct-26 (192.168.1.4); type is 'disconnect' Oct 16 15:15:12 rivo volumio[3214]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"} Oct 16 15:15:12 rivo volumio[3214]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"} Oct 16 15:15:12 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:15:12 rivo volumio[3214]: info: CorePlayQueue::getTrack 34 Oct 16 15:15:25 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 28795. Oct 16 15:15:25 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 15:15:25 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 15:15:25 rivo upmpdcli[4000]: Could not open config: /tmp/upmpdcli.conf Oct 16 15:15:25 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 15:15:25 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 15:15:25 rivo volumio[3214]: info: CorePlayQueue::getTrack 34 Oct 16 15:15:25 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:25 rivo volumio[3214]: info: Prefetching next song Oct 16 15:15:25 rivo volumio[3214]: info: [1792116925349] ControllerQobuz::prefetch Oct 16 15:15:25 rivo volumio[3214]: info: getStreamUrl took 586 milliseconds Oct 16 15:15:25 rivo volumio[3214]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=14310556&eid=356711175&fmt=6&profile=raw&app_id=539451548&cid=4550480&etsp=1792120525&hmac=JmIsvFScbjqwIb8fGholHVdh_hE" Oct 16 15:15:27 rivo volumio[3214]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=14310556&eid=356711175&fmt=6&profile=raw&app_id=539451548&cid=4550480&etsp=1792120525&hmac=JmIsvFScbjqwIb8fGholHVdh_hE" Oct 16 15:15:27 rivo volumio[3214]: info: Oct 16 15:15:27 rivo volumio[3214]: ---------------------------- MPD announces system playlist update Oct 16 15:15:27 rivo volumio[3214]: info: Ignoring MPD Status Update Oct 16 15:15:27 rivo volumio[3214]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=14310556&eid=356711175&fmt=6&profile=raw&app_id=539451548&cid=4550480&etsp=1792120525&hmac=JmIsvFScbjqwIb8fGholHVdh_hE" took 3 milliseconds Oct 16 15:15:27 rivo volumio[3214]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 15:15:27 rivo volumio[3214]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 16 15:15:27 rivo volumio[3214]: info: Oct 16 15:15:27 rivo volumio[3214]: ---------------------------- MPD announces system playlist update Oct 16 15:15:27 rivo volumio[3214]: info: Ignoring MPD Status Update Oct 16 15:15:27 rivo volumio[3214]: info: Oct 16 15:15:27 rivo volumio[3214]: ---------------------------- MPD announces system playlist update Oct 16 15:15:27 rivo volumio[3214]: info: Ignoring MPD Status Update Oct 16 15:15:27 rivo volumio[3214]: info: ------------------------------ 5ms Oct 16 15:15:27 rivo volumio[3214]: info: sendMpdCommand consume 1 took 3 milliseconds Oct 16 15:15:27 rivo volumio[3214]: info: ------------------------------ 4ms Oct 16 15:15:27 rivo volumio[3214]: info: ------------------------------ 3ms Oct 16 15:15:29 rivo volumio[3214]: info: CoreStateMachine::startPlaybackTimer Oct 16 15:15:29 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: CoreStateMachine::pushState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::volumioPushState Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: Oct 16 15:15:30 rivo volumio[3214]: ---------------------------- MPD announces system playlist update Oct 16 15:15:30 rivo volumio[3214]: info: Ignoring MPD Status Update Oct 16 15:15:30 rivo volumio[3214]: info: Oct 16 15:15:30 rivo volumio[3214]: ---------------------------- MPD announces state update: player Oct 16 15:15:30 rivo volumio[3214]: info: ControllerMpd::getState Oct 16 15:15:30 rivo volumio[3214]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:15:30 rivo volumio[3214]: info: Oct 16 15:15:30 rivo volumio[3214]: ---------------------------- MPD announces system playlist update Oct 16 15:15:30 rivo volumio[3214]: info: Ignoring MPD Status Update Oct 16 15:15:30 rivo volumio[3214]: info: Oct 16 15:15:30 rivo volumio[3214]: ---------------------------- MPD announces state update: player Oct 16 15:15:30 rivo volumio[3214]: info: ControllerMpd::getState Oct 16 15:15:30 rivo volumio[3214]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:15:30 rivo volumio[3214]: info: Oct 16 15:15:30 rivo volumio[3214]: ---------------------------- MPD announces system playlist update Oct 16 15:15:30 rivo volumio[3214]: info: Ignoring MPD Status Update Oct 16 15:15:30 rivo volumio[3214]: info: Oct 16 15:15:30 rivo volumio[3214]: ---------------------------- MPD announces state update: player Oct 16 15:15:30 rivo volumio[3214]: info: ControllerMpd::getState Oct 16 15:15:30 rivo volumio[3214]: verbose: ControllerMpd::sendMpdCommand status Oct 16 15:15:30 rivo volumio[3214]: info: ------------------------------ 7ms Oct 16 15:15:30 rivo volumio[3214]: info: sendMpdCommand status took 6 milliseconds Oct 16 15:15:30 rivo volumio[3214]: info: ------------------------------ 6ms Oct 16 15:15:30 rivo volumio[3214]: info: sendMpdCommand status took 5 milliseconds Oct 16 15:15:30 rivo volumio[3214]: info: ------------------------------ 4ms Oct 16 15:15:30 rivo volumio[3214]: info: sendMpdCommand status took 4 milliseconds Oct 16 15:15:30 rivo volumio[3214]: verbose: ControllerMpd::parseState Oct 16 15:15:30 rivo volumio[3214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:15:30 rivo volumio[3214]: verbose: ControllerMpd::parseState Oct 16 15:15:30 rivo volumio[3214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:15:30 rivo volumio[3214]: verbose: ControllerMpd::parseState Oct 16 15:15:30 rivo volumio[3214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 15:15:30 rivo volumio[3214]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 16 15:15:30 rivo volumio[3214]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 15:15:30 rivo volumio[3214]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 16 15:15:30 rivo volumio[3214]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:15:30 rivo volumio[3214]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:15:30 rivo volumio[3214]: verbose: ControllerMpd::parseTrackInfo Oct 16 15:15:30 rivo volumio[3214]: info: ControllerMpd::pushState Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::servicePushState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"479 Kbps","isStreaming":false,"title":"file?uid=14310556&eid=356711175&fmt=6&profile=raw&app_id=539451548&cid=4550480&etsp=1792120525&hmac=JmIsvFScbjqwIb8fGholHVdh_hE","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=14310556&eid=356711175&fmt=6&profile=raw&app_id=539451548&cid=4550480&etsp=1792120525&hmac=JmIsvFScbjqwIb8fGholHVdh_hE","trackType":"qobuz"} Oct 16 15:15:30 rivo volumio[3214]: verbose: CURRENT POSITION 35 Oct 16 15:15:30 rivo volumio[3214]: info: CoreStateMachine::syncState stateService play Oct 16 15:15:30 rivo volumio[3214]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:15:30 rivo volumio[3214]: info: Received an update from plugin. extracting info from payload Oct 16 15:15:30 rivo volumio[3214]: info: CoreStateMachine::pushState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::volumioPushState Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: CoreStateMachine::pushState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::volumioPushState Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: ControllerMpd::pushState Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::servicePushState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"479 Kbps","isStreaming":false,"title":"file?uid=14310556&eid=356711175&fmt=6&profile=raw&app_id=539451548&cid=4550480&etsp=1792120525&hmac=JmIsvFScbjqwIb8fGholHVdh_hE","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=14310556&eid=356711175&fmt=6&profile=raw&app_id=539451548&cid=4550480&etsp=1792120525&hmac=JmIsvFScbjqwIb8fGholHVdh_hE","trackType":"qobuz"} Oct 16 15:15:30 rivo volumio[3214]: verbose: CURRENT POSITION 35 Oct 16 15:15:30 rivo volumio[3214]: info: CoreStateMachine::syncState stateService play Oct 16 15:15:30 rivo volumio[3214]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:15:30 rivo volumio[3214]: info: Received an update from plugin. extracting info from payload Oct 16 15:15:30 rivo volumio[3214]: info: CoreStateMachine::pushState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::volumioPushState Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: CoreStateMachine::pushState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::volumioPushState Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: ControllerMpd::pushState Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::servicePushState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"479 Kbps","isStreaming":false,"title":"file?uid=14310556&eid=356711175&fmt=6&profile=raw&app_id=539451548&cid=4550480&etsp=1792120525&hmac=JmIsvFScbjqwIb8fGholHVdh_hE","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=14310556&eid=356711175&fmt=6&profile=raw&app_id=539451548&cid=4550480&etsp=1792120525&hmac=JmIsvFScbjqwIb8fGholHVdh_hE","trackType":"qobuz"} Oct 16 15:15:30 rivo volumio[3214]: verbose: CURRENT POSITION 35 Oct 16 15:15:30 rivo volumio[3214]: info: CoreStateMachine::syncState stateService play Oct 16 15:15:30 rivo volumio[3214]: info: CoreStateMachine::syncState currentStatus play Oct 16 15:15:30 rivo volumio[3214]: info: Received an update from plugin. extracting info from payload Oct 16 15:15:30 rivo volumio[3214]: info: CoreStateMachine::pushState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::volumioPushState Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: CoreStateMachine::pushState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::volumioPushState Oct 16 15:15:30 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:15:30 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:30 rivo volumio[3214]: info: ------------------------------ 55ms Oct 16 15:15:30 rivo volumio[3214]: info: ------------------------------ 54ms Oct 16 15:15:30 rivo volumio[3214]: info: ------------------------------ 53ms Oct 16 15:15:33 rivo volumio[3214]: info: [squeezelite_mc] 'client' notification received from pCP-Oct-26 (192.168.1.4); type is 'disconnect' Oct 16 15:15:36 rivo volumio[3214]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"} Oct 16 15:15:36 rivo volumio[3214]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"} Oct 16 15:15:36 rivo volumio[3214]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"} Oct 16 15:15:36 rivo volumio[3214]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"} Oct 16 15:15:37 rivo volumio[3214]: info: [squeezelite_mc] Notification listener started Oct 16 15:15:37 rivo volumio[3214]: info: [squeezelite_mc] Getting players connected to pCP-Oct-26 (192.168.1.4) Oct 16 15:15:37 rivo volumio[3214]: info: [squeezelite_mc] Notification listener started Oct 16 15:15:37 rivo volumio[3214]: info: [squeezelite_mc] Getting players connected to Music (192.168.1.4) Oct 16 15:15:37 rivo volumio[3214]: info: [squeezelite_mc] Players connected to pCP-Oct-26 (192.168.1.4): [{"id":"02:00:00:13:2d:01","uuid":null,"ip":"192.168.1.5","name":"rivo","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:ed:a8:de","uuid":null,"ip":"192.168.1.13","name":"Pi3-player","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}}] Oct 16 15:15:37 rivo volumio[3214]: info: [squeezelite_mc] Player found: {"id":"02:00:00:13:2d:01","uuid":null,"ip":"192.168.1.5","name":"rivo","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}} Oct 16 15:15:37 rivo volumio[3214]: info: [squeezelite_mc] Players connected to Music (192.168.1.4): [{"id":"02:00:00:13:2d:01","uuid":null,"ip":"192.168.1.5","name":"rivo","server":{"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:ed:a8:de","uuid":null,"ip":"192.168.1.13","name":"Pi3-player","server":{"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"}}] Oct 16 15:15:38 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:15:38 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:40 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 28796. Oct 16 15:15:40 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 15:15:40 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 15:15:40 rivo upmpdcli[4040]: Could not open config: /tmp/upmpdcli.conf Oct 16 15:15:40 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 15:15:40 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 15:15:53 rivo volumio[3214]: info: [squeezelite_mc] 'client' notification received from pCP-Oct-26 (192.168.1.4); type is 'disconnect' Oct 16 15:15:53 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:15:53 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:15:53 rivo volumio[3214]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"} Oct 16 15:15:55 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 28797. Oct 16 15:15:55 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 15:15:55 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 15:15:55 rivo upmpdcli[4087]: Could not open config: /tmp/upmpdcli.conf Oct 16 15:15:55 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 15:15:55 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 15:16:06 rivo volumio[3214]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"} Oct 16 15:16:06 rivo volumio[3214]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"} Oct 16 15:16:06 rivo volumio[3214]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"} Oct 16 15:16:06 rivo volumio[3214]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"} Oct 16 15:16:07 rivo volumio[3214]: info: [squeezelite_mc] Notification listener started Oct 16 15:16:07 rivo volumio[3214]: info: [squeezelite_mc] Getting players connected to pCP-Oct-26 (192.168.1.4) Oct 16 15:16:07 rivo volumio[3214]: info: [squeezelite_mc] Notification listener started Oct 16 15:16:07 rivo volumio[3214]: info: [squeezelite_mc] Getting players connected to Music (192.168.1.4) Oct 16 15:16:07 rivo volumio[3214]: info: [squeezelite_mc] Players connected to pCP-Oct-26 (192.168.1.4): [{"id":"02:00:00:13:2d:01","uuid":null,"ip":"192.168.1.5","name":"rivo","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:ed:a8:de","uuid":null,"ip":"192.168.1.13","name":"Pi3-player","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}}] Oct 16 15:16:07 rivo volumio[3214]: info: [squeezelite_mc] Player found: {"id":"02:00:00:13:2d:01","uuid":null,"ip":"192.168.1.5","name":"rivo","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}} Oct 16 15:16:07 rivo volumio[3214]: info: [squeezelite_mc] Players connected to Music (192.168.1.4): [{"id":"02:00:00:13:2d:01","uuid":null,"ip":"192.168.1.5","name":"rivo","server":{"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:ed:a8:de","uuid":null,"ip":"192.168.1.13","name":"Pi3-player","server":{"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"}}] Oct 16 15:16:08 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:16:08 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:16:10 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 28798. Oct 16 15:16:10 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 15:16:10 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 15:16:10 rivo upmpdcli[4115]: Could not open config: /tmp/upmpdcli.conf Oct 16 15:16:11 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 15:16:11 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 15:16:26 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 28799. Oct 16 15:16:26 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 15:16:26 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 15:16:26 rivo upmpdcli[4167]: Could not open config: /tmp/upmpdcli.conf Oct 16 15:16:26 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 15:16:26 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 15:16:31 rivo volumio[3214]: info: [squeezelite_mc] 'client' notification received from pCP-Oct-26 (192.168.1.4); type is 'disconnect' Oct 16 15:16:31 rivo volumio[3214]: info: [squeezelite_mc] 'client' notification received from pCP-Oct-26 (192.168.1.4); type is 'disconnect' Oct 16 15:16:31 rivo volumio[3214]: info: [squeezelite_mc] 'client' notification received from Music (192.168.1.4); type is 'disconnect' Oct 16 15:16:35 rivo volumio[3214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 15:16:35 rivo volumio[3214]: info: Received Get System Info Oct 16 15:16:35 rivo volumio[3214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 15:16:35 rivo volumio[3214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 15:16:35 rivo volumio[3214]: info: Discovery: Getting this device information Oct 16 15:16:35 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:16:35 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:16:35 rivo volumio[3214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 15:16:36 rivo volumio[3214]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"} Oct 16 15:16:36 rivo volumio[3214]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"} Oct 16 15:16:36 rivo volumio[3214]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"} Oct 16 15:16:36 rivo volumio[3214]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"} Oct 16 15:16:36 rivo volumio[3214]: info: CoreCommandRouter::volumioGetState Oct 16 15:16:36 rivo volumio[3214]: info: CorePlayQueue::getTrack 35 Oct 16 15:16:37 rivo volumio[3214]: info: [squeezelite_mc] 'client' notification received from pCP-Oct-26 (192.168.1.4); type is 'connect' Oct 16 15:16:37 rivo volumio[3214]: info: [squeezelite_mc] Getting players connected to pCP-Oct-26 (192.168.1.4) Oct 16 15:16:37 rivo volumio[3214]: info: [squeezelite_mc] 'client' notification received from pCP-Oct-26 (192.168.1.4); type is 'connect' Oct 16 15:16:37 rivo volumio[3214]: info: [squeezelite_mc] Getting players connected to pCP-Oct-26 (192.168.1.4) Oct 16 15:16:37 rivo volumio[3214]: info: [squeezelite_mc] Players connected to pCP-Oct-26 (192.168.1.4): [{"id":"02:00:00:13:2d:01","uuid":null,"ip":"192.168.1.5","name":"rivo","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:ed:a8:de","uuid":null,"ip":"192.168.1.13","name":"Pi3-player","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}}] Oct 16 15:16:37 rivo volumio[3214]: info: [squeezelite_mc] Players connected to pCP-Oct-26 (192.168.1.4): [{"id":"02:00:00:13:2d:01","uuid":null,"ip":"192.168.1.5","name":"rivo","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:ed:a8:de","uuid":null,"ip":"192.168.1.13","name":"Pi3-player","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}}] Oct 16 15:16:37 rivo volumio[3214]: info: [squeezelite_mc] Notification listener started Oct 16 15:16:37 rivo volumio[3214]: info: [squeezelite_mc] Getting players connected to pCP-Oct-26 (192.168.1.4) Oct 16 15:16:37 rivo volumio[3214]: info: [squeezelite_mc] Notification listener started Oct 16 15:16:37 rivo volumio[3214]: info: [squeezelite_mc] Getting players connected to Music (192.168.1.4) Oct 16 15:16:37 rivo volumio[3214]: info: [squeezelite_mc] Players connected to pCP-Oct-26 (192.168.1.4): [{"id":"02:00:00:13:2d:01","uuid":null,"ip":"192.168.1.5","name":"rivo","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:ed:a8:de","uuid":null,"ip":"192.168.1.13","name":"Pi3-player","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}}] Oct 16 15:16:37 rivo volumio[3214]: info: [squeezelite_mc] Player found: {"id":"02:00:00:13:2d:01","uuid":null,"ip":"192.168.1.5","name":"rivo","server":{"ip":"192.168.1.4","name":"pCP-Oct-26","ver":"9.1.2","uuid":"5f3573d0-1115-4194-a823-5968f5e4fbcb","jsonPort":"9000","cliPort":"9090"}} Oct 16 15:16:37 rivo volumio[3214]: info: [squeezelite_mc] Players connected to Music (192.168.1.4): [{"id":"02:00:00:13:2d:01","uuid":null,"ip":"192.168.1.5","name":"rivo","server":{"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:ed:a8:de","uuid":null,"ip":"192.168.1.13","name":"Pi3-player","server":{"ip":"192.168.1.4","name":"Music","ver":"9.1.1","uuid":"13b7346b-a004-4606-8c45-7742b2ca74fc","jsonPort":"9000","cliPort":"9090"}}] Oct 16 15:16:40 rivo volumio[3214]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 15:16:40 rivo volumio[3214]: NotificationListenerError: Failed to send server command 'subscribe play,stop,pause,playlist,mixer,sync'. Oct 16 15:16:40 rivo volumio[3214]: at CommandQueue._CommandQueue_shiftAndExec (/data/plugins/music_service/squeezelite_mc/node_modules/lms-cli-notifications/dist/cjs/utils/CommandQueue.js:91:26) { Oct 16 15:16:40 rivo volumio[3214]: code: -20, Oct 16 15:16:40 rivo volumio[3214]: cause: Error: response not received Oct 16 15:16:40 rivo volumio[3214]: at Timeout._onTimeout (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:297:48) Oct 16 15:16:40 rivo volumio[3214]: at listOnTimeout (node:internal/timers:573:17) Oct 16 15:16:40 rivo volumio[3214]: at process.processTimers (node:internal/timers:514:7) Oct 16 15:16:40 rivo volumio[3214]: } Oct 16 15:16:40 rivo volumio[3214]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 15:16:41 rivo sudo[4203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-16 15:15' Oct 16 15:16:41 rivo sudo[4203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:27:36 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="be0c94671215c2b1bf2440825a1e4211"