-- Logs begin at Fri 2026-04-03 20:40:38 +03, end at Fri 2026-04-03 21:14:40 +03. -- Apr 03 21:13:00 primo volumio[7763]: STREAMING PROXY: Resume ended. Received 51035582 bytes Apr 03 21:13:12 primo volumio[7763]: info: CoreCommandRouter::volumioPlay Apr 03 21:13:12 primo volumio[7763]: info: CoreStateMachine::play index undefined Apr 03 21:13:12 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 21:13:12 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:12 primo volumio[7763]: info: CoreStateMachine::startPlaybackTimer Apr 03 21:13:12 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:12 primo volumio[7763]: info: [1775239992244] ControllerQobuz::clearAddPlayTrack Apr 03 21:13:12 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 21:13:13 primo volumio[7763]: info: CoreCommandRouter::volumioPlay Apr 03 21:13:13 primo volumio[7763]: info: CoreStateMachine::play index undefined Apr 03 21:13:13 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 21:13:13 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:13 primo volumio[7763]: info: CoreStateMachine::startPlaybackTimer Apr 03 21:13:13 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:13 primo volumio[7763]: info: [1775239993375] ControllerQobuz::clearAddPlayTrack Apr 03 21:13:13 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 21:13:14 primo volumio[7763]: info: CoreCommandRouter::volumioPlay Apr 03 21:13:14 primo volumio[7763]: info: CoreStateMachine::play index undefined Apr 03 21:13:14 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 21:13:14 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:14 primo volumio[7763]: info: CoreStateMachine::startPlaybackTimer Apr 03 21:13:14 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:14 primo volumio[7763]: info: [1775239994108] ControllerQobuz::clearAddPlayTrack Apr 03 21:13:14 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 21:13:14 primo volumio[7763]: info: CoreCommandRouter::volumioPlay Apr 03 21:13:14 primo volumio[7763]: info: CoreStateMachine::play index undefined Apr 03 21:13:14 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 21:13:14 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:14 primo volumio[7763]: info: CoreStateMachine::startPlaybackTimer Apr 03 21:13:14 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:14 primo volumio[7763]: info: [1775239994591] ControllerQobuz::clearAddPlayTrack Apr 03 21:13:14 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 21:13:15 primo volumio[7763]: info: CoreCommandRouter::volumioPlay Apr 03 21:13:15 primo volumio[7763]: info: CoreStateMachine::play index undefined Apr 03 21:13:15 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 21:13:15 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:15 primo volumio[7763]: info: CoreStateMachine::startPlaybackTimer Apr 03 21:13:15 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:15 primo volumio[7763]: info: [1775239995043] ControllerQobuz::clearAddPlayTrack Apr 03 21:13:15 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 21:13:39 primo volumio[7763]: info: CoreCommandRouter::volumioPlay Apr 03 21:13:39 primo volumio[7763]: info: CoreStateMachine::play index undefined Apr 03 21:13:39 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 21:13:39 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:39 primo volumio[7763]: info: CoreStateMachine::startPlaybackTimer Apr 03 21:13:39 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:39 primo volumio[7763]: info: [1775240019459] ControllerQobuz::clearAddPlayTrack Apr 03 21:13:39 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 21:13:41 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Apr 03 21:13:41 primo volumio[7763]: info: MRS: Starting browser stream Apr 03 21:13:41 primo volumio[7763]: info: MRS: Setting this device as Streaming Server Apr 03 21:13:41 primo volumio[7763]: info: Apr 03 21:13:41 primo volumio[7763]: [1775240021994] ---------------------------- MRS: Setting Streaming Server Apr 03 21:13:42 primo volumio[7763]: info: Enabled audio output: browserPlayback Apr 03 21:13:42 primo volumio[7763]: info: MRS: enable multiroom server output Apr 03 21:13:42 primo volumio[7763]: info: MRS: Set multiroom target PCM to volumioMultiRoom Apr 03 21:13:42 primo volumio[7763]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Apr 03 21:13:42 primo volumio[7763]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Apr 03 21:13:42 primo volumio[7763]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Apr 03 21:13:42 primo volumio[7763]: info: MRS: STARTING volumioStreaming Apr 03 21:13:42 primo sudo[10275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 03 21:13:42 primo sudo[10275]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 21:13:42 primo sudo[10275]: pam_unix(sudo:session): session closed for user root Apr 03 21:13:42 primo sudo[10277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Apr 03 21:13:42 primo sudo[10277]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 21:13:42 primo systemd[1]: Started VolumioStreamingService. Apr 03 21:13:42 primo sudo[10277]: pam_unix(sudo:session): session closed for user root Apr 03 21:13:42 primo volumio[7763]: info: MRS: volumioStreaming STARTED Apr 03 21:13:42 primo sudo[10286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 03 21:13:42 primo sudo[10286]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 21:13:42 primo sudo[10286]: pam_unix(sudo:session): session closed for user root Apr 03 21:13:43 primo volumio[7763]: STREAMING PROXY: Upstream status code: 200 Apr 03 21:13:43 primo mpd[8018]: exception: No such playlist Apr 03 21:13:43 primo volumio[7763]: STREAMING PROXY: Client closed connection Apr 03 21:13:43 primo volumio[7763]: STREAMING PROXY: Upstream closed. Received 15708 bytes Apr 03 21:13:43 primo volumio[7763]: STREAMING PROXY: Incomplete transfer. Expected 51051290, got 15708 Apr 03 21:13:43 primo volumio[7763]: STREAMING PROXY: Retrying with range: bytes=15708- (attempt 1) Apr 03 21:13:43 primo volumio[7763]: STREAMING PROXY: Handling url /?data=qobuz://song/18454013 Apr 03 21:13:44 primo volumio[7763]: info: Executing endpoint getStreamUrlqobuz Apr 03 21:13:44 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 03 21:13:44 primo volumio[7763]: info: CoreCommandRouter::volumioPlay Apr 03 21:13:44 primo volumio[7763]: info: CoreStateMachine::play index undefined Apr 03 21:13:44 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 21:13:44 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:44 primo volumio[7763]: info: CoreStateMachine::startPlaybackTimer Apr 03 21:13:44 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:44 primo volumio[7763]: info: [1775240024294] ControllerQobuz::clearAddPlayTrack Apr 03 21:13:44 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 21:13:44 primo volumio[7763]: info: getStreamUrl took 390 milliseconds Apr 03 21:13:44 primo volumio[7763]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=11451729&eid=18454013&fmt=7&profile=raw&app_id=539451548&cid=4089136&etsp=1775243624&hmac=C8fKfQb2m70UekvqWOURohFYQXI Apr 03 21:13:44 primo volumio[7763]: STREAMING PROXY: Requesting upstream with range: none Apr 03 21:13:44 primo volumio[7763]: STREAMING PROXY: Resuming with range: bytes=15708- Apr 03 21:13:45 primo volumio[7763]: STREAMING PROXY: Resume upstream status code: 206 Apr 03 21:13:46 primo volumio[7763]: STREAMING PROXY: Upstream status code: 200 Apr 03 21:13:46 primo volumio[7763]: STREAMING PROXY: Client closed connection Apr 03 21:13:46 primo volumio[7763]: STREAMING PROXY: Upstream closed. Received 15708 bytes Apr 03 21:13:46 primo volumio[7763]: STREAMING PROXY: Incomplete transfer. Expected 51051290, got 15708 Apr 03 21:13:46 primo volumio[7763]: STREAMING PROXY: Retrying with range: bytes=15708- (attempt 1) Apr 03 21:13:46 primo mpd[8018]: exception: No such playlist Apr 03 21:13:46 primo volumio[7763]: info: Apr 03 21:13:46 primo volumio[7763]: ---------------------------- MPD announces system playlist update Apr 03 21:13:46 primo volumio[7763]: info: Ignoring MPD Status Update Apr 03 21:13:46 primo volumio[7763]: error: MPD error: Error: write EPIPE Apr 03 21:13:46 primo volumio[7763]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 03 21:13:46 primo volumio[7763]: info: Apr 03 21:13:46 primo volumio[7763]: ---------------------------- MPD announces system playlist update Apr 03 21:13:46 primo volumio[7763]: info: Ignoring MPD Status Update Apr 03 21:13:46 primo volumio[7763]: error: MPD error: Error: write EPIPE Apr 03 21:13:46 primo volumio[7763]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 03 21:13:46 primo volumio[7763]: error: updateQueue error: null Apr 03 21:13:46 primo volumio[7763]: error: updateQueue error: null Apr 03 21:13:46 primo volumio[7763]: info: Apr 03 21:13:46 primo volumio[7763]: ---------------------------- MPD announces system playlist update Apr 03 21:13:46 primo volumio[7763]: info: Ignoring MPD Status Update Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/18454013" Apr 03 21:13:46 primo volumio[7763]: info: ------------------------------ 154726ms Apr 03 21:13:46 primo volumio[7763]: info: ------------------------------ 154724ms Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/18454013" took 154030 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 152073 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 146673 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 144252 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 136310 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 134412 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 106949 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 101718 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 100886 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 93840 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 92527 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 34205 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 33076 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 32342 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 31860 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 31409 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 6992 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand stop took 2158 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: ------------------------------ 34ms Apr 03 21:13:46 primo volumio[7763]: info: ------------------------------ 27ms Apr 03 21:13:46 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand play Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 21:13:46 primo volumio[7763]: STREAMING PROXY: Handling url /?data=qobuz://song/18454013 Apr 03 21:13:46 primo mpd[8018]: player: played "http://127.0.0.1:3245?data=qobuz://song/18454013" Apr 03 21:13:46 primo volumio[7763]: info: Apr 03 21:13:46 primo volumio[7763]: ---------------------------- MPD announces system playlist update Apr 03 21:13:46 primo volumio[7763]: info: Ignoring MPD Status Update Apr 03 21:13:46 primo volumio[7763]: info: Apr 03 21:13:46 primo volumio[7763]: ---------------------------- MPD announces system playlist update Apr 03 21:13:46 primo volumio[7763]: info: Ignoring MPD Status Update Apr 03 21:13:46 primo volumio[7763]: info: Apr 03 21:13:46 primo volumio[7763]: ---------------------------- MPD announces system playlist update Apr 03 21:13:46 primo volumio[7763]: info: Ignoring MPD Status Update Apr 03 21:13:46 primo volumio[7763]: info: Apr 03 21:13:46 primo volumio[7763]: ---------------------------- MPD announces system playlist update Apr 03 21:13:46 primo volumio[7763]: info: Ignoring MPD Status Update Apr 03 21:13:46 primo volumio[7763]: info: Apr 03 21:13:46 primo volumio[7763]: ---------------------------- MPD announces system playlist update Apr 03 21:13:46 primo volumio[7763]: info: Ignoring MPD Status Update Apr 03 21:13:46 primo volumio[7763]: info: Apr 03 21:13:46 primo volumio[7763]: ---------------------------- MPD announces system playlist update Apr 03 21:13:46 primo volumio[7763]: info: Ignoring MPD Status Update Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/18454013" took 38 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/18454013" took 38 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/18454013" took 39 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/18454013" took 39 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/18454013" took 40 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/18454013" took 39 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/18454013" took 40 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: ------------------------------ 44ms Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand play took 24 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand play Apr 03 21:13:46 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand play Apr 03 21:13:46 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand play Apr 03 21:13:46 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand play Apr 03 21:13:46 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand play Apr 03 21:13:46 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand play Apr 03 21:13:46 primo volumio[7763]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand play Apr 03 21:13:46 primo volumio[7763]: info: Apr 03 21:13:46 primo volumio[7763]: ---------------------------- MPD announces system playlist update Apr 03 21:13:46 primo volumio[7763]: info: Ignoring MPD Status Update Apr 03 21:13:46 primo volumio[7763]: info: Apr 03 21:13:46 primo volumio[7763]: ---------------------------- MPD announces state update: player Apr 03 21:13:46 primo volumio[7763]: info: Apr 03 21:13:46 primo volumio[7763]: ---------------------------- MPD announces system playlist update Apr 03 21:13:46 primo volumio[7763]: info: Ignoring MPD Status Update Apr 03 21:13:46 primo volumio[7763]: error: updateQueue error: null Apr 03 21:13:46 primo volumio[7763]: error: updateQueue error: null Apr 03 21:13:46 primo volumio[7763]: error: updateQueue error: null Apr 03 21:13:46 primo volumio[7763]: error: updateQueue error: null Apr 03 21:13:46 primo volumio[7763]: error: updateQueue error: null Apr 03 21:13:46 primo volumio[7763]: error: updateQueue error: null Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand clear took 40 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand clear took 40 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: ControllerMpd::getState Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand status Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand clear took 41 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand clear took 41 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand clear took 41 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand clear took 42 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand clear took 41 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand clear took 42 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand clear took 42 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand clear took 42 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand clear took 42 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand clear took 42 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand clear took 43 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand clear took 42 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: ------------------------------ 36ms Apr 03 21:13:46 primo volumio[7763]: info: ------------------------------ 35ms Apr 03 21:13:46 primo volumio[7763]: info: ------------------------------ 34ms Apr 03 21:13:46 primo volumio[7763]: info: ------------------------------ 33ms Apr 03 21:13:46 primo volumio[7763]: info: ------------------------------ 32ms Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand play took 25 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand play took 24 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand play took 24 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand play took 24 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand play took 23 milliseconds Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/18454013" Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/18454013" Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/18454013" Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/18454013" Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/18454013" Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/18454013" Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/18454013" Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/18454013" Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/18454013" Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/18454013" Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/18454013" Apr 03 21:13:46 primo volumio[7763]: STREAMING PROXY: Handling url /?data=qobuz://song/18454013 Apr 03 21:13:46 primo volumio[7763]: error: updateQueue error: null Apr 03 21:13:46 primo volumio[7763]: error: updateQueue error: null Apr 03 21:13:46 primo volumio[7763]: info: ------------------------------ 39ms Apr 03 21:13:46 primo volumio[7763]: info: sendMpdCommand status took 32 milliseconds Apr 03 21:13:46 primo volumio[7763]: info: ------------------------------ 38ms Apr 03 21:13:46 primo volumio[7763]: verbose: ControllerMpd::parseState Apr 03 21:13:46 primo volumio[7763]: info: ControllerMpd::pushState Apr 03 21:13:46 primo volumio[7763]: info: CoreCommandRouter::servicePushState Apr 03 21:13:46 primo volumio[7763]: info: CoreStateMachine::pushState Apr 03 21:13:46 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 21:13:46 primo volumio[7763]: info: CoreCommandRouter::volumioPushState Apr 03 21:13:46 primo volumio[7763]: info: CoreCommandRouter::volumioGetState Apr 03 21:13:46 primo volumio[7763]: info: MRS: Pushing multiroomSync output update for this device Apr 03 21:13:46 primo volumio[7763]: info: MRS: Pushing multiroomSync output Apr 03 21:13:46 primo volumio[7763]: info: CorePlayQueue::getTrack 3 Apr 03 21:13:46 primo volumio[7763]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 03 21:13:46 primo volumio[7763]: verbose: CURRENT POSITION 3 Apr 03 21:13:46 primo volumio[7763]: info: CoreStateMachine::syncState stateService stop Apr 03 21:13:46 primo volumio[7763]: info: CoreStateMachine::syncState currentStatus stop Apr 03 21:13:46 primo volumio[7763]: info: CoreStateMachine::pushState Apr 03 21:13:46 primo volumio[7763]: info: CoreCommandRouter::volumioPushState Apr 03 21:13:46 primo volumio[7763]: info: CoreCommandRouter::volumioGetState Apr 03 21:13:46 primo volumio[7763]: info: MRS: Pushing multiroomSync output update for this device Apr 03 21:13:46 primo volumio[7763]: info: MRS: Pushing multiroomSync output Apr 03 21:13:46 primo volumio[7763]: info: No code Apr 03 21:13:46 primo volumio[7763]: info: CoreStateMachine::pushState Apr 03 21:13:46 primo volumio[7763]: info: CoreCommandRouter::volumioPushState Apr 03 21:13:46 primo volumio[7763]: info: CoreCommandRouter::volumioGetState Apr 03 21:13:46 primo volumio[7763]: info: MRS: Pushing multiroomSync output update for this device Apr 03 21:13:46 primo volumio[7763]: info: MRS: Pushing multiroomSync output Apr 03 21:13:46 primo volumio5-onboarding[4386]: time=2026-04-03T21:13:46.564+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.223:51463,192.168.1.223:51464,00:00:00:00:00:00%26 @ 0x3852cc0" state=STATUS_PLAYING positionMs=2175 volume=100 Apr 03 21:13:46 primo volumio5-onboarding[4386]: time=2026-04-03T21:13:46.564+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.223:51463,192.168.1.223:51464,00:00:00:00:00:00%26 @ 0x3852cc0" id=qobuz://song/37709351 title="The Crystal Ship " Apr 03 21:13:46 primo volumio5-onboarding[4386]: time=2026-04-03T21:13:46.566+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.223:51463,192.168.1.223:51464,00:00:00:00:00:00%26 @ 0x3852cc0" state=STATUS_PLAYING positionMs=2175 volume=100 Apr 03 21:13:46 primo volumio5-onboarding[4386]: time=2026-04-03T21:13:46.567+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.223:51463,192.168.1.223:51464,00:00:00:00:00:00%26 @ 0x3852cc0" id=qobuz://song/37709351 title="The Crystal Ship " Apr 03 21:13:46 primo volumio5-onboarding[4386]: time=2026-04-03T21:13:46.568+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.223:51463,192.168.1.223:51464,00:00:00:00:00:00%26 @ 0x3852cc0" state=STATUS_PLAYING positionMs=2175 volume=100 Apr 03 21:13:46 primo volumio5-onboarding[4386]: time=2026-04-03T21:13:46.569+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.223:51463,192.168.1.223:51464,00:00:00:00:00:00%26 @ 0x3852cc0" id=qobuz://song/37709351 title="The Crystal Ship " Apr 03 21:13:46 primo volumio[7763]: info: ------------------------------ 91ms Apr 03 21:13:46 primo volumio[7763]: info: Executing endpoint getStreamUrlqobuz Apr 03 21:13:46 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 03 21:13:46 primo volumio[7763]: info: Executing endpoint getStreamUrlqobuz Apr 03 21:13:46 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 03 21:13:46 primo volumio[7763]: info: Signalling Playback active due to playback status change Apr 03 21:13:46 primo volumio[7763]: info: Signalling Playback active due to playback status change Apr 03 21:13:46 primo volumio[7763]: info: Signalling Playback active due to playback status change Apr 03 21:13:46 primo volumio[7763]: info: Updating RAAT Signal Path Apr 03 21:13:46 primo volumio[7763]: info: Updating RAAT Signal Path Apr 03 21:13:46 primo volumio[7763]: info: Updating RAAT Signal Path Apr 03 21:13:47 primo volumio[7763]: info: getStreamUrl took 416 milliseconds Apr 03 21:13:47 primo volumio[7763]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=11451729&eid=18454013&fmt=7&profile=raw&app_id=539451548&cid=4089136&etsp=1775243626&hmac=HU0BCXflDTS3WEq9_gD6_BWzuus Apr 03 21:13:47 primo volumio[7763]: STREAMING PROXY: Requesting upstream with range: none Apr 03 21:13:47 primo volumio[7763]: info: getStreamUrl took 449 milliseconds Apr 03 21:13:47 primo volumio[7763]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=11451729&eid=18454013&fmt=7&profile=raw&app_id=539451548&cid=4089136&etsp=1775243626&hmac=HU0BCXflDTS3WEq9_gD6_BWzuus Apr 03 21:13:47 primo volumio[7763]: STREAMING PROXY: Requesting upstream with range: none Apr 03 21:13:47 primo volumio[7763]: STREAMING PROXY: Upstream status code: 200 Apr 03 21:13:47 primo mpd[8018]: exception: No such playlist Apr 03 21:13:47 primo volumio[7763]: STREAMING PROXY: Client closed connection Apr 03 21:13:47 primo volumio[7763]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/18454013" Apr 03 21:13:47 primo volumio[7763]: STREAMING PROXY: Handling url /?data=qobuz://song/18454013 Apr 03 21:13:47 primo volumio[7763]: STREAMING PROXY: Upstream closed. Received 15708 bytes Apr 03 21:13:47 primo volumio[7763]: STREAMING PROXY: Incomplete transfer. Expected 51051290, got 15708 Apr 03 21:13:47 primo volumio[7763]: STREAMING PROXY: Retrying with range: bytes=15708- (attempt 1) Apr 03 21:13:47 primo volumio[7763]: info: Executing endpoint getStreamUrlqobuz Apr 03 21:13:47 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 03 21:13:47 primo volumio[7763]: STREAMING PROXY: Resuming with range: bytes=15708- Apr 03 21:13:47 primo volumio[7763]: STREAMING PROXY: Resume upstream status code: 206 Apr 03 21:13:48 primo volumio[7763]: STREAMING PROXY: Resuming with range: bytes=15708- Apr 03 21:13:48 primo volumio[7763]: STREAMING PROXY: Resume upstream status code: 206 Apr 03 21:13:48 primo volumio[7763]: info: Executing endpoint metavolumio Apr 03 21:13:48 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 21:13:48 primo volumio[7763]: info: Executing endpoint metavolumio Apr 03 21:13:48 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 21:13:48 primo volumio[7763]: info: Executing endpoint metavolumio Apr 03 21:13:48 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 21:13:49 primo volumio[7763]: STREAMING PROXY: Resume ended. Received 51035582 bytes Apr 03 21:13:51 primo volumio[7763]: STREAMING PROXY: Resume upstream status code: 206 Apr 03 21:13:52 primo volumio[7763]: STREAMING PROXY: Resume ended. Received 51035582 bytes Apr 03 21:13:52 primo volumio[7763]: info: getStreamUrl took 5623 milliseconds Apr 03 21:13:52 primo volumio[7763]: error: Could not get QOBUZ Stream URL: undefined Apr 03 21:13:52 primo volumio[7763]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Apr 03 21:13:52 primo mpd[8018]: exception: got HTTP status 404 Apr 03 21:13:52 primo mpd[8018]: exception: No such playlist Apr 03 21:13:52 primo volumio[7763]: STREAMING PROXY: Handling url /?data=qobuz://song/18454013 Apr 03 21:13:52 primo volumio[7763]: info: Executing endpoint getStreamUrlqobuz Apr 03 21:13:52 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 03 21:13:53 primo volumio[7763]: info: getStreamUrl took 409 milliseconds Apr 03 21:13:53 primo volumio[7763]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=11451729&eid=18454013&fmt=7&profile=raw&app_id=539451548&cid=4089136&etsp=1775243633&hmac=CvOntn5-6E1Ar-IuoLnuhZ2-T9A Apr 03 21:13:53 primo volumio[7763]: STREAMING PROXY: Requesting upstream with range: none Apr 03 21:13:53 primo volumio[7763]: STREAMING PROXY: Upstream status code: 200 Apr 03 21:13:53 primo mpd[8018]: exception: No such playlist Apr 03 21:13:53 primo volumio[7763]: STREAMING PROXY: Client closed connection Apr 03 21:13:53 primo volumio[7763]: STREAMING PROXY: Upstream closed. Received 15708 bytes Apr 03 21:13:53 primo volumio[7763]: STREAMING PROXY: Incomplete transfer. Expected 51051290, got 15708 Apr 03 21:13:53 primo volumio[7763]: STREAMING PROXY: Retrying with range: bytes=15708- (attempt 1) Apr 03 21:13:53 primo volumio[7763]: STREAMING PROXY: Handling url /?data=qobuz://song/18454013 Apr 03 21:13:53 primo volumio[7763]: info: Executing endpoint getStreamUrlqobuz Apr 03 21:13:53 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 03 21:13:54 primo volumio[7763]: info: getStreamUrl took 367 milliseconds Apr 03 21:13:54 primo volumio[7763]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=11451729&eid=18454013&fmt=7&profile=raw&app_id=539451548&cid=4089136&etsp=1775243633&hmac=CvOntn5-6E1Ar-IuoLnuhZ2-T9A Apr 03 21:13:54 primo volumio[7763]: STREAMING PROXY: Requesting upstream with range: none Apr 03 21:13:54 primo volumio[7763]: STREAMING PROXY: Upstream status code: 200 Apr 03 21:13:54 primo mpd[8018]: exception: No such playlist Apr 03 21:13:54 primo volumio[7763]: STREAMING PROXY: Client closed connection Apr 03 21:13:54 primo volumio[7763]: STREAMING PROXY: Upstream closed. Received 15708 bytes Apr 03 21:13:54 primo volumio[7763]: STREAMING PROXY: Incomplete transfer. Expected 51051290, got 15708 Apr 03 21:13:54 primo volumio[7763]: STREAMING PROXY: Retrying with range: bytes=15708- (attempt 1) Apr 03 21:13:54 primo volumio[7763]: STREAMING PROXY: Handling url /?data=qobuz://song/18454013 Apr 03 21:13:54 primo volumio[7763]: info: Executing endpoint getStreamUrlqobuz Apr 03 21:13:54 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 03 21:13:54 primo volumio[7763]: info: getStreamUrl took 447 milliseconds Apr 03 21:13:54 primo volumio[7763]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=11451729&eid=18454013&fmt=7&profile=raw&app_id=539451548&cid=4089136&etsp=1775243634&hmac=lfulpk9fBro0ZLYuM7Al0_b-m9Q Apr 03 21:13:54 primo volumio[7763]: STREAMING PROXY: Requesting upstream with range: none Apr 03 21:13:54 primo volumio[7763]: STREAMING PROXY: Resuming with range: bytes=15708- Apr 03 21:13:54 primo volumio[7763]: STREAMING PROXY: Resume upstream status code: 206 Apr 03 21:13:55 primo volumio[7763]: STREAMING PROXY: Resuming with range: bytes=15708- Apr 03 21:13:55 primo volumio[7763]: STREAMING PROXY: Resume upstream status code: 206 Apr 03 21:14:09 primo volumio[7763]: STREAMING PROXY: Upstream request error: Client network socket disconnected before secure TLS connection was established Apr 03 21:14:09 primo volumio[7763]: STREAMING PROXY: Retrying request (attempt 1) Apr 03 21:14:10 primo volumio[7763]: STREAMING PROXY: Requesting upstream with range: none Apr 03 21:14:11 primo volumio[7763]: STREAMING PROXY: Upstream status code: 200 Apr 03 21:14:19 primo volumio[7763]: STREAMING PROXY: Resume ended. Received 51035582 bytes Apr 03 21:14:19 primo volumio[7763]: info: CoreCommandRouter::volumioPause Apr 03 21:14:19 primo volumio[7763]: info: CoreStateMachine::pause Apr 03 21:14:20 primo volumio[7763]: info: CoreCommandRouter::volumioPause Apr 03 21:14:20 primo volumio[7763]: info: CoreStateMachine::pause Apr 03 21:14:21 primo volumio[7763]: info: CoreCommandRouter::volumioPause Apr 03 21:14:21 primo volumio[7763]: info: CoreStateMachine::pause Apr 03 21:14:24 primo volumio[7763]: STREAMING PROXY: Upstream status code: 200 Apr 03 21:14:24 primo mpd[8018]: exception: No such playlist Apr 03 21:14:24 primo volumio[7763]: STREAMING PROXY: Client closed connection Apr 03 21:14:24 primo volumio[7763]: STREAMING PROXY: Upstream closed. Received 15708 bytes Apr 03 21:14:24 primo volumio[7763]: STREAMING PROXY: Incomplete transfer. Expected 51051290, got 15708 Apr 03 21:14:24 primo volumio[7763]: STREAMING PROXY: Retrying with range: bytes=15708- (attempt 1) Apr 03 21:14:24 primo volumio[7763]: STREAMING PROXY: Handling url /?data=qobuz://song/18454013 Apr 03 21:14:24 primo volumio[7763]: info: Executing endpoint getStreamUrlqobuz Apr 03 21:14:24 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 03 21:14:24 primo volumio[7763]: info: getStreamUrl took 457 milliseconds Apr 03 21:14:24 primo volumio[7763]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=11451729&eid=18454013&fmt=7&profile=raw&app_id=539451548&cid=4089136&etsp=1775243664&hmac=AG_EAccfEM6Lysfup2h-xfuzGJ0 Apr 03 21:14:24 primo volumio[7763]: STREAMING PROXY: Requesting upstream with range: none Apr 03 21:14:25 primo volumio[7763]: STREAMING PROXY: Upstream status code: 200 Apr 03 21:14:25 primo volumio[7763]: STREAMING PROXY: Client closed connection Apr 03 21:14:25 primo mpd[8018]: exception: No such playlist Apr 03 21:14:25 primo volumio[7763]: STREAMING PROXY: Upstream closed. Received 15708 bytes Apr 03 21:14:25 primo volumio[7763]: STREAMING PROXY: Incomplete transfer. Expected 51051290, got 15708 Apr 03 21:14:25 primo volumio[7763]: STREAMING PROXY: Retrying with range: bytes=15708- (attempt 1) Apr 03 21:14:25 primo volumio[7763]: STREAMING PROXY: Handling url /?data=qobuz://song/18454013 Apr 03 21:14:25 primo volumio[7763]: info: Executing endpoint getStreamUrlqobuz Apr 03 21:14:25 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 03 21:14:25 primo volumio[7763]: STREAMING PROXY: Resuming with range: bytes=15708- Apr 03 21:14:25 primo volumio[7763]: STREAMING PROXY: Resume upstream status code: 206 Apr 03 21:14:26 primo volumio[7763]: STREAMING PROXY: Resuming with range: bytes=15708- Apr 03 21:14:28 primo volumio[7763]: STREAMING PROXY: Resume ended. Received 51035582 bytes Apr 03 21:14:30 primo volumio[7763]: info: getStreamUrl took 5754 milliseconds Apr 03 21:14:30 primo volumio[7763]: error: Could not get QOBUZ Stream URL: undefined Apr 03 21:14:30 primo volumio[7763]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Apr 03 21:14:30 primo mpd[8018]: exception: got HTTP status 404 Apr 03 21:14:30 primo mpd[8018]: exception: No such playlist Apr 03 21:14:30 primo volumio[7763]: STREAMING PROXY: Handling url /?data=qobuz://song/18454013 Apr 03 21:14:30 primo volumio[7763]: info: Executing endpoint getStreamUrlqobuz Apr 03 21:14:30 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 03 21:14:31 primo volumio[7763]: info: getStreamUrl took 389 milliseconds Apr 03 21:14:31 primo volumio[7763]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=11451729&eid=18454013&fmt=7&profile=raw&app_id=539451548&cid=4089136&etsp=1775243671&hmac=Dg-MkeAwPW6Y5E2zJtNuQWpVXyc Apr 03 21:14:31 primo volumio[7763]: STREAMING PROXY: Requesting upstream with range: none Apr 03 21:14:31 primo volumio[7763]: STREAMING PROXY: Upstream status code: 200 Apr 03 21:14:31 primo volumio[7763]: STREAMING PROXY: Client closed connection Apr 03 21:14:31 primo volumio[7763]: STREAMING PROXY: Upstream closed. Received 15708 bytes Apr 03 21:14:31 primo volumio[7763]: STREAMING PROXY: Incomplete transfer. Expected 51051290, got 15708 Apr 03 21:14:31 primo volumio[7763]: STREAMING PROXY: Retrying with range: bytes=15708- (attempt 1) Apr 03 21:14:31 primo mpd[8018]: exception: No such playlist Apr 03 21:14:31 primo volumio[7763]: STREAMING PROXY: Handling url /?data=qobuz://song/18454013 Apr 03 21:14:31 primo volumio[7763]: info: Executing endpoint getStreamUrlqobuz Apr 03 21:14:31 primo volumio[7763]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 03 21:14:31 primo volumio[7763]: info: getStreamUrl took 467 milliseconds Apr 03 21:14:31 primo volumio[7763]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=11451729&eid=18454013&fmt=7&profile=raw&app_id=539451548&cid=4089136&etsp=1775243671&hmac=Dg-MkeAwPW6Y5E2zJtNuQWpVXyc Apr 03 21:14:31 primo volumio[7763]: STREAMING PROXY: Requesting upstream with range: none Apr 03 21:14:32 primo volumio[7763]: STREAMING PROXY: Resuming with range: bytes=15708- Apr 03 21:14:32 primo volumio[7763]: STREAMING PROXY: Resume upstream status code: 206 Apr 03 21:14:39 primo volumio[7763]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 21:14:39 primo volumio[7763]: Error: connect ETIMEDOUT 146.75.118.79:443 Apr 03 21:14:39 primo volumio[7763]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 03 21:14:39 primo volumio[7763]: errno: -110, Apr 03 21:14:39 primo volumio[7763]: code: 'ETIMEDOUT', Apr 03 21:14:39 primo volumio[7763]: syscall: 'connect', Apr 03 21:14:39 primo volumio[7763]: address: '146.75.118.79', Apr 03 21:14:39 primo volumio[7763]: port: 443 Apr 03 21:14:39 primo volumio[7763]: } Apr 03 21:14:39 primo volumio[7763]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 21:14:40 primo volumio5-onboarding[4386]: time=2026-04-03T21:14:40.019+03:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.223:51463,192.168.1.223:51464,00:00:00:00:00:00%26 @ 0x3852cc0" latency=50.632222ms timeout=3s volume=104 Apr 03 21:14:40 primo volumio5-onboarding[4386]: time=2026-04-03T21:14:40.020+03:00 level=ERROR msg="failed to set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.223:51463,192.168.1.223:51464,00:00:00:00:00:00%26 @ 0x3852cc0" latency=50.632222ms timeout=3s volume=104 error="could not set volume to 104: volume must be between 0 and 100, got 104" Apr 03 21:14:40 primo sudo[10454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-03 21:13 Apr 03 21:14:40 primo sudo[10454]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 12:16:07 PM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="88686576587e39571d6cf25f3920fbc0"