-- Logs begin at Fri 2025-02-28 21:11:22 JST, end at Fri 2025-02-28 21:21:34 JST. -- Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Executing endpoint metavolumio Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Executing endpoint metavolumio Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Executing endpoint metavolumio Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 21:20:00 rivoblackedition volumio[3001]: info: getStreamUrl took 1159 milliseconds Feb 28 21:20:00 rivoblackedition volumio[3001]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3527962&eid=2127011&fmt=6&profile=raw&app_id=539451548&cid=2553630&etsp=1740748800&hmac=-y28Zvu4bbAq3wrRPM5RJxV6Mjw Feb 28 21:20:00 rivoblackedition volumio[3001]: STREAMING PROXY: Response: 200, length: 27546166 Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Feb 28 21:20:00 rivoblackedition volumio[3001]: ---------------------------- MPD announces state update: player Feb 28 21:20:00 rivoblackedition volumio[3001]: info: ControllerMpd::getState Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand status Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Feb 28 21:20:00 rivoblackedition volumio[3001]: ---------------------------- MPD announces state update: player Feb 28 21:20:00 rivoblackedition volumio[3001]: info: ControllerMpd::getState Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand status Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Feb 28 21:20:00 rivoblackedition volumio[3001]: ---------------------------- MPD announces state update: player Feb 28 21:20:00 rivoblackedition volumio[3001]: info: sendMpdCommand status took 348 milliseconds Feb 28 21:20:00 rivoblackedition volumio[3001]: info: ControllerMpd::getState Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand status Feb 28 21:20:00 rivoblackedition volumio[3001]: info: sendMpdCommand status took 348 milliseconds Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseState Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseState Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 21:20:00 rivoblackedition volumio[3001]: info: sendMpdCommand status took 5 milliseconds Feb 28 21:20:00 rivoblackedition volumio[3001]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 28 21:20:00 rivoblackedition volumio[3001]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseState Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseTrackInfo Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseTrackInfo Feb 28 21:20:00 rivoblackedition volumio[3001]: info: ControllerMpd::pushState Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreCommandRouter::servicePushState Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CorePlayQueue::getTrack 3 Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"749 Kbps","isStreaming":false,"title":"2127011","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2127011","trackType":"qobuz"} Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: CURRENT POSITION 3 Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState stateService play Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState currentStatus stop Feb 28 21:20:00 rivoblackedition volumio[3001]: info: ControllerMpd::pushState Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreCommandRouter::servicePushState Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CorePlayQueue::getTrack 3 Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"875 Kbps","isStreaming":false,"title":"2127011","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2127011","trackType":"qobuz"} Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: CURRENT POSITION 3 Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState stateService play Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState currentStatus play Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Received an update from plugin. extracting info from payload Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreStateMachine::pushState Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPushState Feb 28 21:20:00 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output update for this device Feb 28 21:20:00 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreStateMachine::pushState Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPushState Feb 28 21:20:00 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output update for this device Feb 28 21:20:00 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output Feb 28 21:20:00 rivoblackedition volumio[3001]: info: ------------------------------ 406ms Feb 28 21:20:00 rivoblackedition volumio[3001]: info: ------------------------------ 444ms Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Feb 28 21:20:00 rivoblackedition volumio[3001]: ---------------------------- MPD announces state update: player Feb 28 21:20:00 rivoblackedition volumio[3001]: info: ControllerMpd::getState Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand status Feb 28 21:20:00 rivoblackedition volumio[3001]: info: sendMpdCommand playlistinfo took 87 milliseconds Feb 28 21:20:00 rivoblackedition volumio[3001]: info: sendMpdCommand status took 4 milliseconds Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseTrackInfo Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseState Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 21:20:00 rivoblackedition volumio[3001]: info: ControllerMpd::pushState Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreCommandRouter::servicePushState Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CorePlayQueue::getTrack 3 Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1189,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"894 Kbps","isStreaming":false,"title":"2127011","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2127011","trackType":"qobuz"} Feb 28 21:20:00 rivoblackedition volumio[3001]: verbose: CURRENT POSITION 3 Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState stateService play Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState currentStatus play Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Received an update from plugin. extracting info from payload Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreStateMachine::pushState Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPushState Feb 28 21:20:00 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output update for this device Feb 28 21:20:00 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreStateMachine::pushState Feb 28 21:20:00 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPushState Feb 28 21:20:00 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output update for this device Feb 28 21:20:00 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output Feb 28 21:20:00 rivoblackedition volumio[3001]: info: ------------------------------ 190ms Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Signalling Playback active due to playback status change Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Signalling Playback active due to playback status change Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Signalling Playback active due to playback status change Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Signalling Playback active due to playback status change Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Updating RAAT Signal Path Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Updating RAAT Signal Path Feb 28 21:20:00 rivoblackedition volumio[3001]: info: Updating RAAT Signal Path Feb 28 21:20:01 rivoblackedition volumio[3001]: info: Updating RAAT Signal Path Feb 28 21:20:01 rivoblackedition volumio[3001]: info: sendMpdCommand playlistinfo took 212 milliseconds Feb 28 21:20:01 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseTrackInfo Feb 28 21:20:01 rivoblackedition volumio[3001]: info: ControllerMpd::pushState Feb 28 21:20:01 rivoblackedition volumio[3001]: info: CoreCommandRouter::servicePushState Feb 28 21:20:01 rivoblackedition volumio[3001]: info: CorePlayQueue::getTrack 3 Feb 28 21:20:01 rivoblackedition volumio[3001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"850 Kbps","isStreaming":false,"title":"2127011","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2127011","trackType":"qobuz"} Feb 28 21:20:01 rivoblackedition volumio[3001]: verbose: CURRENT POSITION 3 Feb 28 21:20:01 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState stateService play Feb 28 21:20:01 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState currentStatus play Feb 28 21:20:01 rivoblackedition volumio[3001]: info: Received an update from plugin. extracting info from payload Feb 28 21:20:01 rivoblackedition volumio[3001]: info: CoreStateMachine::pushState Feb 28 21:20:01 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 21:20:01 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPushState Feb 28 21:20:01 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output update for this device Feb 28 21:20:01 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output Feb 28 21:20:01 rivoblackedition volumio[3001]: info: CoreStateMachine::pushState Feb 28 21:20:01 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPushState Feb 28 21:20:01 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output update for this device Feb 28 21:20:01 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output Feb 28 21:20:01 rivoblackedition volumio[3001]: info: ------------------------------ 278ms Feb 28 21:20:01 rivoblackedition volumio[3001]: info: Signalling Playback active due to playback status change Feb 28 21:20:01 rivoblackedition volumio[3001]: info: Signalling Playback active due to playback status change Feb 28 21:20:01 rivoblackedition volumio[3001]: info: Updating RAAT Signal Path Feb 28 21:20:01 rivoblackedition volumio[3001]: info: Updating RAAT Signal Path Feb 28 21:20:01 rivoblackedition volumio[3001]: info: MCU Signalled Playback Active Feb 28 21:20:02 rivoblackedition volumio[3001]: STREAMING PROXY: Client dropped request, destroying Feb 28 21:20:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 21:20:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 21:20:19 rivoblackedition volumio[3001]: info: Discovery: Getting this device information Feb 28 21:20:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioGetState Feb 28 21:20:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 21:20:21 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 21:20:21 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 21:20:21 rivoblackedition volumio[3001]: info: Discovery: Getting this device information Feb 28 21:20:21 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioGetState Feb 28 21:20:21 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 21:20:39 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 21:20:39 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 21:20:39 rivoblackedition volumio[3001]: info: Discovery: Getting this device information Feb 28 21:20:39 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioGetState Feb 28 21:20:39 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 21:20:41 rivoblackedition volumio[3001]: Searching all installed plugins Feb 28 21:20:41 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 21:20:41 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: , search Feb 28 21:20:41 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 28 21:20:41 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Feb 28 21:20:41 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Feb 28 21:20:41 rivoblackedition volumio[3001]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Feb 28 21:20:41 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 28 21:20:41 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Feb 28 21:20:42 rivoblackedition volumio[3001]: (node:3001) UnhandledPromiseRejectionWarning: Error: Start tag expected, '<' not found Feb 28 21:20:42 rivoblackedition volumio[3001]: at Object.module.exports.fromXml (/volumio/node_modules/libxmljs/lib/document.js:168:21) Feb 28 21:20:42 rivoblackedition volumio[3001]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Feb 28 21:20:42 rivoblackedition volumio[3001]: at runMicrotasks () Feb 28 21:20:42 rivoblackedition volumio[3001]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 28 21:20:42 rivoblackedition volumio[3001]: (node:3001) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Feb 28 21:20:42 rivoblackedition volumio[3001]: (node:3001) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Feb 28 21:20:43 rivoblackedition volumio[3001]: info: searchQOBUZUri took 2213 milliseconds Feb 28 21:20:46 rivoblackedition volumio[3001]: error: Search in plugin webradio timed out Feb 28 21:20:46 rivoblackedition volumio[3001]: info: All search sources collected, pushing search results Feb 28 21:20:49 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 28 21:20:49 rivoblackedition volumio[3001]: info: CURURI: artists://Klymaxx Feb 28 21:20:49 rivoblackedition volumio[3001]: info: Preload queue cleared Feb 28 21:20:49 rivoblackedition volumio[3001]: info: Preload queue cleared Feb 28 21:20:49 rivoblackedition volumio[3001]: info: Preloading song: music-library/NAS/Synology/Compilations/Silky -Music For The Night-/04 I Miss You.m4a Feb 28 21:20:49 rivoblackedition volumio[3001]: info: Exploding uri music-library/NAS/Synology/Compilations/Silky -Music For The Night-/04 I Miss You.m4a in service mpd Feb 28 21:20:49 rivoblackedition volumio[3001]: info: ALBUMART /albumart?cacheid=610&web=Klymaxx/Silky%20-Music%20For%20The%20Night-/extralarge&path=%2Fmnt%2FNAS%2FSynology%2FCompilations%2FSilky%20-Music%20For%20The%20Night-%2F04%20I%20Miss%20You.m4a&metadata=false Feb 28 21:20:49 rivoblackedition volumio[3001]: info: URI /mnt/NAS/Synology/Compilations/Silky -Music For The Night-/04 I Miss You.m4a Feb 28 21:20:49 rivoblackedition volumio[3001]: info: Executing endpoint getSimilarArtists Feb 28 21:20:49 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 28 21:20:49 rivoblackedition volumio[3001]: info: Executing endpoint getSimilarArtists Feb 28 21:20:49 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 28 21:20:49 rivoblackedition volumio[3001]: info: Executing endpoint getSimilarArtists Feb 28 21:20:49 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 28 21:20:49 rivoblackedition volumio[3001]: info: Executing endpoint getSimilarArtists Feb 28 21:20:49 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 28 21:20:49 rivoblackedition volumio[3001]: info: Executing endpoint getSimilarArtists Feb 28 21:20:49 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 28 21:20:49 rivoblackedition volumio[3001]: info: Executing endpoint getSimilarArtists Feb 28 21:20:49 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 28 21:20:50 rivoblackedition volumio[3001]: info: Executing endpoint getSimilarArtists Feb 28 21:20:50 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 28 21:20:50 rivoblackedition volumio[3001]: info: Executing endpoint getSimilarArtists Feb 28 21:20:50 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 28 21:20:50 rivoblackedition volumio[3001]: info: Executing endpoint metavolumio Feb 28 21:20:50 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 21:20:50 rivoblackedition volumio[3001]: info: Executing endpoint metavolumio Feb 28 21:20:50 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 21:20:50 rivoblackedition volumio[3001]: info: Executing endpoint metavolumio Feb 28 21:20:50 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 21:20:50 rivoblackedition volumio[3001]: info: Executing endpoint metavolumio Feb 28 21:20:50 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 21:20:51 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 21:20:51 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 21:20:51 rivoblackedition volumio[3001]: info: Discovery: Getting this device information Feb 28 21:20:51 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioGetState Feb 28 21:20:51 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 21:20:51 rivoblackedition volumio[3001]: info: Executing endpoint metavolumio Feb 28 21:20:51 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 21:20:51 rivoblackedition volumio[3001]: info: Executing endpoint metavolumio Feb 28 21:20:51 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 21:20:51 rivoblackedition volumio[3001]: info: Executing endpoint metavolumio Feb 28 21:20:51 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 21:20:51 rivoblackedition volumio[3001]: info: Executing endpoint metavolumio Feb 28 21:20:51 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 21:20:59 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 21:20:59 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 21:20:59 rivoblackedition volumio[3001]: info: Discovery: Getting this device information Feb 28 21:20:59 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioGetState Feb 28 21:20:59 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 21:21:15 rivoblackedition volumio[3001]: info: Preload queue cleared Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioAddQueueItems Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreStateMachine::addQueueItems Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CorePlayQueue::addQueueItems Feb 28 21:21:15 rivoblackedition volumio[3001]: info: Preload queue cleared Feb 28 21:21:15 rivoblackedition volumio[3001]: info: Adding Item to queue: qobuz://song/76006379 Feb 28 21:21:15 rivoblackedition volumio[3001]: info: Using cached record of: qobuz://song/76006379 Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPushQueue Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CorePlayQueue::saveQueue Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreStateMachine::updateTrackBlock Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CorePlayQueue::getTrackBlock Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPlay Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreStateMachine::play index 4 Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreStateMachine::stop Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreStateMachine::stPlaybackTimer Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreStateMachine::updateTrackBlock Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CorePlayQueue::getTrackBlock Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreStateMachine::pushState Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CorePlayQueue::getTrack 3 Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPushState Feb 28 21:21:15 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output update for this device Feb 28 21:21:15 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreStateMachine::serviceStop Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CorePlayQueue::getTrack 3 Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreCommandRouter::serviceStop Feb 28 21:21:15 rivoblackedition volumio[3001]: info: [1740745275499] ControllerQobuz::stop Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 28 21:21:15 rivoblackedition volumio[3001]: info: ControllerMpd::stop Feb 28 21:21:15 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand stop Feb 28 21:21:15 rivoblackedition volumio[3001]: info: Updating RAAT Signal Path Feb 28 21:21:15 rivoblackedition volumio[3001]: info: sendMpdCommand stop took 38 milliseconds Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreStateMachine::play index undefined Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CorePlayQueue::getTrack 4 Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreStateMachine::startPlaybackTimer Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CorePlayQueue::getTrack 4 Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioGetVisibleSources Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 28 21:21:15 rivoblackedition volumio[3001]: info: [1740745275543] ControllerQobuz::clearAddPlayTrack Feb 28 21:21:15 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand stop Feb 28 21:21:15 rivoblackedition volumio[3001]: info: Feb 28 21:21:15 rivoblackedition volumio[3001]: ---------------------------- MPD announces state update: player Feb 28 21:21:15 rivoblackedition volumio[3001]: info: ControllerMpd::getState Feb 28 21:21:15 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand status Feb 28 21:21:15 rivoblackedition volumio[3001]: info: Feb 28 21:21:15 rivoblackedition volumio[3001]: ---------------------------- MPD announces state update: player Feb 28 21:21:15 rivoblackedition volumio[3001]: info: ControllerMpd::getState Feb 28 21:21:15 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand status Feb 28 21:21:15 rivoblackedition volumio[3001]: info: sendMpdCommand stop took 5 milliseconds Feb 28 21:21:15 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand clear Feb 28 21:21:15 rivoblackedition volumio[3001]: info: sendMpdCommand status took 9 milliseconds Feb 28 21:21:15 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseState Feb 28 21:21:15 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 21:21:15 rivoblackedition volumio[3001]: info: Feb 28 21:21:15 rivoblackedition volumio[3001]: ---------------------------- MPD announces system playlist update Feb 28 21:21:15 rivoblackedition volumio[3001]: info: Ignoring MPD Status Update Feb 28 21:21:15 rivoblackedition volumio[3001]: info: sendMpdCommand status took 12 milliseconds Feb 28 21:21:15 rivoblackedition volumio[3001]: info: sendMpdCommand clear took 10 milliseconds Feb 28 21:21:15 rivoblackedition volumio[3001]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 28 21:21:15 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseState Feb 28 21:21:15 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 21:21:15 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/76006379" Feb 28 21:21:15 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseTrackInfo Feb 28 21:21:15 rivoblackedition volumio[3001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 28 21:21:15 rivoblackedition volumio[3001]: info: ------------------------------ 21ms Feb 28 21:21:15 rivoblackedition volumio[3001]: info: Feb 28 21:21:15 rivoblackedition volumio[3001]: ---------------------------- MPD announces system playlist update Feb 28 21:21:15 rivoblackedition volumio[3001]: info: Ignoring MPD Status Update Feb 28 21:21:15 rivoblackedition volumio[3001]: STREAMING PROXY: Handling url /?data=qobuz://song/76006379 Feb 28 21:21:15 rivoblackedition volumio[3001]: error: updateQueue error: null Feb 28 21:21:15 rivoblackedition volumio[3001]: info: ------------------------------ 13ms Feb 28 21:21:15 rivoblackedition volumio[3001]: info: MCU Signalled Playback Inactive Feb 28 21:21:15 rivoblackedition volumio[3001]: info: Executing endpoint getStreamUrlqobuz Feb 28 21:21:15 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 28 21:21:16 rivoblackedition volumio[3001]: info: getStreamUrl took 802 milliseconds Feb 28 21:21:16 rivoblackedition volumio[3001]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3527962&eid=76006379&fmt=6&profile=raw&app_id=539451548&cid=2553630&etsp=1740748876&hmac=FB2vgWH2Axp4_wltxCpEQlDztS4 Feb 28 21:21:17 rivoblackedition volumio[3001]: STREAMING PROXY: Response: 200, length: 58812300 Feb 28 21:21:17 rivoblackedition volumio[3001]: STREAMING PROXY: Client dropped request, destroying Feb 28 21:21:17 rivoblackedition volumio[3001]: error: updateQueue error: null Feb 28 21:21:17 rivoblackedition volumio[3001]: info: sendMpdCommand playlistinfo took 2010 milliseconds Feb 28 21:21:17 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/76006379" Feb 28 21:21:17 rivoblackedition volumio[3001]: info: ------------------------------ 2003ms Feb 28 21:21:17 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseTrackInfo Feb 28 21:21:17 rivoblackedition volumio[3001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 28 21:21:17 rivoblackedition volumio[3001]: info: ------------------------------ 2031ms Feb 28 21:21:17 rivoblackedition volumio[3001]: info: Feb 28 21:21:17 rivoblackedition volumio[3001]: ---------------------------- MPD announces system playlist update Feb 28 21:21:17 rivoblackedition volumio[3001]: info: Ignoring MPD Status Update Feb 28 21:21:17 rivoblackedition volumio[3001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/76006379" took 8 milliseconds Feb 28 21:21:17 rivoblackedition volumio[3001]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 28 21:21:17 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand play Feb 28 21:21:17 rivoblackedition volumio[3001]: info: Feb 28 21:21:17 rivoblackedition volumio[3001]: ---------------------------- MPD announces system playlist update Feb 28 21:21:17 rivoblackedition volumio[3001]: info: Ignoring MPD Status Update Feb 28 21:21:17 rivoblackedition volumio[3001]: STREAMING PROXY: Handling url /?data=qobuz://song/76006379 Feb 28 21:21:17 rivoblackedition volumio[3001]: info: ------------------------------ 19ms Feb 28 21:21:17 rivoblackedition volumio[3001]: info: sendMpdCommand play took 4 milliseconds Feb 28 21:21:17 rivoblackedition volumio[3001]: info: ------------------------------ 4ms Feb 28 21:21:17 rivoblackedition volumio[3001]: info: Executing endpoint getStreamUrlqobuz Feb 28 21:21:17 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 28 21:21:18 rivoblackedition volumio[3001]: info: getStreamUrl took 830 milliseconds Feb 28 21:21:18 rivoblackedition volumio[3001]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3527962&eid=76006379&fmt=6&profile=raw&app_id=539451548&cid=2553630&etsp=1740748878&hmac=SYYkxjHW29kf16Tfg9Vo_pNZ8v0 Feb 28 21:21:18 rivoblackedition volumio[3001]: STREAMING PROXY: Response: 200, length: 58812300 Feb 28 21:21:18 rivoblackedition volumio[3001]: info: Feb 28 21:21:18 rivoblackedition volumio[3001]: ---------------------------- MPD announces state update: player Feb 28 21:21:18 rivoblackedition volumio[3001]: info: ControllerMpd::getState Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand status Feb 28 21:21:18 rivoblackedition volumio[3001]: info: Feb 28 21:21:18 rivoblackedition volumio[3001]: ---------------------------- MPD announces state update: player Feb 28 21:21:18 rivoblackedition volumio[3001]: info: ControllerMpd::getState Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand status Feb 28 21:21:18 rivoblackedition volumio[3001]: info: Feb 28 21:21:18 rivoblackedition volumio[3001]: ---------------------------- MPD announces state update: player Feb 28 21:21:18 rivoblackedition volumio[3001]: info: sendMpdCommand status took 232 milliseconds Feb 28 21:21:18 rivoblackedition volumio[3001]: info: ControllerMpd::getState Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand status Feb 28 21:21:18 rivoblackedition volumio[3001]: info: sendMpdCommand status took 232 milliseconds Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseState Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseState Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 21:21:18 rivoblackedition volumio[3001]: info: Feb 28 21:21:18 rivoblackedition volumio[3001]: ---------------------------- MPD announces state update: player Feb 28 21:21:18 rivoblackedition volumio[3001]: info: ControllerMpd::getState Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand status Feb 28 21:21:18 rivoblackedition volumio[3001]: info: sendMpdCommand status took 6 milliseconds Feb 28 21:21:18 rivoblackedition volumio[3001]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 28 21:21:18 rivoblackedition volumio[3001]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 28 21:21:18 rivoblackedition volumio[3001]: info: sendMpdCommand status took 4 milliseconds Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseState Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseTrackInfo Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseTrackInfo Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseState Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 21:21:18 rivoblackedition volumio[3001]: info: ControllerMpd::pushState Feb 28 21:21:18 rivoblackedition volumio[3001]: info: CoreCommandRouter::servicePushState Feb 28 21:21:18 rivoblackedition volumio[3001]: info: CorePlayQueue::getTrack 4 Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":487,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"76006379","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/76006379","trackType":"qobuz"} Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: CURRENT POSITION 4 Feb 28 21:21:18 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState stateService play Feb 28 21:21:18 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState currentStatus stop Feb 28 21:21:18 rivoblackedition volumio[3001]: info: ControllerMpd::pushState Feb 28 21:21:18 rivoblackedition volumio[3001]: info: CoreCommandRouter::servicePushState Feb 28 21:21:18 rivoblackedition volumio[3001]: info: CorePlayQueue::getTrack 4 Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":487,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"76006379","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/76006379","trackType":"qobuz"} Feb 28 21:21:18 rivoblackedition volumio[3001]: verbose: CURRENT POSITION 4 Feb 28 21:21:18 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState stateService play Feb 28 21:21:18 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState currentStatus play Feb 28 21:21:18 rivoblackedition volumio[3001]: info: Received an update from plugin. extracting info from payload Feb 28 21:21:18 rivoblackedition volumio[3001]: info: CoreStateMachine::pushState Feb 28 21:21:18 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 21:21:18 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPushState Feb 28 21:21:18 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output update for this device Feb 28 21:21:18 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output Feb 28 21:21:18 rivoblackedition volumio[3001]: info: CoreStateMachine::pushState Feb 28 21:21:18 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPushState Feb 28 21:21:18 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output update for this device Feb 28 21:21:18 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output Feb 28 21:21:18 rivoblackedition volumio[3001]: info: ------------------------------ 265ms Feb 28 21:21:19 rivoblackedition volumio[3001]: info: ------------------------------ 277ms Feb 28 21:21:19 rivoblackedition volumio[3001]: info: sendMpdCommand playlistinfo took 41 milliseconds Feb 28 21:21:19 rivoblackedition volumio[3001]: info: sendMpdCommand playlistinfo took 40 milliseconds Feb 28 21:21:19 rivoblackedition volumio[3001]: verbose: ControllerMpd::parseTrackInfo Feb 28 21:21:19 rivoblackedition volumio[3001]: info: ControllerMpd::pushState Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::servicePushState Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CorePlayQueue::getTrack 4 Feb 28 21:21:19 rivoblackedition volumio[3001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":487,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"961 Kbps","isStreaming":false,"title":"76006379","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/76006379","trackType":"qobuz"} Feb 28 21:21:19 rivoblackedition volumio[3001]: verbose: CURRENT POSITION 4 Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState stateService play Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState currentStatus play Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Received an update from plugin. extracting info from payload Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreStateMachine::pushState Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPushState Feb 28 21:21:19 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output update for this device Feb 28 21:21:19 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreStateMachine::pushState Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPushState Feb 28 21:21:19 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output update for this device Feb 28 21:21:19 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output Feb 28 21:21:19 rivoblackedition volumio[3001]: info: ControllerMpd::pushState Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::servicePushState Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CorePlayQueue::getTrack 4 Feb 28 21:21:19 rivoblackedition volumio[3001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":487,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"961 Kbps","isStreaming":false,"title":"76006379","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/76006379","trackType":"qobuz"} Feb 28 21:21:19 rivoblackedition volumio[3001]: verbose: CURRENT POSITION 4 Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState stateService play Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreStateMachine::syncState currentStatus play Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Received an update from plugin. extracting info from payload Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreStateMachine::pushState Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPushState Feb 28 21:21:19 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output update for this device Feb 28 21:21:19 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreStateMachine::pushState Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioPushState Feb 28 21:21:19 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output update for this device Feb 28 21:21:19 rivoblackedition volumio[3001]: info: MRS: Pushing multiroomSync output Feb 28 21:21:19 rivoblackedition volumio[3001]: info: ------------------------------ 144ms Feb 28 21:21:19 rivoblackedition volumio[3001]: info: ------------------------------ 139ms Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Signalling Playback active due to playback status change Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Signalling Playback active due to playback status change Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Signalling Playback active due to playback status change Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Signalling Playback active due to playback status change Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Signalling Playback active due to playback status change Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Signalling Playback active due to playback status change Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Updating RAAT Signal Path Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Updating RAAT Signal Path Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Updating RAAT Signal Path Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Updating RAAT Signal Path Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Updating RAAT Signal Path Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Updating RAAT Signal Path Feb 28 21:21:19 rivoblackedition volumio[3001]: info: MCU Signalled Playback Active Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 21:21:19 rivoblackedition volumio[3001]: info: Discovery: Getting this device information Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioGetState Feb 28 21:21:19 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 21:21:21 rivoblackedition volumio[3001]: info: Executing endpoint metavolumio Feb 28 21:21:21 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 21:21:21 rivoblackedition volumio[3001]: info: Executing endpoint metavolumio Feb 28 21:21:21 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 21:21:21 rivoblackedition volumio[3001]: info: Executing endpoint metavolumio Feb 28 21:21:21 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 21:21:21 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 21:21:21 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 21:21:21 rivoblackedition volumio[3001]: info: Discovery: Getting this device information Feb 28 21:21:21 rivoblackedition volumio[3001]: info: CoreCommandRouter::volumioGetState Feb 28 21:21:21 rivoblackedition volumio[3001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 21:21:24 rivoblackedition volumio[3001]: STREAMING PROXY: Client dropped request, destroying Feb 28 21:21:33 rivoblackedition volumio[3001]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 28 21:21:33 rivoblackedition volumio[3001]: Error: connect ETIMEDOUT 151.101.90.79:80 Feb 28 21:21:33 rivoblackedition volumio[3001]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 28 21:21:33 rivoblackedition volumio[3001]: errno: -110, Feb 28 21:21:33 rivoblackedition volumio[3001]: code: 'ETIMEDOUT', Feb 28 21:21:33 rivoblackedition volumio[3001]: syscall: 'connect', Feb 28 21:21:33 rivoblackedition volumio[3001]: address: '151.101.90.79', Feb 28 21:21:33 rivoblackedition volumio[3001]: port: 80 Feb 28 21:21:33 rivoblackedition volumio[3001]: } Feb 28 21:21:33 rivoblackedition volumio[3001]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 28 21:21:34 rivoblackedition sudo[6044]: volumio : unable to resolve host rivoblackedition Feb 28 21:21:34 rivoblackedition sudo[6044]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 21:21:34 rivoblackedition sudo[6044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-28 21:20 Feb 28 21:21:34 rivoblackedition sudo[6044]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"